We read every piece of feedback, and take your input very seriously.
To see all available qualifiers, see our documentation.
Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.
By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.
Already on GitHub? Sign in to your account
# 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
./chip-app1 --command Test_TC_DM_1_3_Simulated --secured-device-port 5540
./chip-tool pairing onnetwork-long 0x654321 20202021 3842
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 192.168.50.121 [1643148897.680362][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148897.682157][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148897.684215][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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 192.168.50.121 [1643148897.701583][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148897.703394][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148897.705228][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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 192.168.50.121 [1643148897.717421][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148897.719189][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148897.721041][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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 192.168.50.121 on port 52131 [1643148900.103818][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 on port 52131 [1643148900.105835][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 on port 52131 [1643148900.108076][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 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 172.17.0.1 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 172.17.0.1 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 172.17.0.1 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 172.17.0.1 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 172.19.0.1 on port 42897 [1643148900.133185][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 on port 42897 [1643148900.134738][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 on port 42897 [1643148900.136292][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 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 172.18.0.1 on port 35240 [1643148900.141613][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 on port 35240 [1643148900.143270][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 on port 35240 [1643148900.144901][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 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 0000000000000001, NodeId 0000000000654321, 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 0000000000000001, NodeId 0000000000654321, 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 192.168.50.121 [1643148900.202514][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148900.206029][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148900.209702][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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 192.168.50.121 on port 52131 [1643148900.229576][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 on port 52131 [1643148900.232028][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 on port 52131 [1643148900.233698][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 192.168.50.121 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 172.17.0.1 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 172.17.0.1 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 172.17.0.1 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 172.17.0.1 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. Fabric ID 0x0000000000000001, Dest node ID 0x0000000000654321, Random data [1643148900.250508][2805:2805] CHIP:IN: 0x4d, 0xc0, 0xf5, 0x8e, 0x13, 0x5e, 0xac, 0xad, [1643148900.250531][2805:2805] CHIP:IN: 0xbb, 0x1e, 0x4c, 0xdd, 0xf6, 0x7d, 0xbb, 0x03, [1643148900.250553][2805:2805] CHIP:IN: 0x70, 0x77, 0xb6, 0xf6, 0xeb, 0xd2, 0x6b, 0xf0, [1643148900.250575][2805:2805] CHIP:IN: 0x96, 0x49, 0x46, 0x8c, 0x40, 0x5b, 0x7f, 0xf2, [1643148900.250595][2805:2805] CHIP:IN: Root pubkey [1643148900.250617][2805:2805] CHIP:IN: 0x04, 0x14, 0x39, 0x89, 0xa4, 0x9c, 0xd4, 0x2e, [1643148900.250638][2805:2805] CHIP:IN: 0x3e, 0x4c, 0x98, 0x66, 0x42, 0xc7, 0x78, 0x81, [1643148900.250709][2805:2805] CHIP:IN: 0x85, 0x0e, 0xc7, 0x18, 0x94, 0x42, 0x45, 0xee, [1643148900.250732][2805:2805] CHIP:IN: 0xf1, 0x77, 0x72, 0xdc, 0x7c, 0x89, 0x0e, 0xe8, [1643148900.250753][2805:2805] CHIP:IN: 0xb6, 0xb2, 0x69, 0x6a, 0x4c, 0x78, 0xd9, 0x45, [1643148900.250776][2805:2805] CHIP:IN: 0x05, 0x18, 0xab, 0xb6, 0xdf, 0x37, 0xf1, 0x90, [1643148900.250798][2805:2805] CHIP:IN: 0x06, 0x9e, 0xaa, 0xba, 0xc5, 0xd7, 0xb2, 0x30, [1643148900.250819][2805:2805] CHIP:IN: 0xeb, 0xb6, 0xb4, 0xa3, 0x6c, 0x50, 0x5a, 0x15, [1643148900.250838][2805:2805] CHIP:IN: 0x51, [1643148900.250858][2805:2805] CHIP:IN: IPK [1643148900.250882][2805:2805] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1643148900.250905][2805:2805] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1643148900.250944][2805:2805] CHIP:IN: Generated DestinationID output [1643148900.250969][2805:2805] CHIP:IN: 0x26, 0x9f, 0x80, 0x2c, 0x04, 0xc7, 0xc6, 0x4d, [1643148900.250991][2805:2805] CHIP:IN: 0x9d, 0xc6, 0x0d, 0x63, 0xf9, 0xe5, 0xe5, 0x2d, [1643148900.251013][2805:2805] CHIP:IN: 0xcf, 0xfa, 0x50, 0x71, 0x21, 0x2c, 0x50, 0x60, [1643148900.251035][2805:2805] CHIP:IN: 0x23, 0x75, 0xef, 0x3c, 0x4b, 0x36, 0x6a, 0x5a, [1643148900.251055][2805:2805] CHIP:DIS: Fabric (1) loaded from storage. 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 172.19.0.1 on port 42897 [1643148900.255892][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 on port 42897 [1643148900.257575][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 on port 42897 [1643148900.259213][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.19.0.1 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 172.18.0.1 on port 35240 [1643148900.266982][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 on port 35240 [1643148900.268610][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 on port 35240 [1643148900.270245][2805:2805] CHIP:DIS: Directly sending mDns reply to peer 172.18.0.1 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 192.168.50.121 [1643148900.290078][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148900.293275][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148900.296715][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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 192.168.50.121 [1643148900.314323][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.17.0.1 [1643148900.315989][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.19.0.1 [1643148900.317699][2805:2805] CHIP:DIS: Broadcasting mDns reply for query from 172.18.0.1 [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: [192.168.50.121]: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: [192.168.50.121]: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: [192.168.50.121]: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: [192.168.50.121]: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: [172.17.0.1]: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: [192.168.50.121]: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: [192.168.50.121]: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: [172.17.0.1]: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: [192.168.50.121]: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: [172.17.0.1]: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: [192.168.50.121]: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: [172.17.0.1]: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: [192.168.50.121]: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: [172.19.0.1]: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: [172.19.0.1]: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: [172.19.0.1]:5540 [1643148900.258024][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.258050][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.259450][2811:2816] CHIP:DIS: Discovered node without a pending query [1643148900.259484][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321 [1643148900.259522][2811:2816] CHIP:DIS: Addr 0: [172.19.0.1]:5540 [1643148900.259545][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.259578][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.263578][2811:2816] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:824388791 on exchange 39456i [1643148900.263618][2811:2816] CHIP:EM: Found matching exchange: 39456i, Delegate: 0xffff8c0a0340 [1643148900.263666][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:1862767198 from Retrans Table on exchange 39456i [1643148900.263691][2811:2816] CHIP:EM: Removed CHIP MessageCounter:1862767198 from RetransTable on exchange 39456i [1643148900.263732][2811:2816] CHIP:SC: Success status report received. Session was established [1643148900.263761][2811:2816] CHIP:IN: New secure session created for device 0x0000000000654321, LSID:2 PSID:2! [1643148900.263872][2811:2816] CHIP:CTL: Calling commissioning complete [1643148900.263926][2811:2816] CHIP:DMG: ICR moving to [AddingComm] [1643148900.263951][2811:2816] CHIP:DMG: ICR moving to [AddedComma] [1643148900.264029][2811:2816] CHIP:IN: Prepared secure message 0xaaaacbab3998 to 0x0000000000654321 (1) of type 0x8 and protocolId (0, 1) on exchange 39457i with MessageCounter:11547771. [1643148900.264066][2811:2816] CHIP:IN: Sending encrypted msg 0xaaaacbab3998 with MessageCounter:11547771 to 0x0000000000654321 (1) at monotonic time: 136213 msec [1643148900.264189][2811:2816] CHIP:DMG: ICR moving to [CommandSen] [1643148900.264238][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:824388791 on exchange 39456i [1643148900.264269][2811:2816] CHIP:IN: Prepared unauthenticated message 0xffff86fdd9e8 to 0x0000000000000000 (0) of type 0x10 and protocolId (0, 0) on exchange 39456i with MessageCounter:1862767199. [1643148900.264300][2811:2816] CHIP:IN: Sending unauthenticated msg 0xffff86fdd9e8 with MessageCounter:1862767199 to 0x0000000000000000 at monotonic time: 136213 msec [1643148900.264420][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:824388791 on exchange 39456i [1643148900.265576][2811:2816] CHIP:DIS: Discovered node without a pending query [1643148900.265610][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321 [1643148900.265636][2811:2816] CHIP:DIS: Addr 0: [fd00:6d61:7474:6572::1]:5540 [1643148900.265658][2811:2816] CHIP:DIS: Addr 1: [fe80::1]:5540 [1643148900.265680][2811:2816] CHIP:DIS: Addr 2: [172.18.0.1]:5540 [1643148900.265701][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.265735][2811:2816] CHIP:TOO: Pairing Success [1643148900.265758][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.267278][2811:2816] CHIP:DIS: Discovered node without a pending query [1643148900.267311][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321 [1643148900.267335][2811:2816] CHIP:DIS: Addr 0: [fd00:6d61:7474:6572::1]:5540 [1643148900.267357][2811:2816] CHIP:DIS: Addr 1: [fe80::1]:5540 [1643148900.267378][2811:2816] CHIP:DIS: Addr 2: [172.18.0.1]:5540 [1643148900.267400][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.267431][2811:2816] CHIP:TOO: Pairing Success [1643148900.267453][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.268835][2811:2816] CHIP:DIS: Discovered node without a pending query [1643148900.268865][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321 [1643148900.268890][2811:2816] CHIP:DIS: Addr 0: [fd00:6d61:7474:6572::1]:5540 [1643148900.268911][2811:2816] CHIP:DIS: Addr 1: [fe80::1]:5540 [1643148900.268933][2811:2816] CHIP:DIS: Addr 2: [172.18.0.1]:5540 [1643148900.268953][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.268983][2811:2816] CHIP:TOO: Pairing Success [1643148900.269005][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.270520][2811:2816] CHIP:DIS: Discovered node without a pending query [1643148900.270544][2811:2816] CHIP:DIS: Node ID resolved for 0x0000000000654321 [1643148900.270578][2811:2816] CHIP:DIS: Addr 0: [fd00:6d61:7474:6572::1]:5540 [1643148900.270601][2811:2816] CHIP:DIS: Addr 1: [fe80::1]:5540 [1643148900.270622][2811:2816] CHIP:DIS: Addr 2: [172.18.0.1]:5540 [1643148900.270673][2811:2816] CHIP:CTL: OperationalDiscoveryComplete for device ID 0x0000000000654321 [1643148900.270707][2811:2816] CHIP:TOO: Pairing Success [1643148900.270727][2811:2816] CHIP:CTL: Address resolved for node: 0x0000000000654321 [1643148900.271847][2811:2816] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:6586819 on exchange 39457i [1643148900.271886][2811:2816] CHIP:EM: Found matching exchange: 39457i, Delegate: 0xffff74004eb0 [1643148900.271931][2811:2816] CHIP:EM: Rxd Ack; Removing MessageCounter:11547771 from Retrans Table on exchange 39457i [1643148900.271955][2811:2816] CHIP:EM: Removed CHIP MessageCounter:11547771 from RetransTable on exchange 39457i [1643148900.271990][2811:2816] CHIP:DMG: ICR moving to [ResponseRe] [1643148900.272038][2811:2816] CHIP:DMG: InvokeResponseMessage = [1643148900.272063][2811:2816] CHIP:DMG: { [1643148900.272087][2811:2816] CHIP:DMG: suppressResponse = false, [1643148900.272115][2811:2816] CHIP:DMG: InvokeResponseIBs = [1643148900.272146][2811:2816] CHIP:DMG: [ [1643148900.272171][2811:2816] CHIP:DMG: InvokeResponseIB = [1643148900.272204][2811:2816] CHIP:DMG: { [1643148900.272233][2811:2816] CHIP:DMG: CommandDataIB = [1643148900.272266][2811:2816] CHIP:DMG: { [1643148900.272361][2811:2816] CHIP:DMG: CommandPathIB = [1643148900.272396][2811:2816] CHIP:DMG: { [1643148900.272430][2811:2816] CHIP:DMG: EndpointId = 0x0, [1643148900.272465][2811:2816] CHIP:DMG: ClusterId = 0x30, [1643148900.272499][2811:2816] CHIP:DMG: CommandId = 0x5, [1643148900.272532][2811:2816] CHIP:DMG: }, [1643148900.272568][2811:2816] CHIP:DMG: [1643148900.272595][2811:2816] CHIP:DMG: CommandData = [1643148900.272627][2811:2816] CHIP:DMG: { [1643148900.272661][2811:2816] CHIP:DMG: 0x0 = 0, [1643148900.272697][2811:2816] CHIP:DMG: 0x1 = "", [1643148900.272729][2811:2816] CHIP:DMG: }, [1643148900.272759][2811:2816] CHIP:DMG: }, [1643148900.272791][2811:2816] CHIP:DMG: [1643148900.272818][2811:2816] CHIP:DMG: }, [1643148900.272850][2811:2816] CHIP:DMG: [1643148900.272873][2811:2816] CHIP:DMG: ], [1643148900.272903][2811:2816] CHIP:DMG: [1643148900.272925][2811:2816] CHIP:DMG: }, [1643148900.272990][2811:2816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005 [1643148900.273025][2811:2816] CHIP:ZCL: CommissioningCompleteResponse: [1643148900.273047][2811:2816] CHIP:ZCL: errorCode: 0 [1643148900.273066][2811:2816] CHIP:ZCL: debugText: [1643148900.273091][2811:2816] CHIP:CTL: Received success response 0x0 [1643148900.273125][2811:2816] CHIP:CTL: Rendezvous cleanup [1643148900.273146][2811:2816] CHIP:TOO: Device commissioning completed with success [1643148900.273213][2811:2816] CHIP:DMG: ICR moving to [AwaitingDe] [1643148900.273246][2811:2816] CHIP:EM: Sending Standalone Ack for MessageCounter:6586819 on exchange 39457i [1643148900.273312][2811:2816] CHIP:IN: Prepared secure message 0xffff86fdd9e8 to 0x0000000000654321 (1) of type 0x10 and protocolId (0, 0) on exchange 39457i with MessageCounter:11547772. [1643148900.273353][2811:2816] CHIP:IN: Sending encrypted msg 0xffff86fdd9e8 with MessageCounter:11547772 to 0x0000000000654321 (1) at monotonic time: 136222 msec [1643148900.273483][2811:2816] CHIP:EM: Flushed pending ack for MessageCounter:6586819 on exchange 39457i [1643148900.273671][2811:2811] CHIP:CTL: Shutting down the commissioner [1643148900.273784][2811:2811] CHIP:CTL: Shutting down the controller [1643148900.273831][2811:2811] CHIP:CTL: Shutting down the commissioner [1643148900.273904][2811:2811] CHIP:CTL: Shutting down the controller [1643148900.273939][2811:2811] CHIP:CTL: Shutting down the commissioner [1643148900.274029][2811:2811] CHIP:CTL: Shutting down the controller [1643148900.274057][2811:2811] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1643148900.276358][2811:2811] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1643148900.276655][2811:2811] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-yGPEzN) [1643148900.277310][2811:2811] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1643148900.277372][2811:2811] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1643148900.277470][2811:2811] CHIP:DL: Inet Layer shutdown [1643148900.277494][2811:2811] CHIP:DL: BLE shutdown [1643148900.277540][2811:2811] CHIP:DL: System Layer shutdown
Thread 1 "chip-app1" received signal SIGSEGV, Segmentation fault. 0x0000aaaaaab6b038 in chip::DeviceLayer::Internal::GenericPlatformManagerImpl<chip::DeviceLayer::PlatformManagerImpl>::DispatchEventToApplication (this=0xaaaaaac48c28 <chip::DeviceLayer::PlatformManagerImpl::sInstance+16>, event=0xfffffffff108) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/internal/GenericPlatformManagerImpl.cpp:276 276 eventHandler->Handler(event, eventHandler->Arg); (gdb) p *eventHandler Cannot access memory at address 0xaaa00061b662 (gdb) p *event $1 = {Type = 32784, {Platform = {{BLECentralConnected = {mConnection = 0xffff00000000}, BLECentralConnectFailed = {mError = {static kRangeStart = 24, static kRangeLength = 8, static kValueStart = 0, static kValueLength = 24, static kSdkPartStart = 8, static kSdkPartLength = 3, static kSdkCodeStart = 0, static kSdkCodeLength = 8, mError = 0, mFile = 0xaaaaaac09ab0 "../../examples/placeholder/linux/third_party/connectedhomeip/src/platform/DeviceControlServer.cpp", mLine = 72}}, BLEWriteComplete = {mConnection = 0xffff00000000}, BLESubscribeOpComplete = {mConnection = 0xffff00000000, mIsSubscribed = 176}, BLEIndicationReceived = {mConnection = 0xffff00000000, mData = 0xaaaaaac09ab0}, BLEPeripheralRegisterAppComplete = { mIsSuccess = false, mpAppstate = 0xaaaaaac09ab0}, BLEPeripheralAdvConfiguredComplete = {mIsSuccess = false, mpAppstate = 0xaaaaaac09ab0}, BLEPeripheralAdvStartComplete = {mIsSuccess = false, mpAppstate = 0xaaaaaac09ab0}, BLEPeripheralAdvStopComplete = {mIsSuccess = false, mpAppstate = 0xaaaaaac09ab0}}}, LambdaEvent = {mLambdaProxy = 0xffff00000000, mLambdaBody = { __data = "\260\232\300\252\252\252\000\000H\000\000\000\377\377\000", __align = {<No data fields>}}}, CallWorkFunct = {WorkFunct = 0xffff00000000, Arg = 187649985911472}, WiFiConnectivityChange = { Result = chip::DeviceLayer::kConnectivity_NoChange}, ThreadConnectivityChange = {Result = chip::DeviceLayer::kConnectivity_NoChange}, InternetConnectivityChange = { IPv4 = chip::DeviceLayer::kConnectivity_NoChange, IPv6 = 65535, address = "\260\232\300\252\252\252\000\000H\000\000\000\377\377\000\000\210\230Ī\252\252\000\000@\340\377\377\377\377\000\000dܭ\252\252\252\000\000i\266\001\000\000"}, ServiceConnectivityChange = {Overall = { Result = chip::DeviceLayer::kConnectivity_NoChange}, ViaThread = {Result = 65535}}, ServiceSubscriptionStateChange = {Result = chip::DeviceLayer::kConnectivity_NoChange}, FabricMembershipChange = { IsMemberOfFabric = false}, ServiceProvisioningChange = {IsServiceProvisioned = false, ServiceConfigUpdated = false}, AccountPairingChange = {IsPairedToAccount = false}, TimeSyncChange = { IsTimeSynchronized = false}, SessionEstablished = {PeerNodeId = 281470681743360, SessionKeyId = 39600, SessionType = 192 '\300', IsCommissioner = 170}, CHIPoBLESubscribe = {ConId = 0xffff00000000}, CHIPoBLEUnsubscribe = {ConId = 0xffff00000000}, CHIPoBLEWriteReceived = {ConId = 0xffff00000000, Data = 0xaaaaaac09ab0}, CHIPoBLEIndicateConfirm = {ConId = 0xffff00000000}, CHIPoBLEConnectionError = { ConId = 0xffff00000000, Reason = {static kRangeStart = 24, static kRangeLength = 8, static kValueStart = 0, static kValueLength = 24, static kSdkPartStart = 8, static kSdkPartLength = 3, static kSdkCodeStart = 0, static kSdkCodeLength = 8, mError = 2864749232, mFile = 0xffff00000048 <error: Cannot access memory at address 0xffff00000048>, mLine = 2865010824}}, CHIPoBLENotifyConfirm = { ConId = 0xffff00000000}, ThreadStateChange = {RoleChanged = false, AddressChanged = false, NetDataChanged = false, ChildNodesChanged = false, OpenThread = {Flags = 65535}}, CHIPoBLEAdvertisingChange = { Result = chip::DeviceLayer::kActivity_NoChange}, InterfaceIpAddressChanged = {Type = chip::DeviceLayer::InterfaceIpChangeType::kIpV4_Assigned}, CommissioningComplete = {status = {static kRangeStart = 24, static kRangeLength = 8, static kValueStart = 0, static kValueLength = 24, static kSdkPartStart = 8, static kSdkPartLength = 3, static kSdkCodeStart = 0, static kSdkCodeLength = 8, mError = 0, mFile = 0xaaaaaac09ab0 "../../examples/placeholder/linux/third_party/connectedhomeip/src/platform/DeviceControlServer.cpp", mLine = 72}}, OperationalNetwork = {network = 0}}} (gdb) bt #0 0x0000aaaaaab6b038 in chip::DeviceLayer::Internal::GenericPlatformManagerImpl<chip::DeviceLayer::PlatformManagerImpl>::DispatchEventToApplication(chip::DeviceLayer::ChipDeviceEvent const*) (this=0xaaaaaac48c28 <chip::DeviceLayer::PlatformManagerImpl::sInstance+16>, event=0xfffffffff108) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/internal/GenericPlatformManagerImpl.cpp:276 #1 0x0000aaaaaab6af40 in chip::DeviceLayer::Internal::GenericPlatformManagerImpl<chip::DeviceLayer::PlatformManagerImpl>::_DispatchEvent(chip::DeviceLayer::ChipDeviceEvent const*) (this=0xaaaaaac48c28 <chip::DeviceLayer::PlatformManagerImpl::sInstance+16>, event=0xfffffffff108) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/internal/GenericPlatformManagerImpl.cpp:241 #2 0x0000aaaaaab4292c in chip::DeviceLayer::PlatformManager::DispatchEvent(chip::DeviceLayer::ChipDeviceEvent const*) (this=0xaaaaaac48c18 <chip::DeviceLayer::PlatformManagerImpl::sInstance>, event=0xfffffffff108) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/PlatformManager.h:425 #3 0x0000aaaaaab6b7b8 in chip::DeviceLayer::Internal::GenericPlatformManagerImpl_POSIX<chip::DeviceLayer::PlatformManagerImpl>::ProcessDeviceEvents() (this=0xaaaaaac48c28 <chip::DeviceLayer::PlatformManagerImpl::sInstance+16>) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/internal/GenericPlatformManagerImpl_POSIX.cpp:146 #4 0x0000aaaaaab6b440 in chip::DeviceLayer::Internal::GenericPlatformManagerImpl_POSIX<chip::DeviceLayer::PlatformManagerImpl>::_RunEventLoop() (this=0xaaaaaac48c28 <chip::DeviceLayer::PlatformManagerImpl::sInstance+16>) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/internal/GenericPlatformManagerImpl_POSIX.cpp:183 #5 0x0000aaaaaaaf3ee8 in chip::DeviceLayer::PlatformManager::RunEventLoop() (this=0xaaaaaac48c18 <chip::DeviceLayer::PlatformManagerImpl::sInstance>) at ../../examples/placeholder/linux/third_party/connectedhomeip/src/include/platform/PlatformManager.h:348 #6 0x0000aaaaaaaf43e4 in ChipLinuxAppMainLoop() () at ../../examples/placeholder/linux/third_party/connectedhomeip/examples/platform/linux/AppMain.cpp:434 #7 0x0000aaaaaaac31b0 in main(int, char**) (argc=5, argv=0xfffffffff388) at ../../examples/placeholder/linux/main.cpp:34 (gdb)
The text was updated successfully, but these errors were encountered:
No branches or pull requests
Problem
Config:
Steps to Repro:
Logs:
Chip Tool:
Debugging Attempts:
The text was updated successfully, but these errors were encountered: