This thread has been locked.

If you have a related question, please click the "Ask a related question" button in the top right corner. The newly created question will be automatically linked to this question.

LP-CC2652R7: The lock-app program in the CC2652R7 burn connecthomeip example cannot connect to the Raspberry Pi boundary route.

Part Number: LP-CC2652R7
Other Parts Discussed in Thread: CC2652R7, UNIFLASH

Hi team,

Here's the request from customer:

May I ask if the problem is that the program in the CC2652R7 was not burned correctly?

Compiled and generated Hex files under ubuntu, and then burned them to cc2652r7 through uniflash.

Project is git clone --recurse-submodules https://github.com/project-chip/connectedhomeip

The serial port printing of CC2652R7 board is as follows:

#000001 [ 0.039 ] INFO: (led_service.c:191) Registered service, 5 attributes, status 0x00
#000002 [ 0.040 ] INFO: (button_service.c:230) Registered service, 7 attributes
#000003 [ 0.040 ] INFO: (data_service.c:209) Registered service, 6 attributes
#000004 [ 0.041 ] INFO: (project_zero.c:660) Registered OAD Service
#000005 [ 0.041 ] INFO: (project_zero.c:2888) Left button not held under boot, not reverting to factory.
#000006 [ 0.041 ] INFO: (project_zero.c:2890) Right+Left button not held under boot, not erasing external flash.
#000007 [ 0.041 ] INFO: (project_zero.c:678) OAD Image v0001
#000008 [ 0.041 ] INFO: (led_service.c:207) Registered callbacks to application. Struct @200021e4
#000009 [ 0.041 ] INFO: (button_service.c:248) Registered callbacks to application. Struct @200021ec
#000010 [ 0.041 ] INFO: (data_service.c:227) Registered callbacks to application. Struct @200021f4
#000011 [ 0.041 ] INFO: (led_service.c:242) SetParameter : LED0 len: 1
#000012 [ 0.041 ] INFO: (led_service.c:250) SetParameter : LED1 len: 1
#000013 [ 0.041 ] INFO: (button_service.c:289) SetParameter : BUTTON0 len: 1
#000014 [ 0.041 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000015 [ 0.041 ] INFO: (button_service.c:300) SetParameter : BUTTON1 len: 1
#000016 [ 0.041 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000017 [ 0.041 ] INFO: (data_service.c:265) SetParameter : String len: 40
#000018 [ 0.041 ] INFO: (data_service.c:276) SetParameter : Stream len: 20
#000019 [ 0.041 ] INFO: (data_service.c:298) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000020 [ 0.048 ] INFO: (project_zero.c:1161) GAP is started. Our address: D8:B6:73:6C:14:EC
#000021 [ 0.048 ] INFO: (project_zero.c:1179) Name in advertData array: ProjectZero
#000022 [ 0.053 ] INFO: (project_zero.c:1382) Adv Set 0 Enabled
#000023 [ 4.195 ] INFO: (project_zero.c:2628) Button interrupt: Button 1
#000024 [ 4.245 ] INFO: (project_zero.c:1994) Button 1 pressed
#000025 [ 4.245 ] INFO: (button_service.c:300) SetParameter : BUTTON1 len: 1
#000026 [ 4.245 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000027 [ 6.896 ] INFO: (project_zero.c:2628) Button interrupt: Button 1
#000028 [ 6.946 ] INFO: (project_zero.c:1994) Button 1 released
#000029 [ 6.946 ] INFO: (button_service.c:300) SetParameter : BUTTON1 len: 1
#000030 [ 6.946 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000031 [ 366.585 ] INFO: (project_zero.c:2628) Button interrupt: Button 1
#000032 [ 366.635 ] INFO: (project_zero.c:1994) Button 1 pressed
#000033 [ 366.635 ] INFO: (button_service.c:300) SetParameter : BUTTON1 len: 1
#000034 [ 366.635 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled
#000035 [ 369.183 ] INFO: (project_zero.c:2628) Button interrupt: Button 1
#000036 [ 369.233 ] INFO: (project_zero.c:1994) Button 1 released
#000037 [ 369.233 ] INFO: (button_service.c:300) SetParameter : BUTTON1 len: 1
#000038 [ 369.233 ] INFO: (button_service.c:322) Trying to send noti/ind: connHandle ffff, Noti/ind disabled

The border routing verification RCP built by Raspberry Pi cannot pass:

ubuntu@raspberrypi:~$ sudo ot-ctl state
[sudo] password for ubuntu:
connect session failed: No such file or directory
ubuntu@raspberrypi:~$

ubuntu@raspberrypi:~$ sudo systemctl status
● raspberrypi
State: running
Jobs: 0 queued
Failed: 0 units
Since: Mon 2023-03-20 22:35:45 CST; 3 months 13 days ago
CGroup: /
├─user.slice
│ └─user-1000.slice
│ ├─user@1000.service …
│ │ ├─user.slice
│ │ │ └─podman-pause-8178418391264919638.scope
│ │ │ └─2852 /usr/bin/podman
│ │ ├─app.slice
│ │ │ └─dbus.service
│ │ │ └─2973 /usr/bin/dbus-daemon --session --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only
│ │ └─init.scope
│ │ ├─2791 /lib/systemd/systemd --user
│ │ └─2792 (sd-pam)
│ └─session-1.scope
│ ├─2787 sshd: ubuntu [priv]
│ ├─2948 sshd: ubuntu@pts/0
│ ├─2949 -bash
│ ├─3668 sudo systemctl status
│ ├─3669 sudo systemctl status
│ ├─3670 systemctl status
│ └─3671 less
├─init.scope
│ └─1 /sbin/init fixrtc splash
└─system.slice
├─irqbalance.service
│ └─715 /usr/sbin/irqbalance --foreground
├─containerd.service …
│ └─974 /usr/bin/containerd
├─systemd-networkd.service
│ └─698 /lib/systemd/systemd-networkd
├─systemd-udevd.service
│ └─444 /lib/systemd/systemd-udevd
├─cron.service
│ └─943 /usr/sbin/cron -f -P
├─system-serial\x2dgetty.slice
│ └─serial-getty@ttyS0.service
│ └─1010 /sbin/agetty -o -p -- \u --keep-baud 115200,57600,38400,9600 ttyS0 vt220
├─otbr-web.service
│ └─1142 /usr/sbin/otbr-web
├─networkd-dispatcher.service
│ └─717 /usr/bin/python3 /usr/bin/networkd-dispatcher --run-startup-triggers
├─bluetooth.service
│ └─988 /usr/lib/bluetooth/bluetoothd
├─multipathd.service
│ └─430 /sbin/multipathd -d -s
├─systemd-journald.service
│ └─400 /lib/systemd/systemd-journald
├─unattended-upgrades.service
│ └─969 /usr/bin/python3 /usr/share/unattended-upgrades/unattended-upgrade-shutdown --wait-for-signal
├─ssh.service

Border routing prompts this:

1 raspberrypi otbr-agent: [NOTE]-AGENT---: Backbone interface: eth0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-AGENT---: Running 0.3.0-thread-reference-20230119-238-ga611a780d0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-AGENT---: Thread version: 1.3.0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-AGENT---: Thread interface: wpan0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-AGENT---: Radio URL: spinel+hdlc+uart:///dev/ttyACM0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-AGENT---: Radio URL: trel://eth0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [NOTE]-ILS-----: Infra link selected: eth0
Jul 3 14:41:21 raspberrypi otbr-agent[2419]: [INFO]-NCP-----: OpenThread log level changed to 4
Jul 3 14:41:23 raspberrypi otbr-agent[2419]: 49d.17:33:38.807 [W] Platform------: Wait for response timeout
Jul 3 14:41:23 raspberrypi otbr-agent[2419]: 49d.17:33:38.808 [I] Platform------: Software reset RCP successfully
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [W] Platform------: Wait for response timeout
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [C] Platform------: Failed to communicate with RCP - no response from RCP during initialization
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [C] Platform------: This is not a bug and typically due a config error (wrong URL parameters) or bad RCP image:
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [C] Platform------: - Make sure RCP is running the correct firmware
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [C] Platform------: - Double check the config parameters passed as `RadioURL` input
Jul 3 14:41:25 raspberrypi otbr-agent[2419]: 49d.17:33:40.810 [C] Platform------: HandleRcpTimeout() at radio_spinel_impl.hpp:2051: RadioSpinelNoResponse
Jul 3 14:41:25 raspberrypi systemd[1]: otbr-agent.service: Main process exited, code=exited, status=6/NOTCONFIGURED
Jul 3 14:41:25 raspberrypi systemd[1]: otbr-agent.service: Failed with result 'exit-code'.
Jul 3 14:41:30 raspberrypi systemd[1]: otbr-agent.service: Scheduled restart job, restart counter is at 84.

Can you please check this case? Thanks.

Thanks and Regards,

