sudo out/chip-tool pairing ble-wifi 12344321 WIFI_SSID WIFI_PASSWORD 0 20202021 3840 sudo out/chip-tool operationalcredentials read fabrics-list 12344321 0 sudo out/chip-tool basic read user-label 12344321 0 (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool pairing ble-wifi 12344321 WIFI_SSID WIFI_PASSWORD 0 20202021 3840 [1635811743.902549][13264:13264] CHIP:CTL: Generating NOC [1635811743.902920][13264:13264] CHIP:CTL: Generating ICAC [1635811743.908196][13264:13264] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT START ==== [1635811743.908257][13264:13264] CHIP:DL: AUDIT: * Validate buf1 and buf2 are <<>> [1635811743.908285][13264:13264] CHIP:DL: AUDIT: * Validate r1 and r2 are <<>> [1635811743.908308][13264:13264] CHIP:DL: AUDIT: * buf1: 3F89E260080C3B5DACE7FE5322330DC8 [1635811743.908329][13264:13264] CHIP:DL: AUDIT: * buf2: 7848B7E1A59F378663E1A64BE98EC28A [1635811743.908395][13264:13264] CHIP:DL: AUDIT: * r1: 0x9D323083 r2: 0x11201002 [1635811743.908418][13264:13264] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT END ==== [1635811743.908965][13264:13264] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-QO0Btj) [1635811743.909557][13264:13264] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811743.909611][13264:13264] CHIP:DL: NVS set: chip-counters/reboot-count = 26 (0x1A) [1635811743.910585][13264:13264] CHIP:DL: Got Ethernet interface: eth0 [1635811743.911271][13264:13264] CHIP:DL: Found the primary Ethernet interface:eth0 [1635811743.911957][13264:13264] CHIP:DL: Got WiFi interface: wlan0 [1635811743.912125][13264:13264] CHIP:DL: Failed to reset WiFi statistic counts [1635811743.912186][13264:13264] CHIP:IN: UDP::Init bind&listen port=5541 [1635811743.912314][13264:13264] CHIP:IN: UDP::Init bound to port=5541 [1635811743.912345][13264:13264] CHIP:IN: UDP::Init bind&listen port=5541 [1635811743.912450][13264:13264] CHIP:IN: UDP::Init bound to port=5541 [1635811743.912479][13264:13264] CHIP:IN: TransportMgr initialized [1635811743.912549][13264:13264] CHIP:DIS: Init fabric pairing table with server storage [1635811743.912926][13264:13264] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Knk1TS) [1635811743.913533][13264:13264] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811743.913591][13264:13264] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 26000 (0x6590) [1635811743.913810][13264:13264] CHIP:CTL: System State Initialized... [1635811743.921769][13264:13264] CHIP:DL: MDNS failed to join multicast group on veth14a080a for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811743.923313][13264:13264] CHIP:DL: MDNS failed to join multicast group on veth1c90749 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811743.924859][13264:13264] CHIP:DL: MDNS failed to join multicast group on vethe58c4bc for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811743.926313][13264:13264] CHIP:DL: MDNS failed to join multicast group on veth03a6c52 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811743.926692][13264:13264] CHIP:ZCL: Using ZAP configuration... [1635811743.927321][13264:13264] CHIP:DIS: Verifying the received credentials [1635811743.929088][13264:13264] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811743.929127][13264:13264] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811743.929152][13264:13264] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811743.929176][13264:13264] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811743.929200][13264:13264] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811743.929222][13264:13264] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811743.929302][13264:13264] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811743.929326][13264:13264] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811743.929349][13264:13264] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811743.929369][13264:13264] CHIP:IN: 0x80, [1635811743.929424][13264:13264] CHIP:IN: Generated compressed fabric ID [1635811743.929450][13264:13264] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811743.929479][13264:13264] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1635811743.929501][13264:13264] CHIP:DIS: Assigned compressed fabric ID: 0x26C6277A0745D8FB, node ID: 0x000000000001B669 [1635811743.929524][13264:13264] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x26C6277A0745D8FB [1635811743.929895][13264:13269] CHIP:DL: CHIP task running [1635811743.930379][13264:13269] CHIP:SC: Assigned local session key ID 42 [1635811743.930478][13264:13269] CHIP:IN: Prepared plaintext message 0xffff96fddda0 to 0x0000000000000000 of type 0x20 and protocolId (0, 0) on exchange 53994i with MessageCounter:1818637536. [1635811743.930524][13264:13269] CHIP:IN: Sending plaintext msg 0xffff96fddda0 with MessageCounter:1818637536 to 0x0000000000000000 at monotonic time: 4907980 msec [1635811743.930565][13264:13269] CHIP:IN: Message appended to BLE send queue [1635811743.930590][13264:13269] CHIP:SC: Sent PBKDF param request [1635811743.931624][13264:13270] CHIP:DL: TRACE: Bluez mainloop starting Thread [1635811743.931853][13264:13267] CHIP:DL: TRACE: Bus acquired for name C-33d0 [1635811743.941571][13264:13269] CHIP:DL: PlatformBlueZInit init success [1635811743.949476][13264:13267] CHIP:BLE: BLE removing known devices. [1635811743.971379][13264:13267] CHIP:BLE: BLE initiating scan. [1635811744.004107][13264:13267] CHIP:BLE: Device 2C:4C:C6:11:03:2D does not look like a CHIP device. [1635811744.007723][13264:13267] CHIP:BLE: Device 56:30:A3:2C:9F:80 does not look like a CHIP device. [1635811744.036824][13264:13267] CHIP:BLE: Device 2C:4C:C6:11:03:2D does not look like a CHIP device. [1635811744.056961][13264:13267] CHIP:BLE: Device 0F:07:4D:50:01:42 does not look like a CHIP device. [1635811744.128181][13264:13267] CHIP:BLE: Device 64:FF:0A:35:31:FC does not look like a CHIP device. [1635811744.176092][13264:13267] CHIP:BLE: Device 4A:9E:BD:B0:D5:8C does not look like a CHIP device. [1635811744.178761][13264:13267] CHIP:BLE: Device 36:52:48:C3:B4:C5 does not look like a CHIP device. [1635811744.208730][13264:13267] CHIP:BLE: Device 7C:88:C6:48:E6:03 does not look like a CHIP device. [1635811744.403835][13264:13267] CHIP:BLE: Device 70:1D:02:D7:9F:06 does not look like a CHIP device. [1635811744.407342][13264:13267] CHIP:BLE: Device 62:D1:A8:65:EE:30 does not look like a CHIP device. [1635811744.431363][13264:13267] CHIP:BLE: Device 31:9A:27:76:89:71 does not look like a CHIP device. [1635811744.464757][13264:13267] CHIP:BLE: Device 3E:9D:F0:C6:AB:9A does not look like a CHIP device. [1635811744.499238][13264:13267] CHIP:BLE: New device scanned: D4:4D:A4:A0:02:A5 [1635811744.499331][13264:13267] CHIP:BLE: Device discriminator match. Attempting to connect. [1635811744.503978][13264:13267] CHIP:BLE: Scan complete notification without an active scan. [1635811748.304993][13264:13267] CHIP:DL: ConnectDevice complete [1635811748.829245][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829324][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829374][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829416][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829482][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829524][13264:13267] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1635811748.829577][13264:13267] CHIP:DL: New BLE connection 0xffff8c038100, device D4:4D:A4:A0:02:A5, path /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5 [1635811748.829800][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1635811748.829900][13264:13269] CHIP:IN: BleConnectionComplete: endPoint 0xaaaac728ede8 [1635811749.083760][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811749.328257][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1635811749.328348][13264:13269] CHIP:BLE: subscribe complete, ep = 0xaaaac728ede8 [1635811749.328568][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811749.328789][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811749.328867][13264:13269] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1635811749.328938][13264:13269] CHIP:BLE: using BTP fragment sizes rx 20 / tx 20. [1635811749.328991][13264:13269] CHIP:BLE: local and remote recv window size = 5 [1635811749.329201][13264:13269] CHIP:IN: BLE EndPoint 0xaaaac728ede8 Connection Complete [1635811749.424841][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811749.521728][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811749.619610][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811749.716629][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811749.717180][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811749.717294][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.107956][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.108262][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.109653][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.109931][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.156562][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.156853][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.253158][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.253904][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.254097][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.254704][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.254877][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.255357][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.255481][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.302873][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.303163][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.303406][13264:13269] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:3040993663 on exchange 53994i [1635811750.303524][13264:13269] CHIP:SC: Received PBKDF param response [1635811750.303614][13264:13269] CHIP:SC: Peer assigned session ID 1 [1635811750.306837][13264:13269] CHIP:IN: Prepared plaintext message 0xffff96fdd8d0 to 0x0000000000000000 of type 0x22 and protocolId (0, 0) on exchange 53994i with MessageCounter:1818637537. [1635811750.306959][13264:13269] CHIP:IN: Sending plaintext msg 0xffff96fdd8d0 with MessageCounter:1818637537 to 0x0000000000000000 at monotonic time: 4914356 msec [1635811750.307040][13264:13269] CHIP:SC: Sent spake2p msg1 [1635811750.399372][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.497340][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.594997][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.692189][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.692955][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811750.693215][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811750.789103][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811750.887399][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811752.594464][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.594779][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.595688][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.595998][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.596186][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.596638][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.596709][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.596907][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.690881][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811752.691450][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.691798][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.692920][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.693264][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.693392][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.693489][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811752.693568][13264:13269] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:3040993664 on exchange 53994i [1635811752.693640][13264:13269] CHIP:SC: Received spake2p msg2 [1635811752.695727][13264:13269] CHIP:IN: Prepared plaintext message 0xffff96fdd9b0 to 0x0000000000000000 of type 0x24 and protocolId (0, 0) on exchange 53994i with MessageCounter:1818637538. [1635811752.695797][13264:13269] CHIP:IN: Sending plaintext msg 0xffff96fdd9b0 with MessageCounter:1818637538 to 0x0000000000000000 at monotonic time: 4916745 msec [1635811752.695932][13264:13269] CHIP:SC: Sent spake2p msg3 [1635811752.788590][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811752.886058][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811752.983790][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811752.984315][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811752.984567][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.032571][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.032844][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.032968][13264:13269] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:3040993665 on exchange 53994i [1635811753.033081][13264:13269] CHIP:IN: New secure session created for device 0x0000000000BC5C01, key 1!! [1635811753.033217][13264:13269] CHIP:CTL: Remote device completed SPAKE2+ handshake [1635811753.033270][13264:13269] CHIP:CTL: Sending Certificate Chain request to 0xffffa6220070 device [1635811753.033402][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811753.033463][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811753.033640][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd6f0 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 53995i with MessageCounter:0. [1635811753.033720][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd6f0 with MessageCounter:0 to 0x0000000000BC5C01 at monotonic time: 4917083 msec [1635811753.033915][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811753.034002][13264:13269] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate [1635811753.130026][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.227585][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.373661][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.470906][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.471405][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.471738][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.519829][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.520165][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.520296][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.520379][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.521514][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.521718][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.666301][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.666963][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.667305][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.668671][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.668973][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.669146][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.669325][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.669558][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.669988][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.763861][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.764364][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.765025][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.766136][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.766447][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.766626][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.766791][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.767103][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.767311][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.861270][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.861993][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.862943][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.863718][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.864008][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.864207][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.864402][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.864651][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.864908][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.958772][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811753.959469][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.959963][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.960845][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.961339][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.962079][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.962458][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811753.962725][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811753.962903][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.056350][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.056744][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.057262][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.058132][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.058691][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.059216][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.059698][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.060045][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.060277][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.153118][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.153942][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.154158][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.154713][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.154895][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.155545][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.155707][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.156396][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.156544][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.250526][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.251141][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.251396][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.251943][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.252061][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.252215][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:1 on exchange 53995i [1635811754.252286][13264:13269] CHIP:DMG: InvokeCommand = [1635811754.252313][13264:13269] CHIP:DMG: { [1635811754.252335][13264:13269] CHIP:DMG: CommandList = [1635811754.252361][13264:13269] CHIP:DMG: [ [1635811754.252384][13264:13269] CHIP:DMG: CommandDataIB = [1635811754.252424][13264:13269] CHIP:DMG: { [1635811754.252561][13264:13269] CHIP:DMG: CommandPathIB = [1635811754.252598][13264:13269] CHIP:DMG: { [1635811754.252630][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811754.252678][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811754.252711][13264:13269] CHIP:DMG: CommandId = 0x3, [1635811754.252751][13264:13269] CHIP:DMG: }, [1635811754.252786][13264:13269] CHIP:DMG: [1635811754.252814][13264:13269] CHIP:DMG: CommandData = [1635811754.252857][13264:13269] CHIP:DMG: { [1635811754.252888][13264:13269] CHIP:DMG: 0x0 = [ [1635811754.252920][13264:13269] CHIP:DMG: ... (byte string too long) ... [1635811754.252966][13264:13269] CHIP:DMG: ] [1635811754.252995][13264:13269] CHIP:DMG: }, [1635811754.253036][13264:13269] CHIP:DMG: }, [1635811754.253069][13264:13269] CHIP:DMG: [1635811754.253092][13264:13269] CHIP:DMG: ], [1635811754.253120][13264:13269] CHIP:DMG: [1635811754.253153][13264:13269] CHIP:DMG: } [1635811754.253201][13264:13269] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1635811754.253234][13264:13269] CHIP:ZCL: CertificateChainResponse: [1635811754.253264][13264:13269] CHIP:ZCL: Certificate: 451 [1635811754.253290][13264:13269] CHIP:CTL: Received certificate chain from the device [1635811754.253314][13264:13269] CHIP:CTL: Sending Certificate Chain request to 0xffffa6220070 device [1635811754.253372][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811754.253401][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811754.253495][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd1a0 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 53996i with MessageCounter:1. [1635811754.253537][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd1a0 with MessageCounter:1 to 0x0000000000BC5C01 at monotonic time: 4918303 msec [1635811754.253643][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811754.253672][13264:13269] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate [1635811754.253721][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811754.348624][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.445976][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.543840][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.640848][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.641591][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.641780][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.690503][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.691026][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.691537][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.692017][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.692181][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.692279][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.786798][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.787449][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.787626][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.788059][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.788313][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.788691][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.788795][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.790248][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.790545][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.884471][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.885610][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.886131][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.886440][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.886609][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.887040][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.887417][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.888954][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.889224][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.982494][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811754.983972][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.984899][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.985030][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.985304][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.985761][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.985983][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811754.986125][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811754.986388][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.079622][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.080293][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.080661][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.081030][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.081450][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.081733][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.082018][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.082943][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.083118][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.177519][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.178518][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.179082][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.179834][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.180152][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.180277][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.180581][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.180964][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.181230][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.275199][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.276762][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.277167][13264:13269] CHIP:DL: [1635811755.277172][13264:13267] CHIP:DL: HandlePlatformSpecificBLEEvent 16389Indication received, conn = 0xffff8c038100 [1635811755.278438][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.278596][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.278727][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.278802][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.278902][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.421900][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.421945][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.422462][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.422669][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.422966][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.423167][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.423440][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.425033][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.425495][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.518813][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.519309][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.519583][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.519854][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2 on exchange 53996i [1635811755.519981][13264:13269] CHIP:DMG: InvokeCommand = [1635811755.520043][13264:13269] CHIP:DMG: { [1635811755.520097][13264:13269] CHIP:DMG: CommandList = [1635811755.520164][13264:13269] CHIP:DMG: [ [1635811755.520223][13264:13269] CHIP:DMG: CommandDataIB = [1635811755.520297][13264:13269] CHIP:DMG: { [1635811755.520361][13264:13269] CHIP:DMG: CommandPathIB = [1635811755.520437][13264:13269] CHIP:DMG: { [1635811755.520516][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811755.520604][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811755.520686][13264:13269] CHIP:DMG: CommandId = 0x3, [1635811755.520764][13264:13269] CHIP:DMG: }, [1635811755.520844][13264:13269] CHIP:DMG: [1635811755.520912][13264:13269] CHIP:DMG: CommandData = [1635811755.520994][13264:13269] CHIP:DMG: { [1635811755.521068][13264:13269] CHIP:DMG: 0x0 = [ [1635811755.521152][13264:13269] CHIP:DMG: ... (byte string too long) ... [1635811755.521242][13264:13269] CHIP:DMG: ] [1635811755.521319][13264:13269] CHIP:DMG: }, [1635811755.521391][13264:13269] CHIP:DMG: }, [1635811755.521465][13264:13269] CHIP:DMG: [1635811755.521522][13264:13269] CHIP:DMG: ], [1635811755.521593][13264:13269] CHIP:DMG: [1635811755.521647][13264:13269] CHIP:DMG: } [1635811755.521751][13264:13269] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1635811755.521826][13264:13269] CHIP:ZCL: CertificateChainResponse: [1635811755.521879][13264:13269] CHIP:ZCL: Certificate: 494 [1635811755.521936][13264:13269] CHIP:CTL: Received certificate chain from the device [1635811755.521996][13264:13269] CHIP:CTL: Sending Attestation Request to the device. [1635811755.522048][13264:13269] CHIP:CTL: Sending Attestation request to 0xffffa6220070 device [1635811755.522140][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811755.522208][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811755.522431][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd190 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 53997i with MessageCounter:2. [1635811755.522526][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd190 with MessageCounter:2 to 0x0000000000BC5C01 at monotonic time: 4919572 msec [1635811755.522717][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811755.522786][13264:13269] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1635811755.522869][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811755.616374][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.714319][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.811094][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811755.811900][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811755.812159][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811755.908857][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.005805][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.102996][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.494320][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.494692][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.495828][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.496118][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.496240][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.496556][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.496889][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.497098][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.591199][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.592101][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.592431][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.592862][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.593297][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.593683][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.593807][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.594516][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.594737][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.688939][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.689568][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.690060][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.691143][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.691473][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.691568][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.691812][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.692221][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.692469][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.786456][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.787968][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.788468][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.789222][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.788719][13264:13269] CHIP:DL: [1635811756.789765][13264:13267] CHIP:DL: HandlePlatformSpecificBLEEvent 16389Indication received, conn = 0xffff8c038100 [1635811756.789943][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.790051][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.790130][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.883568][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811756.885161][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811756.885455][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811756.885693][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:3 on exchange 53997i [1635811756.885837][13264:13269] CHIP:DMG: InvokeCommand = [1635811756.885903][13264:13269] CHIP:DMG: { [1635811756.885957][13264:13269] CHIP:DMG: CommandList = [1635811756.886020][13264:13269] CHIP:DMG: [ [1635811756.886079][13264:13269] CHIP:DMG: CommandDataIB = [1635811756.886145][13264:13269] CHIP:DMG: { [1635811756.886207][13264:13269] CHIP:DMG: CommandPathIB = [1635811756.886388][13264:13269] CHIP:DMG: { [1635811756.886504][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811756.886623][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811756.886740][13264:13269] CHIP:DMG: CommandId = 0x1, [1635811756.886849][13264:13269] CHIP:DMG: }, [1635811756.886958][13264:13269] CHIP:DMG: [1635811756.887054][13264:13269] CHIP:DMG: CommandData = [1635811756.887157][13264:13269] CHIP:DMG: { [1635811756.887261][13264:13269] CHIP:DMG: 0x0 = [ [1635811756.887420][13264:13269] CHIP:DMG: 0x15, 0x30, 0x1, 0x80, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x [1635811756.887665][13264:13269] CHIP:DMG: ] [1635811756.887772][13264:13269] CHIP:DMG: 0x1 = [ [1635811756.887918][13264:13269] CHIP:DMG: 0x89, 0xf8, 0xf4, 0xb7, 0xd7, 0x88, 0x9d, 0x23, 0xfd, 0x74, 0x0, 0x7a, 0x8e, 0x60, 0xa3, 0x7e, 0xa5, 0x9c, 0xb1, 0x46, 0xfc, 0x1c, 0x32, 0x6f, 0x42, 0xf5, 0x68, 0xa, 0x0, 0x78, 0xcc, 0xc7, 0x70, 0x68, 0xcc, 0xf5, 0xdc, 0x9e, 0x62, 0x3d, 0x98, 0xc0, 0 [1635811756.888037][13264:13269] CHIP:DMG: ] [1635811756.888138][13264:13269] CHIP:DMG: }, [1635811756.888238][13264:13269] CHIP:DMG: }, [1635811756.888319][13264:13269] CHIP:DMG: [1635811756.888377][13264:13269] CHIP:DMG: ], [1635811756.888450][13264:13269] CHIP:DMG: [1635811756.888505][13264:13269] CHIP:DMG: } [1635811756.888617][13264:13269] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1635811756.888698][13264:13269] CHIP:ZCL: AttestationResponse: [1635811756.888752][13264:13269] CHIP:ZCL: AttestationElements: 171 [1635811756.888801][13264:13269] CHIP:ZCL: Signature: 64 [1635811756.888857][13264:13269] CHIP:CTL: Received Attestation Information from the device [1635811756.896519][13264:13269] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1635811756.896572][13264:13269] CHIP:CTL: Sending 'CSR request' command to the device. [1635811756.896593][13264:13269] CHIP:CTL: Sending OpCSR request to 0xffffa6220070 device [1635811756.896654][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811756.896689][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811756.896801][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd1a0 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 53998i with MessageCounter:3. [1635811756.896849][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd1a0 with MessageCounter:3 to 0x0000000000BC5C01 at monotonic time: 4920946 msec [1635811756.896977][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811756.897009][13264:13269] CHIP:CTL: Sent OpCSR request, waiting for the CSR [1635811756.897051][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811756.981358][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811757.078819][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811757.175508][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811757.176357][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811757.176555][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811757.273704][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811757.371215][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811757.517578][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.248655][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.248894][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.249001][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.249091][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.250039][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.250312][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.251093][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.251263][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.346277][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.347746][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.348196][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.348730][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.349200][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.349732][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.349866][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.350559][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.350810][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.443973][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.445250][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.445592][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.446545][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.446836][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.446956][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.447163][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.447504][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.447749][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.540917][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.541522][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.541919][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.542194][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.542578][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.542935][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.543283][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.544210][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.544398][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.638902][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.639660][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.640160][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811758.640277][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.640387][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811758.640607][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:4 on exchange 53998i [1635811758.640733][13264:13269] CHIP:DMG: InvokeCommand = [1635811758.640796][13264:13269] CHIP:DMG: { [1635811758.640849][13264:13269] CHIP:DMG: CommandList = [1635811758.640914][13264:13269] CHIP:DMG: [ [1635811758.640972][13264:13269] CHIP:DMG: CommandDataIB = [1635811758.641051][13264:13269] CHIP:DMG: { [1635811758.641114][13264:13269] CHIP:DMG: CommandPathIB = [1635811758.641190][13264:13269] CHIP:DMG: { [1635811758.641274][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811758.641359][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811758.641439][13264:13269] CHIP:DMG: CommandId = 0x5, [1635811758.641516][13264:13269] CHIP:DMG: }, [1635811758.641598][13264:13269] CHIP:DMG: [1635811758.641666][13264:13269] CHIP:DMG: CommandData = [1635811758.641741][13264:13269] CHIP:DMG: { [1635811758.641816][13264:13269] CHIP:DMG: 0x0 = [ [1635811758.641952][13264:13269] CHIP:DMG: 0x15, 0x30, 0x1, 0xcd, 0x30, 0x81, 0xca, 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, [1635811758.642045][13264:13269] CHIP:DMG: ] [1635811758.642120][13264:13269] CHIP:DMG: 0x1 = [ [1635811758.642203][13264:13269] CHIP:DMG: [1635811758.642287][13264:13269] CHIP:DMG: ] [1635811758.642418][13264:13269] CHIP:DMG: }, [1635811758.642498][13264:13269] CHIP:DMG: }, [1635811758.642573][13264:13269] CHIP:DMG: [1635811758.642631][13264:13269] CHIP:DMG: ], [1635811758.642702][13264:13269] CHIP:DMG: [1635811758.642756][13264:13269] CHIP:DMG: } [1635811758.642864][13264:13269] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1635811758.642942][13264:13269] CHIP:ZCL: OpCSRResponse: [1635811758.642993][13264:13269] CHIP:ZCL: NOCSRElements: 254 [1635811758.643042][13264:13269] CHIP:ZCL: AttestationSignature: 0 [1635811758.643091][13264:13269] CHIP:CTL: Received certificate signing request from the device [1635811758.643142][13264:13269] CHIP:CTL: Getting certificate chain for the device from the issuer [1635811758.643194][13264:13269] CHIP:CTL: Verifying Certificate Signing Request [1635811758.644803][13264:13269] CHIP:CTL: Generating NOC [1635811758.645462][13264:13269] CHIP:CTL: Generating ICAC [1635811758.646121][13264:13269] CHIP:CTL: Providing certificate chain to the commissioner [1635811758.646193][13264:13269] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../examples/chip-tool/third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:183: Success [1635811758.646465][13264:13269] CHIP:CTL: Sending root certificate to the device [1635811758.646761][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811758.646834][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811758.647042][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdcec0 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 53999i with MessageCounter:4. [1635811758.647225][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdcec0 with MessageCounter:4 to 0x0000000000BC5C01 at monotonic time: 4922696 msec [1635811758.647448][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811758.647520][13264:13269] CHIP:CTL: Sent root certificate to the device [1635811758.647874][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811758.736265][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.833339][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811758.931232][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.077679][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.078071][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811759.078307][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811759.175078][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.272461][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.369779][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.516264][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.516793][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811759.517024][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811759.613552][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.711176][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.808690][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.906517][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811759.906895][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811759.907124][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.003773][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.100591][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.198793][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.296304][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.296832][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.297105][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.393911][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.443759][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.444252][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.444587][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.444698][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.444731][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.445318][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.540073][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.540583][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.541047][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.541263][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:5 on exchange 53999i [1635811760.541390][13264:13269] CHIP:DMG: InvokeCommand = [1635811760.541454][13264:13269] CHIP:DMG: { [1635811760.541508][13264:13269] CHIP:DMG: CommandList = [1635811760.541578][13264:13269] CHIP:DMG: [ [1635811760.541638][13264:13269] CHIP:DMG: CommandDataIB = [1635811760.541710][13264:13269] CHIP:DMG: { [1635811760.541773][13264:13269] CHIP:DMG: CommandPathIB = [1635811760.541849][13264:13269] CHIP:DMG: { [1635811760.541934][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811760.542017][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811760.542100][13264:13269] CHIP:DMG: CommandId = 0xb, [1635811760.542183][13264:13269] CHIP:DMG: }, [1635811760.542273][13264:13269] CHIP:DMG: [1635811760.542398][13264:13269] CHIP:DMG: StatusIB = [1635811760.542481][13264:13269] CHIP:DMG: { [1635811760.542560][13264:13269] CHIP:DMG: status = 0x0, [1635811760.542635][13264:13269] CHIP:DMG: }, [1635811760.542713][13264:13269] CHIP:DMG: [1635811760.542779][13264:13269] CHIP:DMG: }, [1635811760.542852][13264:13269] CHIP:DMG: [1635811760.542909][13264:13269] CHIP:DMG: ], [1635811760.542978][13264:13269] CHIP:DMG: [1635811760.543033][13264:13269] CHIP:DMG: } [1635811760.543131][13264:13269] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1635811760.543194][13264:13269] CHIP:ZCL: DefaultResponse: [1635811760.543244][13264:13269] CHIP:ZCL: Transaction: 0xffff84004a90 [1635811760.543294][13264:13269] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1635811760.543352][13264:13269] CHIP:CTL: Device confirmed that it has received the root certificate [1635811760.543406][13264:13269] CHIP:CTL: Sending operational certificate chain to the device [1635811760.543500][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811760.543575][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811760.543801][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd350 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 54000i with MessageCounter:5. [1635811760.543896][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd350 with MessageCounter:5 to 0x0000000000BC5C01 at monotonic time: 4924593 msec [1635811760.544097][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811760.544243][13264:13269] CHIP:CTL: Sent operational certificate to the device [1635811760.544389][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811760.636826][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.735022][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.832475][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811760.833175][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811760.833436][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811760.930204][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.027629][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.173102][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.271369][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.271732][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811761.271991][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811761.368834][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.466064][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.563887][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.661397][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.661884][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811761.662212][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811761.758985][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.856341][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811761.953955][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.051506][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.051852][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811762.052101][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811762.148828][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.246051][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.343903][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.441365][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.441892][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811762.442142][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811762.538991][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.636268][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.733861][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.831554][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811762.832057][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811762.832292][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811762.928565][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.025840][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.123810][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.221344][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.221872][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811763.222147][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811763.319011][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.416256][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.513849][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.611543][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811763.611911][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811763.612149][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811763.708870][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811765.854584][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811765.855150][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811765.855427][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811765.855679][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811765.856349][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811765.856632][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811765.951682][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811765.952514][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811765.952795][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811765.953016][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:6 on exchange 54000i [1635811765.953130][13264:13269] CHIP:DMG: InvokeCommand = [1635811765.953182][13264:13269] CHIP:DMG: { [1635811765.953226][13264:13269] CHIP:DMG: CommandList = [1635811765.953279][13264:13269] CHIP:DMG: [ [1635811765.953328][13264:13269] CHIP:DMG: CommandDataIB = [1635811765.953384][13264:13269] CHIP:DMG: { [1635811765.953436][13264:13269] CHIP:DMG: CommandPathIB = [1635811765.953496][13264:13269] CHIP:DMG: { [1635811765.953556][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811765.953620][13264:13269] CHIP:DMG: ClusterId = 0x3e, [1635811765.953682][13264:13269] CHIP:DMG: CommandId = 0x8, [1635811765.953741][13264:13269] CHIP:DMG: }, [1635811765.953805][13264:13269] CHIP:DMG: [1635811765.953858][13264:13269] CHIP:DMG: CommandData = [1635811765.953916][13264:13269] CHIP:DMG: { [1635811765.953977][13264:13269] CHIP:DMG: 0x0 = 0, [1635811765.954041][13264:13269] CHIP:DMG: 0x1 = 1, [1635811765.954107][13264:13269] CHIP:DMG: 0x2 = "", [1635811765.954170][13264:13269] CHIP:DMG: }, [1635811765.954224][13264:13269] CHIP:DMG: }, [1635811765.954290][13264:13269] CHIP:DMG: [1635811765.954400][13264:13269] CHIP:DMG: ], [1635811765.954486][13264:13269] CHIP:DMG: [1635811765.954545][13264:13269] CHIP:DMG: } [1635811765.954652][13264:13269] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1635811765.954733][13264:13269] CHIP:ZCL: NOCResponse: [1635811765.954786][13264:13269] CHIP:ZCL: StatusCode: 0 [1635811765.954836][13264:13269] CHIP:ZCL: FabricIndex: 1 [1635811765.954887][13264:13269] CHIP:ZCL: DebugText: [1635811765.954945][13264:13269] CHIP:CTL: Device returned status 0 on receiving the NOC [1635811765.954999][13264:13269] CHIP:CTL: Operational credentials provisioned on device 0xffffa6220070 [1635811765.962826][13264:13269] CHIP:TOO: Secure Pairing Success [1635811765.964908][13264:13269] CHIP:TOO: Pairing Success [1635811765.965039][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811765.965075][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811765.965134][13264:13269] CHIP:IN: Marking old secure session for device 0x0000000000BC5C01 as expired [1635811765.965165][13264:13269] CHIP:CTL: OnConnectionExpired was called for unknown device, ignoring it. [1635811765.965195][13264:13269] CHIP:IN: New secure session created for device 0x0000000000BC5C01, key 1!! [1635811765.965338][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd080 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 54001i with MessageCounter:7. [1635811765.965387][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd080 with MessageCounter:7 to 0x0000000000BC5C01 at monotonic time: 4930015 msec [1635811765.965496][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811765.965549][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811766.048916][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.146093][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.243177][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.243725][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811766.243904][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811766.341406][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.487854][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.585227][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.634285][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811766.634984][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811766.635044][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811766.635873][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811766.636098][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811766.636335][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811766.636921][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811766.637174][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811766.637461][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:7 on exchange 54001i [1635811766.637593][13264:13269] CHIP:DMG: InvokeCommand = [1635811766.637658][13264:13269] CHIP:DMG: { [1635811766.637712][13264:13269] CHIP:DMG: CommandList = [1635811766.637778][13264:13269] CHIP:DMG: [ [1635811766.637839][13264:13269] CHIP:DMG: CommandDataIB = [1635811766.637917][13264:13269] CHIP:DMG: { [1635811766.637983][13264:13269] CHIP:DMG: CommandPathIB = [1635811766.638062][13264:13269] CHIP:DMG: { [1635811766.638148][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811766.638247][13264:13269] CHIP:DMG: ClusterId = 0x31, [1635811766.638394][13264:13269] CHIP:DMG: CommandId = 0x2, [1635811766.638497][13264:13269] CHIP:DMG: }, [1635811766.638586][13264:13269] CHIP:DMG: [1635811766.638654][13264:13269] CHIP:DMG: StatusIB = [1635811766.638732][13264:13269] CHIP:DMG: { [1635811766.638804][13264:13269] CHIP:DMG: status = 0x0, [1635811766.638874][13264:13269] CHIP:DMG: }, [1635811766.638937][13264:13269] CHIP:DMG: [1635811766.638989][13264:13269] CHIP:DMG: }, [1635811766.639052][13264:13269] CHIP:DMG: [1635811766.639100][13264:13269] CHIP:DMG: ], [1635811766.639160][13264:13269] CHIP:DMG: [1635811766.639205][13264:13269] CHIP:DMG: } [1635811766.639297][13264:13269] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0002 Status=0x0 [1635811766.639351][13264:13269] CHIP:ZCL: DefaultResponse: [1635811766.639391][13264:13269] CHIP:ZCL: Transaction: 0xffff84004a90 [1635811766.639432][13264:13269] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1635811766.639481][13264:13269] CHIP:TOO: AddNetworkResponse [1635811766.639569][13264:13269] CHIP:DMG: ICR moving to [AddingComm] [1635811766.639631][13264:13269] CHIP:DMG: ICR moving to [AddedComma] [1635811766.639801][13264:13269] CHIP:IN: Prepared encrypted message 0xffff96fdd3e0 to 0x0000000000BC5C01 of type 0x8 and protocolId (0, 1) on exchange 54002i with MessageCounter:8. [1635811766.639902][13264:13269] CHIP:IN: Sending encrypted msg 0xffff96fdd3e0 with MessageCounter:8 to 0x0000000000BC5C01 at monotonic time: 4930689 msec [1635811766.639979][13264:13269] CHIP:DMG: ICR moving to [CommandSen] [1635811766.640058][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811766.731559][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.828617][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811766.926422][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811767.024253][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811767.024621][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811767.024858][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811767.121581][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811767.218915][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811767.268085][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811767.268361][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811769.851485][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811774.385904][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811774.386218][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811774.434628][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811774.434935][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811774.435123][13264:13267] CHIP:DL: Indication received, conn = 0xffff8c038100 [1635811774.435254][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1635811774.435405][13264:13269] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:8 on exchange 54002i [1635811774.435539][13264:13269] CHIP:DMG: InvokeCommand = [1635811774.435603][13264:13269] CHIP:DMG: { [1635811774.435657][13264:13269] CHIP:DMG: CommandList = [1635811774.435719][13264:13269] CHIP:DMG: [ [1635811774.435779][13264:13269] CHIP:DMG: CommandDataIB = [1635811774.435853][13264:13269] CHIP:DMG: { [1635811774.435916][13264:13269] CHIP:DMG: CommandPathIB = [1635811774.435990][13264:13269] CHIP:DMG: { [1635811774.436076][13264:13269] CHIP:DMG: EndpointId = 0x0, [1635811774.436166][13264:13269] CHIP:DMG: ClusterId = 0x31, [1635811774.436247][13264:13269] CHIP:DMG: CommandId = 0xc, [1635811774.436329][13264:13269] CHIP:DMG: }, [1635811774.436421][13264:13269] CHIP:DMG: [1635811774.436489][13264:13269] CHIP:DMG: StatusIB = [1635811774.436561][13264:13269] CHIP:DMG: { [1635811774.436643][13264:13269] CHIP:DMG: status = 0x0, [1635811774.436726][13264:13269] CHIP:DMG: }, [1635811774.436809][13264:13269] CHIP:DMG: [1635811774.436876][13264:13269] CHIP:DMG: }, [1635811774.436947][13264:13269] CHIP:DMG: [1635811774.437004][13264:13269] CHIP:DMG: ], [1635811774.437073][13264:13269] CHIP:DMG: [1635811774.437128][13264:13269] CHIP:DMG: } [1635811774.437227][13264:13269] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_000C Status=0x0 [1635811774.437293][13264:13269] CHIP:ZCL: DefaultResponse: [1635811774.437341][13264:13269] CHIP:ZCL: Transaction: 0xffff84014bb0 [1635811774.437391][13264:13269] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1635811774.437449][13264:13269] CHIP:TOO: EnableNetworkResponse [1635811774.437500][13264:13269] CHIP:TOO: Mdns: Updating NodeId: bc5c01 Compressed FabricId: 26c6277a0745d8fb ... [1635811774.438468][13264:13269] CHIP:DIS: Attempt to mDNS broadcast failed: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1035: OS Error 0x02000065: Network is unreachable [1635811774.438818][13264:13269] CHIP:DIS: Attempt to mDNS broadcast failed: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1035: OS Error 0x02000065: Network is unreachable [1635811774.439132][13264:13269] CHIP:DIS: Attempt to mDNS broadcast failed: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1035: OS Error 0x02000065: Network is unreachable [1635811774.440745][13264:13269] CHIP:DMG: ICR moving to [AwaitingDe] [1635811774.723517][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.725124][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:443: Success [1635811774.725206][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.725261][13264:13269] CHIP:CTL: Enabling CASE session establishment for the device [1635811774.726944][13264:13269] CHIP:IN: Marking old secure session for device 0x0000000000BC5C01 as expired [1635811774.731443][13264:13269] CHIP:IN: Generating DestinationID. Fabric ID 0x0000000000000000, Dest node ID 0x0000000000BC5C01, Random data [1635811774.731546][13264:13269] CHIP:IN: 0xcf, 0x7d, 0xac, 0xd4, 0xfb, 0xb7, 0x65, 0xd1, [1635811774.731608][13264:13269] CHIP:IN: 0x54, 0x49, 0x2b, 0x28, 0xf5, 0xaa, 0xb7, 0xb4, [1635811774.731667][13264:13269] CHIP:IN: 0x61, 0x20, 0x22, 0x72, 0x14, 0x5a, 0x94, 0x52, [1635811774.731726][13264:13269] CHIP:IN: 0xad, 0xc8, 0x82, 0x18, 0x15, 0x39, 0x10, 0x0e, [1635811774.731787][13264:13269] CHIP:IN: Root pubkey [1635811774.731842][13264:13269] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811774.731901][13264:13269] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811774.731959][13264:13269] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811774.732016][13264:13269] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811774.732073][13264:13269] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811774.732128][13264:13269] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811774.732184][13264:13269] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811774.732231][13264:13269] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811774.732259][13264:13269] CHIP:IN: 0x80, [1635811774.732286][13264:13269] CHIP:IN: IPK [1635811774.732317][13264:13269] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811774.732417][13264:13269] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811774.732470][13264:13269] CHIP:IN: Generated DestinationID output [1635811774.732504][13264:13269] CHIP:IN: 0x97, 0x60, 0x19, 0xe3, 0x6c, 0xcf, 0xa6, 0xcf, [1635811774.732536][13264:13269] CHIP:IN: 0x5f, 0x7a, 0x69, 0x8b, 0xeb, 0xf0, 0x07, 0x02, [1635811774.732567][13264:13269] CHIP:IN: 0xb4, 0xff, 0xe9, 0x2f, 0x72, 0x10, 0xda, 0x41, [1635811774.732597][13264:13269] CHIP:IN: 0xa6, 0xbe, 0xd9, 0x85, 0x9f, 0x61, 0x9d, 0x8d, [1635811774.732682][13264:13269] CHIP:IN: Prepared plaintext message 0xaaaaf6bf3040 to 0x0000000000000000 of type 0x30 and protocolId (0, 0) on exchange 54003i with MessageCounter:130884865. [1635811774.732740][13264:13269] CHIP:IN: Sending plaintext msg 0xaaaaf6bf3040 with MessageCounter:130884865 to 0x0000000000000000 at monotonic time: 4938782 msec [1635811774.732921][13264:13269] CHIP:SC: Sent Sigma1 msg [1635811774.733085][13264:13269] CHIP:DIS: Discovered node without a pending query [1635811774.733125][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.734046][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:437: Success [1635811774.734094][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.734125][13264:13269] CHIP:CTL: Operational certificates already provisioned for this device [1635811774.739009][13264:13269] CHIP:DIS: Discovered node without a pending query [1635811774.739063][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.750582][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:437: Success [1635811774.750627][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.750658][13264:13269] CHIP:CTL: Operational certificates already provisioned for this device [1635811774.752361][13264:13269] CHIP:DIS: Discovered node without a pending query [1635811774.752540][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.753298][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:437: Success [1635811774.753343][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.753374][13264:13269] CHIP:CTL: Operational certificates already provisioned for this device [1635811774.755251][13264:13269] CHIP:DIS: Discovered node without a pending query [1635811774.755318][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.756272][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:437: Success [1635811774.756331][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.756373][13264:13269] CHIP:CTL: Operational certificates already provisioned for this device [1635811774.758912][13264:13269] CHIP:DIS: Discovered node without a pending query [1635811774.758975][13264:13269] CHIP:DIS: Node ID resolved for 0x0000000000BC5C01 to [192.168.1.111]:5540 [1635811774.760057][13264:13269] CHIP:TOO: OnAddressUpdateComplete: bc5c01: ../examples/chip-tool/third_party/connectedhomeip/src/controller/CHIPDevice.cpp:437: Success [1635811774.760113][13264:13269] CHIP:CTL: OperationalDiscoveryComplete for device ID 12344321 [1635811774.760154][13264:13269] CHIP:CTL: Operational certificates already provisioned for this device [1635811776.079025][13264:13269] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:3047812421 on exchange 54003i [1635811776.079177][13264:13269] CHIP:EM: Rxd Ack; Removing MessageCounter:130884865 from Retrans Table on exchange 54003i [1635811776.079242][13264:13269] CHIP:EM: Removed CHIP MessageCounter:130884865 from RetransTable on exchange 54003i [1635811776.079328][13264:13269] CHIP:SC: Received Sigma2 msg [1635811776.079410][13264:13269] CHIP:SC: Peer assigned session session ID 2 [1635811776.085045][13264:13269] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811776.085330][13264:13269] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811776.085395][13264:13269] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811776.085454][13264:13269] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811776.085511][13264:13269] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811776.085569][13264:13269] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811776.085625][13264:13269] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811776.085680][13264:13269] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811776.085736][13264:13269] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811776.085787][13264:13269] CHIP:IN: 0x80, [1635811776.085902][13264:13269] CHIP:IN: Generated compressed fabric ID [1635811776.085968][13264:13269] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811776.087623][13264:13269] CHIP:SC: Sending Sigma3 [1635811776.088139][13264:13269] CHIP:EM: Piggybacking Ack for MessageCounter:3047812421 on exchange: 54003i [1635811776.088208][13264:13269] CHIP:IN: Prepared plaintext message 0xaaaaf6bf3040 to 0x0000000000000000 of type 0x32 and protocolId (0, 0) on exchange 54003i with MessageCounter:130884866. [1635811776.088263][13264:13269] CHIP:IN: Sending plaintext msg 0xaaaaf6bf3040 with MessageCounter:130884866 to 0x0000000000000000 at monotonic time: 4940137 msec [1635811776.088420][13264:13269] CHIP:SC: Sent Sigma3 msg [1635811776.969170][13264:13269] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1635811778.441026][13264:13269] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:3047812422 on exchange 54003i [1635811778.441173][13264:13269] CHIP:EM: Rxd Ack; Removing MessageCounter:130884866 from Retrans Table on exchange 54003i [1635811778.441237][13264:13269] CHIP:EM: Removed CHIP MessageCounter:130884866 from RetransTable on exchange 54003i [1635811778.441327][13264:13269] CHIP:SC: Success status report received. Session was established [1635811778.441393][13264:13269] CHIP:IN: New secure session created for device 0x0000000000BC5C01, key 2!! [1635811778.441551][13264:13269] CHIP:TOO: Device commissioning completed with success [1635811778.441667][13264:13269] CHIP:EM: Sending Standalone Ack for MessageCounter:3047812422 on exchange 54003i [1635811778.441791][13264:13269] CHIP:IN: Prepared plaintext message 0xffff96fdd990 to 0x0000000000000000 of type 0x10 and protocolId (0, 0) on exchange 54003i with MessageCounter:130884867. [1635811778.441875][13264:13269] CHIP:IN: Sending plaintext msg 0xffff96fdd990 with MessageCounter:130884867 to 0x0000000000000000 at monotonic time: 4942491 msec [1635811778.442151][13264:13269] CHIP:EM: Flushed pending ack for MessageCounter:3047812422 on exchange 54003i [1635811778.442650][13264:13264] CHIP:CTL: Shutting down the commissioner [1635811778.442772][13264:13264] CHIP:CTL: Shutting down the controller [1635811778.445437][13264:13264] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1635811778.445763][13264:13264] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Ua58WH) [1635811778.446704][13264:13264] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811778.446803][13264:13264] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1635811778.446849][13264:13264] CHIP:DL: Inet Layer shutdown [1635811778.447002][13264:13264] CHIP:DL: BLE layer shutdown [1635811778.447054][13264:13264] CHIP:IN: Clearing BLE pending packets. [1635811778.447185][13264:13264] CHIP:BLE: Auto-closing end point's BLE connection. [1635811778.447232][13264:13264] CHIP:DL: Closing BLE GATT connection (con 0xffff8c038100) [1635811778.447311][13264:13264] CHIP:DL: System Layer shutdown [1635811778.447636][13264:13267] CHIP:DL: BluezDisconnect peer=D4:4D:A4:A0:02:A5 (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool identify identify IdentifyTime 12344321 0 [1635811835.216743][13279:13279] CHIP:TOO: InitArgs: Invalid argument IdentifyTime: IdentifyTime Usage: out/chip-tool identify identify IdentifyTime node-id endpoint-id [1635811835.216951][13279:13279] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:114: Error 0x0000002F (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool identify identify IdentifyTime 12344321 1 [1635811837.957721][13281:13281] CHIP:TOO: InitArgs: Invalid argument IdentifyTime: IdentifyTime Usage: out/chip-tool identify identify IdentifyTime node-id endpoint-id [1635811837.957883][13281:13281] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:114: Error 0x0000002F (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool identify identify IdentifyTime 12344321 2 [1635811846.105827][13283:13283] CHIP:TOO: InitArgs: Invalid argument IdentifyTime: IdentifyTime Usage: out/chip-tool identify identify IdentifyTime node-id endpoint-id [1635811846.105975][13283:13283] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:114: Error 0x0000002F (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool identify identify IdentifyTime 12344321 0 [1635811847.845752][13285:13285] CHIP:TOO: InitArgs: Invalid argument IdentifyTime: IdentifyTime Usage: out/chip-tool identify identify IdentifyTime node-id endpoint-id [1635811847.845902][13285:13285] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/Commands.cpp:114: Error 0x0000002F (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool operationalcredentials read fabrics-list 12344321 0 [1635811858.241825][13288:13288] CHIP:CTL: Generating NOC [1635811858.242220][13288:13288] CHIP:CTL: Generating ICAC [1635811858.247579][13288:13288] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT START ==== [1635811858.247640][13288:13288] CHIP:DL: AUDIT: * Validate buf1 and buf2 are <<>> [1635811858.247661][13288:13288] CHIP:DL: AUDIT: * Validate r1 and r2 are <<>> [1635811858.247684][13288:13288] CHIP:DL: AUDIT: * buf1: 3E58374C83FD2F628CE0CF7A3E85289C [1635811858.247706][13288:13288] CHIP:DL: AUDIT: * buf2: AAC1DE72D07C4ECFC5327572BDEA8F02 [1635811858.247772][13288:13288] CHIP:DL: AUDIT: * r1: 0x2F0BEC03 r2: 0xE484C8DF [1635811858.247795][13288:13288] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT END ==== [1635811858.248360][13288:13288] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-f7yBhH) [1635811858.248951][13288:13288] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811858.249002][13288:13288] CHIP:DL: NVS set: chip-counters/reboot-count = 27 (0x1B) [1635811858.249901][13288:13288] CHIP:DL: Got Ethernet interface: eth0 [1635811858.250591][13288:13288] CHIP:DL: Found the primary Ethernet interface:eth0 [1635811858.251239][13288:13288] CHIP:DL: Got WiFi interface: wlan0 [1635811858.251318][13288:13288] CHIP:DL: Failed to reset WiFi statistic counts [1635811858.251373][13288:13288] CHIP:IN: UDP::Init bind&listen port=5541 [1635811858.251489][13288:13288] CHIP:IN: UDP::Init bound to port=5541 [1635811858.251516][13288:13288] CHIP:IN: UDP::Init bind&listen port=5541 [1635811858.251608][13288:13288] CHIP:IN: UDP::Init bound to port=5541 [1635811858.251634][13288:13288] CHIP:IN: TransportMgr initialized [1635811858.251700][13288:13288] CHIP:DIS: Init fabric pairing table with server storage [1635811858.252051][13288:13288] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-j2QKAT) [1635811858.252682][13288:13288] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811858.252736][13288:13288] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 27000 (0x6978) [1635811858.252867][13288:13288] CHIP:CTL: System State Initialized... [1635811858.259708][13288:13288] CHIP:DL: MDNS failed to join multicast group on veth14a080a for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811858.261300][13288:13288] CHIP:DL: MDNS failed to join multicast group on veth1c90749 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811858.262992][13288:13288] CHIP:DL: MDNS failed to join multicast group on vethe58c4bc for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811858.264571][13288:13288] CHIP:DL: MDNS failed to join multicast group on veth03a6c52 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811858.264796][13288:13288] CHIP:ZCL: Using ZAP configuration... [1635811858.265466][13288:13288] CHIP:DIS: Verifying the received credentials [1635811858.267608][13288:13288] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811858.267659][13288:13288] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811858.267685][13288:13288] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811858.267710][13288:13288] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811858.267735][13288:13288] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811858.267760][13288:13288] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811858.267785][13288:13288] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811858.267809][13288:13288] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811858.267833][13288:13288] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811858.267854][13288:13288] CHIP:IN: 0x80, [1635811858.267913][13288:13288] CHIP:IN: Generated compressed fabric ID [1635811858.267942][13288:13288] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811858.267975][13288:13288] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1635811858.268000][13288:13288] CHIP:DIS: Assigned compressed fabric ID: 0x26C6277A0745D8FB, node ID: 0x000000000001B669 [1635811858.268024][13288:13288] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x26C6277A0745D8FB [1635811858.268419][13288:13293] CHIP:DL: CHIP task running [1635811858.268587][13288:13293] CHIP:TOO: Sending command to node 0xbc5c01 [1635811858.269384][13288:13293] CHIP:IN: Generating DestinationID. Fabric ID 0x0000000000000000, Dest node ID 0x0000000000BC5C01, Random data [1635811858.269434][13288:13293] CHIP:IN: 0xce, 0x78, 0x63, 0x68, 0xee, 0xce, 0xf3, 0x59, [1635811858.269460][13288:13293] CHIP:IN: 0xbf, 0x83, 0x50, 0x57, 0x86, 0x17, 0x91, 0x27, [1635811858.269486][13288:13293] CHIP:IN: 0x46, 0x80, 0xbc, 0xdb, 0x03, 0x53, 0xfa, 0x70, [1635811858.269511][13288:13293] CHIP:IN: 0x42, 0x43, 0xf4, 0xd8, 0x2f, 0x6b, 0x24, 0x60, [1635811858.269534][13288:13293] CHIP:IN: Root pubkey [1635811858.269559][13288:13293] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811858.269584][13288:13293] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811858.269608][13288:13293] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811858.269632][13288:13293] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811858.269657][13288:13293] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811858.269681][13288:13293] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811858.269706][13288:13293] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811858.269730][13288:13293] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811858.269752][13288:13293] CHIP:IN: 0x80, [1635811858.269774][13288:13293] CHIP:IN: IPK [1635811858.269798][13288:13293] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811858.269823][13288:13293] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811858.269860][13288:13293] CHIP:IN: Generated DestinationID output [1635811858.269888][13288:13293] CHIP:IN: 0x15, 0xa5, 0x40, 0x03, 0xd8, 0x41, 0xa2, 0xbf, [1635811858.269911][13288:13293] CHIP:IN: 0x3e, 0xbc, 0x8f, 0x1a, 0xae, 0x0f, 0x75, 0x3a, [1635811858.269933][13288:13293] CHIP:IN: 0x96, 0x3e, 0x3c, 0x42, 0x21, 0xc4, 0xad, 0xfc, [1635811858.269956][13288:13293] CHIP:IN: 0xa9, 0x43, 0x70, 0xe3, 0xb7, 0x5e, 0xc2, 0x33, [1635811858.270025][13288:13293] CHIP:IN: Prepared plaintext message 0xaaaaf873a820 to 0x0000000000000000 of type 0x30 and protocolId (0, 0) on exchange 53836i with MessageCounter:3997756984. [1635811858.270069][13288:13293] CHIP:IN: Sending plaintext msg 0xaaaaf873a820 with MessageCounter:3997756984 to 0x0000000000000000 at monotonic time: 5022319 msec [1635811858.270205][13288:13293] CHIP:SC: Sent Sigma1 msg [1635811859.896016][13288:13293] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:3047812423 on exchange 53836i [1635811859.896164][13288:13293] CHIP:EM: Rxd Ack; Removing MessageCounter:3997756984 from Retrans Table on exchange 53836i [1635811859.896229][13288:13293] CHIP:EM: Removed CHIP MessageCounter:3997756984 from RetransTable on exchange 53836i [1635811859.896317][13288:13293] CHIP:SC: Received Sigma2 msg [1635811859.896399][13288:13293] CHIP:SC: Peer assigned session session ID 3 [1635811859.902038][13288:13293] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811859.902134][13288:13293] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811859.902192][13288:13293] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811859.902251][13288:13293] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811859.902311][13288:13293] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811859.902400][13288:13293] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811859.902458][13288:13293] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811859.902515][13288:13293] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811859.902572][13288:13293] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811859.902623][13288:13293] CHIP:IN: 0x80, [1635811859.902734][13288:13293] CHIP:IN: Generated compressed fabric ID [1635811859.902801][13288:13293] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811859.904732][13288:13293] CHIP:SC: Sending Sigma3 [1635811859.905550][13288:13293] CHIP:EM: Piggybacking Ack for MessageCounter:3047812423 on exchange: 53836i [1635811859.905656][13288:13293] CHIP:IN: Prepared plaintext message 0xaaaaf873a820 to 0x0000000000000000 of type 0x32 and protocolId (0, 0) on exchange 53836i with MessageCounter:3997756985. [1635811859.905758][13288:13293] CHIP:IN: Sending plaintext msg 0xaaaaf873a820 with MessageCounter:3997756985 to 0x0000000000000000 at monotonic time: 5023955 msec [1635811859.905993][13288:13293] CHIP:SC: Sent Sigma3 msg [1635811862.230032][13288:13293] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:3047812424 on exchange 53836i [1635811862.230185][13288:13293] CHIP:EM: Rxd Ack; Removing MessageCounter:3997756985 from Retrans Table on exchange 53836i [1635811862.230250][13288:13293] CHIP:EM: Removed CHIP MessageCounter:3997756985 from RetransTable on exchange 53836i [1635811862.230392][13288:13293] CHIP:SC: Success status report received. Session was established [1635811862.230464][13288:13293] CHIP:IN: New secure session created for device 0x0000000000BC5C01, key 3!! [1635811862.230623][13288:13293] CHIP:TOO: Sending cluster (0x003E) command (0x00) on endpoint 0 [1635811862.230749][13288:13293] CHIP:DMG: SendReadRequest: Client[0] [ INIT] [1635811862.230974][13288:13293] CHIP:IN: Prepared encrypted message 0xaaaaf873a820 to 0x0000000000BC5C01 of type 0x2 and protocolId (0, 1) on exchange 53837i with MessageCounter:0. [1635811862.231068][13288:13293] CHIP:IN: Sending encrypted msg 0xaaaaf873a820 with MessageCounter:0 to 0x0000000000BC5C01 at monotonic time: 5026280 msec [1635811862.231275][13288:13293] CHIP:DMG: Client[0] moving to [AwaitingInitialReport] [1635811862.231351][13288:13293] CHIP:EM: Sending Standalone Ack for MessageCounter:3047812424 on exchange 53836i [1635811862.231425][13288:13293] CHIP:IN: Prepared plaintext message 0xffffab7ed990 to 0x0000000000000000 of type 0x10 and protocolId (0, 0) on exchange 53836i with MessageCounter:3997756986. [1635811862.231504][13288:13293] CHIP:IN: Sending plaintext msg 0xffffab7ed990 with MessageCounter:3997756986 to 0x0000000000000000 at monotonic time: 5026281 msec [1635811862.231662][13288:13293] CHIP:EM: Flushed pending ack for MessageCounter:3047812424 on exchange 53836i [1635811862.354450][13288:13293] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:1 on exchange 53837i [1635811862.354527][13288:13293] CHIP:EM: Rxd Ack; Removing MessageCounter:0 from Retrans Table on exchange 53837i [1635811862.354553][13288:13293] CHIP:EM: Removed CHIP MessageCounter:0 from RetransTable on exchange 53837i [1635811862.354607][13288:13293] CHIP:DMG: ReportData = [1635811862.354636][13288:13293] CHIP:DMG: { [1635811862.354658][13288:13293] CHIP:DMG: AttributeDataList = [1635811862.354684][13288:13293] CHIP:DMG: [ [1635811862.354708][13288:13293] CHIP:DMG: AttributeDataElement = [1635811862.354744][13288:13293] CHIP:DMG: { [1635811862.354770][13288:13293] CHIP:DMG: AttributePath = [1635811862.354805][13288:13293] CHIP:DMG: { [1635811862.354836][13288:13293] CHIP:DMG: NodeId = 0xbc5c01, [1635811862.354872][13288:13293] CHIP:DMG: EndpointId = 0x0, [1635811862.354908][13288:13293] CHIP:DMG: ClusterId = 0x3e, [1635811862.354944][13288:13293] CHIP:DMG: FieldTag = 0x0000_0001, [1635811862.354976][13288:13293] CHIP:DMG: } [1635811862.355011][13288:13293] CHIP:DMG: [1635811862.355042][13288:13293] CHIP:DMG: Data = [ [1635811862.355072][13288:13293] CHIP:DMG: [1635811862.355108][13288:13293] CHIP:DMG: { [1635811862.355142][13288:13293] CHIP:DMG: 0x0 = 1, [1635811862.355181][13288:13293] CHIP:DMG: 0x1 = [ [1635811862.355229][13288:13293] CHIP:DMG: 0x4, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x2, 0xb, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x6, 0x5a, 0x9, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, 0x68, 0xe9, 0xe5, 0xf, 0x97, 0x60, 0x70, 0xb8, 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, 0xbc, 0x9d, 0xf [1635811862.355268][13288:13293] CHIP:DMG: ] [1635811862.355304][13288:13293] CHIP:DMG: 0x2 = 65521, [1635811862.355342][13288:13293] CHIP:DMG: 0x3 = 0, [1635811862.355378][13288:13293] CHIP:DMG: 0x4 = 12344321, [1635811862.355417][13288:13293] CHIP:DMG: 0x5 = "", [1635811862.355454][13288:13293] CHIP:DMG: }, [1635811862.355490][13288:13293] CHIP:DMG: ], [1635811862.355521][13288:13293] CHIP:DMG: DataElementVersion = 0x0, [1635811862.355554][13288:13293] CHIP:DMG: }, [1635811862.355590][13288:13293] CHIP:DMG: [1635811862.355614][13288:13293] CHIP:DMG: ], [1635811862.355647][13288:13293] CHIP:DMG: [1635811862.355669][13288:13293] CHIP:DMG: } [1635811862.355785][13288:13293] CHIP:ZCL: ReadAttributesResponse: [1635811862.355828][13288:13293] CHIP:ZCL: ClusterId: 0x0000_003E [1635811862.355873][13288:13293] CHIP:ZCL: attributeId: 0x0000_0001 [1635811862.355913][13288:13293] CHIP:ZCL: status: Success (0x0000) [1635811862.355952][13288:13293] CHIP:ZCL: attribute TLV Type: 0x16 [1635811862.356010][13288:13293] CHIP:TOO: OnOperationalCredentialsFabricsListListAttributeResponse: 1 entries [1635811862.356222][13288:13293] CHIP:TOO: FabricDescriptor[1]: [1635811862.356266][13288:13293] CHIP:TOO: FabricIndex: 1 [1635811862.356303][13288:13293] CHIP:ZCL: RootPublicKey: 65 [1635811862.356337][13288:13293] CHIP:TOO: VendorId: 65521 [1635811862.356373][13288:13293] CHIP:TOO: FabricId: 0 [1635811862.356410][13288:13293] CHIP:TOO: NodeId: 12344321 [1635811862.356447][13288:13293] CHIP:ZCL: Label: [1635811862.356573][13288:13293] CHIP:EM: Piggybacking Ack for MessageCounter:1 on exchange: 53837i [1635811862.356678][13288:13293] CHIP:IN: Prepared encrypted message 0xaaaaf873a820 to 0x0000000000BC5C01 of type 0x1 and protocolId (0, 1) on exchange 53837i with MessageCounter:1. [1635811862.356746][13288:13293] CHIP:IN: Sending encrypted msg 0xaaaaf873a820 with MessageCounter:1 to 0x0000000000BC5C01 at monotonic time: 5026406 msec [1635811862.356936][13288:13293] CHIP:DMG: Client[0] moving to [UNINIT] [1635811862.357205][13288:13288] CHIP:CTL: Shutting down the commissioner [1635811862.357263][13288:13288] CHIP:CTL: Shutting down the controller [1635811862.359729][13288:13288] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1635811862.360153][13288:13288] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-oA4s1V) [1635811862.360996][13288:13288] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811862.361083][13288:13288] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1635811862.361130][13288:13288] CHIP:DL: Inet Layer shutdown [1635811862.361282][13288:13288] CHIP:DL: BLE layer shutdown [1635811862.361328][13288:13288] CHIP:DL: System Layer shutdown (python_env) ubuntu@matter-te-6:~/temp/connectedhomeip$ sudo out/chip-tool basic read user-label 12344321 0 [1635811949.388390][13299:13299] CHIP:CTL: Generating NOC [1635811949.388831][13299:13299] CHIP:CTL: Generating ICAC [1635811949.394406][13299:13299] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT START ==== [1635811949.394469][13299:13299] CHIP:DL: AUDIT: * Validate buf1 and buf2 are <<>> [1635811949.394496][13299:13299] CHIP:DL: AUDIT: * Validate r1 and r2 are <<>> [1635811949.394522][13299:13299] CHIP:DL: AUDIT: * buf1: 9FDBE8E4353DFE813547B42C03B089E9 [1635811949.394544][13299:13299] CHIP:DL: AUDIT: * buf2: E88597E6AA741B37145CAFD114B97135 [1635811949.394613][13299:13299] CHIP:DL: AUDIT: * r1: 0xB3E5A729 r2: 0x8A4D802E [1635811949.394637][13299:13299] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT END ==== [1635811949.395199][13299:13299] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-b6mqbr) [1635811949.395817][13299:13299] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811949.395878][13299:13299] CHIP:DL: NVS set: chip-counters/reboot-count = 28 (0x1C) [1635811949.396802][13299:13299] CHIP:DL: Got Ethernet interface: eth0 [1635811949.397434][13299:13299] CHIP:DL: Found the primary Ethernet interface:eth0 [1635811949.398140][13299:13299] CHIP:DL: Got WiFi interface: wlan0 [1635811949.398229][13299:13299] CHIP:DL: Failed to reset WiFi statistic counts [1635811949.398291][13299:13299] CHIP:IN: UDP::Init bind&listen port=5541 [1635811949.398434][13299:13299] CHIP:IN: UDP::Init bound to port=5541 [1635811949.398465][13299:13299] CHIP:IN: UDP::Init bind&listen port=5541 [1635811949.398569][13299:13299] CHIP:IN: UDP::Init bound to port=5541 [1635811949.398598][13299:13299] CHIP:IN: TransportMgr initialized [1635811949.398667][13299:13299] CHIP:DIS: Init fabric pairing table with server storage [1635811949.399027][13299:13299] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-VddvqU) [1635811949.399603][13299:13299] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811949.399657][13299:13299] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 28000 (0x6D60) [1635811949.399783][13299:13299] CHIP:CTL: System State Initialized... [1635811949.406439][13299:13299] CHIP:DL: MDNS failed to join multicast group on veth14a080a for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811949.407939][13299:13299] CHIP:DL: MDNS failed to join multicast group on veth1c90749 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811949.409462][13299:13299] CHIP:DL: MDNS failed to join multicast group on vethe58c4bc for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811949.411071][13299:13299] CHIP:DL: MDNS failed to join multicast group on veth03a6c52 for address type IPv4: ../examples/chip-tool/third_party/connectedhomeip/src/inet/UDPEndPoint.cpp:1396: Inet Error 0x00000110: Address not found [1635811949.411241][13299:13299] CHIP:ZCL: Using ZAP configuration... [1635811949.411867][13299:13299] CHIP:DIS: Verifying the received credentials [1635811949.413627][13299:13299] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811949.413670][13299:13299] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811949.413695][13299:13299] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811949.413719][13299:13299] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811949.413743][13299:13299] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811949.413765][13299:13299] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811949.413789][13299:13299] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811949.413811][13299:13299] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811949.413834][13299:13299] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811949.413854][13299:13299] CHIP:IN: 0x80, [1635811949.413909][13299:13299] CHIP:IN: Generated compressed fabric ID [1635811949.413935][13299:13299] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811949.413965][13299:13299] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1635811949.413988][13299:13299] CHIP:DIS: Assigned compressed fabric ID: 0x26C6277A0745D8FB, node ID: 0x000000000001B669 [1635811949.414011][13299:13299] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x26C6277A0745D8FB [1635811949.414426][13299:13304] CHIP:DL: CHIP task running [1635811949.414544][13299:13304] CHIP:TOO: Sending command to node 0xbc5c01 [1635811949.415296][13299:13304] CHIP:IN: Generating DestinationID. Fabric ID 0x0000000000000000, Dest node ID 0x0000000000BC5C01, Random data [1635811949.415341][13299:13304] CHIP:IN: 0xd8, 0x52, 0xc1, 0xa0, 0x12, 0xec, 0xcf, 0x18, [1635811949.415365][13299:13304] CHIP:IN: 0x5e, 0xe6, 0x3e, 0x05, 0x14, 0x7e, 0x39, 0x10, [1635811949.415389][13299:13304] CHIP:IN: 0x21, 0xc6, 0x5b, 0xdf, 0xae, 0x0c, 0xa9, 0xb2, [1635811949.415412][13299:13304] CHIP:IN: 0x5a, 0x49, 0x4e, 0xf6, 0x11, 0x23, 0xb7, 0xc4, [1635811949.415434][13299:13304] CHIP:IN: Root pubkey [1635811949.415458][13299:13304] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811949.415481][13299:13304] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811949.415504][13299:13304] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811949.415526][13299:13304] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811949.415550][13299:13304] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811949.415573][13299:13304] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811949.415596][13299:13304] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811949.415618][13299:13304] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811949.415638][13299:13304] CHIP:IN: 0x80, [1635811949.415659][13299:13304] CHIP:IN: IPK [1635811949.415682][13299:13304] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811949.415705][13299:13304] CHIP:IN: 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, [1635811949.415739][13299:13304] CHIP:IN: Generated DestinationID output [1635811949.415765][13299:13304] CHIP:IN: 0x95, 0x95, 0xdf, 0xcd, 0x3f, 0x09, 0x84, 0x35, [1635811949.415788][13299:13304] CHIP:IN: 0xe3, 0x24, 0xee, 0xa4, 0x36, 0xfd, 0x25, 0x77, [1635811949.415811][13299:13304] CHIP:IN: 0x92, 0x52, 0x58, 0x8b, 0x45, 0x79, 0xd8, 0x35, [1635811949.415834][13299:13304] CHIP:IN: 0xfc, 0xec, 0xa4, 0xb4, 0xc3, 0x17, 0xfd, 0xf9, [1635811949.415902][13299:13304] CHIP:IN: Prepared plaintext message 0xaaaae01bf820 to 0x0000000000000000 of type 0x30 and protocolId (0, 0) on exchange 62272i with MessageCounter:2346028795. [1635811949.415944][13299:13304] CHIP:IN: Sending plaintext msg 0xaaaae01bf820 with MessageCounter:2346028795 to 0x0000000000000000 at monotonic time: 5113465 msec [1635811949.416082][13299:13304] CHIP:SC: Sent Sigma1 msg [1635811950.817059][13299:13304] CHIP:EM: Received message of type 0x31 with protocolId (0, 0) and MessageCounter:3047812425 on exchange 62272i [1635811950.817207][13299:13304] CHIP:EM: Rxd Ack; Removing MessageCounter:2346028795 from Retrans Table on exchange 62272i [1635811950.817272][13299:13304] CHIP:EM: Removed CHIP MessageCounter:2346028795 from RetransTable on exchange 62272i [1635811950.817361][13299:13304] CHIP:SC: Received Sigma2 msg [1635811950.817442][13299:13304] CHIP:SC: Peer assigned session session ID 4 [1635811950.823226][13299:13304] CHIP:IN: Generating compressed fabric ID using uncompressed fabric ID 0x0000000000000000 and root pubkey [1635811950.823507][13299:13304] CHIP:IN: 0x04, 0x8c, 0x41, 0x29, 0x85, 0xdf, 0x43, 0x02, [1635811950.823574][13299:13304] CHIP:IN: 0x0b, 0xe2, 0xd6, 0xfa, 0x42, 0xd1, 0x06, 0x5a, [1635811950.823634][13299:13304] CHIP:IN: 0x09, 0x1c, 0x7a, 0x34, 0x5f, 0xbb, 0xe6, 0xd7, [1635811950.823694][13299:13304] CHIP:IN: 0x68, 0xe9, 0xe5, 0x0f, 0x97, 0x60, 0x70, 0xb8, [1635811950.823751][13299:13304] CHIP:IN: 0x73, 0x4b, 0xfe, 0x54, 0x1f, 0x93, 0xc0, 0x4d, [1635811950.823809][13299:13304] CHIP:IN: 0xbc, 0x9d, 0xf5, 0x64, 0xf9, 0x8a, 0xc1, 0x2a, [1635811950.823867][13299:13304] CHIP:IN: 0xb6, 0xf2, 0x16, 0xad, 0xb8, 0xdf, 0xf0, 0xff, [1635811950.823924][13299:13304] CHIP:IN: 0x8d, 0x35, 0x61, 0xef, 0xc9, 0x50, 0xed, 0xf1, [1635811950.823975][13299:13304] CHIP:IN: 0x80, [1635811950.824086][13299:13304] CHIP:IN: Generated compressed fabric ID [1635811950.824153][13299:13304] CHIP:IN: 0x26, 0xc6, 0x27, 0x7a, 0x07, 0x45, 0xd8, 0xfb, [1635811950.825692][13299:13304] CHIP:SC: Sending Sigma3 [1635811950.826215][13299:13304] CHIP:EM: Piggybacking Ack for MessageCounter:3047812425 on exchange: 62272i [1635811950.826278][13299:13304] CHIP:IN: Prepared plaintext message 0xaaaae01bf820 to 0x0000000000000000 of type 0x32 and protocolId (0, 0) on exchange 62272i with MessageCounter:2346028796. [1635811950.826346][13299:13304] CHIP:IN: Sending plaintext msg 0xaaaae01bf820 with MessageCounter:2346028796 to 0x0000000000000000 at monotonic time: 5114876 msec [1635811950.826519][13299:13304] CHIP:SC: Sent Sigma3 msg [1635811953.139299][13299:13304] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:3047812426 on exchange 62272i [1635811953.139401][13299:13304] CHIP:EM: Rxd Ack; Removing MessageCounter:2346028796 from Retrans Table on exchange 62272i [1635811953.139436][13299:13304] CHIP:EM: Removed CHIP MessageCounter:2346028796 from RetransTable on exchange 62272i [1635811953.139490][13299:13304] CHIP:SC: Success status report received. Session was established [1635811953.139529][13299:13304] CHIP:IN: New secure session created for device 0x0000000000BC5C01, key 4!! [1635811953.139629][13299:13304] CHIP:TOO: Sending cluster (0x0028) command (0x00) on endpoint 0 [1635811953.139744][13299:13304] CHIP:DMG: SendReadRequest: Client[0] [ INIT] [1635811953.139886][13299:13304] CHIP:IN: Prepared encrypted message 0xaaaae01bf820 to 0x0000000000BC5C01 of type 0x2 and protocolId (0, 1) on exchange 62273i with MessageCounter:0. [1635811953.139940][13299:13304] CHIP:IN: Sending encrypted msg 0xaaaae01bf820 with MessageCounter:0 to 0x0000000000BC5C01 at monotonic time: 5117189 msec [1635811953.140067][13299:13304] CHIP:DMG: Client[0] moving to [AwaitingInitialReport] [1635811953.140108][13299:13304] CHIP:EM: Sending Standalone Ack for MessageCounter:3047812426 on exchange 62272i [1635811953.140149][13299:13304] CHIP:IN: Prepared plaintext message 0xffff877ed990 to 0x0000000000000000 of type 0x10 and protocolId (0, 0) on exchange 62272i with MessageCounter:2346028797. [1635811953.140193][13299:13304] CHIP:IN: Sending plaintext msg 0xffff877ed990 with MessageCounter:2346028797 to 0x0000000000000000 at monotonic time: 5117189 msec [1635811953.140278][13299:13304] CHIP:EM: Flushed pending ack for MessageCounter:3047812426 on exchange 62272i [1635811953.261512][13299:13304] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:1 on exchange 62273i [1635811953.261635][13299:13304] CHIP:EM: Rxd Ack; Removing MessageCounter:0 from Retrans Table on exchange 62273i [1635811953.261698][13299:13304] CHIP:EM: Removed CHIP MessageCounter:0 from RetransTable on exchange 62273i [1635811953.261798][13299:13304] CHIP:DMG: ReportData = [1635811953.261865][13299:13304] CHIP:DMG: { [1635811953.261920][13299:13304] CHIP:DMG: AttributeDataList = [1635811953.261984][13299:13304] CHIP:DMG: [ [1635811953.262045][13299:13304] CHIP:DMG: AttributeDataElement = [1635811953.262122][13299:13304] CHIP:DMG: { [1635811953.262186][13299:13304] CHIP:DMG: AttributePath = [1635811953.262261][13299:13304] CHIP:DMG: { [1635811953.262376][13299:13304] CHIP:DMG: NodeId = 0xbc5c01, [1635811953.262468][13299:13304] CHIP:DMG: EndpointId = 0x0, [1635811953.262552][13299:13304] CHIP:DMG: ClusterId = 0x28, [1635811953.262638][13299:13304] CHIP:DMG: FieldTag = 0x0000_0005, [1635811953.262716][13299:13304] CHIP:DMG: } [1635811953.262809][13299:13304] CHIP:DMG: [1635811953.262893][13299:13304] CHIP:DMG: Data = "", [1635811953.262972][13299:13304] CHIP:DMG: DataElementVersion = 0x0, [1635811953.263052][13299:13304] CHIP:DMG: }, [1635811953.263133][13299:13304] CHIP:DMG: [1635811953.263194][13299:13304] CHIP:DMG: ], [1635811953.263266][13299:13304] CHIP:DMG: [1635811953.263322][13299:13304] CHIP:DMG: } [1635811953.263497][13299:13304] CHIP:ZCL: ReadAttributesResponse: [1635811953.263553][13299:13304] CHIP:ZCL: ClusterId: 0x0000_0028 [1635811953.263620][13299:13304] CHIP:ZCL: attributeId: 0x0000_0005 [1635811953.263675][13299:13304] CHIP:ZCL: status: Success (0x0000) [1635811953.263728][13299:13304] CHIP:ZCL: attribute TLV Type: 0x0c [1635811953.263785][13299:13304] CHIP:TOO: CharString attribute Response: [1635811953.263931][13299:13304] CHIP:EM: Piggybacking Ack for MessageCounter:1 on exchange: 62273i [1635811953.264060][13299:13304] CHIP:IN: Prepared encrypted message 0xaaaae01bf820 to 0x0000000000BC5C01 of type 0x1 and protocolId (0, 1) on exchange 62273i with MessageCounter:1. [1635811953.264148][13299:13304] CHIP:IN: Sending encrypted msg 0xaaaae01bf820 with MessageCounter:1 to 0x0000000000BC5C01 at monotonic time: 5117313 msec [1635811953.264378][13299:13304] CHIP:DMG: Client[0] moving to [UNINIT] [1635811953.264717][13299:13299] CHIP:CTL: Shutting down the commissioner [1635811953.264801][13299:13299] CHIP:CTL: Shutting down the controller [1635811953.267703][13299:13299] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1635811953.268103][13299:13299] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-R5FE10) [1635811953.269152][13299:13299] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1635811953.269267][13299:13299] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1635811953.269330][13299:13299] CHIP:DL: Inet Layer shutdown [1635811953.269529][13299:13299] CHIP:DL: BLE layer shutdown [1635811953.269588][13299:13299] CHIP:DL: System Layer shutdown