ubuntu@ubuntu-desktop:~$ ./chip-tool pairing ble-thread operationalDataset=hex:0e080000000000010000000300000f35060004001fffe00208a8baf4311d2c1acf0708fd06217852196229051000112233445566778899aabbccddeeff030f4f70656e5468726561642d34343234010212340410978d42ebeab4875a137cf69b3df690f90c0402a0fff8 12344321 73141520 3840 [1632395043.966952][3703:3703] CHIP:CTL: Read local id 0x000000000001B669, remote id 0x0000000000BC5C01 [1632395043.973064][3703:3703] CHIP:CTL: Generating NOC [1632395043.973445][3703:3703] CHIP:CTL: Generating ICAC [1632395043.973729][3703:3703] CHIP:CTL: Generating RCAC [1632395043.979198][3703:3703] CHIP:DL: writing settings to file (/tmp/chip_factory.ini.tmp) [1632395043.979335][3703:3703] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1632395043.979561][3703:3703] CHIP:DL: writing settings to file (/tmp/chip_config.ini.tmp) [1632395043.979672][3703:3703] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1632395043.979863][3703:3703] CHIP:DL: writing settings to file (/tmp/chip_counters.ini.tmp) [1632395043.979951][3703:3703] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1632395043.980169][3703:3703] CHIP:IN: UDP::Init bind&listen port=5541 [1632395043.980290][3703:3703] CHIP:IN: UDP::Init bind&listen port=5541 [1632395043.980385][3703:3703] CHIP:IN: TransportMgr initialized [1632395043.980407][3703:3703] CHIP:DIS: Init fabric pairing table with server storage [1632395043.983876][3703:3703] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:259: Inet Error 0x00000110: Address not found [1632395043.984092][3703:3703] CHIP:ZCL: Using ZAP configuration... [1632395043.984182][3703:3703] CHIP:ZCL: deactivate report event [1632395043.985235][3703:3703] CHIP:DIS: Verifying the received credentials [1632395043.987731][3703:3703] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1632395043.987777][3703:3703] CHIP:DIS: Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669 [1632395043.987806][3703:3703] CHIP:CTL: Joined the fabric at index 1 [1632395043.988178][3703:3708] CHIP:DL: CHIP task running [1632395043.988653][3703:3708] CHIP:CTL: Generated random node id: 0x735A8C653D3E3342 [1632395043.989850][3703:3708] CHIP:SC: Assigned local session key ID 1 [1632395043.989977][3703:3708] CHIP:IN: Message appended to BLE send queue [1632395043.990012][3703:3708] CHIP:SC: Sent PBKDF param request [1632395043.995873][3703:3709] CHIP:DL: TRACE: Bluez mainloop starting Thread [1632395043.996323][3703:3706] CHIP:DL: TRACE: Bus acquired for name C-0e77 [1632395044.005539][3703:3708] CHIP:DL: PlatformBlueZInit init success [1632395044.012731][3703:3706] CHIP:BLE: BLE removing known devices. [1632395044.012826][3703:3706] CHIP:BLE: BLE initiating scan. [1632395044.195723][3703:3706] CHIP:BLE: Device 46:40:93:7C:0E:B0 does not look like a CHIP device. [1632395044.208857][3703:3706] CHIP:BLE: New device scanned: 84:0D:8E:35:22:9E [1632395044.208932][3703:3706] CHIP:BLE: Device discriminator match. Attempting to connect. [1632395044.213496][3703:3706] CHIP:BLE: Scan complete notification without an active scan. [1632395044.700292][3703:3706] CHIP:DL: ConnectDevice complete [1632395045.427857][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.427920][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.427962][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.427989][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.428019][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.428043][3703:3706] CHIP:DL: Char1 /org/bluez/hci0/dev_84_0D_8E_35_22_9E/service000a [1632395045.428076][3703:3706] CHIP:DL: New BLE connection 0xffffac035070, device 84:0D:8E:35:22:9E, path /org/bluez/hci0/dev_84_0D_8E_35_22_9E [1632395045.428210][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1632395045.428251][3703:3708] CHIP:IN: BleConnectionComplete: endPoint 0xaaaacbd495b0 [1632395045.964865][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395046.161531][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1632395046.161630][3703:3708] CHIP:BLE: subscribe complete, ep = 0xaaaacbd495b0 [1632395046.163233][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395046.163481][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395046.163549][3703:3708] CHIP:BLE: peripheral chose BTP version 3; central expected between 2 and 3 [1632395046.163594][3703:3708] CHIP:BLE: using BTP fragment sizes rx 20 / tx 128. [1632395046.163635][3703:3708] CHIP:BLE: local and remote recv window size = 3 [1632395046.163838][3703:3708] CHIP:IN: BLE EndPoint 0xaaaacbd495b0 Connection Complete [1632395046.257233][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395048.891151][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395048.891430][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395051.473511][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395054.009893][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395054.010166][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395056.592176][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395059.177430][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395059.177796][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395061.857398][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395064.296276][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395064.296568][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395066.976242][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395069.414940][3703:3706] CHIP:DL: Indication received, conn = 0xffffac035070 [1632395069.415364][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395072.095099][3703:3708] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395073.998175][3703:3708] CHIP:TOO: Pairing Failure: ../../third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:1545: CHIP Error 0x00000032: Timeout [1632395073.998307][3703:3708] CHIP:SC: PASESession timed out while waiting for a response from the peer. Expected message type was 33 [1632395073.998534][3703:3708] CHIP:TOO: Secure Pairing Failed [1632395073.998595][3703:3703] CHIP:TOO: Run command failure: ../../third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:1545: CHIP Error 0x00000032: Timeout [1632395074.002644][3703:3708] CHIP:TOO: Pairing Failure: ../../third_party/connectedhomeip/src/protocols/secure_channel/PASESession.cpp:333: CHIP Error 0x00000032: Timeout [1632395074.003164][3703:3703] CHIP:CTL: Shutting down the commissioner [1632395074.003237][3703:3703] CHIP:CTL: Shutting down the controller [1632395074.003342][3703:3703] CHIP:DL: Inet Layer shutdown [1632395074.004021][3703:3703] CHIP:DL: BLE layer shutdown [1632395074.004089][3703:3703] CHIP:IN: Clearing BLE pending packets. [1632395074.004229][3703:3703] CHIP:BLE: Auto-closing end point's BLE connection. [1632395074.004284][3703:3703] CHIP:DL: Closing BLE GATT connection (con 0xffffac035070) [1632395074.004389][3703:3703] CHIP:DL: System Layer shutdown [1632395074.004752][3703:3706] CHIP:DL: BluezDisconnect peer=84:0D:8E:35:22:9E ubuntu@ubuntu-desktop:~$ ./chip-tool pairing ble-thread operationalDataset=hex:0e080000000000010000000300000f35060004001fffe00208a8baf4311d2c1acf0708fd06217852196229051000112233445566778899aabbccddeeff030f4f70656e5468726561642d34343234010212340410978d42ebeab4875a137cf69b3df690f90c0402a0fff8 12344321 73141520 3840 [1632395138.913085][3716:3716] CHIP:CTL: Read local id 0x000000000001B669, remote id 0x735A8C653D3E3342 [1632395138.915570][3716:3716] CHIP:CTL: Generating NOC [1632395138.915967][3716:3716] CHIP:CTL: Generating ICAC [1632395138.921045][3716:3716] CHIP:IN: UDP::Init bind&listen port=5541 [1632395138.921190][3716:3716] CHIP:IN: UDP::Init bind&listen port=5541 [1632395138.921280][3716:3716] CHIP:IN: TransportMgr initialized [1632395138.921303][3716:3716] CHIP:DIS: Init fabric pairing table with server storage [1632395138.924498][3716:3716] CHIP:DL: MDNS failed to join multicast group on wpan0 for address type IPv4: ../../third_party/connectedhomeip/src/inet/IPEndPointBasis.cpp:259: Inet Error 0x00000110: Address not found [1632395138.924664][3716:3716] CHIP:ZCL: Using ZAP configuration... [1632395138.924733][3716:3716] CHIP:ZCL: deactivate report event [1632395138.925440][3716:3716] CHIP:DIS: Verifying the received credentials [1632395138.927289][3716:3716] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1632395138.927432][3716:3716] CHIP:DIS: Assigned fabric ID: 0x0000000000000000, node ID: 0x000000000001B669 [1632395138.927456][3716:3716] CHIP:CTL: Joined the fabric at index 1 [1632395138.927760][3716:3721] CHIP:DL: CHIP task running [1632395138.928181][3716:3721] CHIP:CTL: Generated random node id: 0x1890B1FABF106BFD [1632395138.929229][3716:3721] CHIP:SC: Assigned local session key ID 3 [1632395138.929334][3716:3721] CHIP:IN: Message appended to BLE send queue [1632395138.929361][3716:3721] CHIP:SC: Sent PBKDF param request [1632395138.932359][3716:3722] CHIP:DL: TRACE: Bluez mainloop starting Thread [1632395138.932538][3716:3719] CHIP:DL: TRACE: Bus acquired for name C-0e84 [1632395138.942031][3716:3721] CHIP:DL: PlatformBlueZInit init success [1632395138.949171][3716:3719] CHIP:BLE: BLE removing known devices. [1632395138.949263][3716:3719] CHIP:BLE: BLE initiating scan. [1632395139.196401][3716:3719] CHIP:BLE: Device DC:23:4D:9B:03:30 does not look like a CHIP device. [1632395139.214722][3716:3719] CHIP:BLE: Device 78:A9:AC:97:E1:D6 does not look like a CHIP device. [1632395139.261498][3716:3719] CHIP:BLE: Device 50:ED:3C:56:37:66 does not look like a CHIP device. [1632395139.263481][3716:3719] CHIP:BLE: New device scanned: 90:FD:9F:19:B7:75 [1632395139.263541][3716:3719] CHIP:BLE: Device discriminator match. Attempting to connect. [1632395139.267488][3716:3719] CHIP:BLE: Scan complete notification without an active scan. [1632395139.394940][3716:3719] CHIP:DL: ConnectDevice complete [1632395139.909207][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909261][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909291][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909311][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909333][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909353][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909380][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909401][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909425][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909445][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909469][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909488][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909519][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909539][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909573][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909593][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909613][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909633][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909656][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909676][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909739][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909761][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909784][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909803][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909826][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0001 [1632395139.909845][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909871][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909891][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909913][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909933][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.909956][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.909975][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910002][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.910022][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910045][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.910064][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910087][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service000e [1632395139.910106][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910133][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service001b [1632395139.910153][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910176][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service001b [1632395139.910196][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910218][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service001b [1632395139.910238][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910264][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910284][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910308][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910327][3716:3719] CHIP:DL: Char1 /org/bluez/hci0/dev_90_FD_9F_19_B7_75/service0015 [1632395139.910357][3716:3719] CHIP:DL: New BLE connection 0xffff8c046230, device 90:FD:9F:19:B7:75, path /org/bluez/hci0/dev_90_FD_9F_19_B7_75 [1632395139.910482][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1632395139.910552][3716:3721] CHIP:IN: BleConnectionComplete: endPoint 0xaaaac07e65b0 [1632395140.414960][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395140.514018][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1632395140.514678][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.515073][3716:3721] CHIP:BLE: subscribe complete, ep = 0xaaaac07e65b0 [1632395140.515643][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.515706][3716:3721] CHIP:BLE: peripheral chose BTP version 3; central expected between 2 and 3 [1632395140.515759][3716:3721] CHIP:BLE: using BTP fragment sizes rx 20 / tx 128. [1632395140.515807][3716:3721] CHIP:BLE: local and remote recv window size = 3 [1632395140.516010][3716:3721] CHIP:IN: BLE EndPoint 0xaaaac07e65b0 Connection Complete [1632395140.609685][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395140.708386][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.709162][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.757639][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.757949][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.853861][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395140.855450][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.855791][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.856786][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.857021][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.951412][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395140.952435][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395140.952685][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395140.952827][3716:3721] CHIP:EM: Received message of type 0x21 with vendorId 0x0000 and protocolId 0x0000 on exchange 39017 [1632395140.952930][3716:3721] CHIP:SC: Received PBKDF param response [1632395140.956637][3716:3721] CHIP:SC: Sent spake2p msg1 [1632395141.097439][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.098958][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.099221][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.440349][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.440625][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.535886][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.537322][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.537555][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.539193][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.539425][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.633510][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.636165][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.636521][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.636613][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.636780][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.731252][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.732992][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.733684][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.734599][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.735090][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395141.735324][3716:3721] CHIP:EM: Received message of type 0x23 with vendorId 0x0000 and protocolId 0x0000 on exchange 39017 [1632395141.735417][3716:3721] CHIP:SC: Received spake2p msg2 [1632395141.735464][3716:3721] CHIP:SC: Peer assigned session key ID 0 [1632395141.738179][3716:3721] CHIP:SC: Sent spake2p msg3 [1632395141.738238][3716:3721] CHIP:IN: New secure session created for device 0x1890B1FABF106BFD, key 0!! [1632395141.738317][3716:3721] CHIP:CTL: Remote device completed SPAKE2+ handshake [1632395141.738352][3716:3721] CHIP:CTL: Sending OpCSR request to 0xffffe10491a8 device [1632395141.738466][3716:3721] CHIP:DMG: ICR moving to [Initialize] [1632395141.738516][3716:3721] CHIP:DMG: ICR moving to [AddCommand] [1632395141.738644][3716:3721] CHIP:IN: Secure message was encrypted: Msg ID 0 [1632395141.738686][3716:3721] CHIP:IN: Encrypted message 0xffff95404160 to 0x1890B1FABF106BFD of type 8 and protocolId 5 on exchange 39018. [1632395141.738724][3716:3721] CHIP:IN: Sending msg 0xffff95404160 to 0x1890B1FABF106BFD at utc time: 2956565 msec [1632395141.738754][3716:3721] CHIP:IN: Sending secure msg on generic transport [1632395141.738789][3716:3721] CHIP:IN: Secure msg send status ../../third_party/connectedhomeip/src/transport/raw/BLE.cpp:104: Success [1632395141.738821][3716:3721] CHIP:DMG: ICR moving to [ Sending] [1632395141.738852][3716:3721] CHIP:CTL: Sent OpCSR request, waiting for the CSR [1632395141.828659][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.926193][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395141.927661][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395141.927914][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.023188][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.122923][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.123199][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.218837][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.220277][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.221329][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.221374][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.221644][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.316205][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.317922][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.318503][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.318942][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.319357][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.413361][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.414750][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.415428][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.416670][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.416941][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.511288][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.512754][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.513247][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.514244][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.514514][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.608478][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.609883][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.610374][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.611302][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.611618][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.707165][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.709317][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.709957][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.710517][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.711012][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.803999][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.805219][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.806533][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.807176][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.807415][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.901187][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395142.902774][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.903250][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.903788][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395142.904345][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395142.998682][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.000026][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.000455][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.000709][3716:3721] CHIP:EM: Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 39018 [1632395143.000855][3716:3721] CHIP:DMG: InvokeCommand = [1632395143.000917][3716:3721] CHIP:DMG: { [1632395143.000969][3716:3721] CHIP:DMG: CommandList = [1632395143.001028][3716:3721] CHIP:DMG: [ [1632395143.001083][3716:3721] CHIP:DMG: CommandDataElement = [1632395143.001192][3716:3721] CHIP:DMG: { [1632395143.001407][3716:3721] CHIP:DMG: CommandPath = [1632395143.001487][3716:3721] CHIP:DMG: { [1632395143.001638][3716:3721] CHIP:DMG: EndpointId = 0x0, [1632395143.001775][3716:3721] CHIP:DMG: ClusterId = 0x3e, [1632395143.001855][3716:3721] CHIP:DMG: CommandId = 0x5, [1632395143.001929][3716:3721] CHIP:DMG: }, [1632395143.002094][3716:3721] CHIP:DMG: [1632395143.002168][3716:3721] CHIP:DMG: CommandData = [1632395143.002239][3716:3721] CHIP:DMG: { [1632395143.002314][3716:3721] CHIP:DMG: 0x0 = [ [1632395143.002445][3716:3721] CHIP:DMG: 0x15, 0x30, 0x1, 0xcc, 0x30, 0x81, 0xc9, 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, [1632395143.002532][3716:3721] CHIP:DMG: ] [1632395143.002656][3716:3721] CHIP:DMG: 0x1 = [ [1632395143.002739][3716:3721] CHIP:DMG: [1632395143.002820][3716:3721] CHIP:DMG: ] [1632395143.002896][3716:3721] CHIP:DMG: }, [1632395143.002971][3716:3721] CHIP:DMG: }, [1632395143.003110][3716:3721] CHIP:DMG: [1632395143.003166][3716:3721] CHIP:DMG: ], [1632395143.003235][3716:3721] CHIP:DMG: [1632395143.003285][3716:3721] CHIP:DMG: } [1632395143.003388][3716:3721] CHIP:ZCL: Received Cluster Command: Cluster=3e Command=5 Endpoint=0 [1632395143.003455][3716:3721] CHIP:ZCL: OpCSRResponse: [1632395143.003501][3716:3721] CHIP:ZCL: NOCSRElements: 253 [1632395143.003609][3716:3721] CHIP:ZCL: AttestationSignature: 0 [1632395143.003666][3716:3721] CHIP:CTL: Received certificate signing request from the device [1632395143.003714][3716:3721] CHIP:CTL: Getting certificate chain for the device from the issuer [1632395143.003962][3716:3721] CHIP:CTL: Verifying Certificate Signing Request [1632395143.006048][3716:3721] CHIP:CTL: Generating NOC [1632395143.006722][3716:3721] CHIP:CTL: Generating ICAC [1632395143.007435][3716:3721] CHIP:CTL: Providing certificate chain to the commissioner [1632395143.007474][3716:3721] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:182: Success [1632395143.007762][3716:3721] CHIP:CTL: Sending root certificate to the device [1632395143.007821][3716:3721] CHIP:DMG: ICR moving to [Initialize] [1632395143.007862][3716:3721] CHIP:DMG: ICR moving to [AddCommand] [1632395143.007983][3716:3721] CHIP:IN: Secure message was encrypted: Msg ID 1 [1632395143.008020][3716:3721] CHIP:IN: Encrypted message 0xffff95403380 to 0x1890B1FABF106BFD of type 8 and protocolId 5 on exchange 39019. [1632395143.008106][3716:3721] CHIP:IN: Sending msg 0xffff95403380 to 0x1890B1FABF106BFD at utc time: 2957834 msec [1632395143.008135][3716:3721] CHIP:IN: Sending secure msg on generic transport [1632395143.008253][3716:3721] CHIP:IN: Secure msg send status ../../third_party/connectedhomeip/src/transport/raw/BLE.cpp:104: Success [1632395143.008292][3716:3721] CHIP:DMG: ICR moving to [ Sending] [1632395143.008376][3716:3721] CHIP:CTL: Sent root certificate to the device [1632395143.008586][3716:3721] CHIP:DMG: ICR moving to [Uninitiali] [1632395143.291497][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.292662][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.292916][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.486319][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.584308][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.584889][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.585630][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.587108][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.587383][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.681367][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.683352][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.683783][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.684800][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.685014][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.778273][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.779509][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.779810][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395143.780048][3716:3721] CHIP:EM: Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 39019 [1632395143.780180][3716:3721] CHIP:DMG: InvokeCommand = [1632395143.780238][3716:3721] CHIP:DMG: { [1632395143.780287][3716:3721] CHIP:DMG: CommandList = [1632395143.780353][3716:3721] CHIP:DMG: [ [1632395143.780408][3716:3721] CHIP:DMG: CommandDataElement = [1632395143.780597][3716:3721] CHIP:DMG: { [1632395143.780665][3716:3721] CHIP:DMG: CommandPath = [1632395143.780745][3716:3721] CHIP:DMG: { [1632395143.780812][3716:3721] CHIP:DMG: EndpointId = 0x0, [1632395143.780889][3716:3721] CHIP:DMG: ClusterId = 0x3e, [1632395143.780965][3716:3721] CHIP:DMG: CommandId = 0xb, [1632395143.781107][3716:3721] CHIP:DMG: }, [1632395143.781193][3716:3721] CHIP:DMG: [1632395143.781259][3716:3721] CHIP:DMG: StatusElement = [1632395143.781396][3716:3721] CHIP:DMG: { [1632395143.781469][3716:3721] CHIP:DMG: GeneralCode = 0x0, [1632395143.781547][3716:3721] CHIP:DMG: ProtocolId = 0x5, [1632395143.781615][3716:3721] CHIP:DMG: protocolCode = 0x0, [1632395143.781842][3716:3721] CHIP:DMG: }, [1632395143.781935][3716:3721] CHIP:DMG: [1632395143.781998][3716:3721] CHIP:DMG: }, [1632395143.782074][3716:3721] CHIP:DMG: [1632395143.782198][3716:3721] CHIP:DMG: ], [1632395143.782271][3716:3721] CHIP:DMG: [1632395143.782321][3716:3721] CHIP:DMG: } [1632395143.782414][3716:3721] CHIP:ZCL: DefaultResponse: [1632395143.782463][3716:3721] CHIP:ZCL: Transaction: 0xaaaac07e4210 [1632395143.782575][3716:3721] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1632395143.782633][3716:3721] CHIP:CTL: Device confirmed that it has received the root certificate [1632395143.782682][3716:3721] CHIP:CTL: Sending operational certificate chain to the device [1632395143.782762][3716:3721] CHIP:DMG: ICR moving to [Initialize] [1632395143.782839][3716:3721] CHIP:DMG: ICR moving to [AddCommand] [1632395143.783113][3716:3721] CHIP:IN: Secure message was encrypted: Msg ID 2 [1632395143.783180][3716:3721] CHIP:IN: Encrypted message 0xffff95403b00 to 0x1890B1FABF106BFD of type 8 and protocolId 5 on exchange 39020. [1632395143.783241][3716:3721] CHIP:IN: Sending msg 0xffff95403b00 to 0x1890B1FABF106BFD at utc time: 2958609 msec [1632395143.783294][3716:3721] CHIP:IN: Sending secure msg on generic transport [1632395143.783539][3716:3721] CHIP:IN: Secure msg send status ../../third_party/connectedhomeip/src/transport/raw/BLE.cpp:104: Success [1632395143.783606][3716:3721] CHIP:DMG: ICR moving to [ Sending] [1632395143.783658][3716:3721] CHIP:CTL: Sent operational certificate to the device [1632395143.783731][3716:3721] CHIP:DMG: ICR moving to [Uninitiali] [1632395143.924988][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395143.926536][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395143.926791][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395144.022499][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395144.119734][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395144.121106][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395144.121331][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395144.217225][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395144.314905][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395144.316174][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395144.316436][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395144.706539][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395144.706978][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395144.851250][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395144.852556][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395144.852905][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395144.901295][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395144.901529][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.046259][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.047904][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.048325][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.048546][3716:3721] CHIP:EM: Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 39020 [1632395145.048668][3716:3721] CHIP:DMG: InvokeCommand = [1632395145.048728][3716:3721] CHIP:DMG: { [1632395145.048780][3716:3721] CHIP:DMG: CommandList = [1632395145.048839][3716:3721] CHIP:DMG: [ [1632395145.048894][3716:3721] CHIP:DMG: CommandDataElement = [1632395145.048966][3716:3721] CHIP:DMG: { [1632395145.049036][3716:3721] CHIP:DMG: CommandPath = [1632395145.049094][3716:3721] CHIP:DMG: { [1632395145.049151][3716:3721] CHIP:DMG: EndpointId = 0x0, [1632395145.049229][3716:3721] CHIP:DMG: ClusterId = 0x3e, [1632395145.049297][3716:3721] CHIP:DMG: CommandId = 0x8, [1632395145.049372][3716:3721] CHIP:DMG: }, [1632395145.049460][3716:3721] CHIP:DMG: [1632395145.049604][3716:3721] CHIP:DMG: CommandData = [1632395145.049737][3716:3721] CHIP:DMG: { [1632395145.049814][3716:3721] CHIP:DMG: 0x0 = 0, [1632395145.049901][3716:3721] CHIP:DMG: 0x1 = 1, [1632395145.049986][3716:3721] CHIP:DMG: 0x2 = [ [1632395145.050066][3716:3721] CHIP:DMG: [1632395145.050145][3716:3721] CHIP:DMG: ] [1632395145.050225][3716:3721] CHIP:DMG: }, [1632395145.050293][3716:3721] CHIP:DMG: }, [1632395145.050371][3716:3721] CHIP:DMG: [1632395145.050425][3716:3721] CHIP:DMG: ], [1632395145.050493][3716:3721] CHIP:DMG: [1632395145.050542][3716:3721] CHIP:DMG: } [1632395145.050645][3716:3721] CHIP:ZCL: Received Cluster Command: Cluster=3e Command=8 Endpoint=0 [1632395145.050713][3716:3721] CHIP:ZCL: NOCResponse: [1632395145.050760][3716:3721] CHIP:ZCL: StatusCode: 0 [1632395145.050809][3716:3721] CHIP:ZCL: FabricIndex: 1 [1632395145.050855][3716:3721] CHIP:ZCL: DebugText: 0 [1632395145.050908][3716:3721] CHIP:CTL: Device returned status 0 on receiving the NOC [1632395145.050959][3716:3721] CHIP:CTL: Operational credentials provisioned on device 0xffffe10491a8 [1632395145.055672][3716:3721] CHIP:TOO: Secure Pairing Success [1632395145.057779][3716:3721] CHIP:TOO: Pairing Success [1632395145.057917][3716:3721] CHIP:DMG: ICR moving to [Initialize] [1632395145.057967][3716:3721] CHIP:DMG: ICR moving to [AddCommand] [1632395145.058009][3716:3721] CHIP:IN: Marking old secure session for device 0x1890B1FABF106BFD as expired [1632395145.058041][3716:3721] CHIP:CTL: OnConnectionExpired was called for unknown device, ignoring it. [1632395145.058076][3716:3721] CHIP:IN: New secure session created for device 0x1890B1FABF106BFD, key 0!! [1632395145.058249][3716:3721] CHIP:IN: Secure message was encrypted: Msg ID 4 [1632395145.058289][3716:3721] CHIP:IN: Encrypted message 0xffff95403550 to 0x1890B1FABF106BFD of type 8 and protocolId 5 on exchange 39021. [1632395145.058323][3716:3721] CHIP:IN: Sending msg 0xffff95403550 to 0x1890B1FABF106BFD at utc time: 2959884 msec [1632395145.058352][3716:3721] CHIP:IN: Sending secure msg on generic transport [1632395145.058573][3716:3721] CHIP:IN: Secure msg send status ../../third_party/connectedhomeip/src/transport/raw/BLE.cpp:104: Success [1632395145.058609][3716:3721] CHIP:DMG: ICR moving to [ Sending] [1632395145.058666][3716:3721] CHIP:DMG: ICR moving to [Uninitiali] [1632395145.241329][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.242707][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.243001][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.338701][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.436342][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.437295][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.437878][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.439320][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.439576][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.533775][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.535519][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.535812][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.537326][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.537564][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.631025][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1632395145.632340][3716:3719] CHIP:DL: Indication received, conn = 0xffff8c046230 [1632395145.632567][3716:3721] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1632395145.632753][3716:3721] CHIP:EM: Received message of type 0x09 with vendorId 0x0000 and protocolId 0x0005 on exchange 39021 [1632395145.632861][3716:3721] CHIP:DMG: InvokeCommand = [1632395145.632912][3716:3721] CHIP:DMG: { [1632395145.632954][3716:3721] CHIP:DMG: CommandList = [1632395145.633006][3716:3721] CHIP:DMG: [ [1632395145.633052][3716:3721] CHIP:DMG: CommandDataElement = [1632395145.633117][3716:3721] CHIP:DMG: { [1632395145.633167][3716:3721] CHIP:DMG: CommandPath = [1632395145.633328][3716:3721] CHIP:DMG: { [1632395145.633393][3716:3721] CHIP:DMG: EndpointId = 0x0, [1632395145.633464][3716:3721] CHIP:DMG: ClusterId = 0x31, [1632395145.633608][3716:3721] CHIP:DMG: CommandId = 0x6, [1632395145.633673][3716:3721] CHIP:DMG: }, [1632395145.633805][3716:3721] CHIP:DMG: [1632395145.633862][3716:3721] CHIP:DMG: StatusElement = [1632395145.633928][3716:3721] CHIP:DMG: { [1632395145.634053][3716:3721] CHIP:DMG: GeneralCode = 0x1, [1632395145.634122][3716:3721] CHIP:DMG: ProtocolId = 0x5, [1632395145.634186][3716:3721] CHIP:DMG: protocolCode = 0x1, [1632395145.634250][3716:3721] CHIP:DMG: }, [1632395145.634319][3716:3721] CHIP:DMG: [1632395145.634372][3716:3721] CHIP:DMG: }, [1632395145.634495][3716:3721] CHIP:DMG: [1632395145.634544][3716:3721] CHIP:DMG: ], [1632395145.634602][3716:3721] CHIP:DMG: [1632395145.634644][3716:3721] CHIP:DMG: } [1632395145.634725][3716:3721] CHIP:ZCL: DefaultResponse: [1632395145.634767][3716:3721] CHIP:ZCL: Transaction: 0xaaaac07e4210 [1632395145.634864][3716:3721] CHIP:ZCL: status: EMBER_ZCL_STATUS_FAILURE (0x01) [1632395145.634914][3716:3721] CHIP:TOO: Default Failure Response: 0x01 [1632395145.635001][3716:3721] CHIP:DMG: ICR moving to [Uninitiali] [1632395145.635027][3716:3716] CHIP:TOO: Run command failure: ../../commands/pairing/PairingCommand.cpp:332: CHIP Error 0x000000AC: Internal error [1632395145.635587][3716:3716] CHIP:CTL: Shutting down the commissioner [1632395145.635647][3716:3716] CHIP:CTL: Shutting down the controller [1632395145.637141][3716:3716] CHIP:DL: Inet Layer shutdown [1632395145.637738][3716:3716] CHIP:DL: BLE layer shutdown [1632395145.637799][3716:3716] CHIP:IN: Clearing BLE pending packets. [1632395145.637943][3716:3716] CHIP:BLE: Auto-closing end point's BLE connection. [1632395145.637990][3716:3716] CHIP:DL: Closing BLE GATT connection (con 0xffff8c046230) [1632395145.638075][3716:3716] CHIP:DL: System Layer shutdown [1632395145.638410][3716:3719] CHIP:DL: BluezDisconnect peer=90:FD:9F:19:B7:75 ubuntu@ubuntu-desktop:~$