chip app segfaults while trying to run Test_TC_DM_1_3_Simulated test case

Problem

chip app segfaults while trying to run a simulated device test case

Config:

Raspi 4
Ubuntu 21.04 & 21.10 both
All listed SDK and ZAP dependencies installed
Build commands used:

# Chip App
scripts/examples/gn_build_test_example.sh app1

# Chip Tool
chip-tool: gn gen out/debug and ninja -C out/debug in chip-tool folder

Steps to Repro:

Build chip-tool and chip app using the build instructions above
Run the chip app using:

 ./chip-app1 --command Test_TC_DM_1_3_Simulated --secured-device-port 5540

Run the chip-tool using:

./chip-tool pairing onnetwork-long 0x654321 20202021 3842

Logs:
ubuntu@ubuntu:~/chip-certification-tool/backend/managed-docker-images/chip-tool$ ./chip-app1 --command Test_TC_DM_1_3_Simulated --secured-device-port 5540
[1643148897.546908][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-BqXFun)
[1643148897.547217][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini)
[1643148897.547611][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-y1PJv9)
[1643148897.547809][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148897.548067][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-pvAYZF)
[1643148897.548256][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.548516][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-lqPVsN)
[1643148897.549202][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.549271][2805:2805] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1)
[1643148897.549457][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-VAVBbm)
[1643148897.552623][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.552686][2805:2805] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1643148897.552854][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-wI5nEQ)
[1643148897.553369][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.553423][2805:2805] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0)
[1643148897.553564][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-Bh81l9)
[1643148897.554037][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148897.554090][2805:2805] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
[1643148897.554231][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-mJ9QI3)
[1643148897.554709][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148897.554764][2805:2805] CHIP:DL: NVS set: chip-config/location-capability = 0 (0x0)
[1643148897.554907][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-WTrBPt)
[1643148897.556127][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148897.556179][2805:2805] CHIP:DL: NVS set: chip-config/active-locale = "en-US"
[1643148897.556894][2805:2805] CHIP:DL: Got Ethernet interface: eth0
[1643148897.557398][2805:2805] CHIP:DL: Found the primary Ethernet interface:eth0
[1643148897.557945][2805:2805] CHIP:DL: Got WiFi interface: wlan0
[1643148897.558029][2805:2805] CHIP:DL: Failed to reset WiFi statistic counts
[1643148897.558208][2805:2805] CHIP:DL: Device Configuration:
[1643148897.558243][2805:2805] CHIP:DL:   Serial Number: TEST_SN
[1643148897.558270][2805:2805] CHIP:DL:   Vendor Id: 9050 (0x235A)
[1643148897.558295][2805:2805] CHIP:DL:   Product Id: 65279 (0xFEFF)
[1643148897.558323][2805:2805] CHIP:DL:   Hardware Version: 0
[1643148897.558351][2805:2805] CHIP:DL:   Setup Pin Code: 20202021
[1643148897.558379][2805:2805] CHIP:DL:   Setup Discriminator: 3842 (0xF02)
[1643148897.558408][2805:2805] CHIP:DL:   Manufacturing Date: (not set)
[1643148897.558434][2805:2805] CHIP:DL:   Device Type: 65535 (0xFFFF)
[1643148897.558829][2805:2805] CHIP:SVR: SetupQRCode: [MT:YNJV7FS212KA0648G00]
[1643148897.558867][2805:2805] CHIP:SVR: Copy/paste the below URL in a browser to see the QR Code:
[1643148897.558894][2805:2805] CHIP:SVR: https://dhrishi.github.io/connectedhomeip/qrcode.html?data=MT%3AYNJV7FS212KA0648G00
[1643148897.558930][2805:2805] CHIP:SVR: Manual pairing code: [34970112332]
[1643148897.558959][2805:2805] CHIP:DL: wpa_supplicant: Start WiFi management
[1643148897.567149][2805:2808] CHIP:DL: wpa_supplicant: connected to wpa_supplicant proxy
[1643148897.568601][2805:2808] CHIP:DL: wpa_supplicant: WiFi interface: /fi/w1/wpa_supplicant1/Interfaces/0
[1643148897.578451][2805:2808] CHIP:DL: wpa_supplicant: connected to wpa_supplicant interface proxy
[1643148897.578849][2805:2808] CHIP:DL: wpa_supplicant: connected to wpa_supplicant bss proxy
[1643148897.659720][2805:2805] CHIP:TOO:  **** Test Start: Test_TC_DM_1_3_Simulated

[1643148897.659826][2805:2805] CHIP:TOO:  ***** Test Step 0 : Wait for the device to be commissioned

