$ ./out/debug/chip-tool pairing ble-wifi 123456 $SSID $PASS 20202021 3840 [1657846557.516882][19325:19325] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1657846557.517244][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_kvs-N4ub3s) [1657846557.517500][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_kvs) [1657846557.517853][19325:19325] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1657846557.518011][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-kfzZds) [1657846557.518197][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1657846557.518296][19325:19325] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1657846557.518431][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_config.ini-pUFNVr) [1657846557.518609][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1657846557.518699][19325:19325] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1657846557.518824][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-LaTjJr) [1657846557.519000][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1657846557.519236][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-sJsT1s) [1657846557.519738][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1657846557.519800][19325:19325] CHIP:DL: NVS set: chip-factory/unique-id = "1B04CBE93877E543" [1657846557.520031][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-AoeXCr) [1657846557.520573][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1657846557.520642][19325:19325] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1657846557.520807][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-3Gyu8t) [1657846557.521293][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1657846557.521358][19325:19325] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1657846557.521513][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-OtoWGq) [1657846557.521934][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1657846557.521998][19325:19325] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1657846557.522157][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-H2vQku) [1657846557.522625][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1657846557.522689][19325:19325] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1657846557.522877][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-qbRyRr) [1657846557.523355][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1657846557.523421][19325:19325] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1657846557.523575][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_config.ini-dRJsht) [1657846557.524159][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1657846557.524232][19325:19325] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1657846557.524412][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_config.ini-DD8igt) [1657846557.524911][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1657846557.524976][19325:19325] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1657846557.525439][19325:19325] CHIP:DL: Failed to get Ethernet interface [1657846557.525742][19325:19325] CHIP:DL: Failed to reset Ethernet statistic counts [1657846557.525924][19325:19325] CHIP:DL: Got WiFi interface: wlp1s0 [1657846557.527240][19325:19325] CHIP:DL: Found the primary WiFi interface:wlp1s0 [1657846557.527372][19325:19325] CHIP:IN: UDP::Init bind&listen port=0 [1657846557.527451][19325:19325] CHIP:IN: UDP::Init bound to port=39328 [1657846557.527475][19325:19325] CHIP:IN: UDP::Init bind&listen port=0 [1657846557.527524][19325:19325] CHIP:IN: UDP::Init bound to port=60415 [1657846557.527542][19325:19325] CHIP:IN: BLEBase::Init - setting/overriding transport [1657846557.527555][19325:19325] CHIP:IN: TransportMgr initialized [1657846557.527585][19325:19325] CHIP:FP: Initializing FabricTable from persistent storage [1657846557.527632][19325:19325] CHIP:TS: Last Known Good Time: [unknown] [1657846557.527654][19325:19325] CHIP:TS: Setting Last Known Good Time to firmware build time 2022-07-15T09:49:29 [1657846557.529990][19325:19325] CHIP:ZCL: Using ZAP configuration... [1657846557.530755][19325:19325] CHIP:CTL: System State Initialized... [1657846557.530852][19325:19325] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1657846557.530902][19325:19325] CHIP:CTL: Setting attestation nonce to random value [1657846557.530960][19325:19325] CHIP:CTL: Setting CSR nonce to random value [1657846557.531037][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.531109][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.531150][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.531214][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.531251][19325:19325] CHIP:IN: TransportMgr initialized [1657846557.531336][19325:19325] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1657846557.531382][19325:19325] CHIP:CTL: Setting attestation nonce to random value [1657846557.531430][19325:19325] CHIP:CTL: Setting CSR nonce to random value [1657846557.531936][19325:19325] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: ../../config/PersistentStorage.cpp:85: CHIP Error 0x000000A0: Value not found in the persisted storage [1657846557.541714][19325:19325] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: ../../config/PersistentStorage.cpp:85: CHIP Error 0x000000A0: Value not found in the persisted storage [1657846557.543259][19325:19325] CHIP:CTL: Generating RCAC [1657846557.544719][19325:19325] CHIP:CTL: Generating ICAC [1657846557.545842][19325:19325] CHIP:CTL: Generating NOC [1657846557.546766][19325:19325] CHIP:FP: Validating NOC chain [1657846557.549531][19325:19325] CHIP:FP: NOC chain validation successful [1657846557.549789][19325:19325] CHIP:FP: Added new fabric at index: 0x1 [1657846557.549835][19325:19325] CHIP:FP: Assigned compressed fabric ID: 0x21583438A9D94965, node ID: 0x000000000001B669 [1657846557.549879][19325:19325] CHIP:TS: Last Known Good Time: 2022-07-15T09:49:29 [1657846557.549917][19325:19325] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1657846557.549950][19325:19325] CHIP:TS: Retaining current Last Known Good Time [1657846557.551819][19325:19325] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1657846557.554896][19325:19325] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-15T09:49:29 [1657846557.556957][19325:19325] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x0000000000000000 [1657846557.557035][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.557128][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.557173][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.561702][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.561780][19325:19325] CHIP:IN: TransportMgr initialized [1657846557.562010][19325:19325] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1657846557.562064][19325:19325] CHIP:CTL: Setting attestation nonce to random value [1657846557.562127][19325:19325] CHIP:CTL: Setting CSR nonce to random value [1657846557.564225][19325:19325] CHIP:CTL: Generating NOC [1657846557.565208][19325:19325] CHIP:FP: Validating NOC chain [1657846557.567655][19325:19325] CHIP:FP: NOC chain validation successful [1657846557.567890][19325:19325] CHIP:FP: Added new fabric at index: 0x2 [1657846557.567933][19325:19325] CHIP:FP: Assigned compressed fabric ID: 0xA9ABDAB904C8A6C2, node ID: 0x000000000001B669 [1657846557.568028][19325:19325] CHIP:TS: Last Known Good Time: 2022-07-15T09:49:29 [1657846557.568069][19325:19325] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1657846557.568104][19325:19325] CHIP:TS: Retaining current Last Known Good Time [1657846557.570781][19325:19325] CHIP:FP: Metadata for Fabric 0x2 persisted to storage. [1657846557.573372][19325:19325] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-15T09:49:29 [1657846557.575559][19325:19325] CHIP:CTL: Joined the fabric at index 2. Compressed fabric ID is: 0x0000000000000000 [1657846557.575638][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.575732][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.575774][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.575843][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.575886][19325:19325] CHIP:IN: TransportMgr initialized [1657846557.576024][19325:19325] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1657846557.576081][19325:19325] CHIP:CTL: Setting attestation nonce to random value [1657846557.576144][19325:19325] CHIP:CTL: Setting CSR nonce to random value [1657846557.577609][19325:19325] CHIP:CTL: Generating NOC [1657846557.578519][19325:19325] CHIP:FP: Validating NOC chain [1657846557.581276][19325:19325] CHIP:FP: NOC chain validation successful [1657846557.581536][19325:19325] CHIP:FP: Added new fabric at index: 0x3 [1657846557.581579][19325:19325] CHIP:FP: Assigned compressed fabric ID: 0xEE6671190F919364, node ID: 0x000000000001B669 [1657846557.581625][19325:19325] CHIP:TS: Last Known Good Time: 2022-07-15T09:49:29 [1657846557.585818][19325:19325] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1657846557.585856][19325:19325] CHIP:TS: Retaining current Last Known Good Time [1657846557.587900][19325:19325] CHIP:FP: Metadata for Fabric 0x3 persisted to storage. [1657846557.591514][19325:19325] CHIP:TS: Committing Last Known Good Time to storage: 2022-07-15T09:49:29 [1657846557.593915][19325:19325] CHIP:CTL: Joined the fabric at index 3. Compressed fabric ID is: 0x0000000000000000 [1657846557.593994][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.594089][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.594132][19325:19325] CHIP:IN: UDP::Init bind&listen port=5550 [1657846557.594201][19325:19325] CHIP:IN: UDP::Init bound to port=5550 [1657846557.594241][19325:19325] CHIP:IN: TransportMgr initialized [1657846557.677316][19325:19330] CHIP:DL: CHIP task running [1657846557.677712][19325:19330] CHIP:IN: SecureSession[0x7efff0001a30]: Allocated Type:1 LSID:54258 [1657846557.677751][19325:19330] CHIP:SC: Assigned local session key ID 54258 [1657846557.677811][19325:19330] CHIP:SC: Including MRP parameters in PBKDF param request [1657846557.677868][19325:19330] CHIP:IN: Prepared unauthenticated message 0x7f000630e3f0 to 0x0000000000000000 (0) of type 0x20 and protocolId (0, 0) on exchange 60002i with MessageCounter:78130418. [1657846557.677908][19325:19330] CHIP:IN: Sending unauthenticated msg 0x7f000630e3f0 with MessageCounter:78130418 to 0x0000000000000000 at monotonic time: 000000000065DEAC msec [1657846557.677932][19325:19330] CHIP:IN: Message appended to BLE send queue [1657846557.677945][19325:19330] CHIP:SC: Sent PBKDF param request [1657846557.677963][19325:19330] CHIP:CTL: Setting wifi credentials from parameters [1657846557.677974][19325:19330] CHIP:CTL: Setting attestation nonce to random value [1657846557.677995][19325:19330] CHIP:CTL: Setting CSR nonce to random value [1657846557.678028][19325:19330] CHIP:CTL: Commission called for node ID 0x000000000001E240 [1657846557.678256][19325:19331] CHIP:DL: TRACE: Bluez mainloop starting Thread [1657846557.678417][19325:19328] CHIP:DL: TRACE: Bus acquired for name C-4b7d [1657846557.691748][19325:19330] CHIP:DL: PlatformBlueZInit init success [1657846557.709550][19325:19328] CHIP:BLE: BLE removing known devices. [1657846557.714379][19325:19328] CHIP:BLE: BLE initiating scan. [1657846557.828408][19325:19330] CHIP:DL: Long dispatch time: 150 ms, for event type 2 [1657846557.836226][19325:19328] CHIP:BLE: Device 4F:5C:41:45:E9:70 does not look like a CHIP device. [1657846557.892729][19325:19328] CHIP:BLE: New device scanned: D8:7E:7F:CB:27:2F [1657846557.892784][19325:19328] CHIP:BLE: Device discriminator match. Attempting to connect. [1657846557.909613][19325:19328] CHIP:BLE: Scan complete notification without an active scan. [1657846559.499480][19325:19328] CHIP:DL: ConnectDevice complete [1657846560.421830][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service0006 [1657846560.422142][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.422327][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service0006 [1657846560.422502][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.422679][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service0006 [1657846560.422852][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423042][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423217][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423409][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423583][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423763][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.423936][19325:19328] CHIP:DL: Char1 /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F/service000a [1657846560.424198][19325:19328] CHIP:DL: New BLE connection 0x7efffc0363a0, device D8:7E:7F:CB:27:2F, path /org/bluez/hci0/dev_D8_7E_7F_CB_27_2F [1657846560.424458][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1657846560.424641][19325:19330] CHIP:IN: BleConnectionComplete: endPoint 0x55f81d3f2ae0 [1657846560.798029][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846560.900088][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1657846560.900136][19325:19330] CHIP:BLE: subscribe complete, ep = 0x55f81d3f2ae0 [1657846560.900607][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846560.900668][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846560.900688][19325:19330] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1657846560.900700][19325:19330] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1657846560.900709][19325:19330] CHIP:BLE: local and remote recv window size = 5 [1657846560.900771][19325:19330] CHIP:IN: BLE EndPoint 0x55f81d3f2ae0 Connection Complete [1657846560.998129][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846561.052669][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846561.052813][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846561.052927][19325:19330] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:1584448 on exchange 60002i [1657846561.052953][19325:19330] CHIP:EM: Found matching exchange: 60002i, Delegate: 0x7efff0000bb0 [1657846561.052981][19325:19330] CHIP:SC: Received PBKDF param response [1657846561.053017][19325:19330] CHIP:SC: Peer assigned session ID 31463 [1657846561.053042][19325:19330] CHIP:SC: Found MRP parameters in the message [1657846561.059301][19325:19330] CHIP:IN: Prepared unauthenticated message 0x7f000630dfe0 to 0x0000000000000000 (0) of type 0x22 and protocolId (0, 0) on exchange 60002i with MessageCounter:78130419. [1657846561.059345][19325:19330] CHIP:IN: Sending unauthenticated msg 0x7f000630dfe0 with MessageCounter:78130419 to 0x0000000000000000 at monotonic time: 000000000065EBE1 msec [1657846561.059425][19325:19330] CHIP:SC: Sent spake2p msg1 [1657846561.148121][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.052582][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.053118][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.053262][19325:19330] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:1584449 on exchange 60002i [1657846562.053290][19325:19330] CHIP:EM: Found matching exchange: 60002i, Delegate: 0x7efff0000bb0 [1657846562.053321][19325:19330] CHIP:SC: Received spake2p msg2 [1657846562.055135][19325:19330] CHIP:IN: Prepared unauthenticated message 0x7f000630e0e0 to 0x0000000000000000 (0) of type 0x24 and protocolId (0, 0) on exchange 60002i with MessageCounter:78130420. [1657846562.055175][19325:19330] CHIP:IN: Sending unauthenticated msg 0x7f000630e0e0 with MessageCounter:78130420 to 0x0000000000000000 at monotonic time: 000000000065EFC5 msec [1657846562.055257][19325:19330] CHIP:SC: Sent spake2p msg3 [1657846562.148295][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.200495][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.200752][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.200897][19325:19330] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:1584450 on exchange 60002i [1657846562.200920][19325:19330] CHIP:EM: Found matching exchange: 60002i, Delegate: 0x7efff0000bb0 [1657846562.201005][19325:19330] CHIP:SC: SecureSession[0x7efff0001a30]: Moving from state 'kEstablishing' --> 'kActive' [1657846562.201022][19325:19330] CHIP:IN: SecureSession[0x7efff0001a30]: Activated - Type:1 LSID:54258 [1657846562.201033][19325:19330] CHIP:IN: New secure session activated for device , LSID:54258 PSID:31463! [1657846562.201053][19325:19330] CHIP:CTL: Remote device completed SPAKE2+ handshake [1657846562.201065][19325:19330] CHIP:TOO: Pairing Success [1657846562.201072][19325:19330] CHIP:TOO: PASE establishment successful [1657846562.201084][19325:19330] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1657846562.201099][19325:19330] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1657846562.201109][19325:19330] CHIP:CTL: Sending request for commissioning information [1657846562.201140][19325:19330] CHIP:DMG: SendReadRequest ReadClient[0x7efffc037c90]: Sending Read Request [1657846562.201261][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d960 to 0xFFFFFFFB00000000 (0) of type 0x2 and protocolId (0, 1) on exchange 60003i with MessageCounter:86939816. [1657846562.201341][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d960 with MessageCounter:86939816 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F057 msec [1657846562.201436][19325:19330] CHIP:DMG: MoveToState ReadClient[0x7efffc037c90]: Moving to [AwaitingIn] [1657846562.298456][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.355668][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.355933][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.400255][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.400530][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.400655][19325:19330] CHIP:EM: Received message of type 0x5 with protocolId (0, 1) and MessageCounter:202066451 on exchange 60003i [1657846562.400681][19325:19330] CHIP:EM: Found matching exchange: 60003i, Delegate: 0x7efffc037c90 [1657846562.400797][19325:19330] CHIP:DMG: ReportDataMessage = [1657846562.400824][19325:19330] CHIP:DMG: { [1657846562.400837][19325:19330] CHIP:DMG: AttributeReportIBs = [1657846562.400861][19325:19330] CHIP:DMG: [ [1657846562.400874][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.400898][19325:19330] CHIP:DMG: { [1657846562.400915][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.400932][19325:19330] CHIP:DMG: { [1657846562.400956][19325:19330] CHIP:DMG: DataVersion = 0xa7bc798, [1657846562.400975][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.401008][19325:19330] CHIP:DMG: { [1657846562.401032][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.401050][19325:19330] CHIP:DMG: Cluster = 0x31, [1657846562.401068][19325:19330] CHIP:DMG: Attribute = 0x0000_0003, [1657846562.401085][19325:19330] CHIP:DMG: } [1657846562.401108][19325:19330] CHIP:DMG: [1657846562.401128][19325:19330] CHIP:DMG: Data = 30, [1657846562.401146][19325:19330] CHIP:DMG: }, [1657846562.401174][19325:19330] CHIP:DMG: [1657846562.401191][19325:19330] CHIP:DMG: }, [1657846562.401224][19325:19330] CHIP:DMG: [1657846562.401240][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.401266][19325:19330] CHIP:DMG: { [1657846562.401283][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.401303][19325:19330] CHIP:DMG: { [1657846562.401324][19325:19330] CHIP:DMG: DataVersion = 0x4fe072fd, [1657846562.401342][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.401361][19325:19330] CHIP:DMG: { [1657846562.401379][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.401399][19325:19330] CHIP:DMG: Cluster = 0x28, [1657846562.401420][19325:19330] CHIP:DMG: Attribute = 0x0000_0004, [1657846562.401439][19325:19330] CHIP:DMG: } [1657846562.401463][19325:19330] CHIP:DMG: [1657846562.401486][19325:19330] CHIP:DMG: Data = 32769, [1657846562.401503][19325:19330] CHIP:DMG: }, [1657846562.401526][19325:19330] CHIP:DMG: [1657846562.401544][19325:19330] CHIP:DMG: }, [1657846562.401573][19325:19330] CHIP:DMG: [1657846562.401590][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.401613][19325:19330] CHIP:DMG: { [1657846562.401631][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.401650][19325:19330] CHIP:DMG: { [1657846562.401671][19325:19330] CHIP:DMG: DataVersion = 0x4fe072fd, [1657846562.401686][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.401706][19325:19330] CHIP:DMG: { [1657846562.401726][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.401746][19325:19330] CHIP:DMG: Cluster = 0x28, [1657846562.401767][19325:19330] CHIP:DMG: Attribute = 0x0000_0002, [1657846562.401783][19325:19330] CHIP:DMG: } [1657846562.401803][19325:19330] CHIP:DMG: [1657846562.401825][19325:19330] CHIP:DMG: Data = 65521, [1657846562.401841][19325:19330] CHIP:DMG: }, [1657846562.401865][19325:19330] CHIP:DMG: [1657846562.401883][19325:19330] CHIP:DMG: }, [1657846562.401914][19325:19330] CHIP:DMG: [1657846562.401931][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.401955][19325:19330] CHIP:DMG: { [1657846562.401973][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.401992][19325:19330] CHIP:DMG: { [1657846562.402012][19325:19330] CHIP:DMG: DataVersion = 0xab9a44e3, [1657846562.402031][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.402051][19325:19330] CHIP:DMG: { [1657846562.402071][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.402089][19325:19330] CHIP:DMG: Cluster = 0x30, [1657846562.402114][19325:19330] CHIP:DMG: Attribute = 0x0000_0003, [1657846562.402129][19325:19330] CHIP:DMG: } [1657846562.402148][19325:19330] CHIP:DMG: [1657846562.402170][19325:19330] CHIP:DMG: Data = 0, [1657846562.402187][19325:19330] CHIP:DMG: }, [1657846562.402211][19325:19330] CHIP:DMG: [1657846562.402228][19325:19330] CHIP:DMG: }, [1657846562.402257][19325:19330] CHIP:DMG: [1657846562.402273][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.402297][19325:19330] CHIP:DMG: { [1657846562.402314][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.402333][19325:19330] CHIP:DMG: { [1657846562.402354][19325:19330] CHIP:DMG: DataVersion = 0xab9a44e3, [1657846562.402370][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.402388][19325:19330] CHIP:DMG: { [1657846562.402409][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.402429][19325:19330] CHIP:DMG: Cluster = 0x30, [1657846562.402451][19325:19330] CHIP:DMG: Attribute = 0x0000_0002, [1657846562.402470][19325:19330] CHIP:DMG: } [1657846562.402490][19325:19330] CHIP:DMG: [1657846562.402512][19325:19330] CHIP:DMG: Data = 0, [1657846562.402526][19325:19330] CHIP:DMG: }, [1657846562.402550][19325:19330] CHIP:DMG: [1657846562.402568][19325:19330] CHIP:DMG: }, [1657846562.402602][19325:19330] CHIP:DMG: [1657846562.402619][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.402644][19325:19330] CHIP:DMG: { [1657846562.402662][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.402680][19325:19330] CHIP:DMG: { [1657846562.402702][19325:19330] CHIP:DMG: DataVersion = 0xab9a44e3, [1657846562.402721][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.402737][19325:19330] CHIP:DMG: { [1657846562.402758][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.402779][19325:19330] CHIP:DMG: Cluster = 0x30, [1657846562.402800][19325:19330] CHIP:DMG: Attribute = 0x0000_0001, [1657846562.402818][19325:19330] CHIP:DMG: } [1657846562.402837][19325:19330] CHIP:DMG: [1657846562.402860][19325:19330] CHIP:DMG: Data = [1657846562.402876][19325:19330] CHIP:DMG: { [1657846562.402896][19325:19330] CHIP:DMG: 0x0 = 60, [1657846562.402918][19325:19330] CHIP:DMG: 0x1 = 900, [1657846562.402935][19325:19330] CHIP:DMG: }, [1657846562.402954][19325:19330] CHIP:DMG: }, [1657846562.402979][19325:19330] CHIP:DMG: [1657846562.402996][19325:19330] CHIP:DMG: }, [1657846562.403031][19325:19330] CHIP:DMG: [1657846562.403049][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.403071][19325:19330] CHIP:DMG: { [1657846562.403088][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.403103][19325:19330] CHIP:DMG: { [1657846562.403123][19325:19330] CHIP:DMG: DataVersion = 0xab9a44e3, [1657846562.403141][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.403157][19325:19330] CHIP:DMG: { [1657846562.403177][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.403199][19325:19330] CHIP:DMG: Cluster = 0x30, [1657846562.403219][19325:19330] CHIP:DMG: Attribute = 0x0000_0000, [1657846562.403238][19325:19330] CHIP:DMG: } [1657846562.403260][19325:19330] CHIP:DMG: [1657846562.403282][19325:19330] CHIP:DMG: Data = 0, [1657846562.403296][19325:19330] CHIP:DMG: }, [1657846562.403321][19325:19330] CHIP:DMG: [1657846562.403338][19325:19330] CHIP:DMG: }, [1657846562.403370][19325:19330] CHIP:DMG: [1657846562.403387][19325:19330] CHIP:DMG: AttributeReportIB = [1657846562.403410][19325:19330] CHIP:DMG: { [1657846562.403427][19325:19330] CHIP:DMG: AttributeDataIB = [1657846562.403443][19325:19330] CHIP:DMG: { [1657846562.403464][19325:19330] CHIP:DMG: DataVersion = 0xa7bc798, [1657846562.403483][19325:19330] CHIP:DMG: AttributePathIB = [1657846562.403503][19325:19330] CHIP:DMG: { [1657846562.403520][19325:19330] CHIP:DMG: Endpoint = 0x0, [1657846562.403539][19325:19330] CHIP:DMG: Cluster = 0x31, [1657846562.403561][19325:19330] CHIP:DMG: Attribute = 0x0000_FFFC, [1657846562.403578][19325:19330] CHIP:DMG: } [1657846562.403600][19325:19330] CHIP:DMG: [1657846562.403623][19325:19330] CHIP:DMG: Data = 1, [1657846562.403640][19325:19330] CHIP:DMG: }, [1657846562.403661][19325:19330] CHIP:DMG: [1657846562.403679][19325:19330] CHIP:DMG: }, [1657846562.403699][19325:19330] CHIP:DMG: [1657846562.403716][19325:19330] CHIP:DMG: ], [1657846562.403790][19325:19330] CHIP:DMG: [1657846562.403809][19325:19330] CHIP:DMG: SuppressResponse = true, [1657846562.403826][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846562.403841][19325:19330] CHIP:DMG: } [1657846562.405138][19325:19330] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1657846562.405172][19325:19330] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1657846562.405193][19325:19330] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1657846562.405206][19325:19330] CHIP:CTL: Arming failsafe (60 seconds) [1657846562.405260][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846562.405288][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846562.405368][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d6f0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60004i with MessageCounter:86939817. [1657846562.405409][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d6f0 with MessageCounter:86939817 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F123 msec [1657846562.406869][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846562.499206][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.551000][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.551255][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.551433][19325:19330] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:202066452 on exchange 60004i [1657846562.551458][19325:19330] CHIP:EM: Found matching exchange: 60004i, Delegate: 0x7efff00133b0 [1657846562.551498][19325:19330] CHIP:DMG: ICR moving to [ResponseRe] [1657846562.551548][19325:19330] CHIP:DMG: InvokeResponseMessage = [1657846562.551564][19325:19330] CHIP:DMG: { [1657846562.551578][19325:19330] CHIP:DMG: suppressResponse = false, [1657846562.551590][19325:19330] CHIP:DMG: InvokeResponseIBs = [1657846562.551617][19325:19330] CHIP:DMG: [ [1657846562.551638][19325:19330] CHIP:DMG: InvokeResponseIB = [1657846562.551660][19325:19330] CHIP:DMG: { [1657846562.551673][19325:19330] CHIP:DMG: CommandDataIB = [1657846562.551688][19325:19330] CHIP:DMG: { [1657846562.551701][19325:19330] CHIP:DMG: CommandPathIB = [1657846562.551716][19325:19330] CHIP:DMG: { [1657846562.551736][19325:19330] CHIP:DMG: EndpointId = 0x0, [1657846562.551759][19325:19330] CHIP:DMG: ClusterId = 0x30, [1657846562.551778][19325:19330] CHIP:DMG: CommandId = 0x1, [1657846562.551795][19325:19330] CHIP:DMG: }, [1657846562.551816][19325:19330] CHIP:DMG: [1657846562.551835][19325:19330] CHIP:DMG: CommandFields = [1657846562.551854][19325:19330] CHIP:DMG: { [1657846562.551874][19325:19330] CHIP:DMG: 0x0 = 0, [1657846562.551893][19325:19330] CHIP:DMG: 0x1 = "", [1657846562.551908][19325:19330] CHIP:DMG: }, [1657846562.551925][19325:19330] CHIP:DMG: }, [1657846562.551947][19325:19330] CHIP:DMG: [1657846562.551959][19325:19330] CHIP:DMG: }, [1657846562.552035][19325:19330] CHIP:DMG: [1657846562.552053][19325:19330] CHIP:DMG: ], [1657846562.552079][19325:19330] CHIP:DMG: [1657846562.552095][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846562.552107][19325:19330] CHIP:DMG: }, [1657846562.552173][19325:19330] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1657846562.552209][19325:19330] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1657846562.552232][19325:19330] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1657846562.552245][19325:19330] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1657846562.552263][19325:19330] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1657846562.552273][19325:19330] CHIP:CTL: Setting Regulatory Config [1657846562.552282][19325:19330] CHIP:CTL: Device does not support configurable regulatory location [1657846562.552372][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846562.552396][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846562.552464][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d1e0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60005i with MessageCounter:86939818. [1657846562.552496][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d1e0 with MessageCounter:86939818 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F1B6 msec [1657846562.552590][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846562.552643][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846562.648300][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.700607][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.700956][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.701130][19325:19330] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:202066453 on exchange 60005i [1657846562.701159][19325:19330] CHIP:EM: Found matching exchange: 60005i, Delegate: 0x7efff0013b50 [1657846562.701187][19325:19330] CHIP:DMG: ICR moving to [ResponseRe] [1657846562.701237][19325:19330] CHIP:DMG: InvokeResponseMessage = [1657846562.701256][19325:19330] CHIP:DMG: { [1657846562.701274][19325:19330] CHIP:DMG: suppressResponse = false, [1657846562.701290][19325:19330] CHIP:DMG: InvokeResponseIBs = [1657846562.701315][19325:19330] CHIP:DMG: [ [1657846562.701331][19325:19330] CHIP:DMG: InvokeResponseIB = [1657846562.701356][19325:19330] CHIP:DMG: { [1657846562.701372][19325:19330] CHIP:DMG: CommandDataIB = [1657846562.701390][19325:19330] CHIP:DMG: { [1657846562.701406][19325:19330] CHIP:DMG: CommandPathIB = [1657846562.701425][19325:19330] CHIP:DMG: { [1657846562.701446][19325:19330] CHIP:DMG: EndpointId = 0x0, [1657846562.701466][19325:19330] CHIP:DMG: ClusterId = 0x30, [1657846562.701485][19325:19330] CHIP:DMG: CommandId = 0x3, [1657846562.701501][19325:19330] CHIP:DMG: }, [1657846562.701520][19325:19330] CHIP:DMG: [1657846562.701538][19325:19330] CHIP:DMG: CommandFields = [1657846562.701555][19325:19330] CHIP:DMG: { [1657846562.701576][19325:19330] CHIP:DMG: 0x0 = 0, [1657846562.701597][19325:19330] CHIP:DMG: 0x1 = "", [1657846562.701616][19325:19330] CHIP:DMG: }, [1657846562.701633][19325:19330] CHIP:DMG: }, [1657846562.701667][19325:19330] CHIP:DMG: [1657846562.701683][19325:19330] CHIP:DMG: }, [1657846562.701705][19325:19330] CHIP:DMG: [1657846562.701719][19325:19330] CHIP:DMG: ], [1657846562.701744][19325:19330] CHIP:DMG: [1657846562.701760][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846562.701771][19325:19330] CHIP:DMG: }, [1657846562.701837][19325:19330] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1657846562.701874][19325:19330] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1657846562.701901][19325:19330] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1657846562.701917][19325:19330] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1657846562.701939][19325:19330] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1657846562.701953][19325:19330] CHIP:CTL: Sending request for PAI certificate [1657846562.701974][19325:19330] CHIP:CTL: Sending Certificate Chain request to 0x7efff0000b60 device [1657846562.702032][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846562.702086][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846562.702158][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d110 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60006i with MessageCounter:86939819. [1657846562.702194][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d110 with MessageCounter:86939819 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F24C msec [1657846562.702278][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846562.702336][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846562.798432][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846562.855530][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.856176][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.905414][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.905754][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.950809][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846562.951226][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846562.951393][19325:19330] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:202066454 on exchange 60006i [1657846562.951420][19325:19330] CHIP:EM: Found matching exchange: 60006i, Delegate: 0x7efff00133b0 [1657846562.951453][19325:19330] CHIP:DMG: ICR moving to [ResponseRe] [1657846562.951502][19325:19330] CHIP:DMG: InvokeResponseMessage = [1657846562.951520][19325:19330] CHIP:DMG: { [1657846562.951536][19325:19330] CHIP:DMG: suppressResponse = false, [1657846562.951550][19325:19330] CHIP:DMG: InvokeResponseIBs = [1657846562.951573][19325:19330] CHIP:DMG: [ [1657846562.951587][19325:19330] CHIP:DMG: InvokeResponseIB = [1657846562.951610][19325:19330] CHIP:DMG: { [1657846562.951624][19325:19330] CHIP:DMG: CommandDataIB = [1657846562.951640][19325:19330] CHIP:DMG: { [1657846562.951654][19325:19330] CHIP:DMG: CommandPathIB = [1657846562.951671][19325:19330] CHIP:DMG: { [1657846562.951689][19325:19330] CHIP:DMG: EndpointId = 0x0, [1657846562.951707][19325:19330] CHIP:DMG: ClusterId = 0x3e, [1657846562.951723][19325:19330] CHIP:DMG: CommandId = 0x3, [1657846562.951737][19325:19330] CHIP:DMG: }, [1657846562.951755][19325:19330] CHIP:DMG: [1657846562.951770][19325:19330] CHIP:DMG: CommandFields = [1657846562.951786][19325:19330] CHIP:DMG: { [1657846562.951803][19325:19330] CHIP:DMG: 0x0 = [ [1657846562.951969][19325:19330] 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, [1657846562.952102][19325:19330] CHIP:DMG: ] [1657846562.952125][19325:19330] CHIP:DMG: }, [1657846562.952142][19325:19330] CHIP:DMG: }, [1657846562.952167][19325:19330] CHIP:DMG: [1657846562.952184][19325:19330] CHIP:DMG: }, [1657846562.952208][19325:19330] CHIP:DMG: [1657846562.952224][19325:19330] CHIP:DMG: ], [1657846562.952251][19325:19330] CHIP:DMG: [1657846562.952270][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846562.952284][19325:19330] CHIP:DMG: }, [1657846562.952357][19325:19330] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1657846562.952392][19325:19330] CHIP:CTL: Received certificate chain from the device [1657846562.952420][19325:19330] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1657846562.952473][19325:19330] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1657846562.952500][19325:19330] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1657846562.952513][19325:19330] CHIP:CTL: Sending request for DAC certificate [1657846562.952526][19325:19330] CHIP:CTL: Sending Certificate Chain request to 0x7efff0000b60 device [1657846562.952585][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846562.952612][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846562.952723][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d140 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60007i with MessageCounter:86939820. [1657846562.952764][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d140 with MessageCounter:86939820 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F346 msec [1657846562.952863][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846562.952949][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846563.048291][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846563.108152][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.109644][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.155822][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.156158][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.201930][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.202176][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.202335][19325:19330] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:202066455 on exchange 60007i [1657846563.202362][19325:19330] CHIP:EM: Found matching exchange: 60007i, Delegate: 0x7efff0013b50 [1657846563.202394][19325:19330] CHIP:DMG: ICR moving to [ResponseRe] [1657846563.202444][19325:19330] CHIP:DMG: InvokeResponseMessage = [1657846563.202461][19325:19330] CHIP:DMG: { [1657846563.202476][19325:19330] CHIP:DMG: suppressResponse = false, [1657846563.202490][19325:19330] CHIP:DMG: InvokeResponseIBs = [1657846563.202513][19325:19330] CHIP:DMG: [ [1657846563.202526][19325:19330] CHIP:DMG: InvokeResponseIB = [1657846563.202549][19325:19330] CHIP:DMG: { [1657846563.202563][19325:19330] CHIP:DMG: CommandDataIB = [1657846563.202579][19325:19330] CHIP:DMG: { [1657846563.202594][19325:19330] CHIP:DMG: CommandPathIB = [1657846563.202610][19325:19330] CHIP:DMG: { [1657846563.202629][19325:19330] CHIP:DMG: EndpointId = 0x0, [1657846563.202646][19325:19330] CHIP:DMG: ClusterId = 0x3e, [1657846563.202663][19325:19330] CHIP:DMG: CommandId = 0x3, [1657846563.202677][19325:19330] CHIP:DMG: }, [1657846563.202695][19325:19330] CHIP:DMG: [1657846563.202709][19325:19330] CHIP:DMG: CommandFields = [1657846563.202725][19325:19330] CHIP:DMG: { [1657846563.202743][19325:19330] CHIP:DMG: 0x0 = [ [1657846563.202897][19325:19330] 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 [1657846563.202960][19325:19330] CHIP:DMG: ] [1657846563.202977][19325:19330] CHIP:DMG: }, [1657846563.202991][19325:19330] CHIP:DMG: }, [1657846563.203012][19325:19330] CHIP:DMG: [1657846563.203025][19325:19330] CHIP:DMG: }, [1657846563.203046][19325:19330] CHIP:DMG: [1657846563.203058][19325:19330] CHIP:DMG: ], [1657846563.203081][19325:19330] CHIP:DMG: [1657846563.203095][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846563.203107][19325:19330] CHIP:DMG: }, [1657846563.203174][19325:19330] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1657846563.203205][19325:19330] CHIP:CTL: Received certificate chain from the device [1657846563.203230][19325:19330] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1657846563.203250][19325:19330] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1657846563.203271][19325:19330] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1657846563.203282][19325:19330] CHIP:CTL: Sending Attestation Request to the device. [1657846563.203293][19325:19330] CHIP:CTL: Sending Attestation request to 0x7efff0000b60 device [1657846563.203354][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846563.203378][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846563.203451][19325:19330] CHIP:IN: Prepared secure message 0x7f000630d120 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60008i with MessageCounter:86939821. [1657846563.203486][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630d120 with MessageCounter:86939821 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F441 msec [1657846563.203576][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846563.203599][19325:19330] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1657846563.203645][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846563.298304][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846563.505788][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.505944][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.555124][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.555320][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.606158][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846563.606340][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846563.606487][19325:19330] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:202066456 on exchange 60008i [1657846563.606511][19325:19330] CHIP:EM: Found matching exchange: 60008i, Delegate: 0x7efff00133b0 [1657846563.606542][19325:19330] CHIP:DMG: ICR moving to [ResponseRe] [1657846563.606589][19325:19330] CHIP:DMG: InvokeResponseMessage = [1657846563.606605][19325:19330] CHIP:DMG: { [1657846563.606619][19325:19330] CHIP:DMG: suppressResponse = false, [1657846563.606631][19325:19330] CHIP:DMG: InvokeResponseIBs = [1657846563.606654][19325:19330] CHIP:DMG: [ [1657846563.606666][19325:19330] CHIP:DMG: InvokeResponseIB = [1657846563.606688][19325:19330] CHIP:DMG: { [1657846563.606700][19325:19330] CHIP:DMG: CommandDataIB = [1657846563.606716][19325:19330] CHIP:DMG: { [1657846563.606728][19325:19330] CHIP:DMG: CommandPathIB = [1657846563.606743][19325:19330] CHIP:DMG: { [1657846563.606759][19325:19330] CHIP:DMG: EndpointId = 0x0, [1657846563.606775][19325:19330] CHIP:DMG: ClusterId = 0x3e, [1657846563.606791][19325:19330] CHIP:DMG: CommandId = 0x1, [1657846563.606803][19325:19330] CHIP:DMG: }, [1657846563.606819][19325:19330] CHIP:DMG: [1657846563.606833][19325:19330] CHIP:DMG: CommandFields = [1657846563.606847][19325:19330] CHIP:DMG: { [1657846563.606862][19325:19330] CHIP:DMG: 0x0 = [ [1657846563.607009][19325:19330] 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 [1657846563.607065][19325:19330] CHIP:DMG: ] [1657846563.607081][19325:19330] CHIP:DMG: 0x1 = [ [1657846563.607120][19325:19330] CHIP:DMG: 0x2d, 0xe6, 0xa4, 0x69, 0x96, 0xf3, 0x2a, 0xee, 0xc6, 0x1a, 0x22, 0x75, 0x9, 0xea, 0xad, 0x3b, 0x3a, 0x91, 0x5a, 0x6f, 0x5e, 0x4a, 0xb4, 0xc3, 0xab, 0xd9, 0x86, 0x9c, 0xb1, 0x20, 0x98, 0x6, 0x6a, 0x2, 0xf6, 0x8, 0x63, 0x8b, 0x38, 0xe6, 0xe9, 0x8b, 0xc6, 0xd0, 0x44, 0xf3, 0xe6, 0xc9, 0xb7, 0xa7, 0x6d, 0x50, 0xd3, 0xeb, 0x6f, 0x66, 0x49, 0x7c, 0xaa, 0x6d, 0x72, 0x4d, 0xe1, 0x28, [1657846563.607141][19325:19330] CHIP:DMG: ] [1657846563.607156][19325:19330] CHIP:DMG: }, [1657846563.607168][19325:19330] CHIP:DMG: }, [1657846563.607188][19325:19330] CHIP:DMG: [1657846563.607199][19325:19330] CHIP:DMG: }, [1657846563.607219][19325:19330] CHIP:DMG: [1657846563.607230][19325:19330] CHIP:DMG: ], [1657846563.607251][19325:19330] CHIP:DMG: [1657846563.607263][19325:19330] CHIP:DMG: InteractionModelRevision = 1 [1657846563.607274][19325:19330] CHIP:DMG: }, [1657846563.607337][19325:19330] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1657846563.607367][19325:19330] CHIP:CTL: Received Attestation Information from the device [1657846563.607391][19325:19330] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1657846563.607433][19325:19330] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1657846563.607454][19325:19330] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1657846563.607465][19325:19330] CHIP:CTL: Verifying attestation [1657846563.615446][19325:19330] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1657846563.615538][19325:19330] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1657846563.615579][19325:19330] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1657846563.615626][19325:19330] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1657846563.615667][19325:19330] CHIP:CTL: Sending CSR request to 0x7efff0000b60 device [1657846563.615764][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846563.615818][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846563.615944][19325:19330] CHIP:IN: Prepared secure message 0x7f000630c7c0 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60009i with MessageCounter:86939822. [1657846563.616056][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630c7c0 with MessageCounter:86939822 to 0xFFFFFFFB00000000 (0) at monotonic time: 000000000065F5DE msec [1657846563.616194][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846563.616246][19325:19330] CHIP:CTL: Sent CSR request, waiting for the CSR [1657846563.616325][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846563.748333][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846564.306122][19325:19328] CHIP:DL: Indication received, conn = 0x7efffc0363a0 [1657846564.306452][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1657846581.813496][19325:19330] CHIP:BLE: ack recv timeout, closing ep 0x55f81d3f2ae0 [1657846581.813565][19325:19330] CHIP:IN: Clearing BLE pending packets. [1657846581.818393][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1657846581.818454][19325:19330] CHIP:BLE: Auto-closing end point's BLE connection. [1657846581.818468][19325:19330] CHIP:DL: Closing BLE GATT connection (con 0x7efffc0363a0) [1657846581.818695][19325:19328] CHIP:DL: BluezDisconnect peer=D8:7E:7F:CB:27:2F [1657846584.852927][19325:19328] CHIP:DL: Bluez disconnected [1657846584.852976][19325:19328] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1657846584.853445][19325:19330] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1657846584.853496][19325:19330] CHIP:BLE: no endpoint for BLE sent data ack [1657846593.623246][19325:19330] CHIP:DMG: Time out! failed to receive invoke command response from Exchange: 60009i [1657846593.623331][19325:19330] CHIP:CTL: Device failed to receive the CSR request Response: ../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout [1657846593.623368][19325:19330] CHIP:CTL: Error on commissioning step 'SendOpCertSigningRequest': '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' [1657846593.623386][19325:19330] CHIP:CTL: Failed to perform commissioning step 9 [1657846593.623405][19325:19330] CHIP:CTL: Going from commissioning step 'SendOpCertSigningRequest' with lastErr = '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' -> 'Cleanup' [1657846593.623439][19325:19330] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = '../../third_party/connectedhomeip/src/app/CommandSender.cpp:214: CHIP Error 0x00000032: Timeout' [1657846593.623468][19325:19330] CHIP:CTL: Expiring failsafe on proxy 0x7efff0000b60 [1657846593.623552][19325:19330] CHIP:DMG: ICR moving to [AddingComm] [1657846593.623581][19325:19330] CHIP:DMG: ICR moving to [AddedComma] [1657846593.623695][19325:19330] CHIP:IN: Prepared secure message 0x7f000630dd90 to 0xFFFFFFFB00000000 (0) of type 0x8 and protocolId (0, 1) on exchange 60010i with MessageCounter:86939823. [1657846593.623745][19325:19330] CHIP:IN: Sending encrypted msg 0x7f000630dd90 with MessageCounter:86939823 to 0xFFFFFFFB00000000 (0) at monotonic time: 0000000000666B15 msec [1657846593.623783][19325:19330] CHIP:IN: Message appended to BLE send queue [1657846593.623801][19325:19330] CHIP:DMG: ICR moving to [CommandSen] [1657846593.623833][19325:19330] CHIP:DMG: ICR moving to [AwaitingDe] [1657846597.682174][19325:19330] CHIP:DIS: Closing all BLE connections [1657846597.682306][19325:19330] CHIP:TOO: Pairing Failure: ../../third_party/connectedhomeip/src/controller/CHIPDeviceController.cpp:1406: CHIP Error 0x00000032: Timeout [1657846597.682781][19325:19325] CHIP:CTL: Shutting down the commissioner [1657846597.682886][19325:19325] CHIP:CTL: Shutting down the controller [1657846597.682915][19325:19325] CHIP:CTL: Shutting down the commissioner [1657846597.682969][19325:19325] CHIP:CTL: Shutting down the controller [1657846597.682992][19325:19325] CHIP:IN: Expiring all sessions for fabric 0x1!! [1657846597.683008][19325:19325] CHIP:FP: Forgetting fabric 0x1 [1657846597.683034][19325:19325] CHIP:TS: Pending Last Known Good Time: 2022-07-15T09:49:29 [1657846597.683291][19325:19325] CHIP:TS: Previous Last Known Good Time: 2022-07-15T09:49:29 [1657846597.683315][19325:19325] CHIP:TS: Reverted Last Known Good Time to previous value [1657846597.683352][19325:19325] CHIP:CTL: Shutting down the commissioner [1657846597.683407][19325:19325] CHIP:CTL: Shutting down the controller [1657846597.683428][19325:19325] CHIP:IN: Expiring all sessions for fabric 0x2!! [1657846597.683441][19325:19325] CHIP:FP: Forgetting fabric 0x2 [1657846597.683460][19325:19325] CHIP:TS: Pending Last Known Good Time: 2022-07-15T09:49:29 [1657846597.683626][19325:19325] CHIP:TS: Previous Last Known Good Time: 2022-07-15T09:49:29 [1657846597.683648][19325:19325] CHIP:TS: Reverted Last Known Good Time to previous value [1657846597.683679][19325:19325] CHIP:CTL: Shutting down the commissioner [1657846597.683731][19325:19325] CHIP:CTL: Shutting down the controller [1657846597.683752][19325:19325] CHIP:IN: Expiring all sessions for fabric 0x3!! [1657846597.683764][19325:19325] CHIP:FP: Forgetting fabric 0x3 [1657846597.683783][19325:19325] CHIP:TS: Pending Last Known Good Time: 2022-07-15T09:49:29 [1657846597.683951][19325:19325] CHIP:TS: Previous Last Known Good Time: 2022-07-15T09:49:29 [1657846597.684037][19325:19325] CHIP:TS: Reverted Last Known Good Time to previous value [1657846597.684066][19325:19325] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1657846597.684184][19325:19325] CHIP:DMG: IM WH moving to [Uninitialized] [1657846597.684207][19325:19325] CHIP:DMG: IM WH moving to [Uninitialized] [1657846597.684219][19325:19325] CHIP:DMG: IM WH moving to [Uninitialized] [1657846597.684229][19325:19325] CHIP:DMG: IM WH moving to [Uninitialized] [1657846597.684246][19325:19325] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1657846597.684307][19325:19325] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1657846597.684622][19325:19325] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-GXB2Qs) [1657846597.685333][19325:19325] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1657846597.685430][19325:19325] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1657846597.685451][19325:19325] CHIP:DL: Inet Layer shutdown [1657846597.685463][19325:19325] CHIP:DL: BLE shutdown [1657846597.687295][19325:19325] CHIP:DL: System Layer shutdown [1657846597.687401][19325:19325] CHIP:IN: SecureSession[0x7efff0001a30]: MarkForEviction Type:1 LSID:54258 [1657846597.687429][19325:19325] CHIP:SC: SecureSession[0x7efff0001a30]: Moving from state 'kActive' --> 'kPendingEviction' Segmentation fault (core dumped)