Nick

  • Hi Nick,

    The CC2652R7 serial port indicates that the BLE5 project_zero is loaded (default program on out-of-box LaunchPads), not a Thread RCP which should be loaded to work with the Thread Border Router host (RPi).  Please have the customer follow the Matter Getting Started Guide and reference the TI Matter Github repository.  They should be using Uniflash to erase all program memory before re-programming the RCP image.

    Regards,
    Ryan

  • Hi Ryan,

    Thanks for your quick response.

    Customer downloaded the chip-LP_CC2652R7-lock-example-bim.hex file, and it can print out the information over the serial port. The information printed is as follows: 

    Initialize Server
    Failed to remove advertised services: 3
    Failed to advertise extended commissionable node: 3
    Failed to finalize service update: 3
    Initialize LEDs
    Initialize buttons
    Lock completed
    Unlock initiated
    Unlock completed
    Lock initiated
    Lock completed
    Unlock initiated
    Unlock completed
    Lock initiated
    Lock completed
    Unlock initiated
    Unlock completed
    Lock initiated
    Lock completed
    ÿInitialize Server
    Failed to remove advertised services: 3
    Failed to advertise extended commissionable node: 3
    Failed to finalize service update: 3
    Initialize LEDs
    Initialize buttons
    Lock completed
    .

    Is it right for customer to connect this board to the raspberry Pi USB port through the USB cable, as shown in the figure below:

    Is the program in RCP and Matter Device the same in this picture below?

  • If your LP_CC2652R7 is connected to Raspberry Pi to act as RCP, why do you run chip-LP_CC2652R7-lock-example-bim.hex on it? Shouldn't you run RCP on it?

  • Hi YiKai,

    Thanks for your remind.

  • Hi Ryan,

    Customer found that the URL in the [FAQ] CC2652R7: Matter -- Getting Started Guide is Invalid. This is the RCP file he wants to burn.

    Can you please check this? Thanks.

    Thanks and Regards,

    Nick

  • You can import and build RCP for CC2652R7 from simplelink_cc13xx_cc26xx_sdk_7_10_00_98\examples\rtos\LP_CC2652R7\thread\rcp

  • YK is correct on all points, and you can find the RCP image is attached.  https://e2e.ti.com/cfs-file/__key/communityserver-discussions-components-files/158/rcp_5F00_CC26X2R1_5F00_LAUNCHXL_5F00_tirtos_5F00_ticlang.out

    Regards,
    Ryan

  • HI Ryan,

    Is there any guides for customer to install clang compiler of CortexM4?

    Projrct RCP report error as shown in the following figure.

  • Here is a link to the download page: https://www.ti.com/tool/download/ARM-CGT-CLANG/2.1.2.LTS 

    I would have expected it to be an option from the CCS/SDK installation as well, you can check the discovered tools from the CCS Preferences -> Code Composer Studio -> Build -> Compilers.  My installation location for instance is C:\ti\ccs1220\ccs\tools\compiler\ti-cgt-armllvm_2.1.2.LTS

    Regards,
    Ryan

  • Hi Ryan,

    Thanks for your kindly help.

    Thanks and Regards,

    Nick

  • Hi Ryan,

    Here's the further reply from customer:

    Now the RCP has been set up and the matter device is working properly, he would like to use the chiptool to perform pairing, but it seems failed.

    Can you please check this? Thanks.

    Thanks and Regards,

    Nick

  • What Linux Matter host is connected to the ZNP, and what Matter device are they attempting to connect? Have they confirmed that the OpenThread Border Router is operational?  Please have the customer provide the chip-tool terminal log and CC2652R7 Matter device UART log (if possible).

    Regards,
    Ryan

  • Hi Ryan,

    Thanks for your quick response.

    Customer confirmed that the OpenThread bounder router is working properly.

    Terminal log of running chip-tool on bounder router:

    ubuntu@raspberrypi:~/matter/out$ ls
    args.gn  build.ninja  build.ninja.d  build.ninja.stamp  chip-tool  chip-tool-utils.a  chip-tool.map  gen  lib  liblogging-stdio.a  obj  python  relative_path_transformations.json  toolchain.ninja
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ ./chip-tool 
    [1689126501.963905][6142:6142] CHIP:TOO: Missing cluster name
    Usage:
      ./chip-tool cluster_name command_name [param1 param2 ...]
    
      +-------------------------------------------------------------------------------------+
      | Clusters:                                                                           |
      +-------------------------------------------------------------------------------------+
      | * accesscontrol                                                                     |
      | * accountlogin                                                                      |
      | * actions                                                                           |
      | * administratorcommissioning                                                        |
      | * any                                                                               |
      | * applicationbasic                                                                  |
      | * applicationlauncher                                                               |
      | * audiooutput                                                                       |
      | * ballastconfiguration                                                              |
      | * barriercontrol                                                                    |
      | * basicinformation                                                                  |
      | * binaryinputbasic                                                                  |
      | * binding                                                                           |
      | * booleanstate                                                                      |
      | * bridgeddevicebasic                                                                |
      | * channel                                                                           |
      | * clientmonitoring                                                                  |
      | * colorcontrol                                                                      |
      | * contentlauncher                                                                   |
      | * descriptor                                                                        |
      | * diagnosticlogs                                                                    |
      | * discover                                                                          |
      | * doorlock                                                                          |
      | * electricalmeasurement                                                             |
      | * ethernetnetworkdiagnostics                                                        |
      | * fancontrol                                                                        |
      | * faultinjection                                                                    |
      | * fixedlabel                                                                        |
      | * flowmeasurement                                                                   |
      | * generalcommissioning                                                              |
      | * generaldiagnostics                                                                |
      | * groupkeymanagement                                                                |
      | * groupsettings                                                                     |
      | * groups                                                                            |
      | * identify                                                                          |
      | * illuminancemeasurement                                                            |
      | * keypadinput                                                                       |
      | * levelcontrol                                                                      |
      | * localizationconfiguration                                                         |
      | * lowpower                                                                          |
      | * mediainput                                                                        |
      | * mediaplayback                                                                     |
      | * modeselect                                                                        |
      | * networkcommissioning                                                              |
      | * occupancysensing                                                                  |
      | * onoff                                                                             |
      | * onoffswitchconfiguration                                                          |
      | * operationalcredentials                                                            |
      | * otasoftwareupdateprovider                                                         |
      | * otasoftwareupdaterequestor                                                        |
      | * pairing                                                                           |
      | * payload                                                                           |
      | * powersource                                                                       |
      | * powersourceconfiguration                                                          |
      | * pressuremeasurement                                                               |
      | * proxyconfiguration                                                                |
      | * proxydiscovery                                                                    |
      | * proxyvalid                                                                        |
      | * pulsewidthmodulation                                                              |
      | * pumpconfigurationandcontrol                                                       |
      | * relativehumiditymeasurement                                                       |
      | * scenes                                                                            |
      | * softwarediagnostics                                                               |
      | * subscriptions                                                                     |
      | * switch                                                                            |
      | * targetnavigator                                                                   |
      | * temperaturemeasurement                                                            |
      | * tests                                                                             |
      | * thermostat                                                                        |
      | * thermostatuserinterfaceconfiguration                                              |
      | * threadnetworkdiagnostics                                                          |
      | * timeformatlocalization                                                            |
      | * timesynchronization                                                               |
      | * unitlocalization                                                                  |
      | * unittesting                                                                       |
      | * userlabel                                                                         |
      | * wakeonlan                                                                         |
      | * wifinetworkdiagnostics                                                            |
      | * windowcovering                                                                    |
      | * interactive                                                                       |
      | * storage                                                                           |
      +-------------------------------------------------------------------------------------+
    [1689126501.965290][6142:6142] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:78: Error 0x0000002F
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ cd ..
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ ls
     BUILD.gn             CONTRIBUTING.md   NOTICE      README_MATTER.md   README_WIFI.md     REVIEWERS.md                                           build             config        docs       gn_build.sh    lgtm.yml   scripts   third_party
     CODE_OF_CONDUCT.md   LICENSE           README.md   README_THREAD.md   RELEASE_NOTES.md  'Texas Instruments Matter GitHub_1.0.x_manifest.html'   build_overrides   credentials   examples   integrations   out        src       zzz_generated
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ cd ..
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ ls
    connectedhomeip  matter  ot-br-posix
    ubuntu@raspberrypi:~$ cd matter/
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ ls
     BUILD.gn             CONTRIBUTING.md   NOTICE      README_MATTER.md   README_WIFI.md     REVIEWERS.md                                           build             config        docs       gn_build.sh    lgtm.yml   scripts   third_party
     CODE_OF_CONDUCT.md   LICENSE           README.md   README_THREAD.md   RELEASE_NOTES.md  'Texas Instruments Matter GitHub_1.0.x_manifest.html'   build_overrides   credentials   examples   integrations   out        src       zzz_generated
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ cd out
    ubuntu@raspberrypi:~/matter/out$ ls
    args.gn  build.ninja  build.ninja.d  build.ninja.stamp  chip-tool  chip-tool-utils.a  chip-tool.map  gen  lib  liblogging-stdio.a  obj  python  relative_path_transformations.json  toolchain.ninja
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ cd ~
    ubuntu@raspberrypi:~$ sudo ot-ctl dataset active -x
    [sudo] password for ubuntu: 
    000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000
    Done
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ ls
    connectedhomeip  matter  ot-br-posix
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ 
    ubuntu@raspberrypi:~$ cd matter/
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ ls
     BUILD.gn             CONTRIBUTING.md   NOTICE      README_MATTER.md   README_WIFI.md     REVIEWERS.md                                           build             config        docs       gn_build.sh    lgtm.yml   scripts   third_party
     CODE_OF_CONDUCT.md   LICENSE           README.md   README_THREAD.md   RELEASE_NOTES.md  'Texas Instruments Matter GitHub_1.0.x_manifest.html'   build_overrides   credentials   examples   integrations   out        src       zzz_generated
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ 
    ubuntu@raspberrypi:~/matter$ cd out/
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ ls
    args.gn  build.ninja  build.ninja.d  build.ninja.stamp  chip-tool  chip-tool-utils.a  chip-tool.map  gen  lib  liblogging-stdio.a  obj  python  relative_path_transformations.json  toolchain.ninja
    ubuntu@raspberrypi:~/matter/out$ 
    ubuntu@raspberrypi:~/matter/out$ ./chip-tool pairing ble-thread 1 000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000 20202021 3840
    [1689139858.904812][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
    [1689139858.905125][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_kvs-XcUmQc)
    [1689139858.905317][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
    [1689139858.956389][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
    [1689139858.956876][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-NS429B)
    [1689139858.957131][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
    [1689139858.957291][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
    [1689139858.957464][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-x6zM3m)
    [1689139858.957638][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
    [1689139858.957759][10800:10800] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
    [1689139858.957943][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-GP6GH8)
    [1689139858.958132][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139858.958451][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-PMgKOz)
    [1689139858.959067][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
    [1689139858.959134][10800:10800] CHIP:DL: NVS set: chip-factory/unique-id = "4009E09BD06345CA"
    [1689139858.959306][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-9J7xFU)
    [1689139858.959928][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
    [1689139858.959997][10800:10800] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1)
    [1689139858.960166][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-QJSHLl)
    [1689139858.960761][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
    [1689139858.960829][10800:10800] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001)
    [1689139858.960997][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-OVIjDJ)
    [1689139858.961488][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139858.961552][10800:10800] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1)
    [1689139858.961712][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-9Pe51e)
    [1689139858.962484][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139858.962550][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
    [1689139858.962782][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-A2M8aw)
    [1689139858.963339][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139858.963404][10800:10800] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0)
    [1689139858.963565][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-q2oc2F)
    [1689139858.964107][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
    [1689139858.964168][10800:10800] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
    [1689139858.964329][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_config.ini-kW4x99)
    [1689139858.964939][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
    [1689139858.965007][10800:10800] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2)
    [1689139858.965809][10800:10800] CHIP:DL: Got Ethernet interface: eth0
    [1689139858.966389][10800:10800] CHIP:DL: Found the primary Ethernet interface:eth0
    [1689139858.967012][10800:10800] CHIP:DL: Got WiFi interface: wlan0
    [1689139858.967087][10800:10800] CHIP:DL: Failed to reset WiFi statistic counts
    [1689139858.967204][10800:10800] CHIP:IN: UDP::Init bind&listen port=0
    [1689139858.967318][10800:10800] CHIP:IN: UDP::Init bound to port=55610
    [1689139858.967342][10800:10800] CHIP:IN: UDP::Init bind&listen port=0
    [1689139858.967436][10800:10800] CHIP:IN: UDP::Init bound to port=43630
    [1689139858.967459][10800:10800] CHIP:IN: BLEBase::Init - setting/overriding transport
    [1689139858.967478][10800:10800] CHIP:IN: TransportMgr initialized
    [1689139858.967508][10800:10800] CHIP:FP: Initializing FabricTable from persistent storage
    [1689139858.967553][10800:10800] CHIP:TS: Last Known Good Time: [unknown]
    [1689139858.967581][10800:10800] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-07-12T09:42:48
    [1689139858.971126][10800:10800] CHIP:ZCL: Using ZAP configuration...
    [1689139858.973966][10800:10800] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:764: Inet Error 0x00000110: Address not found
    [1689139858.974288][10800:10800] CHIP:CTL: System State Initialized...
    [1689139858.974343][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139858.974422][10800:10800] CHIP:CTL: Setting attestation nonce to random value
    [1689139858.974475][10800:10800] CHIP:CTL: Setting CSR nonce to random value
    [1689139858.974537][10800:10800] CHIP:IN: UDP::Init bind&listen port=5550
    [1689139858.974629][10800:10800] CHIP:IN: UDP::Init bound to port=5550
    [1689139858.974654][10800:10800] CHIP:IN: UDP::Init bind&listen port=5550
    [1689139858.974741][10800:10800] CHIP:IN: UDP::Init bound to port=5550
    [1689139858.974764][10800:10800] CHIP:IN: TransportMgr initialized
    [1689139858.975047][10800:10802] CHIP:DL: CHIP task running
    [1689139858.975684][10800:10802] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139858.975733][10800:10802] CHIP:CTL: Setting attestation nonce to random value
    [1689139858.975913][10800:10802] CHIP:CTL: Setting CSR nonce to random value
    [1689139858.976121][10800:10802] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExamplePersistentStorage.cpp:93: CHIP Error 0x000000A0: Value not found in the persisted storage
    [1689139858.980427][10800:10802] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExamplePersistentStorage.cpp:93: CHIP Error 0x000000A0: Value not found in the persisted storage
    [1689139858.981810][10800:10802] CHIP:CTL: Generating RCAC
    [1689139859.001274][10800:10802] CHIP:CTL: Generating ICAC
    [1689139859.003065][10800:10802] CHIP:CTL: Generating NOC
    [1689139859.004434][10800:10802] CHIP:FP: Validating NOC chain
    [1689139859.009869][10800:10802] CHIP:FP: NOC chain validation successful
    [1689139859.010179][10800:10802] CHIP:FP: Added new fabric at index: 0x1
    [1689139859.010284][10800:10802] CHIP:FP: Assigned compressed fabric ID: 0xB85C55CBB67A0454, node ID: 0x000000000001B669
    [1689139859.010316][10800:10802] CHIP:TS: Last Known Good Time: 2023-07-12T09:42:48
    [1689139859.010339][10800:10802] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
    [1689139859.010360][10800:10802] CHIP:TS: Retaining current Last Known Good Time
    [1689139859.012020][10800:10802] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
    [1689139859.014309][10800:10802] CHIP:TS: Committing Last Known Good Time to storage: 2023-07-12T09:42:48
    [1689139859.020911][10800:10802] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: B85C55CBB67A0454)
    [1689139859.020975][10800:10802] CHIP:IN: UDP::Init bind&listen port=5550
    [1689139859.021119][10800:10802] CHIP:IN: UDP::Init bound to port=5550
    [1689139859.021152][10800:10802] CHIP:IN: UDP::Init bind&listen port=5550
    [1689139859.021280][10800:10802] CHIP:IN: UDP::Init bound to port=5550
    [1689139859.021310][10800:10802] CHIP:IN: TransportMgr initialized
    [1689139859.053533][10800:10802] CHIP:CTL: Setting thread operational dataset from parameters
    [1689139859.053586][10800:10802] CHIP:CTL: Setting attempt thread scan from parameters
    [1689139859.053613][10800:10802] CHIP:CTL: Setting attestation nonce to random value
    [1689139859.053676][10800:10802] CHIP:CTL: Setting CSR nonce to random value
    [1689139859.053717][10800:10802] CHIP:CTL: Setting PASE-only commissioning from parameters
    [1689139859.053745][10800:10802] CHIP:CTL: Commission called for node ID 0x0000000000000001
    [1689139859.059434][10800:10801] CHIP:DL: TRACE: Bus acquired for name C-2a30
    [1689139859.069151][10800:10802] CHIP:DL: PlatformBlueZInit init success
    [1689139859.075467][10800:10801] CHIP:BLE: BLE removing known devices.
    [1689139859.077241][10800:10801] CHIP:BLE: BLE initiating scan.
    [1689139859.085590][10800:10801] CHIP:BLE: Device 03:03:92:77:C5:A7 does not look like a CHIP device.
    [1689139859.111142][10800:10801] CHIP:BLE: Device F2:49:1C:4E:E1:44 does not look like a CHIP device.
    [1689139859.113510][10800:10801] CHIP:BLE: Device 3F:DF:A9:A4:0C:D8 does not look like a CHIP device.
    [1689139859.115651][10800:10801] CHIP:BLE: Device 19:EB:64:8F:CB:B3 does not look like a CHIP device.
    [1689139859.120362][10800:10801] CHIP:BLE: Device 18:E6:CC:61:60:DD does not look like a CHIP device.
    [1689139859.134365][10800:10801] CHIP:BLE: Device 69:7F:AF:12:A3:5A does not look like a CHIP device.
    [1689139859.153198][10800:10801] CHIP:BLE: Device 2C:C1:EB:EF:93:13 does not look like a CHIP device.
    [1689139859.163091][10800:10801] CHIP:BLE: Device 24:A7:D0:E2:35:28 does not look like a CHIP device.
    [1689139859.185214][10800:10801] CHIP:BLE: Device 0D:56:32:DC:D1:73 does not look like a CHIP device.
    [1689139859.205468][10800:10801] CHIP:BLE: Device 53:10:59:59:18:0C does not look like a CHIP device.
    [1689139859.210914][10800:10801] CHIP:BLE: Device 50:EC:82:A0:FF:B2 does not look like a CHIP device.
    [1689139859.238535][10800:10801] CHIP:BLE: New device scanned: 75:19:7E:C1:BD:91
    [1689139859.238614][10800:10801] CHIP:BLE: Device discriminator match. Attempting to connect.
    [1689139859.243421][10800:10801] CHIP:BLE: Scan complete notification without an active scan.
    [1689139859.768354][10800:10801] CHIP:DL: ConnectDevice complete
    [1689139861.012149][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012198][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012225][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012245][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012272][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012292][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012319][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012339][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012362][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012382][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012404][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012423][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012448][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012468][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012490][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012510][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012532][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012552][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012577][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012597][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012644][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012664][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012692][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012712][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012735][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012754][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012776][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012796][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012820][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012840][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012862][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012882][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012904][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012923][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012947][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.012967][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.012989][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013009][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013031][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013050][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013079][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013098][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013120][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013140][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013162][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013182][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013208][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013228][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013250][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013269][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013291][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service000d
    [1689139861.013311][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013336][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013356][10800:10801] CHIP:DL: Char1 /org/bluez/hci0/dev_75_19_7E_C1_BD_91/service0020
    [1689139861.013383][10800:10801] CHIP:DL: New BLE connection 0xffffb405e080, device 75:19:7E:C1:BD:91, path /org/bluez/hci0/dev_75_19_7E_C1_BD_91
    [1689139861.013527][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16385
    [1689139861.013562][10800:10802] CHIP:DIS: Closing all BLE connections
    [1689139861.013614][10800:10802] CHIP:IN: BleConnectionComplete: endPoint 0xaaaac174c038
    [1689139861.013760][10800:10802] CHIP:IN: SecureSession[0xffffac014f10]: Allocated Type:1 LSID:56629
    [1689139861.013793][10800:10802] CHIP:SC: Assigned local session key ID 56629
    [1689139861.013889][10800:10802] CHIP:EM: <<< [E:60471i M:199606471] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
    [1689139861.013938][10800:10802] CHIP:IN: (U) Sending msg 199606471 to IP address 'BLE'
    [1689139861.013968][10800:10802] CHIP:IN: Message appended to BLE send queue
    [1689139861.013991][10800:10802] CHIP:SC: Sent PBKDF param request
    [1689139861.375578][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139861.473877][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16388
    [1689139861.473955][10800:10802] CHIP:BLE: subscribe complete, ep = 0xaaaac174c038
    [1689139861.476785][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139861.477079][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139861.477150][10800:10802] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4
    [1689139861.477203][10800:10802] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244.
    [1689139861.477251][10800:10802] CHIP:BLE: local and remote recv window size = 6
    [1689139861.477439][10800:10802] CHIP:IN: BLE EndPoint 0xaaaac174c038 Connection Complete
    [1689139861.570488][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139861.574061][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139861.574341][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139861.574549][10800:10802] CHIP:EM: >>> [E:60471i M:20804041] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
    [1689139861.574624][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
    [1689139861.574704][10800:10802] CHIP:SC: Received PBKDF param response
    [1689139861.574789][10800:10802] CHIP:SC: Peer assigned session ID 8216
    [1689139861.586902][10800:10802] CHIP:EM: <<< [E:60471i M:199606472] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
    [1689139861.586967][10800:10802] CHIP:IN: (U) Sending msg 199606472 to IP address 'BLE'
    [1689139861.587074][10800:10802] CHIP:SC: Sent spake2p msg1
    [1689139861.667840][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139866.838965][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139866.839284][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139866.839525][10800:10802] CHIP:EM: >>> [E:60471i M:20804042] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
    [1689139866.839601][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
    [1689139866.839689][10800:10802] CHIP:SC: Received spake2p msg2
    [1689139866.843013][10800:10802] CHIP:EM: <<< [E:60471i M:199606473] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
    [1689139866.843120][10800:10802] CHIP:IN: (U) Sending msg 199606473 to IP address 'BLE'
    [1689139866.843293][10800:10802] CHIP:SC: Sent spake2p msg3
    [1689139866.932721][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139866.985069][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139866.985367][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139866.985601][10800:10802] CHIP:EM: >>> [E:60471i M:20804043] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    [1689139866.985676][10800:10802] CHIP:EM: Found matching exchange: 60471i, Delegate: 0xffffac034aa0
    [1689139866.986080][10800:10802] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kEstablishing' --> 'kActive'
    [1689139866.986143][10800:10802] CHIP:IN: SecureSession[0xffffac014f10]: Activated - Type:1 LSID:56629
    [1689139866.986193][10800:10802] CHIP:IN: New secure session activated for device <FFFFFFFB00000000, 0>, LSID:56629 PSID:8216!
    [1689139866.986254][10800:10802] CHIP:CTL: Remote device completed SPAKE2+ handshake
    [1689139866.986304][10800:10802] CHIP:TOO: Pairing Success
    [1689139866.986349][10800:10802] CHIP:TOO: PASE establishment successful
    [1689139866.986400][10800:10802] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
    [1689139866.986456][10800:10802] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo'
    [1689139866.986526][10800:10802] CHIP:CTL: Sending request for commissioning information
    [1689139866.986604][10800:10802] CHIP:DMG: SendReadRequest ReadClient[0xffffac03e000]: Sending Read Request
    [1689139866.986925][10800:10802] CHIP:EM: <<< [E:60472i M:147197894] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
    [1689139866.987013][10800:10802] CHIP:IN: (S) Sending msg 147197894 on secure session with LSID: 56629
    [1689139866.987193][10800:10802] CHIP:DMG: MoveToState ReadClient[0xffffac03e000]: Moving to [AwaitingIn]
    [1689139867.079051][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139867.082846][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.083129][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.179984][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.180281][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.180579][10800:10802] CHIP:EM: >>> [E:60472i M:256335160] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
    [1689139867.180707][10800:10802] CHIP:EM: Found matching exchange: 60472i, Delegate: 0xffffac03e010
    [1689139867.180935][10800:10802] CHIP:DMG: ReportDataMessage =
    [1689139867.180995][10800:10802] CHIP:DMG: {
    [1689139867.181068][10800:10802] CHIP:DMG: 	AttributeReportIBs =
    [1689139867.181144][10800:10802] CHIP:DMG: 	[
    [1689139867.181201][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.181277][10800:10802] CHIP:DMG: 		{
    [1689139867.181361][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.181438][10800:10802] CHIP:DMG: 			{
    [1689139867.181534][10800:10802] CHIP:DMG: 				DataVersion = 0x287c0f6,
    [1689139867.181609][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.181684][10800:10802] CHIP:DMG: 				{
    [1689139867.181782][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.181864][10800:10802] CHIP:DMG: 					Cluster = 0x31,
    [1689139867.181961][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0003,
    [1689139867.182037][10800:10802] CHIP:DMG: 				}
    [1689139867.182135][10800:10802] CHIP:DMG: 					
    [1689139867.182215][10800:10802] CHIP:DMG: 				Data = 20, 
    [1689139867.182307][10800:10802] CHIP:DMG: 			},
    [1689139867.182381][10800:10802] CHIP:DMG: 			
    [1689139867.182441][10800:10802] CHIP:DMG: 		},
    [1689139867.182548][10800:10802] CHIP:DMG: 		
    [1689139867.182605][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.182700][10800:10802] CHIP:DMG: 		{
    [1689139867.182762][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.182856][10800:10802] CHIP:DMG: 			{
    [1689139867.182932][10800:10802] CHIP:DMG: 				DataVersion = 0xd3bb39b0,
    [1689139867.183024][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.183100][10800:10802] CHIP:DMG: 				{
    [1689139867.183171][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.183271][10800:10802] CHIP:DMG: 					Cluster = 0x28,
    [1689139867.183350][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0004,
    [1689139867.183452][10800:10802] CHIP:DMG: 				}
    [1689139867.183530][10800:10802] CHIP:DMG: 					
    [1689139867.183629][10800:10802] CHIP:DMG: 				Data = 32774, 
    [1689139867.183701][10800:10802] CHIP:DMG: 			},
    [1689139867.183795][10800:10802] CHIP:DMG: 			
    [1689139867.183856][10800:10802] CHIP:DMG: 		},
    [1689139867.183941][10800:10802] CHIP:DMG: 		
    [1689139867.184016][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.184092][10800:10802] CHIP:DMG: 		{
    [1689139867.184172][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.184242][10800:10802] CHIP:DMG: 			{
    [1689139867.184290][10800:10802] CHIP:DMG: 				DataVersion = 0xd3bb39b0,
    [1689139867.184365][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.184417][10800:10802] CHIP:DMG: 				{
    [1689139867.184482][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.184535][10800:10802] CHIP:DMG: 					Cluster = 0x28,
    [1689139867.184620][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0002,
    [1689139867.184674][10800:10802] CHIP:DMG: 				}
    [1689139867.184740][10800:10802] CHIP:DMG: 					
    [1689139867.184793][10800:10802] CHIP:DMG: 				Data = 65521, 
    [1689139867.184854][10800:10802] CHIP:DMG: 			},
    [1689139867.184905][10800:10802] CHIP:DMG: 			
    [1689139867.184945][10800:10802] CHIP:DMG: 		},
    [1689139867.185014][10800:10802] CHIP:DMG: 		
    [1689139867.185053][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.185118][10800:10802] CHIP:DMG: 		{
    [1689139867.185159][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.185203][10800:10802] CHIP:DMG: 			{
    [1689139867.185263][10800:10802] CHIP:DMG: 				DataVersion = 0xf328d5ef,
    [1689139867.185308][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.185369][10800:10802] CHIP:DMG: 				{
    [1689139867.185419][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.185486][10800:10802] CHIP:DMG: 					Cluster = 0x30,
    [1689139867.185540][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0003,
    [1689139867.185605][10800:10802] CHIP:DMG: 				}
    [1689139867.185657][10800:10802] CHIP:DMG: 					
    [1689139867.185709][10800:10802] CHIP:DMG: 				Data = 0, 
    [1689139867.185768][10800:10802] CHIP:DMG: 			},
    [1689139867.185818][10800:10802] CHIP:DMG: 			
    [1689139867.185877][10800:10802] CHIP:DMG: 		},
    [1689139867.185934][10800:10802] CHIP:DMG: 		
    [1689139867.185987][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.186037][10800:10802] CHIP:DMG: 		{
    [1689139867.186080][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.186140][10800:10802] CHIP:DMG: 			{
    [1689139867.186186][10800:10802] CHIP:DMG: 				DataVersion = 0xf328d5ef,
    [1689139867.186247][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.186297][10800:10802] CHIP:DMG: 				{
    [1689139867.186361][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.186415][10800:10802] CHIP:DMG: 					Cluster = 0x30,
    [1689139867.186482][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0002,
    [1689139867.186530][10800:10802] CHIP:DMG: 				}
    [1689139867.186584][10800:10802] CHIP:DMG: 					
    [1689139867.186650][10800:10802] CHIP:DMG: 				Data = 0, 
    [1689139867.186696][10800:10802] CHIP:DMG: 			},
    [1689139867.186758][10800:10802] CHIP:DMG: 			
    [1689139867.186799][10800:10802] CHIP:DMG: 		},
    [1689139867.186871][10800:10802] CHIP:DMG: 		
    [1689139867.186910][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.186961][10800:10802] CHIP:DMG: 		{
    [1689139867.187016][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.187062][10800:10802] CHIP:DMG: 			{
    [1689139867.187123][10800:10802] CHIP:DMG: 				DataVersion = 0xf328d5ef,
    [1689139867.187171][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.187234][10800:10802] CHIP:DMG: 				{
    [1689139867.187285][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.187337][10800:10802] CHIP:DMG: 					Cluster = 0x30,
    [1689139867.187404][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0001,
    [1689139867.187454][10800:10802] CHIP:DMG: 				}
    [1689139867.187518][10800:10802] CHIP:DMG: 					
    [1689139867.187567][10800:10802] CHIP:DMG: 				Data = 
    [1689139867.187628][10800:10802] CHIP:DMG: 				{
    [1689139867.187718][10800:10802] CHIP:DMG: 					0x0 = 60, 
    [1689139867.187787][10800:10802] CHIP:DMG: 					0x1 = 900, 
    [1689139867.187840][10800:10802] CHIP:DMG: 				},
    [1689139867.187900][10800:10802] CHIP:DMG: 			},
    [1689139867.187952][10800:10802] CHIP:DMG: 			
    [1689139867.188005][10800:10802] CHIP:DMG: 		},
    [1689139867.188062][10800:10802] CHIP:DMG: 		
    [1689139867.188117][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.188168][10800:10802] CHIP:DMG: 		{
    [1689139867.188208][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.188268][10800:10802] CHIP:DMG: 			{
    [1689139867.188314][10800:10802] CHIP:DMG: 				DataVersion = 0xf328d5ef,
    [1689139867.188374][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.188424][10800:10802] CHIP:DMG: 				{
    [1689139867.188487][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.188541][10800:10802] CHIP:DMG: 					Cluster = 0x30,
    [1689139867.188667][10800:10802] CHIP:DMG: 					Attribute = 0x0000_0000,
    [1689139867.188723][10800:10802] CHIP:DMG: 				}
    [1689139867.188795][10800:10802] CHIP:DMG: 					
    [1689139867.188847][10800:10802] CHIP:DMG: 				Data = 0, 
    [1689139867.188905][10800:10802] CHIP:DMG: 			},
    [1689139867.188954][10800:10802] CHIP:DMG: 			
    [1689139867.189008][10800:10802] CHIP:DMG: 		},
    [1689139867.189065][10800:10802] CHIP:DMG: 		
    [1689139867.189106][10800:10802] CHIP:DMG: 		AttributeReportIB =
    [1689139867.189171][10800:10802] CHIP:DMG: 		{
    [1689139867.189211][10800:10802] CHIP:DMG: 			AttributeDataIB =
    [1689139867.189275][10800:10802] CHIP:DMG: 			{
    [1689139867.189323][10800:10802] CHIP:DMG: 				DataVersion = 0x287c0f6,
    [1689139867.189385][10800:10802] CHIP:DMG: 				AttributePathIB =
    [1689139867.189436][10800:10802] CHIP:DMG: 				{
    [1689139867.189499][10800:10802] CHIP:DMG: 					Endpoint = 0x0,
    [1689139867.189553][10800:10802] CHIP:DMG: 					Cluster = 0x31,
    [1689139867.189606][10800:10802] CHIP:DMG: 					Attribute = 0x0000_FFFC,
    [1689139867.189671][10800:10802] CHIP:DMG: 				}
    [1689139867.189720][10800:10802] CHIP:DMG: 					
    [1689139867.189785][10800:10802] CHIP:DMG: 				Data = 2, 
    [1689139867.189837][10800:10802] CHIP:DMG: 			},
    [1689139867.189900][10800:10802] CHIP:DMG: 			
    [1689139867.189941][10800:10802] CHIP:DMG: 		},
    [1689139867.189988][10800:10802] CHIP:DMG: 		
    [1689139867.190039][10800:10802] CHIP:DMG: 	],
    [1689139867.190145][10800:10802] CHIP:DMG: 	
    [1689139867.190185][10800:10802] CHIP:DMG: 	SuppressResponse = true, 
    [1689139867.190240][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139867.190278][10800:10802] CHIP:DMG: }
    [1689139867.191465][10800:10802] CHIP:CTL: ----- NetworkCommissioning Features: has Thread. endpointid = 0
    [1689139867.191558][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo'
    [1689139867.191605][10800:10802] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
    [1689139867.191648][10800:10802] CHIP:CTL: Performing next commissioning step 'ArmFailSafe'
    [1689139867.191682][10800:10802] CHIP:CTL: Arming failsafe (60 seconds)
    [1689139867.191781][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139867.191826][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139867.192003][10800:10802] CHIP:EM: <<< [E:60473i M:147197895] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139867.192062][10800:10802] CHIP:IN: (S) Sending msg 147197895 on secure session with LSID: 56629
    [1689139867.192207][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139867.276786][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139867.280516][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.280796][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.281077][10800:10802] CHIP:EM: >>> [E:60473i M:256335161] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139867.281145][10800:10802] CHIP:EM: Found matching exchange: 60473i, Delegate: 0xffffb4060ec8
    [1689139867.281240][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139867.281328][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139867.281374][10800:10802] CHIP:DMG: {
    [1689139867.281420][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139867.281469][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139867.281552][10800:10802] CHIP:DMG: 	[
    [1689139867.281602][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139867.281668][10800:10802] CHIP:DMG: 		{
    [1689139867.281738][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139867.281804][10800:10802] CHIP:DMG: 			{
    [1689139867.281878][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139867.281943][10800:10802] CHIP:DMG: 				{
    [1689139867.282026][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139867.282095][10800:10802] CHIP:DMG: 					ClusterId = 0x30,
    [1689139867.282184][10800:10802] CHIP:DMG: 					CommandId = 0x1,
    [1689139867.282249][10800:10802] CHIP:DMG: 				},
    [1689139867.282316][10800:10802] CHIP:DMG: 				
    [1689139867.282393][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139867.282456][10800:10802] CHIP:DMG: 				{
    [1689139867.282540][10800:10802] CHIP:DMG: 					0x0 = 0, 
    [1689139867.282616][10800:10802] CHIP:DMG: 					0x1 = "" (0 chars), 
    [1689139867.282707][10800:10802] CHIP:DMG: 				},
    [1689139867.282767][10800:10802] CHIP:DMG: 			},
    [1689139867.282850][10800:10802] CHIP:DMG: 			
    [1689139867.282902][10800:10802] CHIP:DMG: 		},
    [1689139867.282981][10800:10802] CHIP:DMG: 		
    [1689139867.283030][10800:10802] CHIP:DMG: 	],
    [1689139867.283091][10800:10802] CHIP:DMG: 	
    [1689139867.283157][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139867.283205][10800:10802] CHIP:DMG: },
    [1689139867.283342][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
    [1689139867.283409][10800:10802] CHIP:CTL: Received ArmFailSafe response errorCode=0
    [1689139867.283482][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe'
    [1689139867.283526][10800:10802] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
    [1689139867.283578][10800:10802] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory'
    [1689139867.283640][10800:10802] CHIP:CTL: Setting Regulatory Config
    [1689139867.283679][10800:10802] CHIP:CTL: Device does not support configurable regulatory location
    [1689139867.283798][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139867.283857][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139867.284032][10800:10802] CHIP:EM: <<< [E:60474i M:147197896] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139867.284130][10800:10802] CHIP:IN: (S) Sending msg 147197896 on secure session with LSID: 56629
    [1689139867.284298][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139867.284418][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139867.374134][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139867.377947][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.378237][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.378548][10800:10802] CHIP:EM: >>> [E:60474i M:256335162] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139867.378627][10800:10802] CHIP:EM: Found matching exchange: 60474i, Delegate: 0xffffb4061248
    [1689139867.378708][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139867.378803][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139867.378886][10800:10802] CHIP:DMG: {
    [1689139867.378940][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139867.378997][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139867.379091][10800:10802] CHIP:DMG: 	[
    [1689139867.379148][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139867.379245][10800:10802] CHIP:DMG: 		{
    [1689139867.379307][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139867.379378][10800:10802] CHIP:DMG: 			{
    [1689139867.379462][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139867.379535][10800:10802] CHIP:DMG: 				{
    [1689139867.379635][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139867.379716][10800:10802] CHIP:DMG: 					ClusterId = 0x30,
    [1689139867.379815][10800:10802] CHIP:DMG: 					CommandId = 0x3,
    [1689139867.379892][10800:10802] CHIP:DMG: 				},
    [1689139867.379969][10800:10802] CHIP:DMG: 				
    [1689139867.380056][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139867.380130][10800:10802] CHIP:DMG: 				{
    [1689139867.380231][10800:10802] CHIP:DMG: 					0x0 = 0, 
    [1689139867.380315][10800:10802] CHIP:DMG: 					0x1 = "" (0 chars), 
    [1689139867.380421][10800:10802] CHIP:DMG: 				},
    [1689139867.380494][10800:10802] CHIP:DMG: 			},
    [1689139867.380598][10800:10802] CHIP:DMG: 			
    [1689139867.380709][10800:10802] CHIP:DMG: 		},
    [1689139867.380802][10800:10802] CHIP:DMG: 		
    [1689139867.380859][10800:10802] CHIP:DMG: 	],
    [1689139867.380931][10800:10802] CHIP:DMG: 	
    [1689139867.381008][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139867.381063][10800:10802] CHIP:DMG: },
    [1689139867.381219][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
    [1689139867.381298][10800:10802] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0
    [1689139867.381382][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory'
    [1689139867.381434][10800:10802] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
    [1689139867.381493][10800:10802] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest'
    [1689139867.381562][10800:10802] CHIP:CTL: Sending request for PAI certificate
    [1689139867.381608][10800:10802] CHIP:CTL: Sending Certificate Chain request to 0xffffac034a50 device
    [1689139867.381719][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139867.381894][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139867.382096][10800:10802] CHIP:EM: <<< [E:60475i M:147197897] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139867.382214][10800:10802] CHIP:IN: (S) Sending msg 147197897 on secure session with LSID: 56629
    [1689139867.382413][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139867.382555][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139867.471686][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139867.475382][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.475661][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.572535][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.572881][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.669962][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.670251][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.670576][10800:10802] CHIP:EM: >>> [E:60475i M:256335163] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139867.670654][10800:10802] CHIP:EM: Found matching exchange: 60475i, Delegate: 0xffffb4060ec8
    [1689139867.670764][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139867.670863][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139867.670917][10800:10802] CHIP:DMG: {
    [1689139867.670971][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139867.671028][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139867.671125][10800:10802] CHIP:DMG: 	[
    [1689139867.671183][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139867.671283][10800:10802] CHIP:DMG: 		{
    [1689139867.671345][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139867.671437][10800:10802] CHIP:DMG: 			{
    [1689139867.671506][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139867.671580][10800:10802] CHIP:DMG: 				{
    [1689139867.671680][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139867.671760][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139867.671854][10800:10802] CHIP:DMG: 					CommandId = 0x3,
    [1689139867.671926][10800:10802] CHIP:DMG: 				},
    [1689139867.672003][10800:10802] CHIP:DMG: 				
    [1689139867.672091][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139867.672166][10800:10802] CHIP:DMG: 				{
    [1689139867.672262][10800:10802] CHIP:DMG: 					0x0 = [
    [1689139867.672678][10800:10802] CHIP:DMG: 							0x30, 0x82, 0x01, 0xdc, 0x30, 0x82, 0x01, 0x81, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x7f, 0x7e, 0xf3, 0xdb, 0x08, 0xa3, 0x8f, 0x68, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x4d, 0x31, 0x1f, 0x30, 0x1d, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x16, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x42, 0x93, 0x55, 0x77, 0x35, 0x35, 0x15, 0xce, 0xfa, 0x8f, 0x0e, 0x30, 0xe2, 0x34, 0x7e, 0x90, 0xee, 0xd6, 0xfd, 0x51, 0x5b, 0xe8, 0x82, 0xd1, 0xbc, 0xba, 0x74, 0x83, 0xd7, 0xff, 0x6e, 0xf4, 0xde, 0xdf, 0x98, 0xf7, 0xf7, 0x4f, 0x17, 0x42, 0x1d, 0xe5, 0x45, 0x0c, 0xff, 0xfb, 0x3e, 0x7f, 0x6d, 0x4f, 0x62, 0x28, 0x53, 0x41, 0x14, 0xfb, 0xb8, 0x5c, 0x
    [1689139867.672856][10800:10802] CHIP:DMG: 					] (480 bytes)
    [1689139867.672937][10800:10802] CHIP:DMG: 				},
    [1689139867.673008][10800:10802] CHIP:DMG: 			},
    [1689139867.673086][10800:10802] CHIP:DMG: 			
    [1689139867.673146][10800:10802] CHIP:DMG: 		},
    [1689139867.673217][10800:10802] CHIP:DMG: 		
    [1689139867.673301][10800:10802] CHIP:DMG: 	],
    [1689139867.673375][10800:10802] CHIP:DMG: 	
    [1689139867.673431][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139867.673508][10800:10802] CHIP:DMG: },
    [1689139867.673669][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
    [1689139867.673744][10800:10802] CHIP:CTL: Received certificate chain from the device
    [1689139867.673807][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest'
    [1689139867.673867][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
    [1689139867.673950][10800:10802] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest'
    [1689139867.674001][10800:10802] CHIP:CTL: Sending request for DAC certificate
    [1689139867.674047][10800:10802] CHIP:CTL: Sending Certificate Chain request to 0xffffac034a50 device
    [1689139867.674183][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139867.674249][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139867.674459][10800:10802] CHIP:EM: <<< [E:60476i M:147197898] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139867.674569][10800:10802] CHIP:IN: (S) Sending msg 147197898 on secure session with LSID: 56629
    [1689139867.674770][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139867.674912][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139867.763751][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139867.767889][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.768167][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.865038][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.865326][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.962507][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139867.962807][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139867.963145][10800:10802] CHIP:EM: >>> [E:60476i M:256335164] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139867.963222][10800:10802] CHIP:EM: Found matching exchange: 60476i, Delegate: 0xffffb4061248
    [1689139867.963332][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139867.963434][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139867.963490][10800:10802] CHIP:DMG: {
    [1689139867.963545][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139867.963602][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139867.963701][10800:10802] CHIP:DMG: 	[
    [1689139867.963761][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139867.963860][10800:10802] CHIP:DMG: 		{
    [1689139867.963923][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139867.963994][10800:10802] CHIP:DMG: 			{
    [1689139867.964080][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139867.964156][10800:10802] CHIP:DMG: 				{
    [1689139867.964252][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139867.964333][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139867.964432][10800:10802] CHIP:DMG: 					CommandId = 0x3,
    [1689139867.964508][10800:10802] CHIP:DMG: 				},
    [1689139867.964711][10800:10802] CHIP:DMG: 				
    [1689139867.964785][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139867.964881][10800:10802] CHIP:DMG: 				{
    [1689139867.964959][10800:10802] CHIP:DMG: 					0x0 = [
    [1689139867.965357][10800:10802] CHIP:DMG: 							0x30, 0x82, 0x01, 0xf7, 0x30, 0x82, 0x01, 0x9d, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x46, 0x88, 0xeb, 0x94, 0xad, 0x32, 0xb2, 0xe4, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x4d, 0x31, 0x1f, 0x30, 0x1d, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x16, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x52, 0x31, 0x24, 0x30, 0x22, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1b, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x30, 0x30, 0x30, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x36, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0xc5, 0x65, 0xfd, 0xad, 0xfd, 0x16, 0xdd, 0x62, 0xe4, 0x3f, 0x19, 0x60, 0xb9, 0x93, 0xbb, 0x57, 0x2c, 0xfd, 0xd8, 0x1f, 0x6d, 0x71, 0x67, 0x67, 0x
    [1689139867.965534][10800:10802] CHIP:DMG: 					] (507 bytes)
    [1689139867.965613][10800:10802] CHIP:DMG: 				},
    [1689139867.965683][10800:10802] CHIP:DMG: 			},
    [1689139867.965762][10800:10802] CHIP:DMG: 			
    [1689139867.965842][10800:10802] CHIP:DMG: 		},
    [1689139867.965916][10800:10802] CHIP:DMG: 		
    [1689139867.965972][10800:10802] CHIP:DMG: 	],
    [1689139867.966044][10800:10802] CHIP:DMG: 	
    [1689139867.966128][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139867.966183][10800:10802] CHIP:DMG: },
    [1689139867.966340][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
    [1689139867.966415][10800:10802] CHIP:CTL: Received certificate chain from the device
    [1689139867.966498][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest'
    [1689139867.966559][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
    [1689139867.966622][10800:10802] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest'
    [1689139867.966693][10800:10802] CHIP:CTL: Sending Attestation Request to the device.
    [1689139867.966741][10800:10802] CHIP:CTL: Sending Attestation request to 0xffffac034a50 device
    [1689139867.966879][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139867.966948][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139867.967163][10800:10802] CHIP:EM: <<< [E:60477i M:147197899] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139867.967279][10800:10802] CHIP:IN: (S) Sending msg 147197899 on secure session with LSID: 56629
    [1689139867.967480][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139867.967551][10800:10802] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
    [1689139867.967669][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139868.056690][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139868.206321][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139868.206619][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139868.303822][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139868.304110][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139868.401367][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139868.401653][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139868.402008][10800:10802] CHIP:EM: >>> [E:60477i M:256335165] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139868.402086][10800:10802] CHIP:EM: Found matching exchange: 60477i, Delegate: 0xffffb4060ec8
    [1689139868.402203][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139868.402305][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139868.402359][10800:10802] CHIP:DMG: {
    [1689139868.402413][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139868.402495][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139868.402572][10800:10802] CHIP:DMG: 	[
    [1689139868.402629][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139868.402730][10800:10802] CHIP:DMG: 		{
    [1689139868.402791][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139868.402881][10800:10802] CHIP:DMG: 			{
    [1689139868.402949][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139868.403043][10800:10802] CHIP:DMG: 				{
    [1689139868.403122][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139868.403202][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139868.403303][10800:10802] CHIP:DMG: 					CommandId = 0x1,
    [1689139868.403376][10800:10802] CHIP:DMG: 				},
    [1689139868.403475][10800:10802] CHIP:DMG: 				
    [1689139868.403544][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139868.403637][10800:10802] CHIP:DMG: 				{
    [1689139868.403714][10800:10802] CHIP:DMG: 					0x0 = [
    [1689139868.404103][10800:10802] CHIP:DMG: 							0x15, 0x31, 0x01, 0x1d, 0x02, 0x30, 0x82, 0x02, 0x19, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x0a, 0x30, 0x82, 0x02, 0x06, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x71, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x62, 0x04, 0x82, 0x01, 0x5e, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80, 0x05, 0x42, 0x80, 0x05, 0x43, 0x80, 0x
    [1689139868.404302][10800:10802] CHIP:DMG: 					] (585 bytes)
    [1689139868.404382][10800:10802] CHIP:DMG: 					0x1 = [
    [1689139868.404514][10800:10802] CHIP:DMG: 							0xb9, 0x3a, 0x86, 0x97, 0xff, 0x10, 0xff, 0x43, 0x0c, 0x49, 0x6a, 0x04, 0xee, 0xe4, 0x63, 0xc4, 0x83, 0x80, 0x06, 0x04, 0x82, 0x4c, 0xc5, 0x12, 0x0a, 0x1d, 0xb9, 0xdd, 0x59, 0x44, 0x0a, 0x5e, 0x3a, 0x82, 0x5e, 0x7f, 0x01, 0xb1, 0x8f, 0xf7, 0x5a, 0x86, 0x54, 0xa5, 0x3e, 0xc4, 0xd9, 0xe7, 0x59, 0xd8, 0x6d, 0xae, 0x74, 0x70, 0xb4, 0x66, 0x3e, 0xd1, 0xd0, 0x38, 0xf1, 0xfe, 0xde, 0x77, 
    [1689139868.404671][10800:10802] CHIP:DMG: 					] (64 bytes)
    [1689139868.404752][10800:10802] CHIP:DMG: 				},
    [1689139868.404821][10800:10802] CHIP:DMG: 			},
    [1689139868.404927][10800:10802] CHIP:DMG: 			
    [1689139868.404988][10800:10802] CHIP:DMG: 		},
    [1689139868.405060][10800:10802] CHIP:DMG: 		
    [1689139868.405138][10800:10802] CHIP:DMG: 	],
    [1689139868.405211][10800:10802] CHIP:DMG: 	
    [1689139868.405286][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139868.405343][10800:10802] CHIP:DMG: },
    [1689139868.405503][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
    [1689139868.405580][10800:10802] CHIP:CTL: Received Attestation Information from the device
    [1689139868.405665][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest'
    [1689139868.405723][10800:10802] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 585/585
    [1689139868.405850][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
    [1689139868.405916][10800:10802] CHIP:CTL: Performing next commissioning step 'AttestationVerification'
    [1689139868.405965][10800:10802] CHIP:CTL: Verifying attestation
    [1689139868.443997][10800:10802] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
    [1689139868.444080][10800:10802] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification'
    [1689139868.444101][10800:10802] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
    [1689139868.444132][10800:10802] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest'
    [1689139868.444154][10800:10802] CHIP:CTL: Sending CSR request to 0xffffac034a50 device
    [1689139868.444227][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139868.444259][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139868.444404][10800:10802] CHIP:EM: <<< [E:60478i M:147197900] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139868.444456][10800:10802] CHIP:IN: (S) Sending msg 147197900 on secure session with LSID: 56629
    [1689139868.444568][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139868.444645][10800:10802] CHIP:CTL: Sent CSR request, waiting for the CSR
    [1689139868.444734][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139868.544347][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139869.815289][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139869.815594][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139869.912680][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139869.912979][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139869.913253][10800:10802] CHIP:EM: >>> [E:60478i M:256335166] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139869.913333][10800:10802] CHIP:EM: Found matching exchange: 60478i, Delegate: 0xffffb4061248
    [1689139869.913420][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139869.913522][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139869.913579][10800:10802] CHIP:DMG: {
    [1689139869.913633][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139869.913690][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139869.913766][10800:10802] CHIP:DMG: 	[
    [1689139869.913822][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139869.913901][10800:10802] CHIP:DMG: 		{
    [1689139869.913961][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139869.914032][10800:10802] CHIP:DMG: 			{
    [1689139869.914097][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139869.914172][10800:10802] CHIP:DMG: 				{
    [1689139869.914247][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139869.914327][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139869.914405][10800:10802] CHIP:DMG: 					CommandId = 0x5,
    [1689139869.914480][10800:10802] CHIP:DMG: 				},
    [1689139869.914559][10800:10802] CHIP:DMG: 				
    [1689139869.914627][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139869.914701][10800:10802] CHIP:DMG: 				{
    [1689139869.914776][10800:10802] CHIP:DMG: 					0x0 = [
    [1689139869.915062][10800:10802] CHIP:DMG: 							0x15, 0x30, 0x01, 0xcd, 0x30, 0x81, 0xca, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0xc2, 0x48, 0xa3, 0xfb, 0x15, 0xfd, 0xb3, 0x94, 0xf2, 0x3c, 0x80, 0x01, 0xad, 0x4d, 0x23, 0xd3, 0x12, 0xe4, 0x02, 0x58, 0x45, 0x3f, 0xd0, 0xfe, 0x98, 0xe4, 0xec, 0x37, 0x58, 0xbf, 0x09, 0x66, 0x0d, 0x99, 0xde, 0x34, 0xa7, 0xd2, 0x8f, 0x40, 0xc4, 0xb1, 0xf1, 0xad, 0xae, 0xde, 0x9a, 0xff, 0x8e, 0x6c, 0x18, 0xb7, 0x66, 0x2a, 0xe9, 0xe4, 0x68, 0xc6, 0xc2, 0x90, 0x21, 0xc0, 0x91, 0x30, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0x9f, 0x86, 0xe7, 0x6a, 0x48, 0x3b, 0xa1, 0xaa, 0x1c, 0x05, 0xcc, 0x3f, 0xab, 0xf3, 0x92, 0x13, 0x3a, 0x4f, 0x95, 0xda, 0xab, 0xed, 0x45, 0xbc, 0x28, 0x13, 0xe8, 0x7f, 0x78, 0x5e, 0x20, 0xb2, 0x02, 0x20, 0x02, 0x9a, 0xd4, 0xee, 0xd8, 0xfd, 0x78, 0xa9, 0xe8, 0xa6, 0x21, 0x02, 0xbc, 0xe7, 0xc5, 0x56, 0x57, 0x2f, 0x5d, 0x36, 0xa6, 0x30, 0x83, 0x36, 0x40, 0xbe, 0xc8, 0x29, 0x05, 0xe6, 0xc9, 0xc4, 0x30, 0x02, 0x20, 0x5f, 0x73, 0x91, 0x64, 0x79, 0xeb, 0xb6, 0x39, 0xd8, 0x28, 0x8e, 0x64, 0x68, 0xc8, 0xf5, 0x96, 0xa9, 0x09, 0xf1, 0x38, 0x11, 0x36, 0xcb, 0x51, 0x70, 0xd4, 0x18, 0x9d, 0x51, 0x77, 0x1f, 0xdd, 0x18, 
    [1689139869.915228][10800:10802] CHIP:DMG: 					] (245 bytes)
    [1689139869.915309][10800:10802] CHIP:DMG: 					0x1 = [
    [1689139869.915440][10800:10802] CHIP:DMG: 							0x59, 0xbd, 0x98, 0x38, 0xe3, 0xb4, 0xdc, 0xfc, 0x93, 0x1f, 0x71, 0x46, 0xa9, 0xe3, 0x4e, 0x43, 0x12, 0xac, 0xdc, 0x4b, 0xd1, 0x27, 0x1a, 0x17, 0x5d, 0x10, 0xfb, 0xe4, 0xb2, 0x3a, 0x4d, 0xcb, 0x61, 0xf3, 0x28, 0x70, 0x12, 0x2e, 0x70, 0x3f, 0xf0, 0x43, 0x15, 0x80, 0x74, 0x92, 0x75, 0x30, 0x6a, 0x28, 0x0a, 0xb5, 0xfb, 0x97, 0xb5, 0x09, 0x9e, 0x2b, 0x83, 0x71, 0x99, 0x4e, 0x97, 0x9f, 
    [1689139869.915538][10800:10802] CHIP:DMG: 					] (64 bytes)
    [1689139869.915617][10800:10802] CHIP:DMG: 				},
    [1689139869.915687][10800:10802] CHIP:DMG: 			},
    [1689139869.915765][10800:10802] CHIP:DMG: 			
    [1689139869.915824][10800:10802] CHIP:DMG: 		},
    [1689139869.915897][10800:10802] CHIP:DMG: 		
    [1689139869.915953][10800:10802] CHIP:DMG: 	],
    [1689139869.916025][10800:10802] CHIP:DMG: 	
    [1689139869.916080][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139869.916134][10800:10802] CHIP:DMG: },
    [1689139869.916274][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
    [1689139869.916350][10800:10802] CHIP:CTL: Received certificate signing request from the device
    [1689139869.916414][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest'
    [1689139869.916466][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
    [1689139869.916527][10800:10802] CHIP:CTL: Performing next commissioning step 'ValidateCSR'
    [1689139869.925228][10800:10802] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR'
    [1689139869.925292][10800:10802] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
    [1689139869.925328][10800:10802] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain'
    [1689139869.925355][10800:10802] CHIP:CTL: Getting certificate chain for the device from the issuer
    [1689139869.930035][10800:10802] CHIP:CTL: Verifying Certificate Signing Request
    [1689139869.932973][10800:10802] CHIP:CTL: Generating NOC
    [1689139869.933358][10800:10802] CHIP:CTL: Providing certificate chain to the commissioner
    [1689139869.933395][10800:10802] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
    [1689139869.933432][10800:10802] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain'
    [1689139869.933622][10800:10802] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert'
    [1689139869.933652][10800:10802] CHIP:CTL: Sending root certificate to the device
    [1689139869.933731][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139869.933764][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139869.933896][10800:10802] CHIP:EM: <<< [E:60479i M:147197901] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139869.933943][10800:10802] CHIP:IN: (S) Sending msg 147197901 on secure session with LSID: 56629
    [1689139869.934059][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139869.934091][10800:10802] CHIP:CTL: Sent root certificate to the device
    [1689139869.934602][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139870.007009][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139870.104087][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139870.351076][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139870.351322][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139870.351595][10800:10802] CHIP:EM: >>> [E:60479i M:256335167] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139870.351656][10800:10802] CHIP:EM: Found matching exchange: 60479i, Delegate: 0xffffb4060ec8
    [1689139870.351737][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139870.351815][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139870.351856][10800:10802] CHIP:DMG: {
    [1689139870.351898][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139870.351940][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139870.352015][10800:10802] CHIP:DMG: 	[
    [1689139870.352059][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139870.352133][10800:10802] CHIP:DMG: 		{
    [1689139870.352180][10800:10802] CHIP:DMG: 			CommandStatusIB =
    [1689139870.352231][10800:10802] CHIP:DMG: 			{
    [1689139870.352301][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139870.352359][10800:10802] CHIP:DMG: 				{
    [1689139870.352432][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139870.352493][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139870.352565][10800:10802] CHIP:DMG: 					CommandId = 0xb,
    [1689139870.352655][10800:10802] CHIP:DMG: 				},
    [1689139870.352719][10800:10802] CHIP:DMG: 				
    [1689139870.352785][10800:10802] CHIP:DMG: 				StatusIB =
    [1689139870.352842][10800:10802] CHIP:DMG: 				{
    [1689139870.352915][10800:10802] CHIP:DMG: 					status = 0x00 (SUCCESS),
    [1689139870.352972][10800:10802] CHIP:DMG: 				},
    [1689139870.353044][10800:10802] CHIP:DMG: 				
    [1689139870.353095][10800:10802] CHIP:DMG: 			},
    [1689139870.353151][10800:10802] CHIP:DMG: 			
    [1689139870.353213][10800:10802] CHIP:DMG: 		},
    [1689139870.353264][10800:10802] CHIP:DMG: 		
    [1689139870.353305][10800:10802] CHIP:DMG: 	],
    [1689139870.353374][10800:10802] CHIP:DMG: 	
    [1689139870.353415][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139870.353472][10800:10802] CHIP:DMG: },
    [1689139870.353573][10800:10802] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
    [1689139870.353636][10800:10802] CHIP:CTL: Device confirmed that it has received the root certificate
    [1689139870.353683][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert'
    [1689139870.353722][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
    [1689139870.353784][10800:10802] CHIP:CTL: Performing next commissioning step 'SendNOC'
    [1689139870.353889][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139870.353967][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139870.354175][10800:10802] CHIP:EM: <<< [E:60480i M:147197902] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139870.354256][10800:10802] CHIP:IN: (S) Sending msg 147197902 on secure session with LSID: 56629
    [1689139870.354407][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139870.354475][10800:10802] CHIP:CTL: Sent operational certificate to the device
    [1689139870.354551][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139870.445735][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139870.543064][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139870.640587][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139871.375258][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139871.375576][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139871.375872][10800:10802] CHIP:EM: >>> [E:60480i M:256335168] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139871.375952][10800:10802] CHIP:EM: Found matching exchange: 60480i, Delegate: 0xffffb4061248
    [1689139871.376035][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139871.376133][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139871.376191][10800:10802] CHIP:DMG: {
    [1689139871.376244][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139871.376301][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139871.376374][10800:10802] CHIP:DMG: 	[
    [1689139871.376431][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139871.376509][10800:10802] CHIP:DMG: 		{
    [1689139871.376570][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139871.376696][10800:10802] CHIP:DMG: 			{
    [1689139871.376769][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139871.376844][10800:10802] CHIP:DMG: 				{
    [1689139871.376921][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139871.377000][10800:10802] CHIP:DMG: 					ClusterId = 0x3e,
    [1689139871.377085][10800:10802] CHIP:DMG: 					CommandId = 0x8,
    [1689139871.377156][10800:10802] CHIP:DMG: 				},
    [1689139871.377234][10800:10802] CHIP:DMG: 				
    [1689139871.377300][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139871.377374][10800:10802] CHIP:DMG: 				{
    [1689139871.377451][10800:10802] CHIP:DMG: 					0x0 = 0, 
    [1689139871.377531][10800:10802] CHIP:DMG: 					0x1 = 1, 
    [1689139871.377609][10800:10802] CHIP:DMG: 				},
    [1689139871.377678][10800:10802] CHIP:DMG: 			},
    [1689139871.377752][10800:10802] CHIP:DMG: 			
    [1689139871.377811][10800:10802] CHIP:DMG: 		},
    [1689139871.377883][10800:10802] CHIP:DMG: 		
    [1689139871.377938][10800:10802] CHIP:DMG: 	],
    [1689139871.378008][10800:10802] CHIP:DMG: 	
    [1689139871.378063][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139871.378117][10800:10802] CHIP:DMG: },
    [1689139871.378251][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
    [1689139871.378327][10800:10802] CHIP:CTL: Device returned status 0 on receiving the NOC
    [1689139871.378379][10800:10802] CHIP:CTL: Operational credentials provisioned on device 0xffffac034a50
    [1689139871.378428][10800:10802] CHIP:TOO: Secure Pairing Success
    [1689139871.378472][10800:10802] CHIP:TOO: CASE establishment successful
    [1689139871.378527][10800:10802] CHIP:CTL: Successfully finished commissioning step 'SendNOC'
    [1689139871.378579][10800:10802] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
    [1689139871.378629][10800:10802] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup'
    [1689139871.378687][10800:10802] CHIP:CTL: Performing next commissioning step 'ThreadNetworkSetup'
    [1689139871.378804][10800:10802] CHIP:DMG: ICR moving to [AddingComm]
    [1689139871.378961][10800:10802] CHIP:DMG: ICR moving to [AddedComma]
    [1689139871.379172][10800:10802] CHIP:EM: <<< [E:60481i M:147197903] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    [1689139871.379259][10800:10802] CHIP:IN: (S) Sending msg 147197903 on secure session with LSID: 56629
    [1689139871.379452][10800:10802] CHIP:DMG: ICR moving to [CommandSen]
    [1689139871.379577][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139871.469499][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139871.566706][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
    [1689139871.570271][10800:10801] CHIP:DL: Indication received, conn = 0xffffb405e080
    [1689139871.570506][10800:10802] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
    [1689139871.570768][10800:10802] CHIP:EM: >>> [E:60481i M:256335169] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    [1689139871.570839][10800:10802] CHIP:EM: Found matching exchange: 60481i, Delegate: 0xffffb4060ec8
    [1689139871.570908][10800:10802] CHIP:DMG: ICR moving to [ResponseRe]
    [1689139871.570990][10800:10802] CHIP:DMG: InvokeResponseMessage =
    [1689139871.571032][10800:10802] CHIP:DMG: {
    [1689139871.571177][10800:10802] CHIP:DMG: 	suppressResponse = false, 
    [1689139871.571220][10800:10802] CHIP:DMG: 	InvokeResponseIBs =
    [1689139871.571275][10800:10802] CHIP:DMG: 	[
    [1689139871.571317][10800:10802] CHIP:DMG: 		InvokeResponseIB =
    [1689139871.571375][10800:10802] CHIP:DMG: 		{
    [1689139871.571420][10800:10802] CHIP:DMG: 			CommandDataIB =
    [1689139871.571471][10800:10802] CHIP:DMG: 			{
    [1689139871.571524][10800:10802] CHIP:DMG: 				CommandPathIB =
    [1689139871.571581][10800:10802] CHIP:DMG: 				{
    [1689139871.571638][10800:10802] CHIP:DMG: 					EndpointId = 0x0,
    [1689139871.571698][10800:10802] CHIP:DMG: 					ClusterId = 0x31,
    [1689139871.571757][10800:10802] CHIP:DMG: 					CommandId = 0x5,
    [1689139871.571813][10800:10802] CHIP:DMG: 				},
    [1689139871.571871][10800:10802] CHIP:DMG: 				
    [1689139871.571921][10800:10802] CHIP:DMG: 				CommandFields = 
    [1689139871.571977][10800:10802] CHIP:DMG: 				{
    [1689139871.572043][10800:10802] CHIP:DMG: 					0x0 = 1, 
    [1689139871.572099][10800:10802] CHIP:DMG: 				},
    [1689139871.572155][10800:10802] CHIP:DMG: 			},
    [1689139871.572212][10800:10802] CHIP:DMG: 			
    [1689139871.572256][10800:10802] CHIP:DMG: 		},
    [1689139871.572309][10800:10802] CHIP:DMG: 		
    [1689139871.572350][10800:10802] CHIP:DMG: 	],
    [1689139871.572401][10800:10802] CHIP:DMG: 	
    [1689139871.572442][10800:10802] CHIP:DMG: 	InteractionModelRevision = 1
    [1689139871.572483][10800:10802] CHIP:DMG: },
    [1689139871.572581][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
    [1689139871.572742][10800:10802] CHIP:CTL: Received NetworkConfig response, networkingStatus=1
    [1689139871.572808][10800:10802] CHIP:CTL: Error on commissioning step 'ThreadNetworkSetup': '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
    [1689139871.572850][10800:10802] CHIP:CTL: Failed to perform commissioning step 15
    [1689139871.572897][10800:10802] CHIP:CTL: Going from commissioning step 'ThreadNetworkSetup' with lastErr = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error' -> 'Cleanup'
    [1689139871.572956][10800:10802] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
    [1689139871.573000][10800:10802] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
    [1689139871.573054][10800:10802] CHIP:TOO: Device commissioning Failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
    [1689139871.573148][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139871.573511][10800:10800] CHIP:CTL: Shutting down the commissioner
    [1689139871.573565][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139871.573760][10800:10800] CHIP:DIS: Closing all BLE connections
    [1689139871.573804][10800:10800] CHIP:IN: Clearing BLE pending packets.
    [1689139871.573910][10800:10800] CHIP:BLE: Auto-closing end point's BLE connection.
    [1689139871.573952][10800:10800] CHIP:DL: Closing BLE GATT connection (con 0xffffb405e080)
    [1689139871.574032][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: MarkForEviction Type:1 LSID:56629
    [1689139871.574074][10800:10800] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kActive' --> 'kPendingEviction'
    [1689139871.574154][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: Released - Type:1 LSID:56629
    [1689139871.574204][10800:10800] CHIP:CTL: Shutting down the controller
    [1689139871.574246][10800:10800] CHIP:IN: Expiring all sessions for fabric 0x1!!
    [1689139871.574284][10800:10800] CHIP:FP: Forgetting fabric 0x1
    [1689139871.574344][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.574391][10800:10801] CHIP:DL: BluezDisconnect peer=75:19:7E:C1:BD:91
    [1689139871.574581][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.574632][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
    [1689139871.574701][10800:10800] CHIP:CTL: Shutting down the commissioner
    [1689139871.574738][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139871.574933][10800:10800] CHIP:CTL: Shutting down the controller
    [1689139871.574975][10800:10800] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
    [1689139871.575722][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575777][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575823][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575865][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575903][10800:10800] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
    [1689139871.576068][10800:10800] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
    [1689139871.576126][10800:10800] CHIP:FP: Shutting down FabricTable
    [1689139871.576174][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.576341][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.576389][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
    [1689139871.576832][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-whpZh2)
    [1689139871.581286][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139871.581397][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
    [1689139871.581442][10800:10800] CHIP:DL: Inet Layer shutdown
    [1689139871.581482][10800:10800] CHIP:DL: BLE shutdown
    [1689139871.583467][10800:10800] CHIP:DL: System Layer shutdown
    [1689139873.616336][10800:10800] CHIP:TOO: Run command failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
    ubuntu@raspberrypi:~/matter/out$ 

    Uart logs printed by Matter Device:

    Xshell 7 (Build 0099)
    Copyright (c) 2020 NetSarang Computer, Inc. All rights reserved.
    
    Type `help' to learn how to use Xshell prompt.
    [C:\~]$ 
    
    Connecting to COM4...
    Connected.
    
    ÿOpenThread started: OK
    Setting OpenThread device type to MINIMAL END DEVICE
    Initialize Server
    Server initializing...
    Last Known Good Time: 2023-07-03T16:02:44
    AccessControl: initializing
    Examples::AccessControlDelegate::Init
    AccessControl: setting
    DefaultAclStorage: initializing
    DefaultAclStorage: 0 entries loaded
    Using ZAP configuration...
    AccessControlCluster: initializing
    Initiating Admin Commissioning cluster.
    Door Lock server initialized
    Cluster callback: 0x0000_0030
    Cluster callback: 0x0000_002B
    Updating services using commissioning mode 0
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0
    Failed to advertise extended commissionable node: 3
    Failed to finalize service update: 1c
    Delaying proxy of operational discovery: missing delegate
    CASE Server enabling CASE session setups
    Joining Multicast groups
    Server Listening...
    Initialize LEDs
    Initialize buttons
    Lock completed
    Device Configuration:
      Serial Number: TEST_SN
      Vendor Id: 65521 (0xFFF1)
      Product Id: 32774 (0x8006)
      Hardware Version: 0
      Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
      Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
      Manufacturing Date: (not set)
      Device Type: 65535 (0xFFFF)
    Stopping the watchdog timer
    Starting the periodic query timer, timeout: 86400 seconds
    SetupQRCode: [MT:8IXS142C00KA0648G00]
    Copy/paste the below URL in a browser to see the QR Code:
    https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A8IXS142C00KA0648G00
    Manual pairing code: [34970112332]
    Unlock initiated
    Cluster callback: 0x0000_0101
    Door lock cluster: 0x0000_0101
    Unlock completed
    Updating services using commissioning mode 1
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1
    Failed to advertise commissionable node: 3
    Failed to finalize service update: 1c
    Enabled BLE Advertisements
    local and remote recv window sizes = 6
    selected BTP version 4
    using BTP fragment sizes rx 244 / tx 244.
    >>> [E:60471r M:199606471] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
    <<< [E:60471r M:20804041] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
    (U) Sending msg 20804041 to IP address 'BLE'
    >>> [E:60471r M:199606472] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
    <<< [E:60471r M:20804042] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
    (U) Sending msg 20804042 to IP address 'BLE'
    Long dispatch time: 5152 ms, for event type 7
    >>> [E:60471r M:199606473] (U) Msg RX from 0:3DE92DF1A0FC06D4 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
    <<< [E:60471r M:20804043] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
    (U) Sending msg 20804043 to IP address 'BLE'
    SecureSession[0x200121b8]: Moving from state 'kEstablishing' --> 'kActive'
    Commissioning completed session establishment step
    Updating services using commissioning mode 0
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0
    Failed to advertise extended commissionable node: 3
    Failed to finalize service update: 1c
    Device completed Rendezvous process
    >>> [E:60472r M:147197894] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
    <<< [E:60472r M:256335160] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
    (S) Sending msg 256335160 on secure session with LSID: 8216
    >>> [E:60473r M:147197895] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    GeneralCommissioning: Received ArmFailSafe (60s)
    Cluster callback: 0x0000_0030
    <<< [E:60473r M:256335161] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335161 on secure session with LSID: 8216
    >>> [E:60474r M:147197896] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    Cluster callback: 0x0000_0030
    <<< [E:60474r M:256335162] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335162 on secure session with LSID: 8216
    >>> [E:60475r M:147197897] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Certificate Chain request received for PAI
    <<< [E:60475r M:256335163] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335163 on secure session with LSID: 8216
    >>> [E:60476r M:147197898] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Certificate Chain request received for DAC
    <<< [E:60476r M:256335164] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335164 on secure session with LSID: 8216
    >>> [E:60477r M:147197899] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Received an AttestationRequest command
    OpCreds: AttestationRequest successful.
    <<< [E:60477r M:256335165] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335165 on secure session with LSID: 8216
    Long dispatch time: 159 ms, for event type 7
    >>> [E:60478r M:147197900] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Received a CSRRequest command
    OpCreds: AllocatePendingOperationalKey succeeded
    OpCreds: CSRRequest successful.
    <<< [E:60478r M:256335166] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335166 on secure session with LSID: 8216
    Long dispatch time: 1290 ms, for event type 7
    >>> [E:60479r M:147197901] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Received an AddTrustedRootCertificate command
    OpCreds: AddTrustedRootCertificate successful.
    <<< [E:60479r M:256335167] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335167 on secure session with LSID: 8216
    Long dispatch time: 278 ms, for event type 7
    >>> [E:60480r M:147197902] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
    OpCreds: Received an AddNOC command
    Validating NOC chain
    NOC chain validation successful
    Added new fabric at index: 0x1
    Assigned compressed fabric ID: 0xB85C55CBB67A0454, node ID: 0x0000000000000001
    Last Known Good Time: 2023-07-03T16:02:44
    New proposed Last Known Good Time: 2021-01-01T00:00:00
    Retaining current Last Known Good Time
    OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669
    Advertise operational node B85C55CBB67A0454-0000000000000001
    OpCreds: successfully created fabric index 0x1 via AddNOC
    <<< [E:60480r M:256335168] (S) Msg TX to 1:FFFFFFFB00000000 [0454] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335168 on secure session with LSID: 8216
    Long dispatch time: 718 ms, for event type 7
    >>> [E:60481r M:147197903] (S) Msg RX from 1:FFFFFFFB00000000 [0454] --- Type 0001:08 (IM:InvokeCommandRequest)
    <<< [E:60481r M:256335169] (S) Msg TX to 1:FFFFFFFB00000000 [0454] --- Type 0001:09 (IM:InvokeCommandResponse)
    (S) Sending msg 256335169 on secure session with LSID: 8216
    Releasing end point's BLE connection back to application.
    Fail-safe timer expired
    Failsafe timer expired
    SecureSession[0x200121b8]: Moving from state 'kActive' --> 'kPendingEviction'
    Commissioning failed (attempt 1): 32
    Updating services using commissioning mode 1
    Advertise operational node B85C55CBB67A0454-0000000000000001
    Failed to advertise operational node: 3
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1
    Failed to advertise commissionable node: 3
    Failed to finalize service update: 1c
    OpCreds: Got FailSafeTimerExpired
    OpCreds: Proceeding to FailSafeCleanup on fail-safe expiry!
    Reverting pending fabric data for fabric 0x1
    Warning: metadata not found during delete of fabric 0x1
    Fabric (0x1) deleted.
    UserLabel: Last Fabric index 0x1 was removed
    OpCreds: Fabric index 0x1 was removed
    Updating services using commissioning mode 1
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=1
    Failed to advertise commissionable node: 3
    Failed to finalize service update: 1c
    [OnFabricRemoved] Handling a fabric removal from the door lock server [endpointId=1,fabricIndex=1]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=0]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=1]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=2]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=3]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=4]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=5]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=6]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=7]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=8]
    Door Lock App: LockManager::GetUser [endpoint=1,userIndex=9]
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=0
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=1
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=2
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=3
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=4
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=5
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=6
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=7
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=8
    Lock App: LockManager::GetCredential [credentialType=2], credentialIndex=9
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=0
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=1
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=2
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=3
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=4
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=5
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=6
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=7
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=8
    Lock App: LockManager::GetCredential [credentialType=1], credentialIndex=9
    Lock App: LockManager::GetCredential [credentialType=0], credentialIndex=0
    Pending Last Known Good Time: 2023-07-03T16:02:44
    Previous Last Known Good Time: 2023-07-03T16:02:44
    Reverted Last Known Good Time to previous value
    Warning: metadata not found during delete of fabric 0x1
    OpCreds: failed to delete fabric at index 1: d8
    Cluster callback: 0x0000_0030
    Long dispatch time: 504 ms, for event type 32782
    Fail-safe cleanly disarmed

    Thanks and Regards,

    Nick

  • Thank you for providing the logs.  The direct issue is that the matter chip-tool host cannot access the OTBR thread network.  Please see the "CHIP Error 0x000000AC: Internal error" in the terminal log of running chip-tool:

    [1689139871.572581][10800:10802] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
    [1689139871.572742][10800:10802] CHIP:CTL: Received NetworkConfig response, networkingStatus=1
    [1689139871.572808][10800:10802] CHIP:CTL: Error on commissioning step 'ThreadNetworkSetup': '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
    [1689139871.572850][10800:10802] CHIP:CTL: Failed to perform commissioning step 15
    [1689139871.572897][10800:10802] CHIP:CTL: Going from commissioning step 'ThreadNetworkSetup' with lastErr = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error' -> 'Cleanup'
    [1689139871.572956][10800:10802] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error'
    [1689139871.573000][10800:10802] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
    [1689139871.573054][10800:10802] CHIP:TOO: Device commissioning Failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error
    [1689139871.573148][10800:10802] CHIP:DMG: ICR moving to [AwaitingDe]
    [1689139871.573511][10800:10800] CHIP:CTL: Shutting down the commissioner
    [1689139871.573565][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139871.573760][10800:10800] CHIP:DIS: Closing all BLE connections
    [1689139871.573804][10800:10800] CHIP:IN: Clearing BLE pending packets.
    [1689139871.573910][10800:10800] CHIP:BLE: Auto-closing end point's BLE connection.
    [1689139871.573952][10800:10800] CHIP:DL: Closing BLE GATT connection (con 0xffffb405e080)
    [1689139871.574032][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: MarkForEviction Type:1 LSID:56629
    [1689139871.574074][10800:10800] CHIP:SC: SecureSession[0xffffac014f10]: Moving from state 'kActive' --> 'kPendingEviction'
    [1689139871.574154][10800:10800] CHIP:IN: SecureSession[0xffffac014f10]: Released - Type:1 LSID:56629
    [1689139871.574204][10800:10800] CHIP:CTL: Shutting down the controller
    [1689139871.574246][10800:10800] CHIP:IN: Expiring all sessions for fabric 0x1!!
    [1689139871.574284][10800:10800] CHIP:FP: Forgetting fabric 0x1
    [1689139871.574344][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.574391][10800:10801] CHIP:DL: BluezDisconnect peer=75:19:7E:C1:BD:91
    [1689139871.574581][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.574632][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
    [1689139871.574701][10800:10800] CHIP:CTL: Shutting down the commissioner
    [1689139871.574738][10800:10800] CHIP:CTL: Stopping commissioning discovery over DNS-SD
    [1689139871.574933][10800:10800] CHIP:CTL: Shutting down the controller
    [1689139871.574975][10800:10800] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
    [1689139871.575722][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575777][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575823][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575865][10800:10800] CHIP:DMG: IM WH moving to [Uninitialized]
    [1689139871.575903][10800:10800] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
    [1689139871.576068][10800:10800] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
    [1689139871.576126][10800:10800] CHIP:FP: Shutting down FabricTable
    [1689139871.576174][10800:10800] CHIP:TS: Pending Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.576341][10800:10800] CHIP:TS: Previous Last Known Good Time: 2023-07-12T09:42:48
    [1689139871.576389][10800:10800] CHIP:TS: Reverted Last Known Good Time to previous value
    [1689139871.576832][10800:10800] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-whpZh2)
    [1689139871.581286][10800:10800] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
    [1689139871.581397][10800:10800] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
    [1689139871.581442][10800:10800] CHIP:DL: Inet Layer shutdown
    [1689139871.581482][10800:10800] CHIP:DL: BLE shutdown
    [1689139871.583467][10800:10800] CHIP:DL: System Layer shutdown
    [1689139873.616336][10800:10800] CHIP:TOO: Run command failure: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:2050: CHIP Error 0x000000AC: Internal error

    If they are using the latest TI matter repository then CHIPDeviceController.cpp fails with a CHIP_ERROR_INTERNAL inside OnNetworkConfigResponse.

    void DeviceCommissioner::OnNetworkConfigResponse(void * context,
                                                     const NetworkCommissioning::Commands::NetworkConfigResponse::DecodableType & data)
    {
        CommissioningDelegate::CommissioningReport report;
        CHIP_ERROR err = CHIP_NO_ERROR;
    
        ChipLogProgress(Controller, "Received NetworkConfig response, networkingStatus=%u", to_underlying(data.networkingStatus));
        if (data.networkingStatus != NetworkCommissioning::NetworkCommissioningStatus::kSuccess)
        {
            err = CHIP_ERROR_INTERNAL;
            report.Set<NetworkCommissioningStatusInfo>(data.networkingStatus);
        }
        DeviceCommissioner * commissioner = static_cast<DeviceCommissioner *>(context);
        commissioner->CommissioningStageComplete(err, report);
    }

    How much RAM is available on the RPi 4 they are testing?  And is this same device operating the OTBR?  Are the chip-tool and OTBR connected to the same router?

    Regards,
    Ryan

  • Hi Ryan,

    Thanks for your help. Here's further update from customer:

    He is using the latest TI matter repository so which version should be used can eliminate this error?

    The Raspberry Pi is the 4GB version and  it is operating the OTBR. He runs the chip-tool command on the Raspberry Pi too.

    Thanks and Regards,

    Nick

  • I do not see anything wrong with this setup as I've used the exact same hardware and software solution before.  Are they using the RCP I provided or did they build their own?  Are they able to start up the OTBR and join a thread device?  They could evaluate with the Thread Border Router Setup SimpleLink Academy Lab (Task 3 through 5) and a Thread example.  Is the RPi connected to a router through WiFi or ethernet?  Internet access should not be an issue.

    Important note: The latest chip-tool has slightly updated command patterns so you must put "hex:" before the Thread operational dataset.  I've actually run into this exact same error before as the chip-tool log does not indicate an error but will fail at commissioning step 15 (finding the Thread network).  So your command should look something like this:

    ./chip-tool pairing ble-thread 1 hex:000300000f35060004001fffe00208dead00beef00cafe0708fde2f55f8122e830051000112233445566778899aabbccddeeff030f4f70656e5468726561642d37386237010212340410104810e2315100afd6bc9215a6bfac530c0402a0f7f80e080000000000010000 20202021 3840

    Regards,
    Ryan

  • Hi Ryan,

    Customer still can't solve it.

    What are the necessary conditions for chip-tool to be able to debug the matter device? He is currently operating according to the official documentation.

    This is the log printed by the matter device:

    ÿOpenThread ifconfig up and thread start
    OpenThread started: OK
    Setting OpenThread device type to MINIMAL END DEVICE
    Initialize Server
    Server initializing...
    Last Known Good Time: 2023-07-03T16:02:44
    Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xB07ED91A051673C2, FabricId 0x0000000000000001, NodeId 0x0000000000000001, VendorId 0xFFF1
    AccessControl: initializing
    Examples::AccessControlDelegate::Init
    AccessControl: setting
    DefaultAclStorage: initializing
    DefaultAclStorage: 1 entries loaded
    Using ZAP configuration...
    AccessControlCluster: initializing
    Initiating Admin Commissioning cluster.
    Door Lock server initialized
    Cluster callback: 0x0000_0030
    Cluster callback: 0x0000_002B
    Fabric already commissioned. Disabling BLE advertisement
    Updating services using commissioning mode 0
    Advertise operational node B07ED91A051673C2-0000000000000001
    Failed to advertise operational node: 3
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0
    Failed to advertise extended commissionable node: 3
    Failed to finalize service update: 1c
    Delaying proxy of operational discovery: missing delegate
    CASE Server enabling CASE session setups
    Joining Multicast groups
    Server Listening...
    Initialize LEDs
    Initialize buttons
    Unlock completed
    Device Configuration:
      Serial Number: TEST_SN
      Vendor Id: 65521 (0xFFF1)
      Product Id: 32774 (0x8006)
      Hardware Version: 0
      Setup Pin Code (0 for UNKNOWN/ERROR): 20202021
      Setup Discriminator (0xFFFF for UNKNOWN/ERROR): 3840 (0xF00)
      Manufacturing Date: (not set)
      Device Type: 65535 (0xFFFF)
    Stopping the watchdog timer
    Starting the periodic query timer, timeout: 86400 seconds
    SRP Client was started, detected server: fde2:f55f:8122:e830:6366:0c88IXS142C00KA0Cluster callback: 0x0000_002A
    Cluster callback: 0x0000_002A
    Setting operational delegate post init
    Updating services using commissioning mode 0
    Advertise operational node B07ED91A051673C2-0000000000000001
    advertising srp service: B07ED91A051673C2-0000000000000001._matter._tcp
    Advertise commission parameter vendorID=65521 productID=32774 discriminator=3840/15 cm=0
    advertising srp service: 3812229523780283._matterc._udp

    Does he have to connect the raspberry pie to WiFi when running chiptool? As shown in the following figure:

    Thanks and Regards,

    Nick

  • What are the necessary conditions for chip-tool to be able to debug the matter device?

    I don't understand what you mean by this.  There is no clear issue brought up from the matter device log.  You could enable additional logging through the matter\examples\lock-app\cc13x2x7_26x2x7\args.gni

    chip_progress_logging = true
    chip_detail_logging = true
    chip_automation_logging = true

    WiFi is not a requirement unless running Matter over WiFi (not the case for Thread), but your devices should be connected to a router even if there is no internet connection.

    Regards,
    Ryan

  • Hi Ryan,

    Thank you for your continued follow-up.

    Customer uses your method above and some warning and errors occurred.

    Are these problems?

    Does this mean success?

    What should be written in the last two parameters of this command: ./chip-tool doorlock lock-door destination-id endpoint-id-ignored-for-group-commands?

    Thanks and Regards,

    Nick

  • The first three images indicate an issue establishing a connection with the OTBR, just like with the chip-tool logs previously. 

    The next image (second to last) shows a successful Matter device commission.

    Examples of chip-tool interacting with the lock-app example are provided in the README.  You could also reference the CHIP Tool Guide.  If the node is endpoint one then both of these parameters can be set as one to test your environment.

    Regards,
    Ryan

  • Hi Ryan,

    What is the attribute-values in this command? It is not mentioned in the documentation.

    ./chip-tool doorlock   <attribute-name> <attribute-values> <destination-id> <endpoint-id-ignored-for-group-commands>
    

    Thanks and Regards,

    Nick

  • That depends on the attribute-name being referenced.  There is a section on reading Matter device information in Step 8 of the CHIP Tool Guide and the lock-app README provides examples of writing attributes (lock-door and unlock-door).  They can also refer to the Matter Device Specification for more information concerning possible device types, attributes, and commands.

    Regards,
    Ryan

  • Hi Ryan,

    Thanks for your help.

    What does this --timedInteractionTimeoutMs 1000 mean here? Is it necessary to add this command?

    ./chip-tool doorlock set-user 0 1 TST 6452 1 0 0 1 1  --timedInteractionTimeoutMs 1000

    Thanks and Regards,

    Nick

  • This is the time in milliseconds for which the command interaction on the Matter fabric will time out, it should be optional so you can test removing it or increase the value.

    Regards,
    Ryan