[1643148897.660231][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_kvs-1fw64l)
[1643148897.660592][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1643148897.660805][2805:2805] CHIP:ZCL: Using ZAP configuration...
[1643148897.660993][2805:2805] CHIP:ZCL: OpCreds: Initiating OpCreds cluster by writing fabrics list from fabric table.
[1643148897.661049][2805:2805] CHIP:DIS: Set the fabric pairing table delegate
[1643148897.661390][2805:2805] CHIP:DIS: Init fabric pairing table with server storage
[1643148897.661566][2805:2805] CHIP:IN: UDP::Init bind&listen port=5540
[1643148897.661823][2805:2805] CHIP:IN: UDP::Init bound to port=5540
[1643148897.661889][2805:2805] CHIP:IN: UDP::Init bind&listen port=5540
[1643148897.662097][2805:2805] CHIP:IN: UDP::Init bound to port=5540
[1643148897.662155][2805:2805] CHIP:IN: TransportMgr initialized
[1643148897.662444][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-7RQ3gQ)
[1643148897.663591][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.663701][2805:2805] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 1000 (0x3E8)
[1643148897.664182][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-xCBAje)
[1643148897.665253][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148897.665354][2805:2805] CHIP:DL: NVS set: chip-counters/global-eidc = 65536 (0x10000)
[1643148897.665465][2805:2805] CHIP:DIS: Fabric 1 is not yet configured
[1643148897.665546][2805:2805] CHIP:DIS: Fabric 2 is not yet configured
[1643148897.665623][2805:2805] CHIP:DIS: Fabric 3 is not yet configured
[1643148897.665698][2805:2805] CHIP:DIS: Fabric 4 is not yet configured
[1643148897.665772][2805:2805] CHIP:DIS: Fabric 5 is not yet configured
[1643148897.665847][2805:2805] CHIP:DIS: Fabric 6 is not yet configured
[1643148897.665923][2805:2805] CHIP:DIS: Fabric 7 is not yet configured
[1643148897.665997][2805:2805] CHIP:DIS: Fabric 8 is not yet configured
[1643148897.666071][2805:2805] CHIP:DIS: Fabric 9 is not yet configured
[1643148897.666145][2805:2805] CHIP:DIS: Fabric 10 is not yet configured
[1643148897.666219][2805:2805] CHIP:DIS: Fabric 11 is not yet configured
[1643148897.666293][2805:2805] CHIP:DIS: Fabric 12 is not yet configured
[1643148897.666368][2805:2805] CHIP:DIS: Fabric 13 is not yet configured
[1643148897.666442][2805:2805] CHIP:DIS: Fabric 14 is not yet configured
[1643148897.666516][2805:2805] CHIP:DIS: Fabric 15 is not yet configured
[1643148897.666590][2805:2805] CHIP:DIS: Fabric 16 is not yet configured
[1643148897.666744][2805:2805] CHIP:SC: Assigned local session key ID 1
[1643148897.666806][2805:2805] CHIP:SC: Waiting for PBKDF param request
[1643148897.666858][2805:2805] CHIP:DIS: DNS-SD StartServer mode=1
[1643148897.671689][2805:2805] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found
[1643148897.674752][2805:2805] CHIP:DIS: CHIP minimal mDNS started advertising.
[1643148897.675848][2805:2805] CHIP:DIS: Failed to find a valid admin pairing. Node ID unknown
[1643148897.675880][2805:2805] CHIP:DIS: Start dns-sd server - no current nodeId
[1643148897.675909][2805:2805] CHIP:DL: MAC is not known, using a default.
[1643148897.675950][2805:2805] CHIP:DIS: Advertise commission parameter vendorID=9050 productID=65279 discriminator=3842/15
[1643148897.676021][2805:2805] CHIP:DIS: CHIP minimal mDNS configured as 'Commissionable node device'.
[1643148897.678438][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.680362][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.682157][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.684215][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.686106][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148897.688021][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148897.689858][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148897.689938][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.689969][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.691705][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148897.691776][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.691807][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.691837][2805:2805] CHIP:DIS: Scheduling Discovery timeout in secs=900
[1643148897.691871][2805:2805] CHIP:DIS: DNS-SD StartServer mode=0
[1643148897.694572][2805:2805] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found
[1643148897.697503][2805:2805] CHIP:DIS: CHIP minimal mDNS started advertising.
[1643148897.699797][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.701583][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.703394][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.705228][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.707191][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148897.709096][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148897.711426][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148897.711522][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.711555][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.713273][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148897.713375][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.713406][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.713452][2805:2805] CHIP:DIS: Failed to find a valid admin pairing. Node ID unknown
[1643148897.713475][2805:2805] CHIP:DIS: Start dns-sd server - no current nodeId
[1643148897.713503][2805:2805] CHIP:DL: MAC is not known, using a default.
[1643148897.713543][2805:2805] CHIP:DIS: Advertise commission parameter vendorID=9050 productID=65279 discriminator=3842/15
[1643148897.713611][2805:2805] CHIP:DIS: CHIP minimal mDNS configured as 'Commissionable node device'.
[1643148897.715663][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.717421][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.719189][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.721041][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148897.724391][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148897.726343][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148897.728216][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148897.728305][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.728336][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.730040][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148897.730111][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.730143][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148897.730173][2805:2805] CHIP:DIS: Scheduling Discovery timeout in secs=900
[1643148897.730220][2805:2805] CHIP:IN: CASE Server enabling CASE session setups
[1643148897.730254][2805:2805] CHIP:SVR: Server Listening...
[1643148897.730276][2805:2805] CHIP:DL: Device Configuration:
[1643148897.730308][2805:2805] CHIP:DL:   Serial Number: TEST_SN
[1643148897.730330][2805:2805] CHIP:DL:   Vendor Id: 9050 (0x235A)
[1643148897.730350][2805:2805] CHIP:DL:   Product Id: 65279 (0xFEFF)
[1643148897.730374][2805:2805] CHIP:DL:   Hardware Version: 0
[1643148897.730397][2805:2805] CHIP:DL:   Setup Pin Code: 20202021
[1643148897.730420][2805:2805] CHIP:DL:   Setup Discriminator: 3842 (0xF02)
[1643148897.730443][2805:2805] CHIP:DL:   Manufacturing Date: (not set)
[1643148897.730464][2805:2805] CHIP:DL:   Device Type: 65535 (0xFFFF)
[1643148897.730524][2805:2805] CHIP:SVR: SetupQRCode: [MT:YNJV7FS212KA0648G00]
[1643148897.730552][2805:2805] CHIP:SVR: Copy/paste the below URL in a browser to see the QR Code:
[1643148897.730573][2805:2805] CHIP:SVR: https://dhrishi.github.io/connectedhomeip/qrcode.html?data=MT%3AYNJV7FS212KA0648G00
[1643148897.730603][2805:2805] CHIP:SVR: Manual pairing code: [34970112332]
[1643148897.730968][2805:2805] CHIP:ZCL: PlatformMgrDelegate: OnStartUp
[1643148897.731075][2805:2805] CHIP:EVL: LogEvent event number: 0x0000000000000000 priority: 2, endpoint id:  0x0 cluster id: 0x0000_0028 event id: 0x0 Sys timestamp: 0x0000000000020A30
[1643148897.731137][2805:2805] CHIP:EVL: LogEvent event number: 0x0000000000000001 priority: 2, endpoint id:  0x1 cluster id: 0x0000_0028 event id: 0x0 Sys timestamp: 0x0000000000020A30
[1643148897.731168][2805:2805] CHIP:DL: Disabling auto scan
[1643148900.101634][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.103818][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.105835][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.108076][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.108383][2805:2805] CHIP:EM: Received message of type 0x20 with protocolId (0, 0) and MessageCounter:1862767193 on exchange 39447r
[1643148900.108443][2805:2805] CHIP:EM: Handling via exchange: 39447r, Delegate: 0xaaaaacd6c978
[1643148900.108501][2805:2805] CHIP:SC: Received PBKDF param request
[1643148900.108557][2805:2805] CHIP:SC: Peer assigned session ID 1
[1643148900.108594][2805:2805] CHIP:SC: Found MRP parameters in the message
[1643148900.108694][2805:2805] CHIP:SC: Including MRP parameters in PBKDF param response
[1643148900.109767][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:1862767193 on exchange: 39447r
[1643148900.109824][2805:2805] CHIP:IN: Prepared unauthenticated message 0xaaaaacd6bb08 to 0x0000000000000000 (0)  of type 0x21 and protocolId (0, 0) on exchange 39447r with MessageCounter:824388787.
[1643148900.109875][2805:2805] CHIP:IN: Sending unauthenticated msg 0xaaaaacd6bb08 with MessageCounter:824388787 to 0x0000000000000000 at monotonic time: 136059 msec
[1643148900.110060][2805:2805] CHIP:SC: Sent PBKDF param response
[1643148900.112257][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.114604][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.116926][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.118999][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.120669][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.122235][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.124047][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.125609][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.127315][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.127598][2805:2805] CHIP:EM: Received message of type 0x22 with protocolId (0, 0) and MessageCounter:1862767194 on exchange 39447r
[1643148900.127634][2805:2805] CHIP:EM: Found matching exchange: 39447r, Delegate: 0xaaaaacd6c978
[1643148900.127675][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:824388787 from Retrans Table on exchange 39447r
[1643148900.127700][2805:2805] CHIP:EM: Removed CHIP MessageCounter:824388787 from RetransTable on exchange 39447r
[1643148900.127734][2805:2805] CHIP:SC: Received spake2p msg1
[1643148900.129353][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:1862767194 on exchange: 39447r
[1643148900.129398][2805:2805] CHIP:IN: Prepared unauthenticated message 0xaaaaacd6bb08 to 0x0000000000000000 (0)  of type 0x23 and protocolId (0, 0) on exchange 39447r with MessageCounter:824388788.
[1643148900.129435][2805:2805] CHIP:IN: Sending unauthenticated msg 0xaaaaacd6bb08 with MessageCounter:824388788 to 0x0000000000000000 at monotonic time: 136078 msec
[1643148900.129554][2805:2805] CHIP:SC: Sent spake2p msg2
[1643148900.131529][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.133185][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.134738][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.136292][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.136514][2805:2805] CHIP:EM: Received message of type 0x24 with protocolId (0, 0) and MessageCounter:1862767195 on exchange 39447r
[1643148900.136550][2805:2805] CHIP:EM: Found matching exchange: 39447r, Delegate: 0xaaaaacd6c978
[1643148900.136591][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:824388788 from Retrans Table on exchange 39447r
[1643148900.136615][2805:2805] CHIP:EM: Removed CHIP MessageCounter:824388788 from RetransTable on exchange 39447r
[1643148900.136647][2805:2805] CHIP:SC: Received spake2p msg3
[1643148900.136735][2805:2805] CHIP:SC: Sending status report. Protocol code 0, exchange 39447
[1643148900.136770][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:1862767195 on exchange: 39447r
[1643148900.136804][2805:2805] CHIP:IN: Prepared unauthenticated message 0xaaaaacd6bb08 to 0x0000000000000000 (0)  of type 0x40 and protocolId (0, 0) on exchange 39447r with MessageCounter:824388789.
[1643148900.136839][2805:2805] CHIP:IN: Sending unauthenticated msg 0xaaaaacd6bb08 with MessageCounter:824388789 to 0x0000000000000000 at monotonic time: 136086 msec
[1643148900.136969][2805:2805] CHIP:IN: New secure session created for device 0xFFFFFFFB00000000, LSID:1 PSID:1!
[1643148900.137080][2805:2805] CHIP:SVR: Commissioning completed session establishment step
[1643148900.137132][2805:2805] CHIP:SVR: Device completed Rendezvous process
[1643148900.139840][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.141613][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.143270][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.144901][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.145154][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844430 on exchange 39448r
[1643148900.145205][2805:2805] CHIP:EM: Handling via exchange: 39448r, Delegate: 0xaaaaacd60550
[1643148900.145279][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.145306][2805:2805] CHIP:DMG: {
[1643148900.145329][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.145356][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.145381][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.145412][2805:2805] CHIP:DMG: 	[
[1643148900.145437][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.145503][2805:2805] CHIP:DMG: 		{
[1643148900.145531][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.145563][2805:2805] CHIP:DMG: 			{
[1643148900.145594][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.145627][2805:2805] CHIP:DMG: 				ClusterId = 0x30,
[1643148900.145661][2805:2805] CHIP:DMG: 				CommandId = 0x0,
[1643148900.145692][2805:2805] CHIP:DMG: 			},
[1643148900.145726][2805:2805] CHIP:DMG:
[1643148900.145753][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.145786][2805:2805] CHIP:DMG: 			{
[1643148900.145820][2805:2805] CHIP:DMG: 				0x0 = 60,
[1643148900.145853][2805:2805] CHIP:DMG: 				0x1 = 2,
[1643148900.145886][2805:2805] CHIP:DMG: 				0x2 = 3000,
[1643148900.145920][2805:2805] CHIP:DMG: 			},
[1643148900.145946][2805:2805] CHIP:DMG: 		},
[1643148900.145976][2805:2805] CHIP:DMG:
[1643148900.145999][2805:2805] CHIP:DMG: 	],
[1643148900.146028][2805:2805] CHIP:DMG:
[1643148900.146050][2805:2805] CHIP:DMG: },
[1643148900.146108][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0000
[1643148900.146164][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.146196][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.146229][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.146259][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844430 on exchange: 39448r
[1643148900.146314][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb28 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39448r with MessageCounter:2464750.
[1643148900.146350][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb28 with MessageCounter:2464750 to 0xFFFFFFFB00000000 (0) at monotonic time: 136095 msec
[1643148900.146488][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.146518][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.146779][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:1862767196 on exchange 39447r
[1643148900.146807][2805:2805] CHIP:EM: Found matching exchange: 39447r, Delegate: (nil)
[1643148900.146847][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:824388789 from Retrans Table on exchange 39447r
[1643148900.146872][2805:2805] CHIP:EM: Removed CHIP MessageCounter:824388789 from RetransTable on exchange 39447r
[1643148900.148517][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844431 on exchange 39449r
[1643148900.148559][2805:2805] CHIP:EM: Handling via exchange: 39449r, Delegate: 0xaaaaacd60550
[1643148900.148628][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.148652][2805:2805] CHIP:DMG: {
[1643148900.148675][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.148706][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.148733][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.148768][2805:2805] CHIP:DMG: 	[
[1643148900.148792][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.148822][2805:2805] CHIP:DMG: 		{
[1643148900.148850][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.148885][2805:2805] CHIP:DMG: 			{
[1643148900.148920][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.148956][2805:2805] CHIP:DMG: 				ClusterId = 0x30,
[1643148900.148991][2805:2805] CHIP:DMG: 				CommandId = 0x2,
[1643148900.149025][2805:2805] CHIP:DMG: 			},
[1643148900.149060][2805:2805] CHIP:DMG:
[1643148900.149088][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.149122][2805:2805] CHIP:DMG: 			{
[1643148900.149156][2805:2805] CHIP:DMG: 				0x0 = 0,
[1643148900.149194][2805:2805] CHIP:DMG: 				0x1 = "",
[1643148900.149230][2805:2805] CHIP:DMG: 				0x2 = 3,
[1643148900.149260][2805:2805] CHIP:DMG: 				0x3 = 3000,
[1643148900.149285][2805:2805] CHIP:DMG: 			},
[1643148900.149307][2805:2805] CHIP:DMG: 		},
[1643148900.149339][2805:2805] CHIP:DMG:
[1643148900.149363][2805:2805] CHIP:DMG: 	],
[1643148900.149392][2805:2805] CHIP:DMG:
[1643148900.149413][2805:2805] CHIP:DMG: },
[1643148900.149469][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0002
[1643148900.149693][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-YdGpuI)
[1643148900.150301][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148900.150363][2805:2805] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0)
[1643148900.150511][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-QEgABW)
[1643148900.151575][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148900.151632][2805:2805] CHIP:DL: NVS set: chip-config/country-code = ""
[1643148900.151769][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_config.ini-I3sIl4)
[1643148900.152866][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini)
[1643148900.152913][2805:2805] CHIP:DL: NVS set: chip-config/breadcrumb = 3 (0x3)
[1643148900.152963][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.152996][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.153043][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.153080][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844431 on exchange: 39449r
[1643148900.153156][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb08 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39449r with MessageCounter:2464751.
[1643148900.153199][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb08 with MessageCounter:2464751 to 0xFFFFFFFB00000000 (0) at monotonic time: 136102 msec
[1643148900.153367][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.153396][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.153556][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844432 on exchange 39448r
[1643148900.153593][2805:2805] CHIP:EM: Found matching exchange: 39448r, Delegate: (nil)
[1643148900.153629][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464750 from Retrans Table on exchange 39448r
[1643148900.153652][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464750 from RetransTable on exchange 39448r
[1643148900.155454][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844433 on exchange 39450r
[1643148900.155500][2805:2805] CHIP:EM: Handling via exchange: 39450r, Delegate: 0xaaaaacd60550
[1643148900.155568][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.155597][2805:2805] CHIP:DMG: {
[1643148900.155618][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.155646][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.155672][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.155704][2805:2805] CHIP:DMG: 	[
[1643148900.155730][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.155759][2805:2805] CHIP:DMG: 		{
[1643148900.155786][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.155820][2805:2805] CHIP:DMG: 			{
[1643148900.155854][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.155892][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.155929][2805:2805] CHIP:DMG: 				CommandId = 0x2,
[1643148900.155965][2805:2805] CHIP:DMG: 			},
[1643148900.155999][2805:2805] CHIP:DMG:
[1643148900.156026][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.156058][2805:2805] CHIP:DMG: 			{
[1643148900.156092][2805:2805] CHIP:DMG: 				0x0 = 2,
[1643148900.156129][2805:2805] CHIP:DMG: 			},
[1643148900.156159][2805:2805] CHIP:DMG: 		},
[1643148900.156190][2805:2805] CHIP:DMG:
[1643148900.156215][2805:2805] CHIP:DMG: 	],
[1643148900.156245][2805:2805] CHIP:DMG:
[1643148900.156268][2805:2805] CHIP:DMG: },
[1643148900.156328][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
[1643148900.156363][2805:2805] CHIP:ZCL: OpCreds: Certificate Chain request received for PAI
[1643148900.156408][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.156440][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.156474][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.156505][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844433 on exchange: 39450r
[1643148900.156589][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb28 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39450r with MessageCounter:2464752.
[1643148900.156627][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb28 with MessageCounter:2464752 to 0xFFFFFFFB00000000 (0) at monotonic time: 136105 msec
[1643148900.156749][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.156777][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.156923][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844434 on exchange 39449r
[1643148900.156956][2805:2805] CHIP:EM: Found matching exchange: 39449r, Delegate: (nil)
[1643148900.156994][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464751 from Retrans Table on exchange 39449r
[1643148900.157020][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464751 from RetransTable on exchange 39449r
[1643148900.159300][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844435 on exchange 39451r
[1643148900.159349][2805:2805] CHIP:EM: Handling via exchange: 39451r, Delegate: 0xaaaaacd60550
[1643148900.159423][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.159451][2805:2805] CHIP:DMG: {
[1643148900.159475][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.159506][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.159531][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.159563][2805:2805] CHIP:DMG: 	[
[1643148900.159589][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.159620][2805:2805] CHIP:DMG: 		{
[1643148900.159649][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.159683][2805:2805] CHIP:DMG: 			{
[1643148900.159718][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.159756][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.159790][2805:2805] CHIP:DMG: 				CommandId = 0x2,
[1643148900.159821][2805:2805] CHIP:DMG: 			},
[1643148900.159857][2805:2805] CHIP:DMG:
[1643148900.159885][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.159918][2805:2805] CHIP:DMG: 			{
[1643148900.159952][2805:2805] CHIP:DMG: 				0x0 = 1,
[1643148900.159991][2805:2805] CHIP:DMG: 			},
[1643148900.160023][2805:2805] CHIP:DMG: 		},
[1643148900.160135][2805:2805] CHIP:DMG:
[1643148900.160163][2805:2805] CHIP:DMG: 	],
[1643148900.160195][2805:2805] CHIP:DMG:
[1643148900.160219][2805:2805] CHIP:DMG: },
[1643148900.160284][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0002
[1643148900.160320][2805:2805] CHIP:ZCL: OpCreds: Certificate Chain request received for DAC
[1643148900.160366][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.160399][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.160434][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.160466][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844435 on exchange: 39451r
[1643148900.160553][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb08 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39451r with MessageCounter:2464753.
[1643148900.160594][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb08 with MessageCounter:2464753 to 0xFFFFFFFB00000000 (0) at monotonic time: 136109 msec
[1643148900.160730][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.160762][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.160906][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844436 on exchange 39450r
[1643148900.160943][2805:2805] CHIP:EM: Found matching exchange: 39450r, Delegate: (nil)
[1643148900.160988][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464752 from Retrans Table on exchange 39450r
[1643148900.161017][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464752 from RetransTable on exchange 39450r
[1643148900.162829][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844437 on exchange 39452r
[1643148900.162874][2805:2805] CHIP:EM: Handling via exchange: 39452r, Delegate: 0xaaaaacd60550
[1643148900.162949][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.162980][2805:2805] CHIP:DMG: {
[1643148900.163006][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.163041][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.163072][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.163107][2805:2805] CHIP:DMG: 	[
[1643148900.163134][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.163169][2805:2805] CHIP:DMG: 		{
[1643148900.163197][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.163232][2805:2805] CHIP:DMG: 			{
[1643148900.163268][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.163308][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.163349][2805:2805] CHIP:DMG: 				CommandId = 0x0,
[1643148900.163383][2805:2805] CHIP:DMG: 			},
[1643148900.163420][2805:2805] CHIP:DMG:
[1643148900.163451][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.163486][2805:2805] CHIP:DMG: 			{
[1643148900.163520][2805:2805] CHIP:DMG: 				0x0 = [
[1643148900.163567][2805:2805] CHIP:DMG: 					0x2, 0x5f, 0x90, 0x65, 0x55, 0x6a, 0x45, 0xe5, 0xec, 0x74, 0xc2, 0x8e, 0xe9, 0xc7, 0x53, 0xf6, 0x3e, 0xc1, 0x52, 0x8, 0xf4, 0xbe, 0xa9, 0xee, 0x64, 0xea, 0x47, 0xf1, 0x4b, 0x6f, 0xf5, 0xed,
[1643148900.163611][2805:2805] CHIP:DMG: 			]
[1643148900.163648][2805:2805] CHIP:DMG: 			},
[1643148900.163685][2805:2805] CHIP:DMG: 		},
[1643148900.163721][2805:2805] CHIP:DMG:
[1643148900.163748][2805:2805] CHIP:DMG: 	],
[1643148900.163780][2805:2805] CHIP:DMG:
[1643148900.163804][2805:2805] CHIP:DMG: },
[1643148900.163865][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0000
[1643148900.163903][2805:2805] CHIP:ZCL: OpCreds: received an AttestationRequest
[1643148900.166361][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.166416][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.166461][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.166497][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844437 on exchange: 39452r
[1643148900.166588][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb28 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39452r with MessageCounter:2464754.
[1643148900.166633][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb28 with MessageCounter:2464754 to 0xFFFFFFFB00000000 (0) at monotonic time: 136115 msec
[1643148900.166856][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.166891][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.167058][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844438 on exchange 39451r
[1643148900.167090][2805:2805] CHIP:EM: Found matching exchange: 39451r, Delegate: (nil)
[1643148900.167127][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464753 from Retrans Table on exchange 39451r
[1643148900.167156][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464753 from RetransTable on exchange 39451r
[1643148900.175834][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844439 on exchange 39453r
[1643148900.175877][2805:2805] CHIP:EM: Handling via exchange: 39453r, Delegate: 0xaaaaacd60550
[1643148900.175953][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.175982][2805:2805] CHIP:DMG: {
[1643148900.176007][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.176039][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.176065][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.176096][2805:2805] CHIP:DMG: 	[
[1643148900.176122][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.176155][2805:2805] CHIP:DMG: 		{
[1643148900.176184][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.176217][2805:2805] CHIP:DMG: 			{
[1643148900.176437][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.176475][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.176516][2805:2805] CHIP:DMG: 				CommandId = 0x4,
[1643148900.176552][2805:2805] CHIP:DMG: 			},
[1643148900.176593][2805:2805] CHIP:DMG:
[1643148900.176623][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.176655][2805:2805] CHIP:DMG: 			{
[1643148900.176686][2805:2805] CHIP:DMG: 				0x0 = [
[1643148900.176733][2805:2805] CHIP:DMG: 					0x7e, 0x7c, 0x16, 0x65, 0x42, 0x92, 0x50, 0xe9, 0xb8, 0x43, 0xda, 0x9e, 0x27, 0x1f, 0x8d, 0xda, 0xb6, 0x27, 0x44, 0x8c, 0xb3, 0x38, 0x3b, 0xe3, 0x34, 0xfc, 0x82, 0x6e, 0x1a, 0x34, 0x21, 0xd,
[1643148900.176770][2805:2805] CHIP:DMG: 			]
[1643148900.176799][2805:2805] CHIP:DMG: 			},
[1643148900.176828][2805:2805] CHIP:DMG: 		},
[1643148900.176866][2805:2805] CHIP:DMG:
[1643148900.176890][2805:2805] CHIP:DMG: 	],
[1643148900.176920][2805:2805] CHIP:DMG:
[1643148900.176943][2805:2805] CHIP:DMG: },
[1643148900.177005][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0004
[1643148900.177041][2805:2805] CHIP:ZCL: OpCreds: commissioner has requested an OpCSR
[1643148900.178386][2805:2805] CHIP:ZCL: OpCreds: NewCertificateSigningRequest returned ../../examples/placeholder/linux/third_party/connectedhomeip/src/crypto/CHIPCryptoPALOpenSSL.cpp:1111: Success
[1643148900.178802][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.178840][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.178882][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.178917][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844439 on exchange: 39453r
[1643148900.179000][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb08 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39453r with MessageCounter:2464755.
[1643148900.179043][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb08 with MessageCounter:2464755 to 0xFFFFFFFB00000000 (0) at monotonic time: 136128 msec
[1643148900.179212][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.179242][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.179401][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844440 on exchange 39452r
[1643148900.179437][2805:2805] CHIP:EM: Found matching exchange: 39452r, Delegate: (nil)
[1643148900.179473][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464754 from Retrans Table on exchange 39452r
[1643148900.179500][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464754 from RetransTable on exchange 39452r
[1643148900.184027][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844441 on exchange 39454r
[1643148900.184076][2805:2805] CHIP:EM: Handling via exchange: 39454r, Delegate: 0xaaaaacd60550
[1643148900.184154][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.184183][2805:2805] CHIP:DMG: {
[1643148900.184208][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.184233][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.184261][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.184293][2805:2805] CHIP:DMG: 	[
[1643148900.184319][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.184351][2805:2805] CHIP:DMG: 		{
[1643148900.184381][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.184418][2805:2805] CHIP:DMG: 			{
[1643148900.184454][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.184489][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.184523][2805:2805] CHIP:DMG: 				CommandId = 0xb,
[1643148900.184555][2805:2805] CHIP:DMG: 			},
[1643148900.184591][2805:2805] CHIP:DMG:
[1643148900.184619][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.184651][2805:2805] CHIP:DMG: 			{
[1643148900.184688][2805:2805] CHIP:DMG: 				0x0 = [
[1643148900.184743][2805:2805] CHIP:DMG: 					0x15, 0x30, 0x1, 0x1, 0x0, 0x24, 0x2, 0x1, 0x37, 0x3, 0x24, 0x14, 0x0, 0x24, 0x15, 0x1, 0x18, 0x26, 0x4, 0x80, 0x22, 0x81, 0x27, 0x26, 0x5, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x6, 0x24, 0x14, 0x0, 0x24, 0x15, 0x1, 0x18, 0x24, 0x7, 0x1, 0x24, 0x8, 0x1, 0x3
[1643148900.184781][2805:2805] CHIP:DMG: 			]
[1643148900.184816][2805:2805] CHIP:DMG: 			},
[1643148900.184849][2805:2805] CHIP:DMG: 		},
[1643148900.184883][2805:2805] CHIP:DMG:
[1643148900.184909][2805:2805] CHIP:DMG: 	],
[1643148900.184939][2805:2805] CHIP:DMG:
[1643148900.184963][2805:2805] CHIP:DMG: },
[1643148900.185021][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B
[1643148900.185056][2805:2805] CHIP:ZCL: OpCreds: commissioner has added a trusted root Cert
[1643148900.185102][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.185131][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.185174][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.185207][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844441 on exchange: 39454r
[1643148900.185265][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb28 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39454r with MessageCounter:2464756.
[1643148900.185303][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb28 with MessageCounter:2464756 to 0xFFFFFFFB00000000 (0) at monotonic time: 136134 msec
[1643148900.185419][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.185448][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.185588][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844442 on exchange 39453r
[1643148900.185616][2805:2805] CHIP:EM: Found matching exchange: 39453r, Delegate: (nil)
[1643148900.185656][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464755 from Retrans Table on exchange 39453r
[1643148900.185683][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464755 from RetransTable on exchange 39453r
[1643148900.187449][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11844443 on exchange 39455r
[1643148900.187486][2805:2805] CHIP:EM: Handling via exchange: 39455r, Delegate: 0xaaaaacd60550
[1643148900.187551][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.187581][2805:2805] CHIP:DMG: {
[1643148900.187603][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.187635][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.187661][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.187698][2805:2805] CHIP:DMG: 	[
[1643148900.187724][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.187752][2805:2805] CHIP:DMG: 		{
[1643148900.187779][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.187812][2805:2805] CHIP:DMG: 			{
[1643148900.187845][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.187880][2805:2805] CHIP:DMG: 				ClusterId = 0x3e,
[1643148900.187916][2805:2805] CHIP:DMG: 				CommandId = 0x6,
[1643148900.187948][2805:2805] CHIP:DMG: 			},
[1643148900.187981][2805:2805] CHIP:DMG:
[1643148900.188010][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.188042][2805:2805] CHIP:DMG: 			{
[1643148900.188074][2805:2805] CHIP:DMG: 				0x0 = [
[1643148900.188129][2805:2805] CHIP:DMG: 					0x15, 0x30, 0x1, 0x1, 0x1, 0x24, 0x2, 0x1, 0x37, 0x3, 0x24, 0x13, 0x1, 0x24, 0x15, 0x1, 0x18, 0x26, 0x4, 0x80, 0x22, 0x81, 0x27, 0x26, 0x5, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x6, 0x26, 0x11, 0x21, 0x43, 0x65, 0x0, 0x24, 0x15, 0x1, 0x18, 0x24, 0x7, 0x1, 0
[1643148900.188168][2805:2805] CHIP:DMG: 			]
[1643148900.188203][2805:2805] CHIP:DMG: 				0x1 = [
[1643148900.188256][2805:2805] CHIP:DMG: 					0x15, 0x30, 0x1, 0x1, 0x0, 0x24, 0x2, 0x1, 0x37, 0x3, 0x24, 0x14, 0x0, 0x24, 0x15, 0x1, 0x18, 0x26, 0x4, 0x80, 0x22, 0x81, 0x27, 0x26, 0x5, 0x80, 0x25, 0x4d, 0x3a, 0x37, 0x6, 0x24, 0x13, 0x1, 0x24, 0x15, 0x1, 0x18, 0x24, 0x7, 0x1, 0x24, 0x8, 0x1, 0x3
[1643148900.188298][2805:2805] CHIP:DMG: 			]
[1643148900.188333][2805:2805] CHIP:DMG: 				0x2 = [
[1643148900.188365][2805:2805] CHIP:DMG:
[1643148900.188401][2805:2805] CHIP:DMG: 			]
[1643148900.188437][2805:2805] CHIP:DMG: 				0x3 = 112233,
[1643148900.188475][2805:2805] CHIP:DMG: 				0x4 = 65521,
[1643148900.188509][2805:2805] CHIP:DMG: 			},
[1643148900.188539][2805:2805] CHIP:DMG: 		},
[1643148900.188574][2805:2805] CHIP:DMG:
[1643148900.188598][2805:2805] CHIP:DMG: 	],
[1643148900.188630][2805:2805] CHIP:DMG:
[1643148900.188653][2805:2805] CHIP:DMG: },
[1643148900.188712][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_003E Command=0x0000_0006
[1643148900.188752][2805:2805] CHIP:ZCL: OpCreds: commissioner has added a NOC
[1643148900.188991][2805:2805] CHIP:DIS: Verifying the received credentials
[1643148900.191007][2805:2805] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000001 and root pubkey
[1643148900.191049][2805:2805] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.191075][2805:2805] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.191101][2805:2805] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.191127][2805:2805] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.191151][2805:2805] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.191176][2805:2805] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.191201][2805:2805] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.191226][2805:2805] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.191248][2805:2805] CHIP:IN: 0x51,
[1643148900.191308][2805:2805] CHIP:IN: Generated compressed fabric ID
[1643148900.191336][2805:2805] CHIP:IN: 0x6f, 0x55, 0x25, 0x60, 0xfd, 0xec, 0x45, 0x73,
[1643148900.191367][2805:2805] CHIP:DIS: Added new fabric at index: 1, Initialized: 1
[1643148900.191392][2805:2805] CHIP:DIS: Assigned compressed fabric ID: 0x6F552560FDEC4573, node ID: 0x0000000000654321
[1643148900.191419][2805:2805] CHIP:DIS: Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
[1643148900.191443][2805:2805] CHIP:ZCL: OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, NodeId 0x0000000000654321, VendorId 0xFFF1
[1643148900.191469][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.191764][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_kvs-J4hppW)
[1643148900.192401][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1643148900.192495][2805:2805] CHIP:SVR: Saved into server storage: Fabric1
[1643148900.192526][2805:2805] CHIP:DIS: Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage
[1643148900.192553][2805:2805] CHIP:ZCL: OpCreds: Fabric 1 was persisted to storage. FabricId<PHONE_NUMBER>000001, NodeId<PHONE_NUMBER>654321, VendorId 0xFFF1
[1643148900.192580][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.192610][2805:2805] CHIP:DIS: Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
[1643148900.192634][2805:2805] CHIP:ZCL: OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, NodeId 0x0000000000654321, VendorId 0xFFF1
[1643148900.192659][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.192877][2805:2805] CHIP:DL: writing settings to file (/tmp/chip_kvs-8Kw7Q8)
[1643148900.194127][2805:2805] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs)
[1643148900.194186][2805:2805] CHIP:SVR: Saved into server storage: Fabric1
[1643148900.194215][2805:2805] CHIP:DIS: Fabric (1) persisted to storage. Calling OnFabricPersistedToStorage
[1643148900.194240][2805:2805] CHIP:ZCL: OpCreds: Fabric 1 was persisted to storage. FabricId<PHONE_NUMBER>000001, NodeId<PHONE_NUMBER>654321, VendorId 0xFFF1
[1643148900.194267][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.194300][2805:2805] CHIP:DL: MAC is not known, using a default.
[1643148900.194329][2805:2805] CHIP:DIS: Advertise operational node 6F552560FDEC4573-0000000000654321
[1643148900.194393][2805:2805] CHIP:DIS: CHIP minimal mDNS configured as 'Operational device'.
[1643148900.198777][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.202514][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.206029][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.209702][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.213417][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148900.216943][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148900.220385][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148900.220520][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.220551][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.223816][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148900.223892][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.223923][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.223998][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.224029][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.224050][2805:2805] CHIP:ZCL: OpCreds: successfully added a NOC
[1643148900.224097][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.224133][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11844443 on exchange: 39455r
[1643148900.224203][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb08 to 0xFFFFFFFB00000000 (0)  of type 0x9 and protocolId (0, 1) on exchange 39455r with MessageCounter:2464757.
[1643148900.224243][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb08 with MessageCounter:2464757 to 0xFFFFFFFB00000000 (0) at monotonic time: 136173 msec
[1643148900.224381][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.224408][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.224578][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844444 on exchange 39454r
[1643148900.224609][2805:2805] CHIP:EM: Found matching exchange: 39454r, Delegate: (nil)
[1643148900.224647][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464756 from Retrans Table on exchange 39454r
[1643148900.224673][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464756 from RetransTable on exchange 39454r
[1643148900.227760][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.229576][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.232028][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.233698][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 52131
[1643148900.233967][2805:2805] CHIP:EM: Received message of type 0x10 with protocolId (0, 0) and MessageCounter:11844445 on exchange 39455r
[1643148900.234002][2805:2805] CHIP:EM: Found matching exchange: 39455r, Delegate: (nil)
[1643148900.234050][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:2464757 from Retrans Table on exchange 39455r
[1643148900.234075][2805:2805] CHIP:EM: Removed CHIP MessageCounter:2464757 from RetransTable on exchange 39455r
[1643148900.235583][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.237216][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.238891][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.240452][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.242116][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.244977][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.246662][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.248213][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 59382
[1643148900.249820][2805:2805] CHIP:DIS: Directly sending mDns reply to peer fe80::dea6:32ff:febe:8d58 on port 39551
[1643148900.250054][2805:2805] CHIP:EM: Received message of type 0x30 with protocolId (0, 0) and MessageCounter:1862767197 on exchange 39456r
[1643148900.250102][2805:2805] CHIP:EM: Handling via exchange: 39456r, Delegate: 0xaaaaacd6a630
[1643148900.250144][2805:2805] CHIP:IN: CASE Server received Sigma1 message. Starting handshake. EC 0xaaaaacd6bc40
[1643148900.250176][2805:2805] CHIP:SC: Waiting for Sigma1 msg
[1643148900.250197][2805:2805] CHIP:IN: CASE Server disabling CASE session setups
[1643148900.250220][2805:2805] CHIP:SC: Received Sigma1 msg
[1643148900.250271][2805:2805] CHIP:SC: Found MRP parameters in the message
[1643148900.250300][2805:2805] CHIP:SC: Peer assigned session key ID 2
[1643148900.250324][2805:2805] CHIP:DIS: Fabric (1) loaded from storage. Calling OnFabricRetrievedFromStorage
[1643148900.250346][2805:2805] CHIP:ZCL: OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, NodeId 0x0000000000654321, VendorId 0xFFF1
[1643148900.250369][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.250478][2805:2805] CHIP:IN: Generating DestinationID.  Calling OnFabricRetrievedFromStorage
[1643148900.251076][2805:2805] CHIP:ZCL: OpCreds: Fabric 0x1 was retrieved from storage. FabricId 0x0000000000000001, NodeId 0x0000000000654321, VendorId 0xFFF1
[1643148900.251098][2805:2805] CHIP:ZCL: OpCreds: Call to fabricListChanged
[1643148900.252255][2805:2805] CHIP:SC: Including MRP parameters
[1643148900.252324][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:1862767197 on exchange: 39456r
[1643148900.252364][2805:2805] CHIP:IN: Prepared unauthenticated message 0xaaaaacd6bb08 to 0x0000000000000000 (0)  of type 0x31 and protocolId (0, 0) on exchange 39456r with MessageCounter:824388790.
[1643148900.252403][2805:2805] CHIP:IN: Sending unauthenticated msg 0xaaaaacd6bb08 with MessageCounter:824388790 to 0x0000000000000000 at monotonic time: 136201 msec
[1643148900.252556][2805:2805] CHIP:SC: Sent Sigma2 msg
[1643148900.254219][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.255892][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.257575][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.259213][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 42897
[1643148900.259459][2805:2805] CHIP:EM: Received message of type 0x32 with protocolId (0, 0) and MessageCounter:1862767198 on exchange 39456r
[1643148900.259489][2805:2805] CHIP:EM: Found matching exchange: 39456r, Delegate: 0xaaaaacd6a640
[1643148900.259532][2805:2805] CHIP:EM: Rxd Ack; Removing MessageCounter:824388790 from Retrans Table on exchange 39456r
[1643148900.259560][2805:2805] CHIP:EM: Removed CHIP MessageCounter:824388790 from RetransTable on exchange 39456r
[1643148900.259596][2805:2805] CHIP:SC: Received Sigma3 msg
[1643148900.259733][2805:2805] CHIP:SC: The device does not support GetClock_RealTimeMS() API. This will eventually result in CASE session setup failures.
[1643148900.261530][2805:2805] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000001 and root pubkey
[1643148900.261605][2805:2805] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.261630][2805:2805] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.261652][2805:2805] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.261675][2805:2805] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.261697][2805:2805] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.261716][2805:2805] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.261738][2805:2805] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.261760][2805:2805] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.261780][2805:2805] CHIP:IN: 0x51,
[1643148900.261828][2805:2805] CHIP:IN: Generated compressed fabric ID
[1643148900.261853][2805:2805] CHIP:IN: 0x6f, 0x55, 0x25, 0x60, 0xfd, 0xec, 0x45, 0x73,
[1643148900.263102][2805:2805] CHIP:SC: Sending status report. Protocol code 0, exchange 39456
[1643148900.263155][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:1862767198 on exchange: 39456r
[1643148900.263193][2805:2805] CHIP:IN: Prepared unauthenticated message 0xaaaaacd6bb08 to 0x0000000000000000 (0)  of type 0x40 and protocolId (0, 0) on exchange 39456r with MessageCounter:824388791.
[1643148900.263232][2805:2805] CHIP:IN: Sending unauthenticated msg 0xaaaaacd6bb08 with MessageCounter:824388791 to 0x0000000000000000 at monotonic time: 136212 msec
[1643148900.263395][2805:2805] CHIP:IN: CASE Session established. Setting up the secure channel.
[1643148900.263429][2805:2805] CHIP:IN: New secure session created for device 0x000000000001B669, LSID:2 PSID:2!
[1643148900.263547][2805:2805] CHIP:IN: CASE secure channel is available now.
[1643148900.263573][2805:2805] CHIP:IN: CASE Server enabling CASE session setups
[1643148900.265295][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.266982][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.268610][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.270245][2805:2805] CHIP:DIS: Directly sending mDns reply to peer <IP_ADDRESS> on port 35240
[1643148900.270505][2805:2805] CHIP:EM: Received message of type 0x8 with protocolId (0, 1) and MessageCounter:11547771 on exchange 39457r
[1643148900.270556][2805:2805] CHIP:EM: Handling via exchange: 39457r, Delegate: 0xaaaaacd60550
[1643148900.270630][2805:2805] CHIP:DMG: InvokeRequestMessage =
[1643148900.270674][2805:2805] CHIP:DMG: {
[1643148900.270699][2805:2805] CHIP:DMG: 	suppressResponse = false,
[1643148900.270725][2805:2805] CHIP:DMG: 	timedRequest = false,
[1643148900.270751][2805:2805] CHIP:DMG: 	InvokeRequests =
[1643148900.270781][2805:2805] CHIP:DMG: 	[
[1643148900.270805][2805:2805] CHIP:DMG: 		CommandDataIB =
[1643148900.270835][2805:2805] CHIP:DMG: 		{
[1643148900.270860][2805:2805] CHIP:DMG: 			CommandPathIB =
[1643148900.270890][2805:2805] CHIP:DMG: 			{
[1643148900.270921][2805:2805] CHIP:DMG: 				EndpointId = 0x0,
[1643148900.270954][2805:2805] CHIP:DMG: 				ClusterId = 0x30,
[1643148900.270989][2805:2805] CHIP:DMG: 				CommandId = 0x4,
[1643148900.271018][2805:2805] CHIP:DMG: 			},
[1643148900.271051][2805:2805] CHIP:DMG:
[1643148900.271078][2805:2805] CHIP:DMG: 			CommandData =
[1643148900.271107][2805:2805] CHIP:DMG: 			{
[1643148900.271139][2805:2805] CHIP:DMG: 			},
[1643148900.271170][2805:2805] CHIP:DMG: 		},
[1643148900.271200][2805:2805] CHIP:DMG:
[1643148900.271223][2805:2805] CHIP:DMG: 	],
[1643148900.271250][2805:2805] CHIP:DMG:
[1643148900.271271][2805:2805] CHIP:DMG: },
[1643148900.271327][2805:2805] CHIP:DMG: Received command for Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0004
[1643148900.271382][2805:2805] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.271412][2805:2805] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.271445][2805:2805] CHIP:DMG: Decreasing reference count for CommandHandler, remaining 0
[1643148900.271474][2805:2805] CHIP:EM: Piggybacking Ack for MessageCounter:11547771 on exchange: 39457r
[1643148900.271531][2805:2805] CHIP:IN: Prepared secure message 0xaaaaacd6bb28 to 0x000000000001B669 (1)  of type 0x9 and protocolId (0, 1) on exchange 39457r with MessageCounter:6586819.
[1643148900.271568][2805:2805] CHIP:IN: Sending encrypted msg 0xaaaaacd6bb28 with MessageCounter:6586819 to 0x000000000001B669 (1) at monotonic time: 136220 msec
[1643148900.271712][2805:2805] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.271773][2805:2805] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.271813][2805:2805] CHIP:SVR: Commissioning completed successfully
[1643148900.271841][2805:2805] CHIP:DIS: DNS-SD StartServer mode=0
[1643148900.274349][2805:2805] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found
[1643148900.280334][2805:2805] CHIP:DIS: CHIP minimal mDNS started advertising.
[1643148900.285646][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.290078][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.293275][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.296715][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.300117][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148900.303487][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148900.307235][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148900.307338][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.307370][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.310562][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148900.310634][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.310678][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.310727][2805:2805] CHIP:DL: MAC is not known, using a default.
[1643148900.310754][2805:2805] CHIP:DIS: Advertise operational node 6F552560FDEC4573-0000000000654321
[1643148900.310810][2805:2805] CHIP:DIS: CHIP minimal mDNS configured as 'Operational device'.
[1643148900.312655][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.314323][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.315989][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.317699][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from <IP_ADDRESS>
[1643148900.320234][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 2601:647:4100:b5b:dea6:32ff:febe:8d58
[1643148900.321965][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::dea6:32ff:febe:8d58
[1643148900.323650][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fd00:6d61:7474:6572::1
[1643148900.323731][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.323763][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.325316][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from fe80::1
[1643148900.325414][2805:2805] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.325444][2805:2805] CHIP:DIS: Failed to advertise records: ../../examples/placeholder/linux/third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.325477][2805:2805] CHIP:DIS: Have operational credentials
[1643148900.325502][2805:2805] CHIP:TOO: Commissioning complete
[1643148900.325524][2805:2805] CHIP:TOO:  ***** Test Step 1 : Log OnOff Test Startup

[1643148900.325554][2805:2805] CHIP:TOO: *** Basic Cluster Tests Ready
[1643148900.325575][2805:2805] CHIP:TOO:  ***** Test Step 2 : Query Interaction Model Version

[1643148900.325605][2805:2805] CHIP:TOO: [Endpoint: 0x00000000 Cluster: Basic Attribute: InteractionModelVersion] Query Interaction Model Version
Segmentation fault (core dumped)

Chip Tool:
ubuntu@ubuntu:~/chip-certification-tool/backend/managed-docker-images/chip-tool$ ./chip-tool pairing onnetwork-long 0x654321 20202021 3842
[1643148900.021100][2811:2811] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-4k7n0j)
[1643148900.021956][2811:2811] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148900.022022][2811:2811] CHIP:DL: NVS set: chip-counters/reboot-count = 2 (0x2)
[1643148900.022895][2811:2811] CHIP:DL: Got Ethernet interface: eth0
[1643148900.023372][2811:2811] CHIP:DL: Found the primary Ethernet interface:eth0
[1643148900.023866][2811:2811] CHIP:DL: Got WiFi interface: wlan0
[1643148900.023946][2811:2811] CHIP:DL: Failed to reset WiFi statistic counts
[1643148900.024018][2811:2811] CHIP:IN: UDP::Init bind&listen port=5542
[1643148900.024302][2811:2811] CHIP:IN: UDP::Init bound to port=5542
[1643148900.024339][2811:2811] CHIP:IN: UDP::Init bind&listen port=5542
[1643148900.024436][2811:2811] CHIP:IN: UDP::Init bound to port=5542
[1643148900.024465][2811:2811] CHIP:IN: TransportMgr initialized
[1643148900.024510][2811:2811] CHIP:DIS: Init fabric pairing table with server storage
[1643148900.024801][2811:2811] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-o65kML)
[1643148900.035126][2811:2811] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1643148900.035192][2811:2811] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 2000 (0x7D0)
[1643148900.035323][2811:2811] CHIP:ZCL: Using ZAP configuration...
[1643148900.038707][2811:2811] CHIP:DL: MDNS failed to join multicast group on wlan0 for address type IPv4: ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:772: Inet Error 0x00000110: Address not found
[1643148900.042606][2811:2811] CHIP:CTL: System State Initialized...
[1643148900.061722][2811:2811] CHIP:CTL: Generating NOC
[1643148900.073151][2811:2811] CHIP:CTL: Generating ICAC
[1643148900.073671][2811:2811] CHIP:CTL: Generating RCAC
[1643148900.076435][2811:2811] CHIP:DIS: Verifying the received credentials
[1643148900.080189][2811:2811] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000001 and root pubkey
[1643148900.080264][2811:2811] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.080317][2811:2811] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.080368][2811:2811] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.080416][2811:2811] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.080464][2811:2811] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.080511][2811:2811] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.080559][2811:2811] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.080606][2811:2811] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.080648][2811:2811] CHIP:IN: 0x51,
[1643148900.080750][2811:2811] CHIP:IN: Generated compressed fabric ID
[1643148900.080805][2811:2811] CHIP:IN: 0x6f, 0x55, 0x25, 0x60, 0xfd, 0xec, 0x45, 0x73,
[1643148900.080864][2811:2811] CHIP:DIS: Added new fabric at index: 1, Initialized: 1
[1643148900.080911][2811:2811] CHIP:DIS: Assigned compressed fabric ID: 0x6F552560FDEC4573, node ID: 0x000000000001B669
[1643148900.080958][2811:2811] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x6F552560FDEC4573
[1643148900.081071][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.081413][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.081470][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.081646][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.081697][2811:2811] CHIP:IN: TransportMgr initialized
[1643148900.083409][2811:2811] CHIP:CTL: Generating NOC
[1643148900.083742][2811:2811] CHIP:CTL: Generating ICAC
[1643148900.083997][2811:2811] CHIP:CTL: Generating RCAC
[1643148900.085453][2811:2811] CHIP:DIS: Verifying the received credentials
[1643148900.087459][2811:2811] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000002 and root pubkey
[1643148900.087503][2811:2811] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.087529][2811:2811] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.087554][2811:2811] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.087577][2811:2811] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.087601][2811:2811] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.087625][2811:2811] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.087648][2811:2811] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.087672][2811:2811] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.087690][2811:2811] CHIP:IN: 0x51,
[1643148900.087750][2811:2811] CHIP:IN: Generated compressed fabric ID
[1643148900.087777][2811:2811] CHIP:IN: 0xa6, 0x87, 0xe0, 0x41, 0x1f, 0x66, 0x6f, 0xc3,
[1643148900.087809][2811:2811] CHIP:DIS: Added new fabric at index: 2, Initialized: 1
[1643148900.087833][2811:2811] CHIP:DIS: Assigned compressed fabric ID: 0xA687E0411F666FC3, node ID: 0x000000000001B669
[1643148900.087856][2811:2811] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0xA687E0411F666FC3
[1643148900.087933][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.088069][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.088097][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.088188][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.088213][2811:2811] CHIP:IN: TransportMgr initialized
[1643148900.089422][2811:2811] CHIP:CTL: Generating NOC
[1643148900.089741][2811:2811] CHIP:CTL: Generating ICAC
[1643148900.089985][2811:2811] CHIP:CTL: Generating RCAC
[1643148900.095860][2811:2811] CHIP:DIS: Verifying the received credentials
[1643148900.097932][2811:2811] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000003 and root pubkey
[1643148900.097986][2811:2811] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.098015][2811:2811] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.098043][2811:2811] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.098069][2811:2811] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.098096][2811:2811] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.098122][2811:2811] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.098148][2811:2811] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.098174][2811:2811] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.098197][2811:2811] CHIP:IN: 0x51,
[1643148900.098263][2811:2811] CHIP:IN: Generated compressed fabric ID
[1643148900.098293][2811:2811] CHIP:IN: 0x25, 0xc3, 0xc1, 0x22, 0x35, 0xe9, 0x53, 0xbc,
[1643148900.098327][2811:2811] CHIP:DIS: Added new fabric at index: 3, Initialized: 1
[1643148900.098353][2811:2811] CHIP:DIS: Assigned compressed fabric ID: 0x25C3C12235E953BC, node ID: 0x000000000001B669
[1643148900.098379][2811:2811] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x25C3C12235E953BC
[1643148900.098463][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.098614][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.098666][2811:2811] CHIP:IN: UDP::Init bind&listen port=5550
[1643148900.098774][2811:2811] CHIP:IN: UDP::Init bound to port=5550
[1643148900.098802][2811:2811] CHIP:IN: TransportMgr initialized
[1643148900.099135][2811:2816] CHIP:DL: CHIP task running
[1643148900.099827][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.100006][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.100152][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.100297][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.101967][2811:2816] CHIP:TOO: Discovered Device: 2601:647:4100:b5b:dea6:32ff:febe:8d58:5540
[1643148900.102173][2811:2816] CHIP:SC: Assigned local session key ID 1
[1643148900.102334][2811:2816] CHIP:SC: Including MRP parameters in PBKDF param request
[1643148900.102404][2811:2816] CHIP:IN: Prepared unauthenticated message 0xaaaacbab3998 to 0x0000000000000000 (0)  of type 0x20 and protocolId (0, 0) on exchange 39447i with MessageCounter:1862767193.
[1643148900.102453][2811:2816] CHIP:IN: Sending unauthenticated msg 0xaaaacbab3998 with MessageCounter:1862767193 to 0x0000000000000000 at monotonic time: 136051 msec
[1643148900.102668][2811:2816] CHIP:SC: Sent PBKDF param request
[1643148900.110189][2811:2816] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:824388787 on exchange 39447i
[1643148900.110241][2811:2816] CHIP:EM: Found matching exchange: 39447i, Delegate: 0xffff8c0ab8e8
[1643148900.110295][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:1862767193 from Retrans Table on exchange 39447i
[1643148900.110329][2811:2816] CHIP:EM: Removed CHIP MessageCounter:1862767193 from RetransTable on exchange 39447i
[1643148900.110374][2811:2816] CHIP:SC: Received PBKDF param response
[1643148900.110432][2811:2816] CHIP:SC: Peer assigned session ID 1
[1643148900.110475][2811:2816] CHIP:SC: Found MRP parameters in the message
[1643148900.112198][2811:2816] CHIP:EM: Piggybacking Ack for MessageCounter:824388787 on exchange: 39447i
[1643148900.112261][2811:2816] CHIP:IN: Prepared unauthenticated message 0xaaaacbab3998 to 0x0000000000000000 (0)  of type 0x22 and protocolId (0, 0) on exchange 39447i with MessageCounter:1862767194.
[1643148900.112309][2811:2816] CHIP:IN: Sending unauthenticated msg 0xaaaacbab3998 with MessageCounter:1862767194 to 0x0000000000000000 at monotonic time: 136061 msec
[1643148900.112470][2811:2816] CHIP:SC: Sent spake2p msg1
[1643148900.129653][2811:2816] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:824388788 on exchange 39447i
[1643148900.129691][2811:2816] CHIP:EM: Found matching exchange: 39447i, Delegate: 0xffff8c0ab8e8
[1643148900.129734][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:1862767194 from Retrans Table on exchange 39447i
[1643148900.129759][2811:2816] CHIP:EM: Removed CHIP MessageCounter:1862767194 from RetransTable on exchange 39447i
[1643148900.129793][2811:2816] CHIP:SC: Received spake2p msg2
[1643148900.131119][2811:2816] CHIP:EM: Piggybacking Ack for MessageCounter:824388788 on exchange: 39447i
[1643148900.131171][2811:2816] CHIP:IN: Prepared unauthenticated message 0xaaaacbab3998 to 0x0000000000000000 (0)  of type 0x24 and protocolId (0, 0) on exchange 39447i with MessageCounter:1862767195.
[1643148900.131211][2811:2816] CHIP:IN: Sending unauthenticated msg 0xaaaacbab3998 with MessageCounter:1862767195 to 0x0000000000000000 at monotonic time: 136080 msec
[1643148900.131336][2811:2816] CHIP:SC: Sent spake2p msg3
[1643148900.137056][2811:2816] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:824388789 on exchange 39447i
[1643148900.137093][2811:2816] CHIP:EM: Found matching exchange: 39447i, Delegate: 0xffff8c0ab8e8
[1643148900.137153][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:1862767195 from Retrans Table on exchange 39447i
[1643148900.137177][2811:2816] CHIP:EM: Removed CHIP MessageCounter:1862767195 from RetransTable on exchange 39447i
[1643148900.137224][2811:2816] CHIP:IN: New secure session created for device 0x0000000000654321, LSID:1 PSID:1!
[1643148900.137329][2811:2816] CHIP:CTL: Remote device completed SPAKE2+ handshake
[1643148900.137355][2811:2816] CHIP:CTL: Arming failsafe
[1643148900.137405][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.137433][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.137519][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39448i with MessageCounter:11844430.
[1643148900.137592][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844430 to 0x0000000000654321 (1) at monotonic time: 136086 msec
[1643148900.137712][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.137746][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:824388789 on exchange 39447i
[1643148900.137776][2811:2816] CHIP:IN: Prepared unauthenticated message 0xffff86fdd9e8 to 0x0000000000000000 (0)  of type 0x10 and protocolId (0, 0) on exchange 39447i with MessageCounter:1862767196.
[1643148900.137806][2811:2816] CHIP:IN: Sending unauthenticated msg 0xffff86fdd9e8 with MessageCounter:1862767196 to 0x0000000000000000 at monotonic time: 136087 msec
[1643148900.137873][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:824388789 on exchange 39447i
[1643148900.146613][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464750 on exchange 39448i
[1643148900.146680][2811:2816] CHIP:EM: Found matching exchange: 39448i, Delegate: 0xffff740047f0
[1643148900.146722][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844430 from Retrans Table on exchange 39448i
[1643148900.146746][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844430 from RetransTable on exchange 39448i
[1643148900.146782][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.146830][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.146854][2811:2816] CHIP:DMG: {
[1643148900.146876][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.146898][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.146928][2811:2816] CHIP:DMG: 	[
[1643148900.146953][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.146989][2811:2816] CHIP:DMG: 		{
[1643148900.147017][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.147051][2811:2816] CHIP:DMG: 			{
[1643148900.147081][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.147117][2811:2816] CHIP:DMG: 				{
[1643148900.147151][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.147188][2811:2816] CHIP:DMG: 					ClusterId = 0x30,
[1643148900.147226][2811:2816] CHIP:DMG: 					CommandId = 0x1,
[1643148900.147258][2811:2816] CHIP:DMG: 				},
[1643148900.147292][2811:2816] CHIP:DMG:
[1643148900.147324][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.147356][2811:2816] CHIP:DMG: 				{
[1643148900.147389][2811:2816] CHIP:DMG: 					0x0 = 0,
[1643148900.147425][2811:2816] CHIP:DMG: 					0x1 = "",
[1643148900.147457][2811:2816] CHIP:DMG: 				},
[1643148900.147487][2811:2816] CHIP:DMG: 			},
[1643148900.147523][2811:2816] CHIP:DMG:
[1643148900.147551][2811:2816] CHIP:DMG: 		},
[1643148900.147583][2811:2816] CHIP:DMG:
[1643148900.147608][2811:2816] CHIP:DMG: 	],
[1643148900.147637][2811:2816] CHIP:DMG:
[1643148900.147658][2811:2816] CHIP:DMG: },
[1643148900.147721][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1643148900.147794][2811:2816] CHIP:ZCL: ArmFailSafeResponse:
[1643148900.147817][2811:2816] CHIP:ZCL:   errorCode: 0
[1643148900.147836][2811:2816] CHIP:ZCL:   debugText:
[1643148900.147860][2811:2816] CHIP:CTL: Received success response 0x0

[1643148900.147893][2811:2816] CHIP:CTL: Setting Regulatory Config
[1643148900.147981][2811:2816] CHIP:CTL: Unable to find country code, defaulting to WW
[1643148900.148103][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.148134][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.148222][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39449i with MessageCounter:11844431.
[1643148900.148263][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844431 to 0x0000000000654321 (1) at monotonic time: 136097 msec
[1643148900.148399][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.148441][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.148468][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464750 on exchange 39448i
[1643148900.148507][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39448i with MessageCounter:11844432.
[1643148900.148541][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844432 to 0x0000000000654321 (1) at monotonic time: 136097 msec
[1643148900.148623][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464750 on exchange 39448i
[1643148900.153532][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464751 on exchange 39449i
[1643148900.153576][2811:2816] CHIP:EM: Found matching exchange: 39449i, Delegate: 0xffff74004eb0
[1643148900.153622][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844431 from Retrans Table on exchange 39449i
[1643148900.153648][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844431 from RetransTable on exchange 39449i
[1643148900.153684][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.153734][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.153760][2811:2816] CHIP:DMG: {
[1643148900.153785][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.153808][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.153839][2811:2816] CHIP:DMG: 	[
[1643148900.153865][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.153898][2811:2816] CHIP:DMG: 		{
[1643148900.153925][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.153961][2811:2816] CHIP:DMG: 			{
[1643148900.153992][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.154125][2811:2816] CHIP:DMG: 				{
[1643148900.154165][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.154202][2811:2816] CHIP:DMG: 					ClusterId = 0x30,
[1643148900.154239][2811:2816] CHIP:DMG: 					CommandId = 0x3,
[1643148900.154277][2811:2816] CHIP:DMG: 				},
[1643148900.154317][2811:2816] CHIP:DMG:
[1643148900.154352][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.154386][2811:2816] CHIP:DMG: 				{
[1643148900.154426][2811:2816] CHIP:DMG: 					0x0 = 0,
[1643148900.154464][2811:2816] CHIP:DMG: 					0x1 = "",
[1643148900.154503][2811:2816] CHIP:DMG: 				},
[1643148900.154536][2811:2816] CHIP:DMG: 			},
[1643148900.154571][2811:2816] CHIP:DMG:
[1643148900.154603][2811:2816] CHIP:DMG: 		},
[1643148900.154638][2811:2816] CHIP:DMG:
[1643148900.154692][2811:2816] CHIP:DMG: 	],
[1643148900.154725][2811:2816] CHIP:DMG:
[1643148900.154748][2811:2816] CHIP:DMG: },
[1643148900.154815][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1643148900.154852][2811:2816] CHIP:ZCL: SetRegulatoryConfigResponse:
[1643148900.154874][2811:2816] CHIP:ZCL:   errorCode: 0
[1643148900.154895][2811:2816] CHIP:ZCL:   debugText:
[1643148900.154921][2811:2816] CHIP:CTL: Received success response 0x0

[1643148900.154957][2811:2816] CHIP:CTL: Exchanging vendor certificates
[1643148900.154978][2811:2816] CHIP:CTL: Sending Certificate Chain request to 0xffff8c0ab890 device
[1643148900.155028][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.155055][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.155144][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39450i with MessageCounter:11844433.
[1643148900.155185][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844433 to 0x0000000000654321 (1) at monotonic time: 136104 msec
[1643148900.155328][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.155359][2811:2816] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate
[1643148900.155392][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.155416][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464751 on exchange 39449i
[1643148900.155459][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39449i with MessageCounter:11844434.
[1643148900.155494][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844434 to 0x0000000000654321 (1) at monotonic time: 136104 msec
[1643148900.155589][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464751 on exchange 39449i
[1643148900.157081][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464752 on exchange 39450i
[1643148900.157118][2811:2816] CHIP:EM: Found matching exchange: 39450i, Delegate: 0xffff740047f0
[1643148900.157157][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844433 from Retrans Table on exchange 39450i
[1643148900.157179][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844433 from RetransTable on exchange 39450i
[1643148900.157211][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.157257][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.157283][2811:2816] CHIP:DMG: {
[1643148900.157307][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.157333][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.157366][2811:2816] CHIP:DMG: 	[
[1643148900.157391][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.157424][2811:2816] CHIP:DMG: 		{
[1643148900.157451][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.157478][2811:2816] CHIP:DMG: 			{
[1643148900.157502][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.157539][2811:2816] CHIP:DMG: 				{
[1643148900.157577][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.157617][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.157654][2811:2816] CHIP:DMG: 					CommandId = 0x3,
[1643148900.157689][2811:2816] CHIP:DMG: 				},
[1643148900.157728][2811:2816] CHIP:DMG:
[1643148900.157763][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.157800][2811:2816] CHIP:DMG: 				{
[1643148900.157834][2811:2816] CHIP:DMG: 					0x0 = [
[1643148900.157872][2811:2816] CHIP:DMG: 						... (byte string too long) ...
[1643148900.157912][2811:2816] CHIP:DMG: 				]
[1643148900.157949][2811:2816] CHIP:DMG: 				},
[1643148900.157981][2811:2816] CHIP:DMG: 			},
[1643148900.158015][2811:2816] CHIP:DMG:
[1643148900.158048][2811:2816] CHIP:DMG: 		},
[1643148900.158085][2811:2816] CHIP:DMG:
[1643148900.158113][2811:2816] CHIP:DMG: 	],
[1643148900.158143][2811:2816] CHIP:DMG:
[1643148900.158166][2811:2816] CHIP:DMG: },
[1643148900.158227][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1643148900.158261][2811:2816] CHIP:ZCL: CertificateChainResponse:
[1643148900.158284][2811:2816] CHIP:ZCL:   Certificate: 472
[1643148900.158307][2811:2816] CHIP:CTL: Received certificate chain from the device
[1643148900.158333][2811:2816] CHIP:CTL: Sending Certificate Chain request to 0xffff8c0ab890 device
[1643148900.158376][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.158403][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.158476][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39451i with MessageCounter:11844435.
[1643148900.158592][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844435 to 0x0000000000654321 (1) at monotonic time: 136107 msec
[1643148900.158754][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.158785][2811:2816] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate
[1643148900.158822][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.158849][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464752 on exchange 39450i
[1643148900.158897][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39450i with MessageCounter:11844436.
[1643148900.158933][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844436 to 0x0000000000654321 (1) at monotonic time: 136108 msec
[1643148900.159015][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464752 on exchange 39450i
[1643148900.160914][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464753 on exchange 39451i
[1643148900.160960][2811:2816] CHIP:EM: Found matching exchange: 39451i, Delegate: 0xffff74004eb0
[1643148900.161004][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844435 from Retrans Table on exchange 39451i
[1643148900.161033][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844435 from RetransTable on exchange 39451i
[1643148900.161072][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.161123][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.161152][2811:2816] CHIP:DMG: {
[1643148900.161179][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.161212][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.161252][2811:2816] CHIP:DMG: 	[
[1643148900.161281][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.161321][2811:2816] CHIP:DMG: 		{
[1643148900.161354][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.161391][2811:2816] CHIP:DMG: 			{
[1643148900.161428][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.161470][2811:2816] CHIP:DMG: 				{
[1643148900.161508][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.161547][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.161583][2811:2816] CHIP:DMG: 					CommandId = 0x3,
[1643148900.161624][2811:2816] CHIP:DMG: 				},
[1643148900.161660][2811:2816] CHIP:DMG:
[1643148900.161693][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.161734][2811:2816] CHIP:DMG: 				{
[1643148900.161772][2811:2816] CHIP:DMG: 					0x0 = [
[1643148900.161814][2811:2816] CHIP:DMG: 						... (byte string too long) ...
[1643148900.161854][2811:2816] CHIP:DMG: 				]
[1643148900.161891][2811:2816] CHIP:DMG: 				},
[1643148900.161926][2811:2816] CHIP:DMG: 			},
[1643148900.161969][2811:2816] CHIP:DMG:
[1643148900.162000][2811:2816] CHIP:DMG: 		},
[1643148900.162041][2811:2816] CHIP:DMG:
[1643148900.162070][2811:2816] CHIP:DMG: 	],
[1643148900.162104][2811:2816] CHIP:DMG:
[1643148900.162129][2811:2816] CHIP:DMG: },
[1643148900.162198][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1643148900.162234][2811:2816] CHIP:ZCL: CertificateChainResponse:
[1643148900.162259][2811:2816] CHIP:ZCL:   Certificate: 494
[1643148900.162286][2811:2816] CHIP:CTL: Received certificate chain from the device
[1643148900.162314][2811:2816] CHIP:CTL: Sending Attestation Request to the device.
[1643148900.162339][2811:2816] CHIP:CTL: Sending Attestation request to 0xffff8c0ab890 device
[1643148900.162386][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.162417][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.162501][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39452i with MessageCounter:11844437.
[1643148900.162544][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844437 to 0x0000000000654321 (1) at monotonic time: 136111 msec
[1643148900.162731][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.162765][2811:2816] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
[1643148900.162805][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.162835][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464753 on exchange 39451i
[1643148900.162883][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39451i with MessageCounter:11844438.
[1643148900.162924][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844438 to 0x0000000000654321 (1) at monotonic time: 136112 msec
[1643148900.163024][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464753 on exchange 39451i
[1643148900.167057][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464754 on exchange 39452i
[1643148900.167096][2811:2816] CHIP:EM: Found matching exchange: 39452i, Delegate: 0xffff740047f0
[1643148900.167139][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844437 from Retrans Table on exchange 39452i
[1643148900.167169][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844437 from RetransTable on exchange 39452i
[1643148900.167208][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.167429][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.167465][2811:2816] CHIP:DMG: {
[1643148900.167493][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.167525][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.167562][2811:2816] CHIP:DMG: 	[
[1643148900.167590][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.167628][2811:2816] CHIP:DMG: 		{
[1643148900.167657][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.167697][2811:2816] CHIP:DMG: 			{
[1643148900.167734][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.167772][2811:2816] CHIP:DMG: 				{
[1643148900.168070][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.168114][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.168157][2811:2816] CHIP:DMG: 					CommandId = 0x1,
[1643148900.168194][2811:2816] CHIP:DMG: 				},
[1643148900.168234][2811:2816] CHIP:DMG:
[1643148900.168268][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.168305][2811:2816] CHIP:DMG: 				{
[1643148900.168342][2811:2816] CHIP:DMG: 					0x0 = [
[1643148900.168381][2811:2816] CHIP:DMG: 						... (byte string too long) ...
[1643148900.168422][2811:2816] CHIP:DMG: 				]
[1643148900.168458][2811:2816] CHIP:DMG: 					0x1 = [
[1643148900.168512][2811:2816] CHIP:DMG: 						0xf3, 0x71, 0x3d, 0x17, 0x5a, 0x1, 0x2, 0xc9, 0x8f, 0x48, 0xd8, 0xda, 0x6c, 0xd9, 0x41, 0x45, 0x96, 0xf2, 0xa5, 0x1e, 0x2a, 0x53, 0xe2, 0x13, 0x6e, 0x6a, 0x45, 0x12, 0x5b, 0x96, 0x82, 0x56, 0xfe, 0x68, 0x80, 0x77, 0x7f, 0xb7, 0x8e, 0xda, 0xd9, 0x6a,
[1643148900.168559][2811:2816] CHIP:DMG: 				]
[1643148900.168599][2811:2816] CHIP:DMG: 				},
[1643148900.168642][2811:2816] CHIP:DMG: 			},
[1643148900.168684][2811:2816] CHIP:DMG:
[1643148900.168713][2811:2816] CHIP:DMG: 		},
[1643148900.168749][2811:2816] CHIP:DMG:
[1643148900.168776][2811:2816] CHIP:DMG: 	],
[1643148900.168811][2811:2816] CHIP:DMG:
[1643148900.168836][2811:2816] CHIP:DMG: },
[1643148900.168908][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1643148900.168949][2811:2816] CHIP:ZCL: AttestationResponse:
[1643148900.168973][2811:2816] CHIP:ZCL:   AttestationElements: 278
[1643148900.168996][2811:2816] CHIP:ZCL:   Signature: 64
[1643148900.169024][2811:2816] CHIP:CTL: Received Attestation Information from the device
[1643148900.175139][2811:2816] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
[1643148900.175191][2811:2816] CHIP:CTL: Sending 'CSR request' command to the device.
[1643148900.175214][2811:2816] CHIP:CTL: Sending OpCSR request to 0xffff8c0ab890 device
[1643148900.175315][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.175348][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.175469][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39453i with MessageCounter:11844439.
[1643148900.175514][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844439 to 0x0000000000654321 (1) at monotonic time: 136124 msec
[1643148900.175683][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.175713][2811:2816] CHIP:CTL: Sent OpCSR request, waiting for the CSR
[1643148900.175753][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.175782][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464754 on exchange 39452i
[1643148900.175830][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39452i with MessageCounter:11844440.
[1643148900.175868][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844440 to 0x0000000000654321 (1) at monotonic time: 136125 msec
[1643148900.175963][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464754 on exchange 39452i
[1643148900.179397][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464755 on exchange 39453i
[1643148900.179438][2811:2816] CHIP:EM: Found matching exchange: 39453i, Delegate: 0xffff74004eb0
[1643148900.179480][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844439 from Retrans Table on exchange 39453i
[1643148900.179502][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844439 from RetransTable on exchange 39453i
[1643148900.179536][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.179592][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.179621][2811:2816] CHIP:DMG: {
[1643148900.179647][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.179673][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.179707][2811:2816] CHIP:DMG: 	[
[1643148900.179733][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.179773][2811:2816] CHIP:DMG: 		{
[1643148900.179802][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.179839][2811:2816] CHIP:DMG: 			{
[1643148900.179870][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.179908][2811:2816] CHIP:DMG: 				{
[1643148900.179947][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.179988][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.180028][2811:2816] CHIP:DMG: 					CommandId = 0x5,
[1643148900.180062][2811:2816] CHIP:DMG: 				},
[1643148900.180102][2811:2816] CHIP:DMG:
[1643148900.180134][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.180169][2811:2816] CHIP:DMG: 				{
[1643148900.180205][2811:2816] CHIP:DMG: 					0x0 = [
[1643148900.180265][2811:2816] CHIP:DMG: 						0x15, 0x30, 0x1, 0xcb, 0x30, 0x81, 0xc8, 0x30, 0x70, 0x2, 0x1, 0x0, 0x30, 0xe, 0x31, 0xc, 0x30, 0xa, 0x6, 0x3, 0x55, 0x4, 0xa, 0xc, 0x3, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86
[1643148900.180306][2811:2816] CHIP:DMG: 				]
[1643148900.180344][2811:2816] CHIP:DMG: 					0x1 = [
[1643148900.180398][2811:2816] CHIP:DMG: 						0x60, 0xa7, 0x13, 0xad, 0x58, 0x25, 0x99, 0x78, 0x81, 0x62, 0x74, 0xca, 0xc9, 0xe2, 0xa1, 0xc, 0x27, 0xc0, 0x5b, 0x3e, 0xf1, 0x42, 0xae, 0x11, 0x29, 0x88, 0x7e, 0x9c, 0x0, 0x35, 0xa1, 0xfb, 0xc7, 0x1, 0xe0, 0x5, 0x23, 0x4b, 0x67, 0x57, 0xa5, 0x97, 0
[1643148900.180438][2811:2816] CHIP:DMG: 				]
[1643148900.180475][2811:2816] CHIP:DMG: 				},
[1643148900.180509][2811:2816] CHIP:DMG: 			},
[1643148900.180544][2811:2816] CHIP:DMG:
[1643148900.180573][2811:2816] CHIP:DMG: 		},
[1643148900.180609][2811:2816] CHIP:DMG:
[1643148900.180636][2811:2816] CHIP:DMG: 	],
[1643148900.180668][2811:2816] CHIP:DMG:
[1643148900.180692][2811:2816] CHIP:DMG: },
[1643148900.180759][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1643148900.180797][2811:2816] CHIP:ZCL: OpCSRResponse:
[1643148900.180819][2811:2816] CHIP:ZCL:   NOCSRElements: 243
[1643148900.180840][2811:2816] CHIP:ZCL:   AttestationSignature: 64
[1643148900.180866][2811:2816] CHIP:CTL: Received certificate signing request from the device
[1643148900.180889][2811:2816] CHIP:CTL: Getting certificate chain for the device from the issuer
[1643148900.182016][2811:2816] CHIP:CTL: Verifying Certificate Signing Request
[1643148900.182702][2811:2816] CHIP:CTL: Generating NOC
[1643148900.183016][2811:2816] CHIP:CTL: Generating ICAC
[1643148900.183321][2811:2816] CHIP:CTL: Providing certificate chain to the commissioner
[1643148900.183354][2811:2816] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:183: Success
[1643148900.183480][2811:2816] CHIP:CTL: Sending root certificate to the device
[1643148900.183536][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.183566][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.183673][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39454i with MessageCounter:11844441.
[1643148900.183715][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844441 to 0x0000000000654321 (1) at monotonic time: 136133 msec
[1643148900.183878][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.183912][2811:2816] CHIP:CTL: Sent root certificate to the device
[1643148900.184075][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.184106][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464755 on exchange 39453i
[1643148900.184159][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39453i with MessageCounter:11844442.
[1643148900.184199][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844442 to 0x0000000000654321 (1) at monotonic time: 136133 msec
[1643148900.184300][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464755 on exchange 39453i
[1643148900.185574][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464756 on exchange 39454i
[1643148900.185662][2811:2816] CHIP:EM: Found matching exchange: 39454i, Delegate: 0xffff740047f0
[1643148900.185742][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844441 from Retrans Table on exchange 39454i
[1643148900.185774][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844441 from RetransTable on exchange 39454i
[1643148900.185809][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.185854][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.185880][2811:2816] CHIP:DMG: {
[1643148900.185905][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.185931][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.185965][2811:2816] CHIP:DMG: 	[
[1643148900.185990][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.186025][2811:2816] CHIP:DMG: 		{
[1643148900.186052][2811:2816] CHIP:DMG: 			CommandStatusIB =
[1643148900.186087][2811:2816] CHIP:DMG: 			{
[1643148900.186122][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.186161][2811:2816] CHIP:DMG: 				{
[1643148900.186200][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.186241][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.186280][2811:2816] CHIP:DMG: 					CommandId = 0xb,
[1643148900.186314][2811:2816] CHIP:DMG: 				},
[1643148900.186354][2811:2816] CHIP:DMG:
[1643148900.186385][2811:2816] CHIP:DMG: 				StatusIB =
[1643148900.186423][2811:2816] CHIP:DMG: 				{
[1643148900.186460][2811:2816] CHIP:DMG: 					status = 0x0,
[1643148900.186495][2811:2816] CHIP:DMG: 				},
[1643148900.186530][2811:2816] CHIP:DMG:
[1643148900.186565][2811:2816] CHIP:DMG: 			},
[1643148900.186604][2811:2816] CHIP:DMG:
[1643148900.186633][2811:2816] CHIP:DMG: 		},
[1643148900.186691][2811:2816] CHIP:DMG:
[1643148900.186718][2811:2816] CHIP:DMG: 	],
[1643148900.186750][2811:2816] CHIP:DMG:
[1643148900.186774][2811:2816] CHIP:DMG: },
[1643148900.186838][2811:2816] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1643148900.186867][2811:2816] CHIP:ZCL: DefaultResponse:
[1643148900.186889][2811:2816] CHIP:ZCL:   Transaction: 0xffff740047f0
[1643148900.186911][2811:2816] CHIP:ZCL:   status: EMBER_ZCL_STATUS_SUCCESS (0x00)
[1643148900.186937][2811:2816] CHIP:CTL: Device confirmed that it has received the root certificate
[1643148900.186959][2811:2816] CHIP:CTL: Sending operational certificate chain to the device
[1643148900.187005][2811:2816] CHIP:DMG: ICR moving to [AddingComm]
[1643148900.187038][2811:2816] CHIP:DMG: ICR moving to [AddedComma]
[1643148900.187138][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1)  of type 0x8 and protocolId (0, 1) on exchange 39455i with MessageCounter:11844443.
[1643148900.187179][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11844443 to 0x0000000000654321 (1) at monotonic time: 136136 msec
[1643148900.187312][2811:2816] CHIP:DMG: ICR moving to [CommandSen]
[1643148900.187341][2811:2816] CHIP:CTL: Sent operational certificate to the device
[1643148900.187374][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.187401][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464756 on exchange 39454i
[1643148900.187442][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39454i with MessageCounter:11844444.
[1643148900.187478][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844444 to 0x0000000000654321 (1) at monotonic time: 136136 msec
[1643148900.187563][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464756 on exchange 39454i
[1643148900.224525][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2464757 on exchange 39455i
[1643148900.224567][2811:2816] CHIP:EM: Found matching exchange: 39455i, Delegate: 0xffff74004eb0
[1643148900.224613][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11844443 from Retrans Table on exchange 39455i
[1643148900.224635][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11844443 from RetransTable on exchange 39455i
[1643148900.224669][2811:2816] CHIP:DMG: ICR moving to [ResponseRe]
[1643148900.224718][2811:2816] CHIP:DMG: InvokeResponseMessage =
[1643148900.224743][2811:2816] CHIP:DMG: {
[1643148900.224767][2811:2816] CHIP:DMG: 	suppressResponse = false,
[1643148900.224791][2811:2816] CHIP:DMG: 	InvokeResponseIBs =
[1643148900.224822][2811:2816] CHIP:DMG: 	[
[1643148900.224846][2811:2816] CHIP:DMG: 		InvokeResponseIB =
[1643148900.224879][2811:2816] CHIP:DMG: 		{
[1643148900.224905][2811:2816] CHIP:DMG: 			CommandDataIB =
[1643148900.224933][2811:2816] CHIP:DMG: 			{
[1643148900.224964][2811:2816] CHIP:DMG: 				CommandPathIB =
[1643148900.224994][2811:2816] CHIP:DMG: 				{
[1643148900.225029][2811:2816] CHIP:DMG: 					EndpointId = 0x0,
[1643148900.225061][2811:2816] CHIP:DMG: 					ClusterId = 0x3e,
[1643148900.225095][2811:2816] CHIP:DMG: 					CommandId = 0x8,
[1643148900.225124][2811:2816] CHIP:DMG: 				},
[1643148900.225158][2811:2816] CHIP:DMG:
[1643148900.225186][2811:2816] CHIP:DMG: 				CommandData =
[1643148900.225218][2811:2816] CHIP:DMG: 				{
[1643148900.225250][2811:2816] CHIP:DMG: 					0x0 = 0,
[1643148900.225285][2811:2816] CHIP:DMG: 					0x1 = 1,
[1643148900.225321][2811:2816] CHIP:DMG: 					0x2 = "",
[1643148900.225355][2811:2816] CHIP:DMG: 				},
[1643148900.225387][2811:2816] CHIP:DMG: 			},
[1643148900.225422][2811:2816] CHIP:DMG:
[1643148900.225450][2811:2816] CHIP:DMG: 		},
[1643148900.225481][2811:2816] CHIP:DMG:
[1643148900.225505][2811:2816] CHIP:DMG: 	],
[1643148900.225534][2811:2816] CHIP:DMG:
[1643148900.225556][2811:2816] CHIP:DMG: },
[1643148900.225624][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1643148900.225659][2811:2816] CHIP:ZCL: NOCResponse:
[1643148900.225681][2811:2816] CHIP:ZCL:   StatusCode: 0
[1643148900.225700][2811:2816] CHIP:ZCL:   FabricIndex: 1
[1643148900.225719][2811:2816] CHIP:ZCL:   DebugText:
[1643148900.225744][2811:2816] CHIP:CTL: Device returned status 0 on receiving the NOC
[1643148900.225767][2811:2816] CHIP:CTL: Operational credentials provisioned on device 0xffff8c0ab890
[1643148900.225793][2811:2816] CHIP:TOO: Secure Pairing Success
[1643148900.226164][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.226318][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.226442][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.226571][2811:2816] CHIP:DIS: Attempt to mDNS broadcast failed:  ../../third_party/connectedhomeip/src/inet/UDPEndPointImplSockets.cpp:417: OS Error 0x02000065: Network is unreachable
[1643148900.226638][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe]
[1643148900.226701][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:2464757 on exchange 39455i
[1643148900.226782][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1)  of type 0x10 and protocolId (0, 0) on exchange 39455i with MessageCounter:11844445.
[1643148900.226822][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11844445 to 0x0000000000654321 (1) at monotonic time: 136176 msec
[1643148900.226931][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:2464757 on exchange 39455i
[1643148900.228060][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.228100][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.228124][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.228146][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.228169][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.229047][2811:2816] CHIP:TOO: Pairing Success
[1643148900.229587][2811:2816] CHIP:IN: Generating DestinationID. Fabric ID 0x0000000000000001, Dest node ID 0x0000000000654321, Random data
[1643148900.229620][2811:2816] CHIP:IN: 0x4d, 0xc0, 0xf5, 0x8e, 0x13, 0x5e, 0xac, 0xad,
[1643148900.229641][2811:2816] CHIP:IN: 0xbb, 0x1e, 0x4c, 0xdd, 0xf6, 0x7d, 0xbb, 0x03,
[1643148900.229665][2811:2816] CHIP:IN: 0x70, 0x77, 0xb6, 0xf6, 0xeb, 0xd2, 0x6b, 0xf0,
[1643148900.229689][2811:2816] CHIP:IN: 0x96, 0x49, 0x46, 0x8c, 0x40, 0x5b, 0x7f, 0xf2,
[1643148900.229710][2811:2816] CHIP:IN: Root pubkey
[1643148900.229733][2811:2816] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.229756][2811:2816] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.229776][2811:2816] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.229798][2811:2816] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.229821][2811:2816] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.229843][2811:2816] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.229865][2811:2816] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.229888][2811:2816] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.229908][2811:2816] CHIP:IN: 0x51,
[1643148900.229928][2811:2816] CHIP:IN: IPK
[1643148900.229951][2811:2816] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00,
[1643148900.229974][2811:2816] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00,
[1643148900.230017][2811:2816] CHIP:IN: Generated DestinationID output
[1643148900.230045][2811:2816] CHIP:IN: 0x26, 0x9f, 0x80, 0x2c, 0x04, 0xc7, 0xc6, 0x4d,
[1643148900.230070][2811:2816] CHIP:IN: 0x9d, 0xc6, 0x0d, 0x63, 0xf9, 0xe5, 0xe5, 0x2d,
[1643148900.230092][2811:2816] CHIP:IN: 0xcf, 0xfa, 0x50, 0x71, 0x21, 0x2c, 0x50, 0x60,
[1643148900.230115][2811:2816] CHIP:IN: 0x23, 0x75, 0xef, 0x3c, 0x4b, 0x36, 0x6a, 0x5a,
[1643148900.230141][2811:2816] CHIP:SC: Including MRP parameters
[1643148900.230209][2811:2816] CHIP:IN: Prepared unauthenticated message 0xaaaacbab3998 to 0x0000000000000000 (0)  of type 0x30 and protocolId (0, 0) on exchange 39456i with MessageCounter:1862767197.
[1643148900.230255][2811:2816] CHIP:IN: Sending unauthenticated msg 0xaaaacbab3998 with MessageCounter:1862767197 to 0x0000000000000000 at monotonic time: 136179 msec
[1643148900.230393][2811:2816] CHIP:SC: Sent Sigma1 msg
[1643148900.230430][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.230629][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.230693][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.230721][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.230745][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.230766][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.230788][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.230817][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.232303][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.232337][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.232367][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.232392][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.232415][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.232438][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.232471][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.233925][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.233958][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.233986][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.234008][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.234068][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.234093][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.234127][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.235870][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.235903][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.235929][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.235951][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.235982][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.237468][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.237501][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.237528][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.237552][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.237574][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.237594][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.237625][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.239128][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.239159][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.239184][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.239206][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.239227][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.239248][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.239279][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.240714][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.240742][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.240764][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.240785][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.240816][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.242338][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.242367][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.242391][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.242414][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.242435][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.242455][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.242486][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.245248][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.245280][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.245305][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.245326][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.245359][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.246902][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.246932][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.246957][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.246980][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.247001][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.247022][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.247052][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.248429][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.248459][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.248482][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.248502][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.248532][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.250041][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.250071][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.250094][2811:2816] CHIP:DIS:     Addr 0: [2601:647:4100:b5b:dea6:32ff:febe:8d58]:5540
[1643148900.250116][2811:2816] CHIP:DIS:     Addr 1: [fe80::dea6:32ff:febe:8d58]:5540
[1643148900.250136][2811:2816] CHIP:DIS:     Addr 2: [<IP_ADDRESS>]:5540
[1643148900.250157][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.250188][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.252672][2811:2816] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:824388790 on exchange 39456i
[1643148900.252709][2811:2816] CHIP:EM: Found matching exchange: 39456i, Delegate: 0xffff8c0a0340
[1643148900.252757][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:1862767197 from Retrans Table on exchange 39456i
[1643148900.252781][2811:2816] CHIP:EM: Removed CHIP MessageCounter:1862767197 from RetransTable on exchange 39456i
[1643148900.252819][2811:2816] CHIP:SC: Received Sigma2 msg
[1643148900.252857][2811:2816] CHIP:SC: Peer assigned session session ID 2
[1643148900.253501][2811:2816] CHIP:SC: The device does not support GetClock_RealTimeMS() API. This will eventually result in CASE session setup failures.
[1643148900.255371][2811:2816] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000001 and root pubkey
[1643148900.255423][2811:2816] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e,
[1643148900.255447][2811:2816] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81,
[1643148900.255470][2811:2816] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee,
[1643148900.255493][2811:2816] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8,
[1643148900.255516][2811:2816] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45,
[1643148900.255539][2811:2816] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90,
[1643148900.255561][2811:2816] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30,
[1643148900.255583][2811:2816] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15,
[1643148900.255604][2811:2816] CHIP:IN: 0x51,
[1643148900.255663][2811:2816] CHIP:IN: Generated compressed fabric ID
[1643148900.255690][2811:2816] CHIP:IN: 0x6f, 0x55, 0x25, 0x60, 0xfd, 0xec, 0x45, 0x73,
[1643148900.256579][2811:2816] CHIP:SC: Found MRP parameters in the message
[1643148900.256630][2811:2816] CHIP:SC: Sending Sigma3
[1643148900.257021][2811:2816] CHIP:EM: Piggybacking Ack for MessageCounter:824388790 on exchange: 39456i
[1643148900.257075][2811:2816] CHIP:IN: Prepared unauthenticated message 0xaaaacbab3998 to 0x0000000000000000 (0)  of type 0x32 and protocolId (0, 0) on exchange 39456i with MessageCounter:1862767198.
[1643148900.257115][2811:2816] CHIP:IN: Sending unauthenticated msg 0xaaaacbab3998 with MessageCounter:1862767198 to 0x0000000000000000 at monotonic time: 136206 msec
[1643148900.257254][2811:2816] CHIP:SC: Sent Sigma3 msg
[1643148900.257459][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.257489][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.257514][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.257536][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.257571][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.257718][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.257746][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.257769][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.257789][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.257816][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321
[1643148900.257954][2811:2816] CHIP:DIS: Discovered node without a pending query
[1643148900.257981][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321
[1643148900.258003][2811:2816] CHIP:DIS:     Addr 0: [<IP_ADDRESS>]:5540
[1643148900.258024][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321
[1643148900.258050][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321