[1650319716665] [35348:4676899] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319716671] [35348:4676899] CHIP: [DL] _Init [1650319716671] [35348:4676899] CHIP: [IN] UDP::Init bind&listen port=0 [1650319716671] [35348:4676899] CHIP: [IN] UDP::Init bound to port=52555 [1650319716671] [35348:4676899] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319716671] [35348:4676899] CHIP: [IN] TransportMgr initialized [1650319716671] [35348:4676899] CHIP: [DIS] Init fabric pairing table with server storage [1650319716671] [35348:4676899] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319716671] [35348:4676899] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319716671] [35348:4676899] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319716672] [35348:4676899] CHIP: [DIS] Add fabric pairing table delegate [1650319716673] [35348:4676899] CHIP: [ZCL] Using ZAP configuration... [1650319716673] [35348:4676899] CHIP: [CTL] System State Initialized... [1650319716674] [35348:4676899] CHIP: [CTL] Setting attestation nonce to random value [1650319716674] [35348:4676899] CHIP: [CTL] Setting CSR nonce to random value [1650319716674] [35348:4676899] CHIP: [CTL] Setting attestation nonce to random value [1650319716674] [35348:4676899] CHIP: [CTL] Setting CSR nonce to random value [1650319716676] [35348:4676899] CHIP: [CTL] Generating NOC [1650319716679] [35348:4676899] CHIP: [DIS] Verifying the received credentials [1650319716688] [35348:4676899] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319716689] [35348:4676899] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319716689] [35348:4676899] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319716689] [35348:4676899] CHIP: [CTL] Setting attestation nonce to random value [1650319716689] [35348:4676899] CHIP: [CTL] Setting CSR nonce to random value [1650319716691] [35348:4676899] CHIP: [CTL] Generating NOC [1650319716693] [35348:4676899] CHIP: [DIS] Verifying the received credentials [1650319716703] [35348:4676899] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319716703] [35348:4676899] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319716703] [35348:4676899] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319716703] [35348:4676899] CHIP: [CTL] Setting attestation nonce to random value [1650319716703] [35348:4676899] CHIP: [CTL] Setting CSR nonce to random value [1650319716706] [35348:4676899] CHIP: [CTL] Generating NOC [1650319716708] [35348:4676899] CHIP: [DIS] Verifying the received credentials [1650319716718] [35348:4676899] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319716718] [35348:4676899] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319716718] [35348:4676899] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319716729] [35348:4676902] CHIP: [DL] _OnPlatformEvent [1650319716729] [35348:4676902] CHIP: [BLE] NewConnection [1650319716730] [35348:4676902] CHIP: [SC] Assigned local session key ID 21798 [1650319716730] [35348:4676902] CHIP: [SC] Including MRP parameters in PBKDF param request [1650319716730] [35348:4676902] CHIP: [IN] Prepared unauthenticated message 0x16d4a1588 to 0x0000000000000000 (0) of type 0x20 and protocolId (0, 0) on exchange 28337i with MessageCounter:3824091034. [1650319716730] [35348:4676902] CHIP: [IN] Sending unauthenticated msg 0x16d4a1588 with MessageCounter:3824091034 to 0x0000000000000000 at monotonic time: 000000001FDCCB37 msec [1650319716730] [35348:4676902] CHIP: [IN] Message appended to BLE send queue [1650319716730] [35348:4676902] CHIP: [SC] Sent PBKDF param request [1650319716730] [35348:4676902] CHIP: [CTL] Setting thread operational dataset from parameters [1650319716730] [35348:4676902] CHIP: [CTL] Setting attestation nonce to random value [1650319716730] [35348:4676902] CHIP: [CTL] Setting CSR nonce to random value [1650319716730] [35348:4676902] CHIP: [CTL] Commission called for node ID 0x0000000000000002 [1650319716745] [35348:4676903] CHIP: [BLE] CBManagerState: ON [1650319719247] [35348:4676903] CHIP: [BLE] Connecting to device with discriminator: 3840 [1650319720475] [35348:4676903] CHIP: [IN] BleConnectionComplete: endPoint 0x10367a8f8 [1650319720475] [35348:4676903] CHIP: [BLE] ATT MTU = 256 [1650319720575] [35348:4676903] CHIP: [BLE] subscribe complete, ep = 0x10367a8f8 [1650319720575] [35348:4676903] CHIP: [BLE] peripheral chose BTP version 4; central expected between 4 and 4 [1650319720575] [35348:4676903] CHIP: [BLE] using BTP fragment sizes rx 244 / tx 244. [1650319720575] [35348:4676903] CHIP: [BLE] local and remote recv window size = 5 [1650319720575] [35348:4676903] CHIP: [IN] BLE EndPoint 0x10367a8f8 Connection Complete [1650319720670] [35348:4676902] CHIP: [EM] Received message of type 0x21 with protocolId (0, 0) and MessageCounter:1908224135 on exchange 28337i [1650319720670] [35348:4676902] CHIP: [EM] Found matching exchange: 28337i, Delegate: 0x14900d260 [1650319720670] [35348:4676902] CHIP: [SC] Received PBKDF param response [1650319720670] [35348:4676902] CHIP: [SC] Peer assigned session ID 57912 [1650319720670] [35348:4676902] CHIP: [SC] Found MRP parameters in the message [1650319720694] [35348:4676902] CHIP: [IN] Prepared unauthenticated message 0x16d4a0c38 to 0x0000000000000000 (0) of type 0x22 and protocolId (0, 0) on exchange 28337i with MessageCounter:3824091035. [1650319720694] [35348:4676902] CHIP: [IN] Sending unauthenticated msg 0x16d4a0c38 with MessageCounter:3824091035 to 0x0000000000000000 at monotonic time: 000000001FDCDAB4 msec [1650319720694] [35348:4676902] CHIP: [SC] Sent spake2p msg1 [1650319723367] [35348:4676903] CHIP: [EM] Received message of type 0x23 with protocolId (0, 0) and MessageCounter:1908224136 on exchange 28337i [1650319723367] [35348:4676903] CHIP: [EM] Found matching exchange: 28337i, Delegate: 0x14900d260 [1650319723368] [35348:4676903] CHIP: [SC] Received spake2p msg2 [1650319723392] [35348:4676903] CHIP: [IN] Prepared unauthenticated message 0x16d52d088 to 0x0000000000000000 (0) of type 0x24 and protocolId (0, 0) on exchange 28337i with MessageCounter:3824091036. [1650319723392] [35348:4676903] CHIP: [IN] Sending unauthenticated msg 0x16d52d088 with MessageCounter:3824091036 to 0x0000000000000000 at monotonic time: 000000001FDCE53D msec [1650319723392] [35348:4676903] CHIP: [SC] Sent spake2p msg3 [1650319723466] [35348:4676903] CHIP: [EM] Received message of type 0x40 with protocolId (0, 0) and MessageCounter:1908224137 on exchange 28337i [1650319723466] [35348:4676903] CHIP: [EM] Found matching exchange: 28337i, Delegate: 0x14900d260 [1650319723466] [35348:4676903] CHIP: [IN] New secure session created for device 0x0000000000000002, LSID:21798 PSID:57912! [1650319723466] [35348:4676903] CHIP: [CTL] Remote device completed SPAKE2+ handshake [1650319723466] [35348:4676903] CHIP: [TOO] Pairing Success [1650319723466] [35348:4676903] CHIP: [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1650319723466] [35348:4676903] CHIP: [CTL] Performing next commissioning step 'ReadCommissioningInfo' [1650319723466] [35348:4676903] CHIP: [CTL] Sending request for commissioning information [1650319723466] [35348:4676903] CHIP: [DMG] SendReadRequest ReadClient[0x600000958000]: Sending Read Request [1650319723466] [35348:4676903] CHIP: [IN] Prepared secure message 0x16d52aff8 to 0x0000000000000002 (1) of type 0x2 and protocolId (0, 1) on exchange 28338i with MessageCounter:7761371. [1650319723466] [35348:4676903] CHIP: [IN] Sending encrypted msg 0x16d52aff8 with MessageCounter:7761371 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE588 msec [1650319723466] [35348:4676903] CHIP: [DMG] MoveToState ReadClient[0x600000958000]: Moving to [AwaitingIn] [1650319723600] [35348:4676903] CHIP: [EM] Received message of type 0x5 with protocolId (0, 1) and MessageCounter:15404079 on exchange 28338i [1650319723600] [35348:4676903] CHIP: [EM] Found matching exchange: 28338i, Delegate: 0x600000958000 [1650319723600] [35348:4676903] CHIP: [DMG] ReportDataMessage = [1650319723600] [35348:4676903] CHIP: [DMG] { [1650319723600] [35348:4676903] CHIP: [DMG] AttributeReportIBs = [1650319723600] [35348:4676903] CHIP: [DMG] [ [1650319723600] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723600] [35348:4676903] CHIP: [DMG] { [1650319723600] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723600] [35348:4676903] CHIP: [DMG] { [1650319723600] [35348:4676903] CHIP: [DMG] DataVersion = 0x6c26d9c5, [1650319723600] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723600] [35348:4676903] CHIP: [DMG] { [1650319723600] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723600] [35348:4676903] CHIP: [DMG] Cluster = 0x31, [1650319723600] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0003, [1650319723600] [35348:4676903] CHIP: [DMG] } [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] Data = 30, [1650319723601] [35348:4676903] CHIP: [DMG] }, [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] }, [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] DataVersion = 0x8f999a77, [1650319723601] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723601] [35348:4676903] CHIP: [DMG] Cluster = 0x28, [1650319723601] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0004, [1650319723601] [35348:4676903] CHIP: [DMG] } [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] Data = 32769, [1650319723601] [35348:4676903] CHIP: [DMG] }, [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] }, [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] DataVersion = 0x8f999a77, [1650319723601] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723601] [35348:4676903] CHIP: [DMG] { [1650319723601] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723601] [35348:4676903] CHIP: [DMG] Cluster = 0x28, [1650319723601] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0002, [1650319723601] [35348:4676903] CHIP: [DMG] } [1650319723601] [35348:4676903] CHIP: [DMG] [1650319723601] [35348:4676903] CHIP: [DMG] Data = 65521, [1650319723601] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] DataVersion = 0x1f99268e, [1650319723602] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723602] [35348:4676903] CHIP: [DMG] Cluster = 0x30, [1650319723602] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0003, [1650319723602] [35348:4676903] CHIP: [DMG] } [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] Data = 0, [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] DataVersion = 0x1f99268e, [1650319723602] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723602] [35348:4676903] CHIP: [DMG] Cluster = 0x30, [1650319723602] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0002, [1650319723602] [35348:4676903] CHIP: [DMG] } [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] Data = 0, [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] DataVersion = 0x1f99268e, [1650319723602] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723602] [35348:4676903] CHIP: [DMG] Cluster = 0x30, [1650319723602] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0001, [1650319723602] [35348:4676903] CHIP: [DMG] } [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] Data = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] 0x0 = 60, [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] }, [1650319723602] [35348:4676903] CHIP: [DMG] [1650319723602] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723602] [35348:4676903] CHIP: [DMG] { [1650319723602] [35348:4676903] CHIP: [DMG] DataVersion = 0x1f99268e, [1650319723603] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723603] [35348:4676903] CHIP: [DMG] { [1650319723603] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723603] [35348:4676903] CHIP: [DMG] Cluster = 0x30, [1650319723603] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_0000, [1650319723603] [35348:4676903] CHIP: [DMG] } [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] Data = 0, [1650319723603] [35348:4676903] CHIP: [DMG] }, [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] }, [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] AttributeReportIB = [1650319723603] [35348:4676903] CHIP: [DMG] { [1650319723603] [35348:4676903] CHIP: [DMG] AttributeDataIB = [1650319723603] [35348:4676903] CHIP: [DMG] { [1650319723603] [35348:4676903] CHIP: [DMG] DataVersion = 0x6c26d9c5, [1650319723603] [35348:4676903] CHIP: [DMG] AttributePathIB = [1650319723603] [35348:4676903] CHIP: [DMG] { [1650319723603] [35348:4676903] CHIP: [DMG] Endpoint = 0x0, [1650319723603] [35348:4676903] CHIP: [DMG] Cluster = 0x31, [1650319723603] [35348:4676903] CHIP: [DMG] Attribute = 0x0000_FFFC, [1650319723603] [35348:4676903] CHIP: [DMG] } [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] Data = 1, [1650319723603] [35348:4676903] CHIP: [DMG] }, [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] }, [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] ], [1650319723603] [35348:4676903] CHIP: [DMG] [1650319723603] [35348:4676903] CHIP: [DMG] SuppressResponse = true, [1650319723603] [35348:4676903] CHIP: [DMG] InteractionModelRevision = 1 [1650319723603] [35348:4676903] CHIP: [DMG] } [1650319723604] [35348:4676903] CHIP: [CTL] Successfully finished commissioning step 'ReadCommissioningInfo' [1650319723604] [35348:4676903] CHIP: [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1650319723604] [35348:4676903] CHIP: [CTL] Performing next commissioning step 'ArmFailSafe' [1650319723604] [35348:4676903] CHIP: [CTL] Arming failsafe (60 seconds) [1650319723604] [35348:4676903] CHIP: [DMG] ICR moving to [AddingComm] [1650319723604] [35348:4676903] CHIP: [DMG] ICR moving to [AddedComma] [1650319723604] [35348:4676903] CHIP: [IN] Prepared secure message 0x16d52ac88 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28339i with MessageCounter:7761372. [1650319723604] [35348:4676903] CHIP: [IN] Sending encrypted msg 0x16d52ac88 with MessageCounter:7761372 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE612 msec [1650319723604] [35348:4676903] CHIP: [DMG] ICR moving to [CommandSen] [1650319723705] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404080 on exchange 28339i [1650319723705] [35348:4676902] CHIP: [EM] Found matching exchange: 28339i, Delegate: 0x131004080 [1650319723705] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319723705] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319723705] [35348:4676902] CHIP: [DMG] { [1650319723705] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319723705] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319723705] [35348:4676902] CHIP: [DMG] [ [1650319723705] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319723705] [35348:4676902] CHIP: [DMG] { [1650319723705] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319723705] [35348:4676902] CHIP: [DMG] { [1650319723705] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319723705] [35348:4676902] CHIP: [DMG] { [1650319723705] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319723705] [35348:4676902] CHIP: [DMG] ClusterId = 0x30, [1650319723705] [35348:4676902] CHIP: [DMG] CommandId = 0x1, [1650319723705] [35348:4676902] CHIP: [DMG] }, [1650319723705] [35348:4676902] CHIP: [DMG] [1650319723705] [35348:4676902] CHIP: [DMG] CommandData = [1650319723705] [35348:4676902] CHIP: [DMG] { [1650319723705] [35348:4676902] CHIP: [DMG] 0x0 = 0, [1650319723705] [35348:4676902] CHIP: [DMG] 0x1 = "", [1650319723705] [35348:4676902] CHIP: [DMG] }, [1650319723705] [35348:4676902] CHIP: [DMG] }, [1650319723705] [35348:4676902] CHIP: [DMG] [1650319723705] [35348:4676902] CHIP: [DMG] }, [1650319723705] [35348:4676902] CHIP: [DMG] [1650319723705] [35348:4676902] CHIP: [DMG] ], [1650319723705] [35348:4676902] CHIP: [DMG] [1650319723705] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319723705] [35348:4676902] CHIP: [DMG] }, [1650319723705] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1650319723705] [35348:4676902] CHIP: [CTL] Received ArmFailSafe response [1650319723705] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'ArmFailSafe' [1650319723705] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1650319723705] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'ConfigRegulatory' [1650319723705] [35348:4676902] CHIP: [CTL] Setting Regulatory Config [1650319723705] [35348:4676902] CHIP: [CTL] Device does not support configurable regulatory location [1650319723705] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319723705] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319723705] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d49e478 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28340i with MessageCounter:7761373. [1650319723705] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d49e478 with MessageCounter:7761373 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE677 msec [1650319723706] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319723706] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319723793] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404081 on exchange 28340i [1650319723793] [35348:4676902] CHIP: [EM] Found matching exchange: 28340i, Delegate: 0x127704490 [1650319723793] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319723793] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319723793] [35348:4676902] CHIP: [DMG] { [1650319723793] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319723793] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319723793] [35348:4676902] CHIP: [DMG] [ [1650319723793] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319723793] [35348:4676902] CHIP: [DMG] { [1650319723793] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319723793] [35348:4676902] CHIP: [DMG] { [1650319723793] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319723793] [35348:4676902] CHIP: [DMG] { [1650319723793] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319723793] [35348:4676902] CHIP: [DMG] ClusterId = 0x30, [1650319723793] [35348:4676902] CHIP: [DMG] CommandId = 0x3, [1650319723794] [35348:4676902] CHIP: [DMG] }, [1650319723794] [35348:4676902] CHIP: [DMG] [1650319723794] [35348:4676902] CHIP: [DMG] CommandData = [1650319723794] [35348:4676902] CHIP: [DMG] { [1650319723794] [35348:4676902] CHIP: [DMG] 0x0 = 0, [1650319723794] [35348:4676902] CHIP: [DMG] 0x1 = "", [1650319723794] [35348:4676902] CHIP: [DMG] }, [1650319723794] [35348:4676902] CHIP: [DMG] }, [1650319723794] [35348:4676902] CHIP: [DMG] [1650319723794] [35348:4676902] CHIP: [DMG] }, [1650319723794] [35348:4676902] CHIP: [DMG] [1650319723794] [35348:4676902] CHIP: [DMG] ], [1650319723794] [35348:4676902] CHIP: [DMG] [1650319723794] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319723794] [35348:4676902] CHIP: [DMG] }, [1650319723794] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1650319723794] [35348:4676902] CHIP: [CTL] Received SetRegulatoryConfig response [1650319723794] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'ConfigRegulatory' [1650319723794] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1650319723794] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'SendPAICertificateRequest' [1650319723794] [35348:4676902] CHIP: [CTL] Sending request for PAI certificate [1650319723794] [35348:4676902] CHIP: [CTL] Sending Certificate Chain request to 0x14900d200 device [1650319723794] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319723794] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319723794] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d49e3a8 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28341i with MessageCounter:7761374. [1650319723794] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d49e3a8 with MessageCounter:7761374 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE6D0 msec [1650319723795] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319723795] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319723916] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404082 on exchange 28341i [1650319723916] [35348:4676902] CHIP: [EM] Found matching exchange: 28341i, Delegate: 0x1277045d0 [1650319723916] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319723917] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319723917] [35348:4676902] CHIP: [DMG] { [1650319723917] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319723917] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319723917] [35348:4676902] CHIP: [DMG] [ [1650319723917] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319723917] [35348:4676902] CHIP: [DMG] { [1650319723917] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319723917] [35348:4676902] CHIP: [DMG] { [1650319723917] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319723917] [35348:4676902] CHIP: [DMG] { [1650319723917] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319723917] [35348:4676902] CHIP: [DMG] ClusterId = 0x3e, [1650319723917] [35348:4676902] CHIP: [DMG] CommandId = 0x3, [1650319723917] [35348:4676902] CHIP: [DMG] }, [1650319723917] [35348:4676902] CHIP: [DMG] [1650319723917] [35348:4676902] CHIP: [DMG] CommandData = [1650319723917] [35348:4676902] CHIP: [DMG] { [1650319723917] [35348:4676902] CHIP: [DMG] 0x0 = [ [1650319723917] [35348:4676902] CHIP: [DMG] ... (byte string too long) ... [1650319723917] [35348:4676902] CHIP: [DMG] ] [1650319723917] [35348:4676902] CHIP: [DMG] }, [1650319723917] [35348:4676902] CHIP: [DMG] }, [1650319723917] [35348:4676902] CHIP: [DMG] [1650319723917] [35348:4676902] CHIP: [DMG] }, [1650319723917] [35348:4676902] CHIP: [DMG] [1650319723917] [35348:4676902] CHIP: [DMG] ], [1650319723917] [35348:4676902] CHIP: [DMG] [1650319723917] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319723917] [35348:4676902] CHIP: [DMG] }, [1650319723917] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1650319723917] [35348:4676902] CHIP: [CTL] Received certificate chain from the device [1650319723917] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'SendPAICertificateRequest' [1650319723917] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1650319723917] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'SendDACCertificateRequest' [1650319723917] [35348:4676902] CHIP: [CTL] Sending request for DAC certificate [1650319723917] [35348:4676902] CHIP: [CTL] Sending Certificate Chain request to 0x14900d200 device [1650319723917] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319723917] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319723918] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d49e328 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28342i with MessageCounter:7761375. [1650319723918] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d49e328 with MessageCounter:7761375 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE74B msec [1650319723918] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319723918] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319724060] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404083 on exchange 28342i [1650319724061] [35348:4676902] CHIP: [EM] Found matching exchange: 28342i, Delegate: 0x131004080 [1650319724061] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319724061] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319724061] [35348:4676902] CHIP: [DMG] { [1650319724061] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319724061] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319724061] [35348:4676902] CHIP: [DMG] [ [1650319724061] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319724061] [35348:4676902] CHIP: [DMG] { [1650319724061] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319724061] [35348:4676902] CHIP: [DMG] { [1650319724061] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319724061] [35348:4676902] CHIP: [DMG] { [1650319724061] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319724061] [35348:4676902] CHIP: [DMG] ClusterId = 0x3e, [1650319724061] [35348:4676902] CHIP: [DMG] CommandId = 0x3, [1650319724061] [35348:4676902] CHIP: [DMG] }, [1650319724061] [35348:4676902] CHIP: [DMG] [1650319724061] [35348:4676902] CHIP: [DMG] CommandData = [1650319724061] [35348:4676902] CHIP: [DMG] { [1650319724061] [35348:4676902] CHIP: [DMG] 0x0 = [ [1650319724061] [35348:4676902] CHIP: [DMG] ... (byte string too long) ... [1650319724061] [35348:4676902] CHIP: [DMG] ] [1650319724061] [35348:4676902] CHIP: [DMG] }, [1650319724061] [35348:4676902] CHIP: [DMG] }, [1650319724061] [35348:4676902] CHIP: [DMG] [1650319724061] [35348:4676902] CHIP: [DMG] }, [1650319724061] [35348:4676902] CHIP: [DMG] [1650319724061] [35348:4676902] CHIP: [DMG] ], [1650319724061] [35348:4676902] CHIP: [DMG] [1650319724061] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319724061] [35348:4676902] CHIP: [DMG] }, [1650319724061] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1650319724061] [35348:4676902] CHIP: [CTL] Received certificate chain from the device [1650319724061] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'SendDACCertificateRequest' [1650319724061] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1650319724061] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'SendAttestationRequest' [1650319724061] [35348:4676902] CHIP: [CTL] Sending Attestation Request to the device. [1650319724061] [35348:4676902] CHIP: [CTL] Sending Attestation request to 0x14900d200 device [1650319724061] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319724061] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319724061] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d49e2f8 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28343i with MessageCounter:7761376. [1650319724061] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d49e2f8 with MessageCounter:7761376 to 0x0000000000000002 (1) at monotonic time: 000000001FDCE7DB msec [1650319724062] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319724062] [35348:4676902] CHIP: [CTL] Sent Attestation request, waiting for the Attestation Information [1650319724062] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319724743] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404084 on exchange 28343i [1650319724743] [35348:4676902] CHIP: [EM] Found matching exchange: 28343i, Delegate: 0x1277048e0 [1650319724743] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319724743] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319724743] [35348:4676902] CHIP: [DMG] { [1650319724743] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319724743] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319724743] [35348:4676902] CHIP: [DMG] [ [1650319724743] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319724743] [35348:4676902] CHIP: [DMG] { [1650319724743] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319724743] [35348:4676902] CHIP: [DMG] { [1650319724743] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319724743] [35348:4676902] CHIP: [DMG] { [1650319724743] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319724743] [35348:4676902] CHIP: [DMG] ClusterId = 0x3e, [1650319724743] [35348:4676902] CHIP: [DMG] CommandId = 0x1, [1650319724743] [35348:4676902] CHIP: [DMG] }, [1650319724743] [35348:4676902] CHIP: [DMG] [1650319724743] [35348:4676902] CHIP: [DMG] CommandData = [1650319724743] [35348:4676902] CHIP: [DMG] { [1650319724743] [35348:4676902] CHIP: [DMG] 0x0 = [ [1650319724743] [35348:4676902] CHIP: [DMG] ... (byte string too long) ... [1650319724743] [35348:4676902] CHIP: [DMG] ] [1650319724743] [35348:4676902] CHIP: [DMG] 0x1 = [ [1650319724743] [35348:4676902] CHIP: [DMG] 0x1f, 0x87, 0x53, 0xff, 0x78, 0xda, 0x11, 0xc0, 0x35, 0x9f, 0xca, 0xb2, 0x19, 0xf0, 0x78, 0x21, 0x4a, 0x78, 0xd, 0x88, 0xa8, 0x42, 0x96, 0xd6, 0x78, 0x65, 0x1b, 0x5c, 0x69, 0x8f, 0x8b, 0x21, 0x41, 0xf, 0xd [1650319724743] [35348:4676902] CHIP: [DMG] ] [1650319724743] [35348:4676902] CHIP: [DMG] }, [1650319724743] [35348:4676902] CHIP: [DMG] }, [1650319724743] [35348:4676902] CHIP: [DMG] [1650319724743] [35348:4676902] CHIP: [DMG] }, [1650319724743] [35348:4676902] CHIP: [DMG] [1650319724743] [35348:4676902] CHIP: [DMG] ], [1650319724743] [35348:4676902] CHIP: [DMG] [1650319724743] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319724743] [35348:4676902] CHIP: [DMG] }, [1650319724743] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1650319724744] [35348:4676902] CHIP: [CTL] Received Attestation Information from the device [1650319724744] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'SendAttestationRequest' [1650319724744] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1650319724744] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'AttestationVerification' [1650319724744] [35348:4676902] CHIP: [CTL] Verifying attestation [1650319724759] [35348:4676902] CHIP: [CR] mbedTLS error: ERROR - Generic error [1650319724784] [35348:4676902] CHIP: [CTL] Successfully validated 'Attestation Information' command received from the device. [1650319724784] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'AttestationVerification' [1650319724784] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1650319724784] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'SendOpCertSigningRequest' [1650319724784] [35348:4676902] CHIP: [CTL] Sending CSR request to 0x14900d200 device [1650319724784] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319724784] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319724784] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d49b968 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28344i with MessageCounter:7761377. [1650319724784] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d49b968 with MessageCounter:7761377 to 0x0000000000000002 (1) at monotonic time: 000000001FDCEAAD msec [1650319724784] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319724784] [35348:4676902] CHIP: [CTL] Sent CSR request, waiting for the CSR [1650319724784] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319726945] [35348:4676902] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404085 on exchange 28344i [1650319726946] [35348:4676902] CHIP: [EM] Found matching exchange: 28344i, Delegate: 0x131104080 [1650319726946] [35348:4676902] CHIP: [DMG] ICR moving to [ResponseRe] [1650319726946] [35348:4676902] CHIP: [DMG] InvokeResponseMessage = [1650319726946] [35348:4676902] CHIP: [DMG] { [1650319726946] [35348:4676902] CHIP: [DMG] suppressResponse = false, [1650319726946] [35348:4676902] CHIP: [DMG] InvokeResponseIBs = [1650319726946] [35348:4676902] CHIP: [DMG] [ [1650319726946] [35348:4676902] CHIP: [DMG] InvokeResponseIB = [1650319726946] [35348:4676902] CHIP: [DMG] { [1650319726946] [35348:4676902] CHIP: [DMG] CommandDataIB = [1650319726946] [35348:4676902] CHIP: [DMG] { [1650319726946] [35348:4676902] CHIP: [DMG] CommandPathIB = [1650319726946] [35348:4676902] CHIP: [DMG] { [1650319726946] [35348:4676902] CHIP: [DMG] EndpointId = 0x0, [1650319726946] [35348:4676902] CHIP: [DMG] ClusterId = 0x3e, [1650319726946] [35348:4676902] CHIP: [DMG] CommandId = 0x5, [1650319726946] [35348:4676902] CHIP: [DMG] }, [1650319726946] [35348:4676902] CHIP: [DMG] [1650319726946] [35348:4676902] CHIP: [DMG] CommandData = [1650319726946] [35348:4676902] CHIP: [DMG] { [1650319726946] [35348:4676902] CHIP: [DMG] 0x0 = [ [1650319726946] [35348:4676902] CHIP: [DMG] 0x15, 0x30, 0x1, 0xcd, 0x30, 0x81, 0xca, 0x30, 0x70, 0x2, 0x1, 0x0, 0x30, 0xe, 0x31, 0xc, 0x30, 0xa, 0x6, 0x3, 0x55, 0x4, 0xa, 0xc, 0x3, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48 [1650319726946] [35348:4676902] CHIP: [DMG] ] [1650319726946] [35348:4676902] CHIP: [DMG] 0x1 = [ [1650319726946] [35348:4676902] CHIP: [DMG] 0x89, 0x2a, 0x29, 0xbc, 0x91, 0xd6, 0xc7, 0x1f, 0xc4, 0x99, 0xc9, 0x95, 0xe8, 0xb1, 0x3f, 0xe4, 0x59, 0xe9, 0x34, 0x41, 0x8d, 0x98, 0xc1, 0x4c, 0x53, 0x25, 0x84, 0xf8, 0x28, 0x9a, 0x16, 0x80, 0x47, 0xc3, 0 [1650319726946] [35348:4676902] CHIP: [DMG] ] [1650319726946] [35348:4676902] CHIP: [DMG] }, [1650319726946] [35348:4676902] CHIP: [DMG] }, [1650319726946] [35348:4676902] CHIP: [DMG] [1650319726946] [35348:4676902] CHIP: [DMG] }, [1650319726946] [35348:4676902] CHIP: [DMG] [1650319726946] [35348:4676902] CHIP: [DMG] ], [1650319726946] [35348:4676902] CHIP: [DMG] [1650319726947] [35348:4676902] CHIP: [DMG] InteractionModelRevision = 1 [1650319726947] [35348:4676902] CHIP: [DMG] }, [1650319726947] [35348:4676902] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1650319726947] [35348:4676902] CHIP: [CTL] Received certificate signing request from the device [1650319726947] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest' [1650319726947] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1650319726947] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'ValidateCSR' [1650319726961] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'ValidateCSR' [1650319726961] [35348:4676902] CHIP: [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1650319726961] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'GenerateNOCChain' [1650319726961] [35348:4676902] CHIP: [CTL] Getting certificate chain for the device from the issuer [1650319726961] [35348:4676902] CHIP: [CTL] Verifying Certificate Signing Request [1650319726971] [35348:4676902] CHIP: [CTL] Generating NOC [1650319726975] [35348:4676902] CHIP: [CTL] Providing certificate chain to the commissioner [1650319726975] [35348:4676902] CHIP: [CTL] Received callback from the CA for NOC Chain generation. Status ../../src/controller/ExampleOperationalCredentialsIssuer.cpp:255: Success [1650319726975] [35348:4676902] CHIP: [CTL] Successfully finished commissioning step 'GenerateNOCChain' [1650319726975] [35348:4676902] CHIP: [CTL] Performing next commissioning step 'SendTrustedRootCert' [1650319726975] [35348:4676902] CHIP: [CTL] Sending root certificate to the device [1650319726975] [35348:4676902] CHIP: [DMG] ICR moving to [AddingComm] [1650319726975] [35348:4676902] CHIP: [DMG] ICR moving to [AddedComma] [1650319726976] [35348:4676902] CHIP: [IN] Prepared secure message 0x16d499868 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28345i with MessageCounter:7761378. [1650319726976] [35348:4676902] CHIP: [IN] Sending encrypted msg 0x16d499868 with MessageCounter:7761378 to 0x0000000000000002 (1) at monotonic time: 000000001FDCF33D msec [1650319726976] [35348:4676902] CHIP: [DMG] ICR moving to [CommandSen] [1650319726976] [35348:4676902] CHIP: [CTL] Sent root certificate to the device [1650319726976] [35348:4676902] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319727102] [35348:4676903] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404086 on exchange 28345i [1650319727102] [35348:4676903] CHIP: [EM] Found matching exchange: 28345i, Delegate: 0x148b052c0 [1650319727102] [35348:4676903] CHIP: [DMG] ICR moving to [ResponseRe] [1650319727102] [35348:4676903] CHIP: [DMG] InvokeResponseMessage = [1650319727102] [35348:4676903] CHIP: [DMG] { [1650319727102] [35348:4676903] CHIP: [DMG] suppressResponse = false, [1650319727102] [35348:4676903] CHIP: [DMG] InvokeResponseIBs = [1650319727102] [35348:4676903] CHIP: [DMG] [ [1650319727102] [35348:4676903] CHIP: [DMG] InvokeResponseIB = [1650319727102] [35348:4676903] CHIP: [DMG] { [1650319727102] [35348:4676903] CHIP: [DMG] CommandStatusIB = [1650319727102] [35348:4676903] CHIP: [DMG] { [1650319727102] [35348:4676903] CHIP: [DMG] CommandPathIB = [1650319727102] [35348:4676903] CHIP: [DMG] { [1650319727102] [35348:4676903] CHIP: [DMG] EndpointId = 0x0, [1650319727102] [35348:4676903] CHIP: [DMG] ClusterId = 0x3e, [1650319727102] [35348:4676903] CHIP: [DMG] CommandId = 0xb, [1650319727102] [35348:4676903] CHIP: [DMG] }, [1650319727102] [35348:4676903] CHIP: [DMG] [1650319727102] [35348:4676903] CHIP: [DMG] StatusIB = [1650319727102] [35348:4676903] CHIP: [DMG] { [1650319727102] [35348:4676903] CHIP: [DMG] status = 0x00 (SUCCESS), [1650319727102] [35348:4676903] CHIP: [DMG] }, [1650319727102] [35348:4676903] CHIP: [DMG] [1650319727102] [35348:4676903] CHIP: [DMG] }, [1650319727102] [35348:4676903] CHIP: [DMG] [1650319727102] [35348:4676903] CHIP: [DMG] }, [1650319727102] [35348:4676903] CHIP: [DMG] [1650319727102] [35348:4676903] CHIP: [DMG] ], [1650319727102] [35348:4676903] CHIP: [DMG] [1650319727102] [35348:4676903] CHIP: [DMG] InteractionModelRevision = 1 [1650319727102] [35348:4676903] CHIP: [DMG] }, [1650319727102] [35348:4676903] CHIP: [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1650319727102] [35348:4676903] CHIP: [CTL] Device confirmed that it has received the root certificate [1650319727102] [35348:4676903] CHIP: [CTL] Successfully finished commissioning step 'SendTrustedRootCert' [1650319727102] [35348:4676903] CHIP: [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1650319727102] [35348:4676903] CHIP: [CTL] Performing next commissioning step 'SendNOC' [1650319727102] [35348:4676903] CHIP: [DMG] ICR moving to [AddingComm] [1650319727102] [35348:4676903] CHIP: [DMG] ICR moving to [AddedComma] [1650319727102] [35348:4676903] CHIP: [IN] Prepared secure message 0x16d52a3c8 to 0x0000000000000002 (1) of type 0x8 and protocolId (0, 1) on exchange 28346i with MessageCounter:7761379. [1650319727102] [35348:4676903] CHIP: [IN] Sending encrypted msg 0x16d52a3c8 with MessageCounter:7761379 to 0x0000000000000002 (1) at monotonic time: 000000001FDCF3BC msec [1650319727103] [35348:4676903] CHIP: [DMG] ICR moving to [CommandSen] [1650319727103] [35348:4676903] CHIP: [CTL] Sent operational certificate to the device [1650319727103] [35348:4676903] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319729542] [35348:4676903] CHIP: [EM] Received message of type 0x9 with protocolId (0, 1) and MessageCounter:15404087 on exchange 28346i [1650319729542] [35348:4676903] CHIP: [EM] Found matching exchange: 28346i, Delegate: 0x131004080 [1650319729542] [35348:4676903] CHIP: [DMG] ICR moving to [ResponseRe] [1650319729542] [35348:4676903] CHIP: [DMG] InvokeResponseMessage = [1650319729542] [35348:4676903] CHIP: [DMG] { [1650319729542] [35348:4676903] CHIP: [DMG] suppressResponse = false, [1650319729542] [35348:4676903] CHIP: [DMG] InvokeResponseIBs = [1650319729542] [35348:4676903] CHIP: [DMG] [ [1650319729542] [35348:4676903] CHIP: [DMG] InvokeResponseIB = [1650319729542] [35348:4676903] CHIP: [DMG] { [1650319729542] [35348:4676903] CHIP: [DMG] CommandDataIB = [1650319729542] [35348:4676903] CHIP: [DMG] { [1650319729542] [35348:4676903] CHIP: [DMG] CommandPathIB = [1650319729542] [35348:4676903] CHIP: [DMG] { [1650319729542] [35348:4676903] CHIP: [DMG] EndpointId = 0x0, [1650319729542] [35348:4676903] CHIP: [DMG] ClusterId = 0x3e, [1650319729542] [35348:4676903] CHIP: [DMG] CommandId = 0x8, [1650319729542] [35348:4676903] CHIP: [DMG] }, [1650319729542] [35348:4676903] CHIP: [DMG] [1650319729542] [35348:4676903] CHIP: [DMG] CommandData = [1650319729542] [35348:4676903] CHIP: [DMG] { [1650319729542] [35348:4676903] CHIP: [DMG] 0x0 = 0, [1650319729542] [35348:4676903] CHIP: [DMG] 0x1 = 1, [1650319729542] [35348:4676903] CHIP: [DMG] }, [1650319729542] [35348:4676903] CHIP: [DMG] }, [1650319729542] [35348:4676903] CHIP: [DMG] [1650319729542] [35348:4676903] CHIP: [DMG] }, [1650319729542] [35348:4676903] CHIP: [DMG] [1650319729542] [35348:4676903] CHIP: [DMG] ], [1650319729542] [35348:4676903] CHIP: [DMG] [1650319729542] [35348:4676903] CHIP: [DMG] InteractionModelRevision = 1 [1650319729542] [35348:4676903] CHIP: [DMG] }, [1650319729542] [35348:4676903] CHIP: [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1650319729542] [35348:4676903] CHIP: [CTL] Device returned status 0 on receiving the NOC [1650319729542] [35348:4676903] CHIP: [CTL] Operational credentials provisioned on device 0x14900d200 [1650319729542] [35348:4676903] CHIP: [TOO] Secure Pairing Success [1650319729543] [35348:4676903] CHIP: [CTL] Successfully finished commissioning step 'SendNOC' [1650319729543] [35348:4676903] CHIP: [CTL] Required network information not provided in commissioning parameters [1650319729543] [35348:4676903] CHIP: [CTL] Parameters supplied: wifi (no) thread (yes) [1650319729543] [35348:4676903] CHIP: [CTL] Device supports: wifi (yes) thread(no) [1650319729543] [35348:4676903] CHIP: [CTL] Going from commissioning step 'SendNOC' with lastErr = '../../src/controller/AutoCommissioner.cpp:203: CHIP Error 0x0000002F: Invalid argument' -> 'Cleanup' [1650319729543] [35348:4676903] CHIP: [CTL] Performing next commissioning step 'Cleanup' [1650319729543] [35348:4676903] CHIP: [CTL] Successfully finished commissioning step 'Cleanup' [1650319729543] [35348:4676903] CHIP: [TOO] Device commissioning completed with success [1650319729543] [35348:4676903] CHIP: [DMG] ICR moving to [AwaitingDe] [1650319729543] [35348:4676899] CHIP: [CTL] Shutting down the commissioner [1650319729543] [35348:4676899] CHIP: [CTL] Shutting down the controller [1650319729543] [35348:4676899] CHIP: [CTL] Shutting down the commissioner [1650319729543] [35348:4676899] CHIP: [CTL] Shutting down the controller [1650319729543] [35348:4676899] CHIP: [IN] Expiring all connections for fabric 1!! [1650319729544] [35348:4676899] CHIP: [CTL] Shutting down the commissioner [1650319729544] [35348:4676899] CHIP: [CTL] Shutting down the controller [1650319729544] [35348:4676899] CHIP: [IN] Expiring all connections for fabric 2!! [1650319729544] [35348:4676899] CHIP: [CTL] Shutting down the commissioner [1650319729544] [35348:4676899] CHIP: [CTL] Shutting down the controller [1650319729544] [35348:4676899] CHIP: [IN] Expiring all connections for fabric 3!! [1650319729544] [35348:4676899] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319729544] [35348:4676899] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319729544] [35348:4676899] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319729544] [35348:4676899] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319729544] [35348:4676899] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319729544] [35348:4676899] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319729544] [35348:4676899] CHIP: [BLE] CancelConnection [1650319729544] [35348:4676899] CHIP: [IN] Clearing BLE pending packets. [1650319729544] [35348:4676899] CHIP: [BLE] BtpEngine unsub failed [1650319729544] [35348:4676899] CHIP: [BLE] Auto-closing end point's BLE connection. [1650319729545] [35348:4676899] CHIP: [DL] Inet Layer shutdown [1650319729545] [35348:4676899] CHIP: [DL] BLE shutdown [1650319729545] [35348:4676899] CHIP: [DL] System Layer shutdown priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319736780] [35416:4677474] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319736787] [35416:4677474] CHIP: [DL] _Init [1650319736787] [35416:4677474] CHIP: [IN] UDP::Init bind&listen port=0 [1650319736788] [35416:4677474] CHIP: [IN] UDP::Init bound to port=57846 [1650319736788] [35416:4677474] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319736788] [35416:4677474] CHIP: [IN] TransportMgr initialized [1650319736788] [35416:4677474] CHIP: [DIS] Init fabric pairing table with server storage [1650319736788] [35416:4677474] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319736788] [35416:4677474] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319736788] [35416:4677474] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319736788] [35416:4677474] CHIP: [DIS] Add fabric pairing table delegate [1650319736789] [35416:4677474] CHIP: [ZCL] Using ZAP configuration... [1650319736789] [35416:4677474] CHIP: [CTL] System State Initialized... [1650319736789] [35416:4677474] CHIP: [CTL] Setting attestation nonce to random value [1650319736789] [35416:4677474] CHIP: [CTL] Setting CSR nonce to random value [1650319736789] [35416:4677474] CHIP: [CTL] Setting attestation nonce to random value [1650319736789] [35416:4677474] CHIP: [CTL] Setting CSR nonce to random value [1650319736792] [35416:4677474] CHIP: [CTL] Generating NOC [1650319736794] [35416:4677474] CHIP: [DIS] Verifying the received credentials [1650319736805] [35416:4677474] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319736805] [35416:4677474] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319736805] [35416:4677474] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319736805] [35416:4677474] CHIP: [CTL] Setting attestation nonce to random value [1650319736805] [35416:4677474] CHIP: [CTL] Setting CSR nonce to random value [1650319736807] [35416:4677474] CHIP: [CTL] Generating NOC [1650319736810] [35416:4677474] CHIP: [DIS] Verifying the received credentials [1650319736820] [35416:4677474] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319736820] [35416:4677474] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319736820] [35416:4677474] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319736820] [35416:4677474] CHIP: [CTL] Setting attestation nonce to random value [1650319736820] [35416:4677474] CHIP: [CTL] Setting CSR nonce to random value [1650319736823] [35416:4677474] CHIP: [CTL] Generating NOC [1650319736825] [35416:4677474] CHIP: [DIS] Verifying the received credentials [1650319736835] [35416:4677474] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319736835] [35416:4677474] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319736835] [35416:4677474] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319736846] [35416:4677482] CHIP: [DL] _OnPlatformEvent [1650319736846] [35416:4677482] CHIP: [TOO] Sending command to node 0x2 [1650319736846] [35416:4677482] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319736846] [35416:4677482] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319736846] [35416:4677482] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319736846] [35416:4677482] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319737049] [35416:4677482] CHIP: [DIS] Checking node lookup status after 202 ms [1650319746847] [35416:4677474] CHIP: [CTL] Shutting down the commissioner [1650319746847] [35416:4677474] CHIP: [CTL] Shutting down the controller [1650319746847] [35416:4677474] CHIP: [CTL] Shutting down the commissioner [1650319746847] [35416:4677474] CHIP: [CTL] Shutting down the controller [1650319746847] [35416:4677474] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319746847] [35416:4677474] CHIP: [DIS] Discovery does not require any more timeouts [1650319746848] [35416:4677474] CHIP: [IN] Expiring all connections for fabric 1!! [1650319746848] [35416:4677474] CHIP: [CTL] Shutting down the commissioner [1650319746848] [35416:4677474] CHIP: [CTL] Shutting down the controller [1650319746848] [35416:4677474] CHIP: [IN] Expiring all connections for fabric 2!! [1650319746848] [35416:4677474] CHIP: [CTL] Shutting down the commissioner [1650319746848] [35416:4677474] CHIP: [CTL] Shutting down the controller [1650319746848] [35416:4677474] CHIP: [IN] Expiring all connections for fabric 3!! [1650319746848] [35416:4677474] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319746848] [35416:4677474] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319746848] [35416:4677474] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319746848] [35416:4677474] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319746848] [35416:4677474] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319746848] [35416:4677474] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319746848] [35416:4677474] CHIP: [BLE] CancelConnection [1650319746848] [35416:4677474] CHIP: [DL] Inet Layer shutdown [1650319746848] [35416:4677474] CHIP: [DL] BLE shutdown [1650319746848] [35416:4677474] CHIP: [DL] System Layer shutdown [1650319746848] [35416:4677474] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319751792] [35435:4677807] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319751798] [35435:4677807] CHIP: [DL] _Init [1650319751798] [35435:4677807] CHIP: [IN] UDP::Init bind&listen port=0 [1650319751798] [35435:4677807] CHIP: [IN] UDP::Init bound to port=63973 [1650319751798] [35435:4677807] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319751798] [35435:4677807] CHIP: [IN] TransportMgr initialized [1650319751798] [35435:4677807] CHIP: [DIS] Init fabric pairing table with server storage [1650319751798] [35435:4677807] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319751798] [35435:4677807] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319751798] [35435:4677807] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319751798] [35435:4677807] CHIP: [DIS] Add fabric pairing table delegate [1650319751799] [35435:4677807] CHIP: [ZCL] Using ZAP configuration... [1650319751799] [35435:4677807] CHIP: [CTL] System State Initialized... [1650319751799] [35435:4677807] CHIP: [CTL] Setting attestation nonce to random value [1650319751799] [35435:4677807] CHIP: [CTL] Setting CSR nonce to random value [1650319751799] [35435:4677807] CHIP: [CTL] Setting attestation nonce to random value [1650319751799] [35435:4677807] CHIP: [CTL] Setting CSR nonce to random value [1650319751802] [35435:4677807] CHIP: [CTL] Generating NOC [1650319751804] [35435:4677807] CHIP: [DIS] Verifying the received credentials [1650319751814] [35435:4677807] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319751814] [35435:4677807] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319751814] [35435:4677807] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319751814] [35435:4677807] CHIP: [CTL] Setting attestation nonce to random value [1650319751814] [35435:4677807] CHIP: [CTL] Setting CSR nonce to random value [1650319751817] [35435:4677807] CHIP: [CTL] Generating NOC [1650319751819] [35435:4677807] CHIP: [DIS] Verifying the received credentials [1650319751829] [35435:4677807] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319751829] [35435:4677807] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319751829] [35435:4677807] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319751829] [35435:4677807] CHIP: [CTL] Setting attestation nonce to random value [1650319751829] [35435:4677807] CHIP: [CTL] Setting CSR nonce to random value [1650319751831] [35435:4677807] CHIP: [CTL] Generating NOC [1650319751834] [35435:4677807] CHIP: [DIS] Verifying the received credentials [1650319751844] [35435:4677807] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319751844] [35435:4677807] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319751844] [35435:4677807] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319751855] [35435:4677809] CHIP: [DL] _OnPlatformEvent [1650319751855] [35435:4677809] CHIP: [TOO] Sending command to node 0x2 [1650319751855] [35435:4677809] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319751855] [35435:4677809] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319751855] [35435:4677809] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319751855] [35435:4677809] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319752056] [35435:4677809] CHIP: [DIS] Checking node lookup status after 202 ms [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the commissioner [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the controller [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the commissioner [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the controller [1650319761856] [35435:4677807] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319761856] [35435:4677807] CHIP: [DIS] Discovery does not require any more timeouts [1650319761856] [35435:4677807] CHIP: [IN] Expiring all connections for fabric 1!! [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the commissioner [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the controller [1650319761856] [35435:4677807] CHIP: [IN] Expiring all connections for fabric 2!! [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the commissioner [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the controller [1650319761856] [35435:4677807] CHIP: [IN] Expiring all connections for fabric 3!! [1650319761856] [35435:4677807] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319761856] [35435:4677807] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319761856] [35435:4677807] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319761856] [35435:4677807] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319761856] [35435:4677807] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319761856] [35435:4677807] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319761857] [35435:4677807] CHIP: [BLE] CancelConnection [1650319761857] [35435:4677807] CHIP: [DL] Inet Layer shutdown [1650319761857] [35435:4677807] CHIP: [DL] BLE shutdown [1650319761857] [35435:4677807] CHIP: [DL] System Layer shutdown [1650319761857] [35435:4677807] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319770391] [35443:4678008] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319770396] [35443:4678008] CHIP: [DL] _Init [1650319770396] [35443:4678008] CHIP: [IN] UDP::Init bind&listen port=0 [1650319770396] [35443:4678008] CHIP: [IN] UDP::Init bound to port=49415 [1650319770396] [35443:4678008] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319770396] [35443:4678008] CHIP: [IN] TransportMgr initialized [1650319770396] [35443:4678008] CHIP: [DIS] Init fabric pairing table with server storage [1650319770396] [35443:4678008] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319770396] [35443:4678008] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319770397] [35443:4678008] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319770397] [35443:4678008] CHIP: [DIS] Add fabric pairing table delegate [1650319770398] [35443:4678008] CHIP: [ZCL] Using ZAP configuration... [1650319770398] [35443:4678008] CHIP: [CTL] System State Initialized... [1650319770398] [35443:4678008] CHIP: [CTL] Setting attestation nonce to random value [1650319770398] [35443:4678008] CHIP: [CTL] Setting CSR nonce to random value [1650319770398] [35443:4678008] CHIP: [CTL] Setting attestation nonce to random value [1650319770398] [35443:4678008] CHIP: [CTL] Setting CSR nonce to random value [1650319770400] [35443:4678008] CHIP: [CTL] Generating NOC [1650319770403] [35443:4678008] CHIP: [DIS] Verifying the received credentials [1650319770413] [35443:4678008] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319770413] [35443:4678008] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319770413] [35443:4678008] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319770413] [35443:4678008] CHIP: [CTL] Setting attestation nonce to random value [1650319770413] [35443:4678008] CHIP: [CTL] Setting CSR nonce to random value [1650319770415] [35443:4678008] CHIP: [CTL] Generating NOC [1650319770418] [35443:4678008] CHIP: [DIS] Verifying the received credentials [1650319770428] [35443:4678008] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319770428] [35443:4678008] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319770428] [35443:4678008] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319770428] [35443:4678008] CHIP: [CTL] Setting attestation nonce to random value [1650319770428] [35443:4678008] CHIP: [CTL] Setting CSR nonce to random value [1650319770430] [35443:4678008] CHIP: [CTL] Generating NOC [1650319770433] [35443:4678008] CHIP: [DIS] Verifying the received credentials [1650319770443] [35443:4678008] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319770443] [35443:4678008] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319770443] [35443:4678008] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319770455] [35443:4678016] CHIP: [DL] _OnPlatformEvent [1650319770455] [35443:4678016] CHIP: [TOO] Sending command to node 0x2 [1650319770455] [35443:4678016] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319770455] [35443:4678016] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319770455] [35443:4678016] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319770455] [35443:4678016] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319770656] [35443:4678019] CHIP: [DIS] Checking node lookup status after 202 ms [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the commissioner [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the controller [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the commissioner [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the controller [1650319780460] [35443:4678008] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319780460] [35443:4678008] CHIP: [DIS] Discovery does not require any more timeouts [1650319780460] [35443:4678008] CHIP: [IN] Expiring all connections for fabric 1!! [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the commissioner [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the controller [1650319780460] [35443:4678008] CHIP: [IN] Expiring all connections for fabric 2!! [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the commissioner [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the controller [1650319780460] [35443:4678008] CHIP: [IN] Expiring all connections for fabric 3!! [1650319780460] [35443:4678008] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319780460] [35443:4678008] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319780460] [35443:4678008] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319780460] [35443:4678008] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319780460] [35443:4678008] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319780460] [35443:4678008] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319780460] [35443:4678008] CHIP: [BLE] CancelConnection [1650319780460] [35443:4678008] CHIP: [DL] Inet Layer shutdown [1650319780460] [35443:4678008] CHIP: [DL] BLE shutdown [1650319780460] [35443:4678008] CHIP: [DL] System Layer shutdown [1650319780460] [35443:4678008] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319803159] [35577:4678990] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319803165] [35577:4678990] CHIP: [DL] _Init [1650319803165] [35577:4678990] CHIP: [IN] UDP::Init bind&listen port=0 [1650319803165] [35577:4678990] CHIP: [IN] UDP::Init bound to port=57219 [1650319803165] [35577:4678990] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319803165] [35577:4678990] CHIP: [IN] TransportMgr initialized [1650319803166] [35577:4678990] CHIP: [DIS] Init fabric pairing table with server storage [1650319803166] [35577:4678990] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319803167] [35577:4678990] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319803167] [35577:4678990] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319803167] [35577:4678990] CHIP: [DIS] Add fabric pairing table delegate [1650319803168] [35577:4678990] CHIP: [ZCL] Using ZAP configuration... [1650319803168] [35577:4678990] CHIP: [CTL] System State Initialized... [1650319803168] [35577:4678990] CHIP: [CTL] Setting attestation nonce to random value [1650319803168] [35577:4678990] CHIP: [CTL] Setting CSR nonce to random value [1650319803169] [35577:4678990] CHIP: [CTL] Setting attestation nonce to random value [1650319803169] [35577:4678990] CHIP: [CTL] Setting CSR nonce to random value [1650319803171] [35577:4678990] CHIP: [CTL] Generating NOC [1650319803174] [35577:4678990] CHIP: [DIS] Verifying the received credentials [1650319803183] [35577:4678990] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319803183] [35577:4678990] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319803183] [35577:4678990] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319803183] [35577:4678990] CHIP: [CTL] Setting attestation nonce to random value [1650319803183] [35577:4678990] CHIP: [CTL] Setting CSR nonce to random value [1650319803186] [35577:4678990] CHIP: [CTL] Generating NOC [1650319803188] [35577:4678990] CHIP: [DIS] Verifying the received credentials [1650319803198] [35577:4678990] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319803198] [35577:4678990] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319803198] [35577:4678990] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319803198] [35577:4678990] CHIP: [CTL] Setting attestation nonce to random value [1650319803198] [35577:4678990] CHIP: [CTL] Setting CSR nonce to random value [1650319803201] [35577:4678990] CHIP: [CTL] Generating NOC [1650319803204] [35577:4678990] CHIP: [DIS] Verifying the received credentials [1650319803213] [35577:4678990] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319803213] [35577:4678990] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319803213] [35577:4678990] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319803224] [35577:4678991] CHIP: [DL] _OnPlatformEvent [1650319803224] [35577:4678991] CHIP: [TOO] Sending command to node 0x2 [1650319803224] [35577:4678991] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319803224] [35577:4678991] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319803224] [35577:4678991] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319803224] [35577:4678991] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319803425] [35577:4678991] CHIP: [DIS] Checking node lookup status after 201 ms [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the commissioner [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the controller [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the commissioner [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the controller [1650319813230] [35577:4678990] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319813230] [35577:4678990] CHIP: [DIS] Discovery does not require any more timeouts [1650319813230] [35577:4678990] CHIP: [IN] Expiring all connections for fabric 1!! [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the commissioner [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the controller [1650319813230] [35577:4678990] CHIP: [IN] Expiring all connections for fabric 2!! [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the commissioner [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the controller [1650319813230] [35577:4678990] CHIP: [IN] Expiring all connections for fabric 3!! [1650319813230] [35577:4678990] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319813231] [35577:4678990] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319813231] [35577:4678990] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319813231] [35577:4678990] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319813231] [35577:4678990] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319813231] [35577:4678990] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319813231] [35577:4678990] CHIP: [BLE] CancelConnection [1650319813231] [35577:4678990] CHIP: [DL] Inet Layer shutdown [1650319813231] [35577:4678990] CHIP: [DL] BLE shutdown [1650319813231] [35577:4678990] CHIP: [DL] System Layer shutdown [1650319813231] [35577:4678990] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319837093] [35697:4679896] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319837099] [35697:4679896] CHIP: [DL] _Init [1650319837099] [35697:4679896] CHIP: [IN] UDP::Init bind&listen port=0 [1650319837099] [35697:4679896] CHIP: [IN] UDP::Init bound to port=53939 [1650319837099] [35697:4679896] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319837099] [35697:4679896] CHIP: [IN] TransportMgr initialized [1650319837099] [35697:4679896] CHIP: [DIS] Init fabric pairing table with server storage [1650319837099] [35697:4679896] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319837100] [35697:4679896] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319837100] [35697:4679896] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319837100] [35697:4679896] CHIP: [DIS] Add fabric pairing table delegate [1650319837101] [35697:4679896] CHIP: [ZCL] Using ZAP configuration... [1650319837101] [35697:4679896] CHIP: [CTL] System State Initialized... [1650319837101] [35697:4679896] CHIP: [CTL] Setting attestation nonce to random value [1650319837101] [35697:4679896] CHIP: [CTL] Setting CSR nonce to random value [1650319837101] [35697:4679896] CHIP: [CTL] Setting attestation nonce to random value [1650319837101] [35697:4679896] CHIP: [CTL] Setting CSR nonce to random value [1650319837104] [35697:4679896] CHIP: [CTL] Generating NOC [1650319837106] [35697:4679896] CHIP: [DIS] Verifying the received credentials [1650319837116] [35697:4679896] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319837116] [35697:4679896] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319837116] [35697:4679896] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319837116] [35697:4679896] CHIP: [CTL] Setting attestation nonce to random value [1650319837116] [35697:4679896] CHIP: [CTL] Setting CSR nonce to random value [1650319837119] [35697:4679896] CHIP: [CTL] Generating NOC [1650319837121] [35697:4679896] CHIP: [DIS] Verifying the received credentials [1650319837131] [35697:4679896] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319837131] [35697:4679896] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319837131] [35697:4679896] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319837131] [35697:4679896] CHIP: [CTL] Setting attestation nonce to random value [1650319837131] [35697:4679896] CHIP: [CTL] Setting CSR nonce to random value [1650319837133] [35697:4679896] CHIP: [CTL] Generating NOC [1650319837136] [35697:4679896] CHIP: [DIS] Verifying the received credentials [1650319837146] [35697:4679896] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319837146] [35697:4679896] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319837146] [35697:4679896] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319837157] [35697:4679901] CHIP: [DL] _OnPlatformEvent [1650319837157] [35697:4679901] CHIP: [TOO] Sending command to node 0x2 [1650319837157] [35697:4679901] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319837157] [35697:4679901] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319837157] [35697:4679901] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319837157] [35697:4679901] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319837359] [35697:4679901] CHIP: [DIS] Checking node lookup status after 203 ms [1650319847158] [35697:4679896] CHIP: [CTL] Shutting down the commissioner [1650319847158] [35697:4679896] CHIP: [CTL] Shutting down the controller [1650319847158] [35697:4679896] CHIP: [CTL] Shutting down the commissioner [1650319847158] [35697:4679896] CHIP: [CTL] Shutting down the controller [1650319847158] [35697:4679896] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319847159] [35697:4679896] CHIP: [DIS] Discovery does not require any more timeouts [1650319847159] [35697:4679896] CHIP: [IN] Expiring all connections for fabric 1!! [1650319847159] [35697:4679896] CHIP: [CTL] Shutting down the commissioner [1650319847159] [35697:4679896] CHIP: [CTL] Shutting down the controller [1650319847159] [35697:4679896] CHIP: [IN] Expiring all connections for fabric 2!! [1650319847159] [35697:4679896] CHIP: [CTL] Shutting down the commissioner [1650319847159] [35697:4679896] CHIP: [CTL] Shutting down the controller [1650319847159] [35697:4679896] CHIP: [IN] Expiring all connections for fabric 3!! [1650319847159] [35697:4679896] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319847159] [35697:4679896] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319847159] [35697:4679896] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319847159] [35697:4679896] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319847159] [35697:4679896] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319847159] [35697:4679896] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319847159] [35697:4679896] CHIP: [BLE] CancelConnection [1650319847159] [35697:4679896] CHIP: [DL] Inet Layer shutdown [1650319847159] [35697:4679896] CHIP: [DL] BLE shutdown [1650319847160] [35697:4679896] CHIP: [DL] System Layer shutdown [1650319847160] [35697:4679896] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout priyankahonnakasturi@Priyankas-MacBook-Pro connectedhomeip % sudo ./out/debug/chip-tool onoff toggle 2 1 [1650319900730] [35964:4682791] CHIP: [DL] KVS will be written to: file:///tmp/chip_kvs [1650319900737] [35964:4682791] CHIP: [DL] _Init [1650319900737] [35964:4682791] CHIP: [IN] UDP::Init bind&listen port=0 [1650319900737] [35964:4682791] CHIP: [IN] UDP::Init bound to port=63858 [1650319900737] [35964:4682791] CHIP: [IN] BLEBase::Init - setting/overriding transport [1650319900737] [35964:4682791] CHIP: [IN] TransportMgr initialized [1650319900737] [35964:4682791] CHIP: [DIS] Init fabric pairing table with server storage [1650319900738] [35964:4682791] CHIP: [IN] Loading from storage for fabric index 0x1 [1650319900739] [35964:4682791] CHIP: [IN] Loading from storage for fabric index 0x2 [1650319900739] [35964:4682791] CHIP: [IN] Loading from storage for fabric index 0x3 [1650319900739] [35964:4682791] CHIP: [DIS] Add fabric pairing table delegate [1650319900740] [35964:4682791] CHIP: [ZCL] Using ZAP configuration... [1650319900741] [35964:4682791] CHIP: [CTL] System State Initialized... [1650319900741] [35964:4682791] CHIP: [CTL] Setting attestation nonce to random value [1650319900741] [35964:4682791] CHIP: [CTL] Setting CSR nonce to random value [1650319900741] [35964:4682791] CHIP: [CTL] Setting attestation nonce to random value [1650319900741] [35964:4682791] CHIP: [CTL] Setting CSR nonce to random value [1650319900744] [35964:4682791] CHIP: [CTL] Generating NOC [1650319900746] [35964:4682791] CHIP: [DIS] Verifying the received credentials [1650319900756] [35964:4682791] CHIP: [DIS] Added new fabric at index: 0x1, Initialized: 1 [1650319900756] [35964:4682791] CHIP: [DIS] Assigned compressed fabric ID: 0x732DA42E801C7C2D, node ID: 0x000000000001B669 [1650319900756] [35964:4682791] CHIP: [CTL] Joined the fabric at index 1. Compressed fabric ID is: 0x732DA42E801C7C2D [1650319900756] [35964:4682791] CHIP: [CTL] Setting attestation nonce to random value [1650319900756] [35964:4682791] CHIP: [CTL] Setting CSR nonce to random value [1650319900759] [35964:4682791] CHIP: [CTL] Generating NOC [1650319900761] [35964:4682791] CHIP: [DIS] Verifying the received credentials [1650319900771] [35964:4682791] CHIP: [DIS] Added new fabric at index: 0x2, Initialized: 1 [1650319900771] [35964:4682791] CHIP: [DIS] Assigned compressed fabric ID: 0xE6E75F40B8EDD62F, node ID: 0x000000000001B669 [1650319900771] [35964:4682791] CHIP: [CTL] Joined the fabric at index 2. Compressed fabric ID is: 0xE6E75F40B8EDD62F [1650319900771] [35964:4682791] CHIP: [CTL] Setting attestation nonce to random value [1650319900771] [35964:4682791] CHIP: [CTL] Setting CSR nonce to random value [1650319900774] [35964:4682791] CHIP: [CTL] Generating NOC [1650319900777] [35964:4682791] CHIP: [DIS] Verifying the received credentials [1650319900786] [35964:4682791] CHIP: [DIS] Added new fabric at index: 0x3, Initialized: 1 [1650319900786] [35964:4682791] CHIP: [DIS] Assigned compressed fabric ID: 0xD93825D0690DFEF3, node ID: 0x000000000001B669 [1650319900786] [35964:4682791] CHIP: [CTL] Joined the fabric at index 3. Compressed fabric ID is: 0xD93825D0690DFEF3 [1650319900797] [35964:4682793] CHIP: [DL] _OnPlatformEvent [1650319900797] [35964:4682793] CHIP: [TOO] Sending command to node 0x2 [1650319900797] [35964:4682793] CHIP: [CSM] FindOrEstablishSession: PeerId = 732DA42E801C7C2D:0000000000000002 [1650319900797] [35964:4682793] CHIP: [CSM] FindOrEstablishSession: No existing OperationalDeviceProxy instance found [1650319900797] [35964:4682793] CHIP: [DIS] Resolving 732DA42E801C7C2D:0000000000000002 ... [1650319900797] [35964:4682793] CHIP: [CTL] Resolve type=_matter._tcp name=732DA42E801C7C2D-0000000000000002 [1650319900998] [35964:4682793] CHIP: [DIS] Checking node lookup status after 201 ms [1650319910798] [35964:4682791] CHIP: [CTL] Shutting down the commissioner [1650319910798] [35964:4682791] CHIP: [CTL] Shutting down the controller [1650319910798] [35964:4682791] CHIP: [CTL] Shutting down the commissioner [1650319910798] [35964:4682791] CHIP: [CTL] Shutting down the controller [1650319910798] [35964:4682791] CHIP: [DIS] Cancelling incomplete address resolution as device is being deleted. [1650319910799] [35964:4682791] CHIP: [DIS] Discovery does not require any more timeouts [1650319910799] [35964:4682791] CHIP: [IN] Expiring all connections for fabric 1!! [1650319910799] [35964:4682791] CHIP: [CTL] Shutting down the commissioner [1650319910799] [35964:4682791] CHIP: [CTL] Shutting down the controller [1650319910799] [35964:4682791] CHIP: [IN] Expiring all connections for fabric 2!! [1650319910799] [35964:4682791] CHIP: [CTL] Shutting down the commissioner [1650319910799] [35964:4682791] CHIP: [CTL] Shutting down the controller [1650319910799] [35964:4682791] CHIP: [IN] Expiring all connections for fabric 3!! [1650319910799] [35964:4682791] CHIP: [CTL] Shutting down the System State, this will teardown the CHIP Stack [1650319910799] [35964:4682791] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319910799] [35964:4682791] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319910799] [35964:4682791] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319910799] [35964:4682791] CHIP: [DMG] IM WH moving to [Uninitialized] [1650319910799] [35964:4682791] CHIP: [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1650319910800] [35964:4682791] CHIP: [BLE] CancelConnection [1650319910800] [35964:4682791] CHIP: [DL] Inet Layer shutdown [1650319910800] [35964:4682791] CHIP: [DL] BLE shutdown [1650319910800] [35964:4682791] CHIP: [DL] System Layer shutdown [1650319910800] [35964:4682791] CHIP: [TOO] Run command failure: ../../examples/chip-tool/commands/common/CHIPCommand.cpp:390: CHIP Error 0x00000032: Timeout