$ ./out/debug/chip-tool pairing ble-wifi 12344321 $SSID $PASS 20202021 3840 --ble-adapter 0 [1658188114.443172][10375:10375] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1658188114.444822][10375:10375] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1658188114.445615][10375:10375] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1658188114.446222][10375:10375] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1658188114.447731][10375:10375] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-bD3veK) [1658188114.448959][10375:10375] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658188114.449189][10375:10375] CHIP:DL: NVS set: chip-counters/reboot-count = 10 (0xA) [1658188114.449888][10375:10375] CHIP:DL: Failed to get Ethernet interface [1658188114.450324][10375:10375] CHIP:DL: Failed to reset Ethernet statistic counts [1658188114.450652][10375:10375] CHIP:DL: Got WiFi interface: wlp1s0 [1658188114.452094][10375:10375] CHIP:DL: Found the primary WiFi interface:wlp1s0 [1658188114.452402][10375:10375] CHIP:IN: UDP::Init bind&listen port=0 [1658188114.452589][10375:10375] CHIP:IN: UDP::Init bound to port=38982 [1658188114.452611][10375:10375] CHIP:IN: UDP::Init bind&listen port=0 [1658188114.452865][10375:10375] CHIP:IN: UDP::Init bound to port=57796 [1658188114.452885][10375:10375] CHIP:IN: BLEBase::Init - setting/overriding transport [1658188114.452895][10375:10375] CHIP:IN: TransportMgr initialized [1658188114.453089][10375:10375] CHIP:FP: Initializing FabricTable from persistent storage [1658188114.453470][10375:10375] CHIP:TS: Last Known Good Time: 2022-07-18T17:39:40 [1658188114.456954][10375:10375] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x260FC187F506344B, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658188114.457524][10375:10375] CHIP:FP: Fabric index 0x2 was retrieved from storage. Compressed FabricId 0xB426A51C70AC738C, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658188114.458046][10375:10375] CHIP:FP: Fabric index 0x3 was retrieved from storage. Compressed FabricId 0xBEA4F0E1DEB7653B, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1 [1658188114.469718][10375:10375] CHIP:ZCL: Using ZAP configuration... [1658188114.470435][10375:10375] CHIP:CTL: System State Initialized... [1658188114.470493][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188114.470521][10375:10375] CHIP:CTL: Setting attestation nonce to random value [1658188114.470554][10375:10375] CHIP:CTL: Setting CSR nonce to random value [1658188114.470607][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.470655][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.470674][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.470715][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.470731][10375:10375] CHIP:IN: TransportMgr initialized [1658188114.472869][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188114.472939][10375:10375] CHIP:CTL: Setting attestation nonce to random value [1658188114.473007][10375:10375] CHIP:CTL: Setting CSR nonce to random value [1658188114.487726][10375:10375] CHIP:CTL: Generating NOC [1658188114.489373][10375:10375] CHIP:FP: Validating NOC chain [1658188114.496790][10375:10375] CHIP:FP: NOC chain validation successful [1658188114.497021][10375:10375] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1658188114.497051][10375:10375] CHIP:TS: Last Known Good Time: 2022-07-18T17:39:40 [1658188114.497065][10375:10375] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658188114.497076][10375:10375] CHIP:TS: Retaining current Last Known Good Time [1658188114.517616][10375:10375] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1658188114.525361][10375:10375] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T17:39:40 [1658188114.530339][10375:10375] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1658188114.530400][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.530471][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.530491][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.530535][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.530551][10375:10375] CHIP:IN: TransportMgr initialized [1658188114.530658][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188114.530688][10375:10375] CHIP:CTL: Setting attestation nonce to random value [1658188114.530728][10375:10375] CHIP:CTL: Setting CSR nonce to random value [1658188114.533013][10375:10375] CHIP:CTL: Generating NOC [1658188114.534280][10375:10375] CHIP:FP: Validating NOC chain [1658188114.544745][10375:10375] CHIP:FP: NOC chain validation successful [1658188114.544993][10375:10375] CHIP:FP: Updated fabric at index: 0x2, Node ID: 0x000000000001B669 [1658188114.545023][10375:10375] CHIP:TS: Last Known Good Time: 2022-07-18T17:39:40 [1658188114.545035][10375:10375] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658188114.545043][10375:10375] CHIP:TS: Retaining current Last Known Good Time [1658188114.552555][10375:10375] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1658188114.560031][10375:10375] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T17:39:40 [1658188114.566112][10375:10375] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1658188114.566170][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.566239][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.566255][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.566296][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.566308][10375:10375] CHIP:IN: TransportMgr initialized [1658188114.566411][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188114.566437][10375:10375] CHIP:CTL: Setting attestation nonce to random value [1658188114.566474][10375:10375] CHIP:CTL: Setting CSR nonce to random value [1658188114.573056][10375:10375] CHIP:CTL: Generating NOC [1658188114.574556][10375:10375] CHIP:FP: Validating NOC chain [1658188114.577662][10375:10375] CHIP:FP: NOC chain validation successful [1658188114.577883][10375:10375] CHIP:FP: Updated fabric at index: 0x3, Node ID: 0x000000000001B669 [1658188114.577913][10375:10375] CHIP:TS: Last Known Good Time: 2022-07-18T17:39:40 [1658188114.577925][10375:10375] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1658188114.577934][10375:10375] CHIP:TS: Retaining current Last Known Good Time [1658188114.584181][10375:10375] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1658188114.589480][10375:10375] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-18T17:39:40 [1658188114.596111][10375:10375] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1658188114.596172][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.596246][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.596267][10375:10375] CHIP:IN: UDP::Init bind&listen port=5550 [1658188114.596314][10375:10375] CHIP:IN: UDP::Init bound to port=5550 [1658188114.596327][10375:10375] CHIP:IN: TransportMgr initialized [1658188114.671968][10375:10380] CHIP:DL: CHIP task running [1658188114.672346][10375:10380] CHIP:IN: SecureSession[0x7efeb4001a30]: Allocated Type:1 LSID:56321 [1658188114.672384][10375:10380] CHIP:SC: Assigned local session key ID 56321 [1658188114.672441][10375:10380] CHIP:SC: Including MRP parameters in PBKDF param request [1658188114.672498][10375:10380] CHIP:IN: Prepared unauthenticated message 0x7efec77fd3f0 to 0x0000000000000000 (0) of type 0x20 and protocolId (0, 0) on exchange 53507i with MessageCounter:215778387. [1658188114.672534][10375:10380] CHIP:IN: Sending unauthenticated msg 0x7efec77fd3f0 with MessageCounter:215778387 to 0x0000000000000000 at monotonic time: 00000000001D48F2 msec [1658188114.672559][10375:10380] CHIP:IN: Message appended to BLE send queue [1658188114.672572][10375:10380] CHIP:SC: Sent PBKDF param request [1658188114.672589][10375:10380] CHIP:CTL: Setting wifi credentials from parameters [1658188114.672601][10375:10380] CHIP:CTL: Setting attestation nonce to random value [1658188114.672622][10375:10380] CHIP:CTL: Setting CSR nonce to random value [1658188114.672645][10375:10380] CHIP:CTL: Commission called for node ID 0x0000000000BC5C01 [1658188114.673011][10375:10378] CHIP:DL: TRACE: Bus acquired for name C-2887 [1658188114.678651][10375:10381] CHIP:DL: TRACE: Bluez mainloop starting Thread [1658188114.696937][10375:10380] CHIP:DL: PlatformBlueZInit init success [1658188114.738571][10375:10378] CHIP:BLE: BLE removing known devices. [1658188114.743484][10375:10378] CHIP:BLE: BLE initiating scan. [1658188115.572593][10375:10380] CHIP:DL: Long dispatch time: 900 ms, for event type 2 [1658188115.665574][10375:10378] CHIP:BLE: Device 44:9C:A2:91:73:B3 does not look like a CHIP device. [1658188115.746144][10375:10378] CHIP:BLE: Device 56:DA:A2:17:2F:B3 does not look like a CHIP device. [1658188115.872645][10375:10378] CHIP:BLE: Device 3A:95:B6:12:08:82 does not look like a CHIP device. [1658188115.973886][10375:10378] CHIP:BLE: Device 44:9C:A2:91:73:B3 does not look like a CHIP device. [1658188115.981082][10375:10378] CHIP:BLE: Device 3A:95:B6:12:08:82 does not look like a CHIP device. [1658188115.993256][10375:10378] CHIP:BLE: New device scanned: C8:F1:E5:46:9F:1E [1658188115.993301][10375:10378] CHIP:BLE: Device discriminator match. Attempting to connect. [1658188116.006700][10375:10378] CHIP:BLE: Scan complete notification without an active scan. [1658188117.099556][10375:10378] CHIP:DL: ConnectDevice complete [1658188118.027994][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.028037][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.028051][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.032469][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.032739][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.032758][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.032880][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.032899][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033003][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033022][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033227][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service0006 [1658188118.033246][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033401][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service0006 [1658188118.033419][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033519][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service0006 [1658188118.033537][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033643][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.033662][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033764][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.033863][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.033961][10375:10378] CHIP:DL: Char1 /org/bluez/hci1/dev_C6_A0_3B_F9_E8_12/service000a [1658188118.033978][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034082][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service0006 [1658188118.034100][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034115][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service0006 [1658188118.034125][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034138][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service0006 [1658188118.034148][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034462][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034482][10375:10378] CHIP:DL: Char1 /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E/service000a [1658188118.034509][10375:10378] CHIP:DL: New BLE connection 0x7efebc040230, device C8:F1:E5:46:9F:1E, path /org/bluez/hci0/dev_C8_F1_E5_46_9F_1E [1658188118.034671][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1658188118.034722][10375:10380] CHIP:IN: BleConnectionComplete: endPoint 0x56550811fae0 [1658188118.399573][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188118.498693][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1658188118.498746][10375:10380] CHIP:BLE: subscribe complete, ep = 0x56550811fae0 [1658188118.502629][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188118.502872][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188118.502905][10375:10380] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1658188118.502916][10375:10380] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1658188118.502925][10375:10380] CHIP:BLE: local and remote recv window size = 5 [1658188118.502997][10375:10380] CHIP:IN: BLE EndPoint 0x56550811fae0 Connection Complete [1658188118.597227][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188118.652367][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188118.652632][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188118.652739][10375:10380] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:255993114 on exchange 53507i [1658188118.652766][10375:10380] CHIP:EM: Found matching exchange: 53507i, Delegate: 0x7efeb4000bb0 [1658188118.652790][10375:10380] CHIP:SC: Received PBKDF param response [1658188118.652821][10375:10380] CHIP:SC: Peer assigned session ID 13541 [1658188118.652842][10375:10380] CHIP:SC: Found MRP parameters in the message [1658188118.658545][10375:10380] CHIP:IN: Prepared unauthenticated message 0x7efec77fcfc0 to 0x0000000000000000 (0) of type 0x22 and protocolId (0, 0) on exchange 53507i with MessageCounter:215778388. [1658188118.658590][10375:10380] CHIP:IN: Sending unauthenticated msg 0x7efec77fcfc0 with MessageCounter:215778388 to 0x0000000000000000 at monotonic time: 00000000001D5884 msec [1658188118.658953][10375:10380] CHIP:SC: Sent spake2p msg1 [1658188118.747241][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188119.601669][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188119.601970][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188119.602076][10375:10380] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:255993115 on exchange 53507i [1658188119.602097][10375:10380] CHIP:EM: Found matching exchange: 53507i, Delegate: 0x7efeb4000bb0 [1658188119.602121][10375:10380] CHIP:SC: Received spake2p msg2 [1658188119.603802][10375:10380] CHIP:IN: Prepared unauthenticated message 0x7efec77fd0c0 to 0x0000000000000000 (0) of type 0x24 and protocolId (0, 0) on exchange 53507i with MessageCounter:215778389. [1658188119.603835][10375:10380] CHIP:IN: Sending unauthenticated msg 0x7efec77fd0c0 with MessageCounter:215778389 to 0x0000000000000000 at monotonic time: 00000000001D5C35 msec [1658188119.604580][10375:10380] CHIP:SC: Sent spake2p msg3 [1658188119.697908][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188119.749584][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188119.749850][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188119.749958][10375:10380] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:255993116 on exchange 53507i [1658188119.749980][10375:10380] CHIP:EM: Found matching exchange: 53507i, Delegate: 0x7efeb4000bb0 [1658188119.750058][10375:10380] CHIP:SC: SecureSession[0x7efeb4001a30]: Moving from state 'kEstablishing' --> 'kActive' [1658188119.750078][10375:10380] CHIP:IN: SecureSession[0x7efeb4001a30]: Activated - Type:1 LSID:56321 [1658188119.750088][10375:10380] CHIP:IN: New secure session activated for device , LSID:56321 PSID:13541! [1658188119.750110][10375:10380] CHIP:CTL: Remote device completed SPAKE2+ handshake [1658188119.750123][10375:10380] CHIP:TOO: Pairing Success [1658188119.750133][10375:10380] CHIP:TOO: PASE establishment successful [1658188119.750154][10375:10380] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1658188119.750173][10375:10380] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1658188119.750186][10375:10380] CHIP:CTL: Sending request for commissioning information [1658188119.750216][10375:10380] CHIP:DMG: SendReadRequest ReadClient[0x7efebc040890]: Sending Read Request [1658188119.750350][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc940 to 0xFFFFFFFB00000000 (0) of type 0x2 and protocolId (0, 1) on exchange 53508i with MessageCounter:256988183. [1658188119.750414][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc940 with MessageCounter:256988183 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D5CC8 msec [1658188119.750813][10375:10380] CHIP:DMG: MoveToState ReadClient[0x7efebc040890]: Moving to [AwaitingIn] [1658188119.847220][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188119.904404][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188119.904667][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188119.949678][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188119.949931][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188119.950045][10375:10380] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:25702975 on exchange 53508i [1658188119.950068][10375:10380] CHIP:EM: Found matching exchange: 53508i, Delegate: 0x7efebc040890 [1658188119.950165][10375:10380] CHIP:DMG: ReportDataMessage = [1658188119.950181][10375:10380] CHIP:DMG: { [1658188119.950191][10375:10380] CHIP:DMG: AttributeReportIBs = [1658188119.950214][10375:10380] CHIP:DMG: [ [1658188119.950227][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.950250][10375:10380] CHIP:DMG: { [1658188119.950265][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.950282][10375:10380] CHIP:DMG: { [1658188119.950301][10375:10380] CHIP:DMG: DataVersion = 0xc1510a72, [1658188119.950317][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.950334][10375:10380] CHIP:DMG: { [1658188119.950352][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.950370][10375:10380] CHIP:DMG: Cluster = 0x31, [1658188119.950389][10375:10380] CHIP:DMG: Attribute = 0x0000_0003, [1658188119.950407][10375:10380] CHIP:DMG: } [1658188119.950426][10375:10380] CHIP:DMG: [1658188119.950445][10375:10380] CHIP:DMG: Data = 30, [1658188119.950460][10375:10380] CHIP:DMG: }, [1658188119.950479][10375:10380] CHIP:DMG: [1658188119.950493][10375:10380] CHIP:DMG: }, [1658188119.950519][10375:10380] CHIP:DMG: [1658188119.950533][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.950554][10375:10380] CHIP:DMG: { [1658188119.950569][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.950582][10375:10380] CHIP:DMG: { [1658188119.950599][10375:10380] CHIP:DMG: DataVersion = 0x155d7fe1, [1658188119.950612][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.950629][10375:10380] CHIP:DMG: { [1658188119.950646][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.950664][10375:10380] CHIP:DMG: Cluster = 0x28, [1658188119.950683][10375:10380] CHIP:DMG: Attribute = 0x0000_0004, [1658188119.950698][10375:10380] CHIP:DMG: } [1658188119.950717][10375:10380] CHIP:DMG: [1658188119.950734][10375:10380] CHIP:DMG: Data = 32769, [1658188119.950749][10375:10380] CHIP:DMG: }, [1658188119.950767][10375:10380] CHIP:DMG: [1658188119.950781][10375:10380] CHIP:DMG: }, [1658188119.950807][10375:10380] CHIP:DMG: [1658188119.950822][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.950843][10375:10380] CHIP:DMG: { [1658188119.950858][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.950874][10375:10380] CHIP:DMG: { [1658188119.950892][10375:10380] CHIP:DMG: DataVersion = 0x155d7fe1, [1658188119.950907][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.950923][10375:10380] CHIP:DMG: { [1658188119.950940][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.950957][10375:10380] CHIP:DMG: Cluster = 0x28, [1658188119.950975][10375:10380] CHIP:DMG: Attribute = 0x0000_0002, [1658188119.950991][10375:10380] CHIP:DMG: } [1658188119.951009][10375:10380] CHIP:DMG: [1658188119.951027][10375:10380] CHIP:DMG: Data = 65521, [1658188119.951041][10375:10380] CHIP:DMG: }, [1658188119.951059][10375:10380] CHIP:DMG: [1658188119.951073][10375:10380] CHIP:DMG: }, [1658188119.951099][10375:10380] CHIP:DMG: [1658188119.951114][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.951136][10375:10380] CHIP:DMG: { [1658188119.951151][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.951166][10375:10380] CHIP:DMG: { [1658188119.951182][10375:10380] CHIP:DMG: DataVersion = 0xeb718ac8, [1658188119.951197][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.951213][10375:10380] CHIP:DMG: { [1658188119.951231][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.951248][10375:10380] CHIP:DMG: Cluster = 0x30, [1658188119.951263][10375:10380] CHIP:DMG: Attribute = 0x0000_0003, [1658188119.951276][10375:10380] CHIP:DMG: } [1658188119.951290][10375:10380] CHIP:DMG: [1658188119.951305][10375:10380] CHIP:DMG: Data = 0, [1658188119.951316][10375:10380] CHIP:DMG: }, [1658188119.951331][10375:10380] CHIP:DMG: [1658188119.951342][10375:10380] CHIP:DMG: }, [1658188119.951364][10375:10380] CHIP:DMG: [1658188119.951375][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.951392][10375:10380] CHIP:DMG: { [1658188119.951404][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.951416][10375:10380] CHIP:DMG: { [1658188119.951429][10375:10380] CHIP:DMG: DataVersion = 0xeb718ac8, [1658188119.951444][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.951457][10375:10380] CHIP:DMG: { [1658188119.951471][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.951485][10375:10380] CHIP:DMG: Cluster = 0x30, [1658188119.951499][10375:10380] CHIP:DMG: Attribute = 0x0000_0002, [1658188119.951511][10375:10380] CHIP:DMG: } [1658188119.951597][10375:10380] CHIP:DMG: [1658188119.951614][10375:10380] CHIP:DMG: Data = 0, [1658188119.951626][10375:10380] CHIP:DMG: }, [1658188119.951641][10375:10380] CHIP:DMG: [1658188119.951652][10375:10380] CHIP:DMG: }, [1658188119.951677][10375:10380] CHIP:DMG: [1658188119.951688][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.951708][10375:10380] CHIP:DMG: { [1658188119.951719][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.951732][10375:10380] CHIP:DMG: { [1658188119.951745][10375:10380] CHIP:DMG: DataVersion = 0xeb718ac8, [1658188119.951757][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.951770][10375:10380] CHIP:DMG: { [1658188119.951783][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.951798][10375:10380] CHIP:DMG: Cluster = 0x30, [1658188119.951812][10375:10380] CHIP:DMG: Attribute = 0x0000_0001, [1658188119.951825][10375:10380] CHIP:DMG: } [1658188119.951839][10375:10380] CHIP:DMG: [1658188119.951852][10375:10380] CHIP:DMG: Data = [1658188119.951864][10375:10380] CHIP:DMG: { [1658188119.951879][10375:10380] CHIP:DMG: 0x0 = 60, [1658188119.951893][10375:10380] CHIP:DMG: 0x1 = 900, [1658188119.952179][10375:10380] CHIP:DMG: }, [1658188119.952196][10375:10380] CHIP:DMG: }, [1658188119.952229][10375:10380] CHIP:DMG: [1658188119.952286][10375:10380] CHIP:DMG: }, [1658188119.952362][10375:10380] CHIP:DMG: [1658188119.952378][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.952397][10375:10380] CHIP:DMG: { [1658188119.952408][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.952421][10375:10380] CHIP:DMG: { [1658188119.952435][10375:10380] CHIP:DMG: DataVersion = 0xeb718ac8, [1658188119.952447][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.952459][10375:10380] CHIP:DMG: { [1658188119.952473][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.952488][10375:10380] CHIP:DMG: Cluster = 0x30, [1658188119.952502][10375:10380] CHIP:DMG: Attribute = 0x0000_0000, [1658188119.952514][10375:10380] CHIP:DMG: } [1658188119.952528][10375:10380] CHIP:DMG: [1658188119.952543][10375:10380] CHIP:DMG: Data = 0, [1658188119.952554][10375:10380] CHIP:DMG: }, [1658188119.952569][10375:10380] CHIP:DMG: [1658188119.952579][10375:10380] CHIP:DMG: }, [1658188119.952603][10375:10380] CHIP:DMG: [1658188119.952614][10375:10380] CHIP:DMG: AttributeReportIB = [1658188119.952632][10375:10380] CHIP:DMG: { [1658188119.952643][10375:10380] CHIP:DMG: AttributeDataIB = [1658188119.952655][10375:10380] CHIP:DMG: { [1658188119.952668][10375:10380] CHIP:DMG: DataVersion = 0xc1510a72, [1658188119.952680][10375:10380] CHIP:DMG: AttributePathIB = [1658188119.952693][10375:10380] CHIP:DMG: { [1658188119.952706][10375:10380] CHIP:DMG: Endpoint = 0x0, [1658188119.952720][10375:10380] CHIP:DMG: Cluster = 0x31, [1658188119.952735][10375:10380] CHIP:DMG: Attribute = 0x0000_FFFC, [1658188119.952746][10375:10380] CHIP:DMG: } [1658188119.952761][10375:10380] CHIP:DMG: [1658188119.952775][10375:10380] CHIP:DMG: Data = 1, [1658188119.952786][10375:10380] CHIP:DMG: }, [1658188119.952801][10375:10380] CHIP:DMG: [1658188119.952812][10375:10380] CHIP:DMG: }, [1658188119.952828][10375:10380] CHIP:DMG: [1658188119.952837][10375:10380] CHIP:DMG: ], [1658188119.952897][10375:10380] CHIP:DMG: [1658188119.952909][10375:10380] CHIP:DMG: SuppressResponse = true, [1658188119.952921][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188119.952930][10375:10380] CHIP:DMG: } [1658188119.953948][10375:10380] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1658188119.953971][10375:10380] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1658188119.953989][10375:10380] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1658188119.954006][10375:10380] CHIP:CTL: Arming failsafe (60 seconds) [1658188119.954054][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188119.954074][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188119.954143][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc6d0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53509i with MessageCounter:256988184. [1658188119.954173][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc6d0 with MessageCounter:256988184 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D5D94 msec [1658188119.954547][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188120.047052][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188120.102264][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.102523][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.102660][10375:10380] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:25702976 on exchange 53509i [1658188120.102686][10375:10380] CHIP:EM: Found matching exchange: 53509i, Delegate: 0x7efeb401a1a0 [1658188120.102708][10375:10380] CHIP:DMG: ICR moving to [ResponseRe] [1658188120.102753][10375:10380] CHIP:DMG: InvokeResponseMessage = [1658188120.102767][10375:10380] CHIP:DMG: { [1658188120.102779][10375:10380] CHIP:DMG: suppressResponse = false, [1658188120.102790][10375:10380] CHIP:DMG: InvokeResponseIBs = [1658188120.102809][10375:10380] CHIP:DMG: [ [1658188120.102820][10375:10380] CHIP:DMG: InvokeResponseIB = [1658188120.102839][10375:10380] CHIP:DMG: { [1658188120.102850][10375:10380] CHIP:DMG: CommandDataIB = [1658188120.102864][10375:10380] CHIP:DMG: { [1658188120.102875][10375:10380] CHIP:DMG: CommandPathIB = [1658188120.102889][10375:10380] CHIP:DMG: { [1658188120.102903][10375:10380] CHIP:DMG: EndpointId = 0x0, [1658188120.102918][10375:10380] CHIP:DMG: ClusterId = 0x30, [1658188120.102931][10375:10380] CHIP:DMG: CommandId = 0x1, [1658188120.102943][10375:10380] CHIP:DMG: }, [1658188120.102957][10375:10380] CHIP:DMG: [1658188120.102970][10375:10380] CHIP:DMG: CommandFields = [1658188120.102983][10375:10380] CHIP:DMG: { [1658188120.102997][10375:10380] CHIP:DMG: 0x0 = 0, [1658188120.103013][10375:10380] CHIP:DMG: 0x1 = "", [1658188120.103027][10375:10380] CHIP:DMG: }, [1658188120.103037][10375:10380] CHIP:DMG: }, [1658188120.103054][10375:10380] CHIP:DMG: [1658188120.103069][10375:10380] CHIP:DMG: }, [1658188120.103090][10375:10380] CHIP:DMG: [1658188120.103101][10375:10380] CHIP:DMG: ], [1658188120.103119][10375:10380] CHIP:DMG: [1658188120.103131][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188120.103140][10375:10380] CHIP:DMG: }, [1658188120.103195][10375:10380] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1658188120.103225][10375:10380] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1658188120.103246][10375:10380] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1658188120.103258][10375:10380] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1658188120.103274][10375:10380] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1658188120.103283][10375:10380] CHIP:CTL: Setting Regulatory Config [1658188120.103291][10375:10380] CHIP:CTL: Device does not support configurable regulatory location [1658188120.103343][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188120.103365][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188120.103426][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc1c0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53510i with MessageCounter:256988185. [1658188120.103455][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc1c0 with MessageCounter:256988185 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D5E29 msec [1658188120.103784][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188120.103837][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188120.197029][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188120.249200][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.249428][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.249559][10375:10380] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:25702977 on exchange 53510i [1658188120.249582][10375:10380] CHIP:EM: Found matching exchange: 53510i, Delegate: 0x7efeb401a940 [1658188120.249604][10375:10380] CHIP:DMG: ICR moving to [ResponseRe] [1658188120.249645][10375:10380] CHIP:DMG: InvokeResponseMessage = [1658188120.249659][10375:10380] CHIP:DMG: { [1658188120.249670][10375:10380] CHIP:DMG: suppressResponse = false, [1658188120.249681][10375:10380] CHIP:DMG: InvokeResponseIBs = [1658188120.249700][10375:10380] CHIP:DMG: [ [1658188120.249711][10375:10380] CHIP:DMG: InvokeResponseIB = [1658188120.249730][10375:10380] CHIP:DMG: { [1658188120.249742][10375:10380] CHIP:DMG: CommandDataIB = [1658188120.249771][10375:10380] CHIP:DMG: { [1658188120.249791][10375:10380] CHIP:DMG: CommandPathIB = [1658188120.249805][10375:10380] CHIP:DMG: { [1658188120.249823][10375:10380] CHIP:DMG: EndpointId = 0x0, [1658188120.249842][10375:10380] CHIP:DMG: ClusterId = 0x30, [1658188120.249859][10375:10380] CHIP:DMG: CommandId = 0x3, [1658188120.249874][10375:10380] CHIP:DMG: }, [1658188120.249890][10375:10380] CHIP:DMG: [1658188120.249902][10375:10380] CHIP:DMG: CommandFields = [1658188120.249915][10375:10380] CHIP:DMG: { [1658188120.249930][10375:10380] CHIP:DMG: 0x0 = 0, [1658188120.249946][10375:10380] CHIP:DMG: 0x1 = "", [1658188120.249960][10375:10380] CHIP:DMG: }, [1658188120.249971][10375:10380] CHIP:DMG: }, [1658188120.249988][10375:10380] CHIP:DMG: [1658188120.249998][10375:10380] CHIP:DMG: }, [1658188120.250016][10375:10380] CHIP:DMG: [1658188120.250025][10375:10380] CHIP:DMG: ], [1658188120.250044][10375:10380] CHIP:DMG: [1658188120.250055][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188120.250064][10375:10380] CHIP:DMG: }, [1658188120.250982][10375:10380] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1658188120.251064][10375:10380] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1658188120.251088][10375:10380] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1658188120.251103][10375:10380] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1658188120.251126][10375:10380] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1658188120.251140][10375:10380] CHIP:CTL: Sending request for PAI certificate [1658188120.251152][10375:10380] CHIP:CTL: Sending Certificate Chain request to 0x7efeb4000b60 device [1658188120.251212][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188120.251235][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188120.251326][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc0f0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53511i with MessageCounter:256988186. [1658188120.251357][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc0f0 with MessageCounter:256988186 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D5EBD msec [1658188120.251805][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188120.251860][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188120.347087][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188120.405199][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.405432][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.464177][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.464415][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.499061][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.499304][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.499415][10375:10380] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:25702978 on exchange 53511i [1658188120.499436][10375:10380] CHIP:EM: Found matching exchange: 53511i, Delegate: 0x7efeb401a1a0 [1658188120.499462][10375:10380] CHIP:DMG: ICR moving to [ResponseRe] [1658188120.499504][10375:10380] CHIP:DMG: InvokeResponseMessage = [1658188120.499517][10375:10380] CHIP:DMG: { [1658188120.499529][10375:10380] CHIP:DMG: suppressResponse = false, [1658188120.499541][10375:10380] CHIP:DMG: InvokeResponseIBs = [1658188120.499559][10375:10380] CHIP:DMG: [ [1658188120.499575][10375:10380] CHIP:DMG: InvokeResponseIB = [1658188120.499595][10375:10380] CHIP:DMG: { [1658188120.499610][10375:10380] CHIP:DMG: CommandDataIB = [1658188120.499625][10375:10380] CHIP:DMG: { [1658188120.499636][10375:10380] CHIP:DMG: CommandPathIB = [1658188120.499649][10375:10380] CHIP:DMG: { [1658188120.500190][10375:10380] CHIP:DMG: EndpointId = 0x0, [1658188120.500224][10375:10380] CHIP:DMG: ClusterId = 0x3e, [1658188120.500239][10375:10380] CHIP:DMG: CommandId = 0x3, [1658188120.500251][10375:10380] CHIP:DMG: }, [1658188120.500271][10375:10380] CHIP:DMG: [1658188120.500288][10375:10380] CHIP:DMG: CommandFields = [1658188120.500306][10375:10380] CHIP:DMG: { [1658188120.500324][10375:10380] CHIP:DMG: 0x0 = [ [1658188120.500501][10375:10380] CHIP:DMG: 0x30, 0x82, 0x1, 0xcb, 0x30, 0x82, 0x1, 0x71, 0xa0, 0x3, 0x2, 0x1, 0x2, 0x2, 0x8, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0xa, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x4, 0x3, 0x2, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0xf, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0xd, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0xf, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x3, 0x1, 0x7, 0x3, 0x42, 0x0, 0x4, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0xc, 0x8c, 0x87, 0x6d, 0x3, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x5, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x9, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0xb, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x6, 0x3, 0x55, 0x1d, 0x13, 0x1, 0x1, 0xff, 0x4, 0x8, 0x30, 0x6, 0x1, 0x1, 0xff, 0x2, 0x1, [1658188120.500579][10375:10380] CHIP:DMG: ] [1658188120.500598][10375:10380] CHIP:DMG: }, [1658188120.500612][10375:10380] CHIP:DMG: }, [1658188120.500633][10375:10380] CHIP:DMG: [1658188120.500646][10375:10380] CHIP:DMG: }, [1658188120.500668][10375:10380] CHIP:DMG: [1658188120.500681][10375:10380] CHIP:DMG: ], [1658188120.500704][10375:10380] CHIP:DMG: [1658188120.500720][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188120.500733][10375:10380] CHIP:DMG: }, [1658188120.500795][10375:10380] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1658188120.500826][10375:10380] CHIP:CTL: Received certificate chain from the device [1658188120.500852][10375:10380] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1658188120.500873][10375:10380] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1658188120.500895][10375:10380] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1658188120.500908][10375:10380] CHIP:CTL: Sending request for DAC certificate [1658188120.500918][10375:10380] CHIP:CTL: Sending Certificate Chain request to 0x7efeb4000b60 device [1658188120.500974][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188120.500998][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188120.501085][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc120 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53512i with MessageCounter:256988187. [1658188120.501119][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc120 with MessageCounter:256988187 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D5FB7 msec [1658188120.501873][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188120.501935][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188120.597137][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188120.653887][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.654185][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.704035][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.704278][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.749836][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188120.750089][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188120.750203][10375:10380] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:25702979 on exchange 53512i [1658188120.750241][10375:10380] CHIP:EM: Found matching exchange: 53512i, Delegate: 0x7efeb401a940 [1658188120.750268][10375:10380] CHIP:DMG: ICR moving to [ResponseRe] [1658188120.750322][10375:10380] CHIP:DMG: InvokeResponseMessage = [1658188120.750341][10375:10380] CHIP:DMG: { [1658188120.750354][10375:10380] CHIP:DMG: suppressResponse = false, [1658188120.750369][10375:10380] CHIP:DMG: InvokeResponseIBs = [1658188120.750391][10375:10380] CHIP:DMG: [ [1658188120.750405][10375:10380] CHIP:DMG: InvokeResponseIB = [1658188120.750427][10375:10380] CHIP:DMG: { [1658188120.750449][10375:10380] CHIP:DMG: CommandDataIB = [1658188120.750467][10375:10380] CHIP:DMG: { [1658188120.750482][10375:10380] CHIP:DMG: CommandPathIB = [1658188120.750500][10375:10380] CHIP:DMG: { [1658188120.750519][10375:10380] CHIP:DMG: EndpointId = 0x0, [1658188120.750537][10375:10380] CHIP:DMG: ClusterId = 0x3e, [1658188120.750553][10375:10380] CHIP:DMG: CommandId = 0x3, [1658188120.750568][10375:10380] CHIP:DMG: }, [1658188120.750586][10375:10380] CHIP:DMG: [1658188120.750602][10375:10380] CHIP:DMG: CommandFields = [1658188120.750618][10375:10380] CHIP:DMG: { [1658188120.750635][10375:10380] CHIP:DMG: 0x0 = [ [1658188120.750765][10375:10380] CHIP:DMG: 0x30, 0x82, 0x1, 0xe7, 0x30, 0x82, 0x1, 0x8e, 0xa0, 0x3, 0x2, 0x1, 0x2, 0x2, 0x8, 0x69, 0xcd, 0xf1, 0xd, 0xe9, 0xe5, 0x4e, 0xd1, 0x30, 0xa, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x4, 0x3, 0x2, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0xd, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0xf, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x6, 0x3, 0x55, 0x4, 0x3, 0xc, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x1, 0xc, 0x4, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x6, 0xa, 0x2b, 0x6, 0x1, 0x4, 0x1, 0x82, 0xa2, 0x7c, 0x2, 0x2, 0xc, 0x4, 0x38, 0x30, 0x30, 0x31, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x3, 0x1, 0x7, 0x3, 0x42, 0x0, 0x4, 0x46, 0x3a, 0xc6, 0x93, 0x42, 0x91, 0xa, 0xe, 0x55, 0x88, 0xfc, 0x6f, 0xf5, 0x6b, 0xb6, 0x3e, 0x62, 0xec, 0xce, 0xcb, 0x14, 0x8f, 0x7d, 0x4e, 0xb0, 0x3e, 0xe5, 0x52, 0x60, 0x14, 0x15, 0x76, 0x7d, 0x16, 0xa5, 0xc6, 0x63, 0xf7, 0x93, 0xe4, 0x91, 0x23, 0x26, 0xb, 0x82, 0x97, 0xa7, 0xcd, 0x7e, 0x7c, 0xfc, 0x [1658188120.750826][10375:10380] CHIP:DMG: ] [1658188120.750843][10375:10380] CHIP:DMG: }, [1658188120.750857][10375:10380] CHIP:DMG: }, [1658188120.750877][10375:10380] CHIP:DMG: [1658188120.750891][10375:10380] CHIP:DMG: }, [1658188120.750911][10375:10380] CHIP:DMG: [1658188120.750924][10375:10380] CHIP:DMG: ], [1658188120.750947][10375:10380] CHIP:DMG: [1658188120.750963][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188120.750976][10375:10380] CHIP:DMG: }, [1658188120.751034][10375:10380] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1658188120.751076][10375:10380] CHIP:CTL: Received certificate chain from the device [1658188120.751103][10375:10380] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1658188120.751123][10375:10380] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1658188120.751144][10375:10380] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1658188120.751158][10375:10380] CHIP:CTL: Sending Attestation Request to the device. [1658188120.751169][10375:10380] CHIP:CTL: Sending Attestation request to 0x7efeb4000b60 device [1658188120.751225][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188120.751249][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188120.751317][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fc100 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53513i with MessageCounter:256988188. [1658188120.751349][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fc100 with MessageCounter:256988188 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D60B1 msec [1658188120.754508][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188120.754561][10375:10380] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1658188120.754611][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188120.847282][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188121.053680][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188121.053995][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188121.104014][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188121.104323][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188121.153802][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188121.154046][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188121.154159][10375:10380] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:25702980 on exchange 53513i [1658188121.154180][10375:10380] CHIP:EM: Found matching exchange: 53513i, Delegate: 0x7efeb401a1a0 [1658188121.154206][10375:10380] CHIP:DMG: ICR moving to [ResponseRe] [1658188121.154249][10375:10380] CHIP:DMG: InvokeResponseMessage = [1658188121.154282][10375:10380] CHIP:DMG: { [1658188121.154299][10375:10380] CHIP:DMG: suppressResponse = false, [1658188121.154315][10375:10380] CHIP:DMG: InvokeResponseIBs = [1658188121.154339][10375:10380] CHIP:DMG: [ [1658188121.154354][10375:10380] CHIP:DMG: InvokeResponseIB = [1658188121.154377][10375:10380] CHIP:DMG: { [1658188121.154391][10375:10380] CHIP:DMG: CommandDataIB = [1658188121.154409][10375:10380] CHIP:DMG: { [1658188121.154424][10375:10380] CHIP:DMG: CommandPathIB = [1658188121.154441][10375:10380] CHIP:DMG: { [1658188121.154459][10375:10380] CHIP:DMG: EndpointId = 0x0, [1658188121.154477][10375:10380] CHIP:DMG: ClusterId = 0x3e, [1658188121.154494][10375:10380] CHIP:DMG: CommandId = 0x1, [1658188121.154537][10375:10380] CHIP:DMG: }, [1658188121.154557][10375:10380] CHIP:DMG: [1658188121.154574][10375:10380] CHIP:DMG: CommandFields = [1658188121.154590][10375:10380] CHIP:DMG: { [1658188121.154608][10375:10380] CHIP:DMG: 0x0 = [ [1658188121.154746][10375:10380] CHIP:DMG: 0x15, 0x31, 0x1, 0x1d, 0x2, 0x30, 0x82, 0x2, 0x19, 0x6, 0x9, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0xd, 0x1, 0x7, 0x2, 0xa0, 0x82, 0x2, 0xa, 0x30, 0x82, 0x2, 0x6, 0x2, 0x1, 0x3, 0x31, 0xd, 0x30, 0xb, 0x6, 0x9, 0x60, 0x86, 0x48, 0x1, 0x65, 0x3, 0x4, 0x2, 0x1, 0x30, 0x82, 0x1, 0x71, 0x6, 0x9, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0xd, 0x1, 0x7, 0x1, 0xa0, 0x82, 0x1, 0x62, 0x4, 0x82, 0x1, 0x5e, 0x15, 0x24, 0x0, 0x1, 0x25, 0x1, 0xf1, 0xff, 0x36, 0x2, 0x5, 0x0, 0x80, 0x5, 0x1, 0x80, 0x5, 0x2, 0x80, 0x5, 0x3, 0x80, 0x5, 0x4, 0x80, 0x5, 0x5, 0x80, 0x5, 0x6, 0x80, 0x5, 0x7, 0x80, 0x5, 0x8, 0x80, 0x5, 0x9, 0x80, 0x5, 0xa, 0x80, 0x5, 0xb, 0x80, 0x5, 0xc, 0x80, 0x5, 0xd, 0x80, 0x5, 0xe, 0x80, 0x5, 0xf, 0x80, 0x5, 0x10, 0x80, 0x5, 0x11, 0x80, 0x5, 0x12, 0x80, 0x5, 0x13, 0x80, 0x5, 0x14, 0x80, 0x5, 0x15, 0x80, 0x5, 0x16, 0x80, 0x5, 0x17, 0x80, 0x5, 0x18, 0x80, 0x5, 0x19, 0x80, 0x5, 0x1a, 0x80, 0x5, 0x1b, 0x80, 0x5, 0x1c, 0x80, 0x5, 0x1d, 0x80, 0x5, 0x1e, 0x80, 0x5, 0x1f, 0x80, 0x5, 0x20, 0x80, 0x5, 0x21, 0x80, 0x5, 0x22, 0x80, 0x5, 0x23, 0x80, 0x5, 0x24, 0x80, 0x5, 0x25, 0x80, 0x5, 0x26, 0x80, 0x5, 0x27, 0x80, 0x5, 0x28, 0x80, 0x5, 0x29, 0x80, 0x5, 0x2a, 0x80, 0x5, 0x2b, 0x80, 0x5, 0x2c, 0x80, 0x5, 0x2d, 0x80, 0x5, 0x2e, 0x80, 0x5, 0x2f, 0x80, 0x5, 0x30, 0x80, 0x5, 0x31, 0x80, 0x5, 0x32, 0x80, 0x5, 0x33, 0x80, 0x5, 0x34, 0x80, 0x5, 0x35, 0x80, 0x5, 0x36, 0x80, 0x5, 0x37, 0x80, 0x5, 0x38, 0x80, 0x5, 0x39, 0x80, 0x5, 0x3a, 0x80, 0x5, 0x3b, 0x80, 0x5, 0x3c, 0x80, 0x5, 0x3d, 0x80, 0x5, 0x3e, 0x80, 0x5, 0x3f, 0x80, 0x5, 0x40, 0x80, 0x5, 0x41, 0x80, 0x5, 0x42, 0x80, 0x5, 0x43, 0x80, 0x5, 0x44, 0x80, 0x5, 0x45, 0x80, 0x5, 0x46, 0x80, 0x5, 0x47, 0x80, 0x5, 0x48, 0x80, 0x5, 0x49, 0x80, 0x5, 0x4a, 0x80, 0x5, 0 [1658188121.154807][10375:10380] CHIP:DMG: ] [1658188121.154824][10375:10380] CHIP:DMG: 0x1 = [ [1658188121.154862][10375:10380] CHIP:DMG: 0x76, 0xa7, 0x90, 0x7d, 0x79, 0x66, 0x53, 0xaf, 0x82, 0x5, 0x3e, 0xd0, 0xd8, 0x95, 0x5d, 0x8, 0xd7, 0x32, 0xe4, 0xf6, 0xb1, 0xdc, 0x1c, 0xcf, 0xf0, 0x6e, 0xd5, 0x8c, 0xf4, 0x5e, 0xb2, 0x99, 0xc3, 0x88, 0xbc, 0x5d, 0x58, 0x23, 0xca, 0x19, 0x39, 0xe5, 0x31, 0x91, 0xff, 0xd2, 0x93, 0x48, 0xd7, 0x8e, 0xdc, 0x5e, 0x72, 0x69, 0xc6, 0xeb, 0xd3, 0x5e, 0xfd, 0x5e, 0x1d, 0x95, 0xa3, 0xa0, [1658188121.154888][10375:10380] CHIP:DMG: ] [1658188121.154905][10375:10380] CHIP:DMG: }, [1658188121.154919][10375:10380] CHIP:DMG: }, [1658188121.154941][10375:10380] CHIP:DMG: [1658188121.154964][10375:10380] CHIP:DMG: }, [1658188121.154986][10375:10380] CHIP:DMG: [1658188121.154999][10375:10380] CHIP:DMG: ], [1658188121.155021][10375:10380] CHIP:DMG: [1658188121.155036][10375:10380] CHIP:DMG: InteractionModelRevision = 1 [1658188121.155049][10375:10380] CHIP:DMG: }, [1658188121.155109][10375:10380] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1658188121.155140][10375:10380] CHIP:CTL: Received Attestation Information from the device [1658188121.155167][10375:10380] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1658188121.155211][10375:10380] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1658188121.155234][10375:10380] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1658188121.155247][10375:10380] CHIP:CTL: Verifying attestation [1658188121.163787][10375:10380] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1658188121.163848][10375:10380] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1658188121.165981][10375:10380] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1658188121.166210][10375:10380] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1658188121.166231][10375:10380] CHIP:CTL: Sending CSR request to 0x7efeb4000b60 device [1658188121.166476][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188121.166591][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188121.166789][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fb7a0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53514i with MessageCounter:256988189. [1658188121.166938][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fb7a0 with MessageCounter:256988189 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001D6250 msec [1658188121.171702][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188121.171758][10375:10380] CHIP:CTL: Sent CSR request, waiting for the CSR [1658188121.171819][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188121.247314][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188121.804291][10375:10378] CHIP:DL: Indication received, conn = 0x7efebc040230 [1658188121.804534][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1658188139.319992][10375:10380] CHIP:BLE: ack recv timeout, closing ep 0x56550811fae0 [1658188139.320052][10375:10380] CHIP:IN: Clearing BLE pending packets. [1658188139.323376][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1658188139.323426][10375:10380] CHIP:BLE: Auto-closing end point's BLE connection. [1658188139.323435][10375:10380] CHIP:DL: Closing BLE GATT connection (con 0x7efebc040230) [1658188139.323500][10375:10378] CHIP:DL: BluezDisconnect peer=C8:F1:E5:46:9F:1E [1658188141.649971][10375:10378] CHIP:DL: Bluez disconnected [1658188141.650016][10375:10378] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1658188141.650236][10375:10380] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1658188141.650254][10375:10380] CHIP:BLE: no endpoint for BLE sent data ack [1658188151.168122][10375:10380] CHIP:DMG: Time out! failed to receive invoke command response from Exchange: 53514i [1658188151.168195][10375:10380] CHIP:CTL: Device failed to receive the CSR request Response: ../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout [1658188151.168231][10375:10380] CHIP:CTL: Error on commissioning step 'SendOpCertSigningRequest': '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' [1658188151.168246][10375:10380] CHIP:CTL: Failed to perform commissioning step 9 [1658188151.168260][10375:10380] CHIP:CTL: Going from commissioning step 'SendOpCertSigningRequest' with lastErr = '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' -> 'Cleanup' [1658188151.168285][10375:10380] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' [1658188151.168299][10375:10380] CHIP:CTL: Expiring failsafe on proxy 0x7efeb4000b60 [1658188151.168362][10375:10380] CHIP:DMG: ICR moving to [AddingComm] [1658188151.168383][10375:10380] CHIP:DMG: ICR moving to [AddedComma] [1658188151.168477][10375:10380] CHIP:IN: Prepared secure message 0x7efec77fcd90 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 53515i with MessageCounter:256988190. [1658188151.168511][10375:10380] CHIP:IN: Sending encrypted msg 0x7efec77fcd90 with MessageCounter:256988190 to 0xFFFFFFFB00000000 (0) at monotonic time: 00000000001DD782 msec [1658188151.168536][10375:10380] CHIP:IN: Message appended to BLE send queue [1658188151.168549][10375:10380] CHIP:DMG: ICR moving to [CommandSen] [1658188151.168569][10375:10380] CHIP:DMG: ICR moving to [AwaitingDe] [1658188154.676094][10375:10380] CHIP:DIS: Closing all BLE connections [1658188154.676240][10375:10380] CHIP:TOO: Pairing Failure: ../../third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:1408: CHIP Error 0x00000032: Timeout [1658188154.677038][10375:10375] CHIP:CTL: Shutting down the commissioner [1658188154.677088][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188154.677191][10375:10375] CHIP:CTL: Shutting down the controller [1658188154.677208][10375:10375] CHIP:IN: Expiring all sessions for fabric 0x1!! [1658188154.677219][10375:10375] CHIP:FP: Forgetting fabric 0x1 [1658188154.677243][10375:10375] CHIP:TS: Pending Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677456][10375:10375] CHIP:TS: Previous Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677469][10375:10375] CHIP:TS: Reverted Last Known Good Time to previous value [1658188154.677505][10375:10375] CHIP:CTL: Shutting down the commissioner [1658188154.677516][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188154.677557][10375:10375] CHIP:CTL: Shutting down the controller [1658188154.677570][10375:10375] CHIP:IN: Expiring all sessions for fabric 0x2!! [1658188154.677578][10375:10375] CHIP:FP: Forgetting fabric 0x2 [1658188154.677591][10375:10375] CHIP:TS: Pending Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677717][10375:10375] CHIP:TS: Previous Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677730][10375:10375] CHIP:TS: Reverted Last Known Good Time to previous value [1658188154.677752][10375:10375] CHIP:CTL: Shutting down the commissioner [1658188154.677763][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188154.677801][10375:10375] CHIP:CTL: Shutting down the controller [1658188154.677814][10375:10375] CHIP:IN: Expiring all sessions for fabric 0x3!! [1658188154.677822][10375:10375] CHIP:FP: Forgetting fabric 0x3 [1658188154.677835][10375:10375] CHIP:TS: Pending Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677958][10375:10375] CHIP:TS: Previous Last Known Good Time: 2022-07-18T17:39:40 [1658188154.677970][10375:10375] CHIP:TS: Reverted Last Known Good Time to previous value [1658188154.677991][10375:10375] CHIP:CTL: Shutting down the commissioner [1658188154.678003][10375:10375] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1658188154.678041][10375:10375] CHIP:CTL: Shutting down the controller [1658188154.678053][10375:10375] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1658188154.678137][10375:10375] CHIP:DMG: IM WH moving to [Uninitialized] [1658188154.678150][10375:10375] CHIP:DMG: IM WH moving to [Uninitialized] [1658188154.678158][10375:10375] CHIP:DMG: IM WH moving to [Uninitialized] [1658188154.678165][10375:10375] CHIP:DMG: IM WH moving to [Uninitialized] [1658188154.678175][10375:10375] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1658188154.678222][10375:10375] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1658188154.678477][10375:10375] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-ygh4BG) [1658188154.680026][10375:10375] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1658188154.680390][10375:10375] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1658188154.680643][10375:10375] CHIP:DL: Inet Layer shutdown [1658188154.680817][10375:10375] CHIP:DL: BLE shutdown [1658188154.682515][10375:10375] CHIP:DL: System Layer shutdown [1658188154.682796][10375:10375] CHIP:IN: SecureSession[0x7efeb4001a30]: MarkForEviction Type:1 LSID:56321 [1658188154.682982][10375:10375] CHIP:SC: SecureSession[0x7efeb4001a30]: Moving from state 'kActive' --> 'kPendingEviction' Segmentation fault (core dumped)