[1756729182.855] [4637:4665] [DL] ConnectDevice retry: 1 out of 4 [1756729183.135] [4637:4665] [DL] ConnectDevice complete [1756729183.135] [4637:4665] [BLE] New device connected: F8:11:F3:72:95:B3 [1756729184.193] [4637:4665] [DL] CHIP service found [1756729184.193] [4637:4665] [DL] Valid C2 characteristic found [1756729184.193] [4637:4665] [DL] Valid C1 characteristic found [1756729184.193] [4637:4665] [DL] New BLE connection: conn=0x559d170f30 device=F8:11:F3:72:95:B3 path=/org/bluez/hci0/dev_F8_11_F3_72_95_B3 [1756729184.193] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16387 [1756729184.193] [4637:4666] [DIS] Closing all BLE connections [1756729184.194] [4637:4666] [IN] BleConnectionComplete: endPoint 0x5586c61980 [1756729184.194] [4637:4666] [IN] SecureSession[0x7f8c013400]: Allocated Type:1 LSID:55443 [1756729184.195] [4637:4666] [SC] Assigned local session key ID 55443 [1756729184.195] [4637:4666] [EM] <<< [E:62475i S:0 M:104807131] (U) Msg TX from 7BAB2C28A239BDC5 to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98) [1756729184.196] [4637:4666] [IN] Message appended to BLE send queue [1756729184.196] [4637:4666] [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms) [1756729184.572] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729184.663] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16390 [1756729184.663] [4637:4666] [BLE] subscribe complete, ep = 0x5586c61980 [1756729184.665] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729184.665] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729184.665] [4637:4666] [BLE] peripheral chose BTP version 4; central expected between 4 and 4 [1756729184.665] [4637:4666] [BLE] using BTP fragment sizes rx 244 / tx 244. [1756729184.665] [4637:4666] [BLE] local and remote recv window size = 5 [1756729184.666] [4637:4666] [IN] BLE EndPoint 0x5586c61980 Connection Complete [1756729184.797] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729184.846] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729184.846] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729184.846] [4637:4666] [EM] >>> [E:62475i S:0 M:83346254] (U) Msg RX from 0:0000000000000000 [0000] to 7BAB2C28A239BDC5 --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:154) [1756729184.846] [4637:4666] [EM] Found matching exchange: 62475i, Delegate: 0x7f8c041d08 [1756729184.847] [4637:4666] [SC] Received PBKDF param response [1756729184.847] [4637:4666] [SC] Peer assigned session ID 31551 [1756729184.847] [4637:4666] [SC] Found MRP parameters in the message [1756729184.862] [4637:4666] [EM] <<< [E:62475i S:0 M:104807132] (U) Msg TX from 7BAB2C28A239BDC5 to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92) [1756729184.863] [4637:4666] [SC] Sent spake2p msg1 [1756729184.977] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729185.158] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.158] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.158] [4637:4666] [EM] >>> [E:62475i S:0 M:83346255] (U) Msg RX from 0:0000000000000000 [0000] to 7BAB2C28A239BDC5 --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127) [1756729185.158] [4637:4666] [EM] Found matching exchange: 62475i, Delegate: 0x7f8c041d08 [1756729185.158] [4637:4666] [SC] Received spake2p msg2 [1756729185.161] [4637:4666] [EM] <<< [E:62475i S:0 M:104807133] (U) Msg TX from 7BAB2C28A239BDC5 to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59) [1756729185.162] [4637:4666] [SC] Sent spake2p msg3 [1756729185.247] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729185.249] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.250] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.250] [4637:4666] [EM] >>> [E:62475i S:0 M:83346256] (U) Msg RX from 0:0000000000000000 [0000] to 7BAB2C28A239BDC5 --- Type 0000:40 (SecureChannel:StatusReport) (B:30) [1756729185.250] [4637:4666] [EM] Found matching exchange: 62475i, Delegate: 0x7f8c041d08 [1756729185.250] [4637:4666] [SC] SecureSession[0x7f8c013400, LSID:55443]: State change 'kEstablishing' --> 'kActive' [1756729185.250] [4637:4666] [IN] SecureSession[0x7f8c013400]: Activated - Type:1 LSID:55443 [1756729185.250] [4637:4666] [IN] New secure session activated for device , LSID:55443 PSID:31551! [1756729185.250] [4637:4666] [CTL] Remote device completed SPAKE2+ handshake [1756729185.250] [4637:4666] [TOO] Pairing Success [1756729185.250] [4637:4666] [TOO] PASE establishment successful [1756729185.250] [4637:4666] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1756729185.250] [4637:4666] [CTL] Performing next commissioning step 'ReadCommissioningInfo' [1756729185.250] [4637:4666] [CTL] Sending read requests for commissioning information [1756729185.251] [4637:4666] [DMG] SendReadRequest ReadClient[0x7f8c017800]: Sending Read Request [1756729185.251] [4637:4666] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1756729185.251] [4637:4666] [EM] <<< [E:62476i S:55443 M:88078146] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:134) [1756729185.252] [4637:4666] [DMG] MoveToState ReadClient[0x7f8c017800]: Moving to [AwaitingIn] [1756729185.252] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 32792 [1756729185.382] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729185.473] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.473] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.564] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.564] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.564] [4637:4666] [EM] >>> [E:62476i S:55443 M:189832959] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:257) [1756729185.564] [4637:4666] [EM] Found matching exchange: 62476i, Delegate: 0x7f8c017810 [1756729185.565] [4637:4666] [DMG] ReportDataMessage = [1756729185.565] [4637:4666] [DMG] { [1756729185.565] [4637:4666] [DMG] AttributeReportIBs = [1756729185.565] [4637:4666] [DMG] [ [1756729185.565] [4637:4666] [DMG] AttributeReportIB = [1756729185.565] [4637:4666] [DMG] { [1756729185.565] [4637:4666] [DMG] AttributeDataIB = [1756729185.565] [4637:4666] [DMG] { [1756729185.565] [4637:4666] [DMG] DataVersion = 0x96cc64bf, [1756729185.565] [4637:4666] [DMG] AttributePathIB = [1756729185.565] [4637:4666] [DMG] { [1756729185.565] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.565] [4637:4666] [DMG] Cluster = 0x31, [1756729185.565] [4637:4666] [DMG] Attribute = 0x0000_FFFC, [1756729185.565] [4637:4666] [DMG] } [1756729185.565] [4637:4666] [DMG] [1756729185.565] [4637:4666] [DMG] Data = 2 (unsigned), [1756729185.565] [4637:4666] [DMG] }, [1756729185.565] [4637:4666] [DMG] [1756729185.565] [4637:4666] [DMG] }, [1756729185.565] [4637:4666] [DMG] [1756729185.565] [4637:4666] [DMG] AttributeReportIB = [1756729185.565] [4637:4666] [DMG] { [1756729185.565] [4637:4666] [DMG] AttributeDataIB = [1756729185.565] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] DataVersion = 0xb0fb55ae, [1756729185.566] [4637:4666] [DMG] AttributePathIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.566] [4637:4666] [DMG] Cluster = 0x28, [1756729185.566] [4637:4666] [DMG] Attribute = 0x0000_0004, [1756729185.566] [4637:4666] [DMG] } [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] Data = 32775 (unsigned), [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] AttributeReportIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] AttributeDataIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] DataVersion = 0xb0fb55ae, [1756729185.566] [4637:4666] [DMG] AttributePathIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.566] [4637:4666] [DMG] Cluster = 0x28, [1756729185.566] [4637:4666] [DMG] Attribute = 0x0000_0002, [1756729185.566] [4637:4666] [DMG] } [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] Data = 65521 (unsigned), [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] AttributeReportIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] AttributeDataIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] DataVersion = 0x8c803ba9, [1756729185.566] [4637:4666] [DMG] AttributePathIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.566] [4637:4666] [DMG] Cluster = 0x30, [1756729185.566] [4637:4666] [DMG] Attribute = 0x0000_0003, [1756729185.566] [4637:4666] [DMG] } [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] Data = 0 (unsigned), [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] }, [1756729185.566] [4637:4666] [DMG] [1756729185.566] [4637:4666] [DMG] AttributeReportIB = [1756729185.566] [4637:4666] [DMG] { [1756729185.566] [4637:4666] [DMG] AttributeDataIB = [1756729185.567] [4637:4666] [DMG] { [1756729185.567] [4637:4666] [DMG] DataVersion = 0x8c803ba9, [1756729185.567] [4637:4666] [DMG] AttributePathIB = [1756729185.567] [4637:4666] [DMG] { [1756729185.567] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.567] [4637:4666] [DMG] Cluster = 0x30, [1756729185.567] [4637:4666] [DMG] Attribute = 0x0000_0002, [1756729185.567] [4637:4666] [DMG] } [1756729185.567] [4637:4666] [DMG] [1756729185.567] [4637:4666] [DMG] Data = 0 (unsigned), [1756729185.567] [4637:4666] [DMG] }, [1756729185.567] [4637:4666] [DMG] [1756729185.567] [4637:4666] [DMG] }, [1756729185.567] [4637:4666] [DMG] [1756729185.567] [4637:4666] [DMG] AttributeReportIB = [1756729185.568] [4637:4666] [DMG] { [1756729185.568] [4637:4666] [DMG] AttributeDataIB = [1756729185.568] [4637:4666] [DMG] { [1756729185.568] [4637:4666] [DMG] DataVersion = 0x8c803ba9, [1756729185.568] [4637:4666] [DMG] AttributePathIB = [1756729185.568] [4637:4666] [DMG] { [1756729185.568] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.568] [4637:4666] [DMG] Cluster = 0x30, [1756729185.568] [4637:4666] [DMG] Attribute = 0x0000_0001, [1756729185.569] [4637:4666] [DMG] } [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] Data = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] 0x0 = 60 (unsigned), [1756729185.569] [4637:4666] [DMG] 0x1 = 900 (unsigned), [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] AttributeReportIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] AttributeDataIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] DataVersion = 0x8c803ba9, [1756729185.569] [4637:4666] [DMG] AttributePathIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.569] [4637:4666] [DMG] Cluster = 0x30, [1756729185.569] [4637:4666] [DMG] Attribute = 0x0000_0000, [1756729185.569] [4637:4666] [DMG] } [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] Data = 0 (unsigned), [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] AttributeReportIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] AttributeDataIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] DataVersion = 0x8c803ba9, [1756729185.569] [4637:4666] [DMG] AttributePathIB = [1756729185.569] [4637:4666] [DMG] { [1756729185.569] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.569] [4637:4666] [DMG] Cluster = 0x30, [1756729185.569] [4637:4666] [DMG] Attribute = 0x0000_0004, [1756729185.569] [4637:4666] [DMG] } [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] Data = true, [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] }, [1756729185.569] [4637:4666] [DMG] [1756729185.569] [4637:4666] [DMG] ], [1756729185.570] [4637:4666] [DMG] [1756729185.570] [4637:4666] [DMG] SuppressResponse = true, [1756729185.570] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729185.570] [4637:4666] [DMG] } [1756729185.571] [4637:4666] [DMG] SendReadRequest ReadClient[0x7f8c017800]: Sending Read Request [1756729185.571] [4637:4666] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1756729185.572] [4637:4666] [EM] <<< [E:62477i S:55443 M:88078147] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:103) [1756729185.573] [4637:4666] [DMG] MoveToState ReadClient[0x7f8c017800]: Moving to [AwaitingIn] [1756729185.697] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729185.790] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.790] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.790] [4637:4666] [EM] >>> [E:62477i S:55443 M:189832960] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:192) [1756729185.791] [4637:4666] [EM] Found matching exchange: 62477i, Delegate: 0x7f8c017810 [1756729185.791] [4637:4666] [DMG] ReportDataMessage = [1756729185.791] [4637:4666] [DMG] { [1756729185.791] [4637:4666] [DMG] AttributeReportIBs = [1756729185.791] [4637:4666] [DMG] [ [1756729185.791] [4637:4666] [DMG] AttributeReportIB = [1756729185.791] [4637:4666] [DMG] { [1756729185.791] [4637:4666] [DMG] AttributeDataIB = [1756729185.791] [4637:4666] [DMG] { [1756729185.791] [4637:4666] [DMG] DataVersion = 0xd11f275b, [1756729185.791] [4637:4666] [DMG] AttributePathIB = [1756729185.791] [4637:4666] [DMG] { [1756729185.791] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.791] [4637:4666] [DMG] Cluster = 0x46, [1756729185.791] [4637:4666] [DMG] Attribute = 0x0000_0002, [1756729185.791] [4637:4666] [DMG] } [1756729185.791] [4637:4666] [DMG] [1756729185.791] [4637:4666] [DMG] Data = 300 (unsigned), [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] AttributeReportIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] AttributeDataIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] DataVersion = 0xd11f275b, [1756729185.792] [4637:4666] [DMG] AttributePathIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.792] [4637:4666] [DMG] Cluster = 0x46, [1756729185.792] [4637:4666] [DMG] Attribute = 0x0000_0001, [1756729185.792] [4637:4666] [DMG] } [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] Data = 300 (unsigned), [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] AttributeReportIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] AttributeDataIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] DataVersion = 0xd11f275b, [1756729185.792] [4637:4666] [DMG] AttributePathIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.792] [4637:4666] [DMG] Cluster = 0x46, [1756729185.792] [4637:4666] [DMG] Attribute = 0x0000_0000, [1756729185.792] [4637:4666] [DMG] } [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] Data = 120 (unsigned), [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] }, [1756729185.792] [4637:4666] [DMG] [1756729185.792] [4637:4666] [DMG] AttributeReportIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] AttributeStatusIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] AttributePathIB = [1756729185.792] [4637:4666] [DMG] { [1756729185.792] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.792] [4637:4666] [DMG] Cluster = 0x46, [1756729185.792] [4637:4666] [DMG] Attribute = 0x0000_0007, [1756729185.792] [4637:4666] [DMG] } [1756729185.792] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] StatusIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] AttributeReportIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] AttributeStatusIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] AttributePathIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.793] [4637:4666] [DMG] Cluster = 0x46, [1756729185.793] [4637:4666] [DMG] Attribute = 0x0000_0006, [1756729185.793] [4637:4666] [DMG] } [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] StatusIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] AttributeReportIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] AttributeDataIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] DataVersion = 0x96cc64bf, [1756729185.793] [4637:4666] [DMG] AttributePathIB = [1756729185.793] [4637:4666] [DMG] { [1756729185.793] [4637:4666] [DMG] Endpoint = 0x0, [1756729185.793] [4637:4666] [DMG] Cluster = 0x31, [1756729185.793] [4637:4666] [DMG] Attribute = 0x0000_0003, [1756729185.793] [4637:4666] [DMG] } [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] Data = 20 (unsigned), [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.793] [4637:4666] [DMG] }, [1756729185.793] [4637:4666] [DMG] [1756729185.794] [4637:4666] [DMG] ], [1756729185.794] [4637:4666] [DMG] [1756729185.794] [4637:4666] [DMG] SuppressResponse = true, [1756729185.794] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729185.794] [4637:4666] [DMG] } [1756729185.795] [4637:4666] [CTL] NetworkCommissioning Features: has Thread. endpointid = 0 [1756729185.795] [4637:4666] [SVR] OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8007 [1756729185.795] [4637:4666] [SVR] OnReadCommissioningInfo ICD - IdleModeDuration=0 activeModeDuration=0 activeModeThreshold=0 [1756729185.795] [4637:4666] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo' [1756729185.795] [4637:4666] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1756729185.795] [4637:4666] [CTL] Performing next commissioning step 'ArmFailSafe' [1756729185.795] [4637:4666] [CTL] Arming failsafe (60 seconds) [1756729185.795] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729185.795] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729185.796] [4637:4666] [EM] <<< [E:62478i S:55443 M:88078148] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1756729185.797] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729185.877] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729185.880] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729185.880] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729185.881] [4637:4666] [EM] >>> [E:62478i S:55443 M:189832961] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1756729185.881] [4637:4666] [EM] Found matching exchange: 62478i, Delegate: 0x7f8c008628 [1756729185.881] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729185.881] [4637:4666] [DMG] InvokeResponseMessage = [1756729185.881] [4637:4666] [DMG] { [1756729185.881] [4637:4666] [DMG] suppressResponse = false, [1756729185.881] [4637:4666] [DMG] InvokeResponseIBs = [1756729185.881] [4637:4666] [DMG] [ [1756729185.881] [4637:4666] [DMG] InvokeResponseIB = [1756729185.881] [4637:4666] [DMG] { [1756729185.881] [4637:4666] [DMG] CommandDataIB = [1756729185.881] [4637:4666] [DMG] { [1756729185.881] [4637:4666] [DMG] CommandPathIB = [1756729185.881] [4637:4666] [DMG] { [1756729185.881] [4637:4666] [DMG] EndpointId = 0x0, [1756729185.881] [4637:4666] [DMG] ClusterId = 0x30, [1756729185.881] [4637:4666] [DMG] CommandId = 0x1, [1756729185.881] [4637:4666] [DMG] }, [1756729185.881] [4637:4666] [DMG] [1756729185.881] [4637:4666] [DMG] CommandFields = [1756729185.881] [4637:4666] [DMG] { [1756729185.881] [4637:4666] [DMG] 0x0 = 0 (unsigned), [1756729185.881] [4637:4666] [DMG] 0x1 = "" (0 chars), [1756729185.881] [4637:4666] [DMG] }, [1756729185.881] [4637:4666] [DMG] }, [1756729185.881] [4637:4666] [DMG] [1756729185.881] [4637:4666] [DMG] }, [1756729185.881] [4637:4666] [DMG] [1756729185.881] [4637:4666] [DMG] ], [1756729185.881] [4637:4666] [DMG] [1756729185.881] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729185.881] [4637:4666] [DMG] }, [1756729185.882] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1756729185.882] [4637:4666] [CTL] Received ArmFailSafe response errorCode=0 [1756729185.882] [4637:4666] [CTL] Successfully finished commissioning step 'ArmFailSafe' [1756729185.882] [4637:4666] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1756729185.882] [4637:4666] [CTL] Performing next commissioning step 'ConfigRegulatory' [1756729185.882] [4637:4666] [CTL] Setting Regulatory Config [1756729185.882] [4637:4666] [CTL] Device does not support configurable regulatory location [1756729185.882] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729185.882] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729185.882] [4637:4666] [EM] <<< [E:62479i S:55443 M:88078149] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:70) [1756729185.883] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729185.883] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729186.012] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729186.060] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.061] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.061] [4637:4666] [EM] >>> [E:62479i S:55443 M:189832962] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1756729186.061] [4637:4666] [EM] Found matching exchange: 62479i, Delegate: 0x7f8c008478 [1756729186.061] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729186.061] [4637:4666] [DMG] InvokeResponseMessage = [1756729186.061] [4637:4666] [DMG] { [1756729186.061] [4637:4666] [DMG] suppressResponse = false, [1756729186.061] [4637:4666] [DMG] InvokeResponseIBs = [1756729186.061] [4637:4666] [DMG] [ [1756729186.061] [4637:4666] [DMG] InvokeResponseIB = [1756729186.061] [4637:4666] [DMG] { [1756729186.061] [4637:4666] [DMG] CommandDataIB = [1756729186.061] [4637:4666] [DMG] { [1756729186.061] [4637:4666] [DMG] CommandPathIB = [1756729186.061] [4637:4666] [DMG] { [1756729186.061] [4637:4666] [DMG] EndpointId = 0x0, [1756729186.062] [4637:4666] [DMG] ClusterId = 0x30, [1756729186.062] [4637:4666] [DMG] CommandId = 0x3, [1756729186.062] [4637:4666] [DMG] }, [1756729186.062] [4637:4666] [DMG] [1756729186.062] [4637:4666] [DMG] CommandFields = [1756729186.062] [4637:4666] [DMG] { [1756729186.062] [4637:4666] [DMG] 0x0 = 0 (unsigned), [1756729186.062] [4637:4666] [DMG] 0x1 = "" (0 chars), [1756729186.062] [4637:4666] [DMG] }, [1756729186.062] [4637:4666] [DMG] }, [1756729186.062] [4637:4666] [DMG] [1756729186.062] [4637:4666] [DMG] }, [1756729186.062] [4637:4666] [DMG] [1756729186.062] [4637:4666] [DMG] ], [1756729186.062] [4637:4666] [DMG] [1756729186.062] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729186.062] [4637:4666] [DMG] }, [1756729186.062] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1756729186.062] [4637:4666] [CTL] Received SetRegulatoryConfig response errorCode=0 [1756729186.062] [4637:4666] [CTL] Successfully finished commissioning step 'ConfigRegulatory' [1756729186.062] [4637:4666] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'ConfigureTCAcknowledgments' [1756729186.062] [4637:4666] [CTL] Performing next commissioning step 'ConfigureTCAcknowledgments' [1756729186.062] [4637:4666] [CTL] Setting Terms and Conditions [1756729186.062] [4637:4666] [CTL] Setting Terms and Conditions: Skipped [1756729186.062] [4637:4666] [CTL] Successfully finished commissioning step 'ConfigureTCAcknowledgments' [1756729186.062] [4637:4666] [CTL] Commissioning stage next step: 'ConfigureTCAcknowledgments' -> 'SendPAICertificateRequest' [1756729186.062] [4637:4666] [CTL] Performing next commissioning step 'SendPAICertificateRequest' [1756729186.062] [4637:4666] [CTL] Sending request for PAI certificate [1756729186.062] [4637:4666] [CTL] Sending Certificate Chain request to 0x7f8c041cb0 device [1756729186.062] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729186.062] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729186.062] [4637:4666] [EM] <<< [E:62480i S:55443 M:88078150] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1756729186.063] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729186.063] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729186.147] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729186.241] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.241] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.376] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.376] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.464] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.465] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.465] [4637:4666] [EM] >>> [E:62480i S:55443 M:189832963] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:527) [1756729186.465] [4637:4666] [EM] Found matching exchange: 62480i, Delegate: 0x7f8c008628 [1756729186.465] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729186.465] [4637:4666] [DMG] InvokeResponseMessage = [1756729186.465] [4637:4666] [DMG] { [1756729186.465] [4637:4666] [DMG] suppressResponse = false, [1756729186.465] [4637:4666] [DMG] InvokeResponseIBs = [1756729186.465] [4637:4666] [DMG] [ [1756729186.465] [4637:4666] [DMG] InvokeResponseIB = [1756729186.465] [4637:4666] [DMG] { [1756729186.465] [4637:4666] [DMG] CommandDataIB = [1756729186.465] [4637:4666] [DMG] { [1756729186.465] [4637:4666] [DMG] CommandPathIB = [1756729186.465] [4637:4666] [DMG] { [1756729186.465] [4637:4666] [DMG] EndpointId = 0x0, [1756729186.465] [4637:4666] [DMG] ClusterId = 0x3e, [1756729186.465] [4637:4666] [DMG] CommandId = 0x3, [1756729186.465] [4637:4666] [DMG] }, [1756729186.465] [4637:4666] [DMG] [1756729186.465] [4637:4666] [DMG] CommandFields = [1756729186.465] [4637:4666] [DMG] { [1756729186.465] [4637:4666] [DMG] 0x0 = [ [1756729186.466] [4637:4666] [DMG] 0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 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, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x1d, 0x [1756729186.466] [4637:4666] [DMG] ] (463 bytes) [1756729186.466] [4637:4666] [DMG] }, [1756729186.466] [4637:4666] [DMG] }, [1756729186.466] [4637:4666] [DMG] [1756729186.466] [4637:4666] [DMG] }, [1756729186.466] [4637:4666] [DMG] [1756729186.466] [4637:4666] [DMG] ], [1756729186.466] [4637:4666] [DMG] [1756729186.466] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729186.466] [4637:4666] [DMG] }, [1756729186.466] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1756729186.466] [4637:4666] [CTL] Received certificate chain from the device [1756729186.466] [4637:4666] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest' [1756729186.466] [4637:4666] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1756729186.466] [4637:4666] [CTL] Performing next commissioning step 'SendDACCertificateRequest' [1756729186.466] [4637:4666] [CTL] Sending request for DAC certificate [1756729186.466] [4637:4666] [CTL] Sending Certificate Chain request to 0x7f8c041cb0 device [1756729186.466] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729186.466] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729186.466] [4637:4666] [EM] <<< [E:62481i S:55443 M:88078151] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1756729186.467] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729186.467] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729186.552] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729186.645] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.646] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.781] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.781] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.870] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729186.871] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729186.871] [4637:4666] [EM] >>> [E:62481i S:55443 M:189832964] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:555) [1756729186.871] [4637:4666] [EM] Found matching exchange: 62481i, Delegate: 0x7f8c008478 [1756729186.871] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729186.871] [4637:4666] [DMG] InvokeResponseMessage = [1756729186.871] [4637:4666] [DMG] { [1756729186.871] [4637:4666] [DMG] suppressResponse = false, [1756729186.871] [4637:4666] [DMG] InvokeResponseIBs = [1756729186.872] [4637:4666] [DMG] [ [1756729186.872] [4637:4666] [DMG] InvokeResponseIB = [1756729186.872] [4637:4666] [DMG] { [1756729186.872] [4637:4666] [DMG] CommandDataIB = [1756729186.872] [4637:4666] [DMG] { [1756729186.872] [4637:4666] [DMG] CommandPathIB = [1756729186.872] [4637:4666] [DMG] { [1756729186.872] [4637:4666] [DMG] EndpointId = 0x0, [1756729186.872] [4637:4666] [DMG] ClusterId = 0x3e, [1756729186.872] [4637:4666] [DMG] CommandId = 0x3, [1756729186.872] [4637:4666] [DMG] }, [1756729186.872] [4637:4666] [DMG] [1756729186.872] [4637:4666] [DMG] CommandFields = [1756729186.872] [4637:4666] [DMG] { [1756729186.872] [4637:4666] [DMG] 0x0 = [ [1756729186.872] [4637:4666] [DMG] 0x30, 0x82, 0x01, 0xe7, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x58, 0x48, 0xb6, 0x4f, 0xbd, 0x0c, 0x9a, 0x6b, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 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, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 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, 0x37, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x37, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x1b, 0x68, 0x65, 0x0f, 0x97, 0xaa, 0xda, 0x54, 0x62, 0xa0, 0xd4, 0xdb, 0x5f, 0xd7, 0x84, 0x41, 0xb1, 0x48, 0x95, 0xd8, 0xd3, 0xc6, 0x20, 0x18, 0x33, 0x7f, 0x15, 0x23, 0xbb, 0xea, 0xb6, 0xd5, 0xf0, 0xdd, 0x6e, 0xed, 0x28, 0x83, 0x2e, 0x [1756729186.872] [4637:4666] [DMG] ] (491 bytes) [1756729186.872] [4637:4666] [DMG] }, [1756729186.872] [4637:4666] [DMG] }, [1756729186.872] [4637:4666] [DMG] [1756729186.872] [4637:4666] [DMG] }, [1756729186.872] [4637:4666] [DMG] [1756729186.872] [4637:4666] [DMG] ], [1756729186.872] [4637:4666] [DMG] [1756729186.872] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729186.872] [4637:4666] [DMG] }, [1756729186.872] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1756729186.872] [4637:4666] [CTL] Received certificate chain from the device [1756729186.872] [4637:4666] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest' [1756729186.872] [4637:4666] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1756729186.873] [4637:4666] [CTL] Performing next commissioning step 'SendAttestationRequest' [1756729186.873] [4637:4666] [CTL] Sending Attestation Request to the device. [1756729186.873] [4637:4666] [CTL] Sending Attestation request to 0x7f8c041cb0 device [1756729186.873] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729186.873] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729186.873] [4637:4666] [EM] <<< [E:62482i S:55443 M:88078152] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1756729186.874] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729186.874] [4637:4666] [CTL] Sent Attestation request, waiting for the Attestation Information [1756729186.874] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729187.092] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729187.186] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729187.186] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729187.321] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729187.321] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729187.456] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729187.457] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729187.457] [4637:4666] [EM] >>> [E:62482i S:55443 M:189832965] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:716) [1756729187.457] [4637:4666] [EM] Found matching exchange: 62482i, Delegate: 0x7f8c008628 [1756729187.457] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729187.457] [4637:4666] [DMG] InvokeResponseMessage = [1756729187.457] [4637:4666] [DMG] { [1756729187.457] [4637:4666] [DMG] suppressResponse = false, [1756729187.457] [4637:4666] [DMG] InvokeResponseIBs = [1756729187.457] [4637:4666] [DMG] [ [1756729187.457] [4637:4666] [DMG] InvokeResponseIB = [1756729187.457] [4637:4666] [DMG] { [1756729187.457] [4637:4666] [DMG] CommandDataIB = [1756729187.457] [4637:4666] [DMG] { [1756729187.457] [4637:4666] [DMG] CommandPathIB = [1756729187.457] [4637:4666] [DMG] { [1756729187.457] [4637:4666] [DMG] EndpointId = 0x0, [1756729187.457] [4637:4666] [DMG] ClusterId = 0x3e, [1756729187.457] [4637:4666] [DMG] CommandId = 0x1, [1756729187.457] [4637:4666] [DMG] }, [1756729187.457] [4637:4666] [DMG] [1756729187.457] [4637:4666] [DMG] CommandFields = [1756729187.457] [4637:4666] [DMG] { [1756729187.458] [4637:4666] [DMG] 0x0 = [ [1756729187.458] [4637:4666] [DMG] 0x15, 0x31, 0x01, 0x1d, 0x02, 0x30, 0x82, 0x02, 0x19, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x0a, 0x30, 0x82, 0x02, 0x06, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x71, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x62, 0x04, 0x82, 0x01, 0x5e, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80, 0x05, 0x42, 0x80, 0x05, 0x43, 0x80, 0x [1756729187.458] [4637:4666] [DMG] ] (585 bytes) [1756729187.458] [4637:4666] [DMG] 0x1 = [ [1756729187.458] [4637:4666] [DMG] 0x9f, 0x45, 0x9e, 0x15, 0xa5, 0x84, 0x57, 0xe4, 0xfa, 0x27, 0x5f, 0x76, 0xef, 0x1a, 0x9a, 0xa3, 0xec, 0xb5, 0x5f, 0x3a, 0xd2, 0x23, 0xd6, 0x5d, 0x68, 0x89, 0xa2, 0x28, 0x93, 0x62, 0x51, 0x76, 0x05, 0x50, 0x47, 0xb4, 0x9d, 0xe8, 0x78, 0x4b, 0x03, 0x2b, 0x07, 0x58, 0xcc, 0x0b, 0x18, 0x0f, 0x46, 0xbf, 0xbf, 0x1a, 0x20, 0xd9, 0x9c, 0xde, 0x03, 0xf1, 0x53, 0x32, 0xff, 0x52, 0x5a, 0xb4, [1756729187.458] [4637:4666] [DMG] ] (64 bytes) [1756729187.458] [4637:4666] [DMG] }, [1756729187.458] [4637:4666] [DMG] }, [1756729187.458] [4637:4666] [DMG] [1756729187.458] [4637:4666] [DMG] }, [1756729187.458] [4637:4666] [DMG] [1756729187.458] [4637:4666] [DMG] ], [1756729187.458] [4637:4666] [DMG] [1756729187.458] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729187.458] [4637:4666] [DMG] }, [1756729187.458] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1756729187.458] [4637:4666] [CTL] Received Attestation Information from the device [1756729187.458] [4637:4666] [CTL] Successfully finished commissioning step 'SendAttestationRequest' [1756729187.458] [4637:4666] [CTL] AutoCommissioner setting attestationElements buffer size 585/585 [1756729187.458] [4637:4666] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1756729187.458] [4637:4666] [CTL] Performing next commissioning step 'AttestationVerification' [1756729187.458] [4637:4666] [CTL] Verifying Device Attestation information received from the device [1756729187.478] [4637:4666] [-] Device candidate DAC chain details: [1756729187.478] [4637:4666] [-] --> DAC's VID: 0xFFF1, PID: 0x8007 [1756729187.478] [4637:4666] [-] ==== DAC certificate considered (491 bytes) ==== [1756729187.478] [4637:4666] [-] -----BEGIN CERTIFICATE----- [1756729187.478] [4637:4666] [-] MIIB5zCCAY6gAwIBAgIIWEi2T70MmmswCgYIKoZIzj0EAwIwPTElMCMGA1UEAwwc [1756729187.478] [4637:4666] [-] TWF0dGVyIERldiBQQUkgMHhGRkYxIG5vIFBJRDEUMBIGCisGAQQBgqJ8AgEMBEZG [1756729187.478] [4637:4666] [-] RjEwIBcNMjIwMjA1MDAwMDAwWhgPOTk5OTEyMzEyMzU5NTlaMFMxJTAjBgNVBAMM [1756729187.478] [4637:4666] [-] HE1hdHRlciBEZXYgREFDIDB4RkZGMS8weDgwMDcxFDASBgorBgEEAYKifAIBDARG [1756729187.478] [4637:4666] [-] RkYxMRQwEgYKKwYBBAGConwCAgwEODAwNzBZMBMGByqGSM49AgEGCCqGSM49AwEH [1756729187.478] [4637:4666] [-] A0IABBtoZQ+XqtpUYqDU21/XhEGxSJXY08YgGDN/FSO76rbV8N1u7SiDLvlIiRuX [1756729187.478] [4637:4666] [-] aoMb6oLbmnfj6klHtB2Hagji3WKjYDBeMAwGA1UdEwEB/wQCMAAwDgYDVR0PAQH/ [1756729187.478] [4637:4666] [-] BAQDAgeAMB0GA1UdDgQWBBShgQsewlHhuLRFNTSRqRhdV1IRRzAfBgNVHSMEGDAW [1756729187.478] [4637:4666] [-] gBRjVA5H9kscONE4hKRi0WwZXY/7PDAKBggqhkjOPQQDAgNHADBEAiBIldL+9SOX [1756729187.478] [4637:4666] [-] PHjbxgV9RPzHbiHjUlNuiRDzLuwZ/im8JAIgCMwbPSElYHRxX7xZ6Qdn/iLuXxW7 [1756729187.478] [4637:4666] [-] +glAsd/WUkT1UWM= [1756729187.478] [4637:4666] [-] -----END CERTIFICATE----- [1756729187.481] [4637:4666] [-] --> DAC certificate SKID: A1:81:0B:1E:C2:51:E1:B8:B4:45:35:34:91:A9:18:5D:57:52:11:47 [1756729187.483] [4637:4666] [-] --> DAC certificate AKID: 63:54:0E:47:F6:4B:1C:38:D1:38:84:A4:62:D1:6C:19:5D:8F:FB:3C [1756729187.483] [4637:4666] [-] ==== PAI certificate considered (463 bytes) ==== [1756729187.483] [4637:4666] [-] -----BEGIN CERTIFICATE----- [1756729187.483] [4637:4666] [-] MIIByzCCAXGgAwIBAgIIVq2CIq2UW2QwCgYIKoZIzj0EAwIwMDEYMBYGA1UEAwwP [1756729187.483] [4637:4666] [-] TWF0dGVyIFRlc3QgUEFBMRQwEgYKKwYBBAGConwCAQwERkZGMTAgFw0yMjAyMDUw [1756729187.483] [4637:4666] [-] MDAwMDBaGA85OTk5MTIzMTIzNTk1OVowPTElMCMGA1UEAwwcTWF0dGVyIERldiBQ [1756729187.483] [4637:4666] [-] QUkgMHhGRkYxIG5vIFBJRDEUMBIGCisGAQQBgqJ8AgEMBEZGRjEwWTATBgcqhkjO [1756729187.483] [4637:4666] [-] PQIBBggqhkjOPQMBBwNCAARBmpMVwhc+DIyHbQPM/JRIUmR/f+xeUIL0BZko7KiU [1756729187.483] [4637:4666] [-] xZQVEwmsYx5MsDOSr2hLC6+35ls7gWLC9Sv5MbjneqqCo2YwZDASBgNVHRMBAf8E [1756729187.483] [4637:4666] [-] CDAGAQH/AgEAMA4GA1UdDwEB/wQEAwIBBjAdBgNVHQ4EFgQUY1QOR/ZLHDjROISk [1756729187.483] [4637:4666] [-] YtFsGV2P+zwwHwYDVR0jBBgwFoAUav0idx9RH+y/FkGXZxDc3DGhcX4wCgYIKoZI [1756729187.483] [4637:4666] [-] zj0EAwIDSAAwRQIhALLvJ/Sa6bUPuR7qyUxNC9u415KcbLiPrOUpNo0SBUwMAiBl [1756729187.483] [4637:4666] [-] Xckrhr2QmIKmxiF3uCXX0F7b58Ivn+pxIg5+pwP4kQ== [1756729187.483] [4637:4666] [-] -----END CERTIFICATE----- [1756729187.486] [4637:4666] [-] --> PAI certificate SKID: 63:54:0E:47:F6:4B:1C:38:D1:38:84:A4:62:D1:6C:19:5D:8F:FB:3C [1756729187.488] [4637:4666] [-] --> PAI certificate AKID: 6A:FD:22:77:1F:51:1F:EC:BF:16:41:97:67:10:DC:DC:31:A1:71:7E [1756729187.495] [4637:4666] [CTL] Error on commissioning step 'AttestationVerification': 'src/controller/CHIPDeviceController.cpp:1291: CHIP Error 0x00000020: Failed Device Attestation' [1756729187.495] [4637:4666] [CTL] Failed verifying attestation information. Now checking DAC chain revoked status. [1756729187.495] [4637:4666] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck' [1756729187.495] [4637:4666] [CTL] Performing next commissioning step 'AttestationRevocationCheck' with completion status = 'src/controller/CHIPDeviceController.cpp:1291: CHIP Error 0x00000020: Failed Device Attestation' [1756729187.495] [4637:4666] [CTL] Verifying the device's DAC chain revocation status [1756729187.495] [4637:4666] [-] WARNING: No revocation delegate available. Revocation checks will be skipped! [1756729187.495] [4637:4666] [CTL] Failed in verifying 'Attestation Information' command received from the device: err 500 (Attestation signature failed to validate against DAC subject public key) [1756729187.495] [4637:4666] [CTL] Error on commissioning step 'AttestationRevocationCheck': 'src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation' [1756729187.495] [4637:4666] [CTL] Going from commissioning step 'AttestationRevocationCheck' with lastErr = 'src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation' -> 'Cleanup' [1756729187.495] [4637:4666] [CTL] Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation' [1756729187.496] [4637:4666] [CTL] Disarming failsafe on device 0x7f8c041cb0 [1756729187.496] [4637:4666] [DMG] ICR moving to [AddingComm] [1756729187.496] [4637:4666] [DMG] ICR moving to [AddedComma] [1756729187.496] [4637:4666] [EM] <<< [E:62483i S:55443 M:88078153] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1756729187.497] [4637:4666] [DMG] ICR moving to [AwaitingRe] [1756729187.497] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729187.588] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16389 [1756729187.592] [4637:4665] [DL] Indication received, conn = 0x559d170f30 [1756729187.592] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16391 [1756729187.593] [4637:4666] [EM] >>> [E:62483i S:55443 M:189832966] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1756729187.593] [4637:4666] [EM] Found matching exchange: 62483i, Delegate: 0x7f8c008478 [1756729187.593] [4637:4666] [DMG] ICR moving to [ResponseRe] [1756729187.593] [4637:4666] [DMG] InvokeResponseMessage = [1756729187.593] [4637:4666] [DMG] { [1756729187.593] [4637:4666] [DMG] suppressResponse = false, [1756729187.593] [4637:4666] [DMG] InvokeResponseIBs = [1756729187.593] [4637:4666] [DMG] [ [1756729187.593] [4637:4666] [DMG] InvokeResponseIB = [1756729187.593] [4637:4666] [DMG] { [1756729187.593] [4637:4666] [DMG] CommandDataIB = [1756729187.593] [4637:4666] [DMG] { [1756729187.593] [4637:4666] [DMG] CommandPathIB = [1756729187.593] [4637:4666] [DMG] { [1756729187.594] [4637:4666] [DMG] EndpointId = 0x0, [1756729187.594] [4637:4666] [DMG] ClusterId = 0x30, [1756729187.594] [4637:4666] [DMG] CommandId = 0x1, [1756729187.594] [4637:4666] [DMG] }, [1756729187.594] [4637:4666] [DMG] [1756729187.594] [4637:4666] [DMG] CommandFields = [1756729187.594] [4637:4666] [DMG] { [1756729187.594] [4637:4666] [DMG] 0x0 = 0 (unsigned), [1756729187.594] [4637:4666] [DMG] 0x1 = "" (0 chars), [1756729187.594] [4637:4666] [DMG] }, [1756729187.594] [4637:4666] [DMG] }, [1756729187.594] [4637:4666] [DMG] [1756729187.594] [4637:4666] [DMG] }, [1756729187.594] [4637:4666] [DMG] [1756729187.594] [4637:4666] [DMG] ], [1756729187.594] [4637:4666] [DMG] [1756729187.594] [4637:4666] [DMG] InteractionModelRevision = 12 [1756729187.594] [4637:4666] [DMG] }, [1756729187.595] [4637:4666] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1756729187.595] [4637:4666] [CTL] Failsafe disarmed [1756729187.595] [4637:4666] [CTL] Successfully finished commissioning step 'Cleanup' [1756729187.595] [4637:4666] [DIS] Closing all BLE connections [1756729187.595] [4637:4666] [IN] Clearing BLE pending packets. [1756729187.597] [4637:4666] [BLE] Auto-closing end point's BLE connection. [1756729187.597] [4637:4666] [DL] Closing BLE GATT connection (con 0x559d170f30) [1756729187.597] [4637:4665] [DL] Close BLE connection: peer=F8:11:F3:72:95:B3 [1756729187.818] [4637:4666] [IN] SecureSession[0x7f8c013400]: MarkForEviction Type:1 LSID:55443 [1756729187.818] [4637:4666] [SC] SecureSession[0x7f8c013400, LSID:55443]: State change 'kActive' --> 'kPendingEviction' [1756729187.818] [4637:4666] [CTL] Commissioning complete for node ID 0x0000000000000001: src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation [1756729187.818] [4637:4666] [TOO] Device commissioning Failure: src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation [1756729187.818] [4637:4666] [TOO] Run command failure: src/controller/CHIPDeviceController.cpp:1338: CHIP Error 0x00000020: Failed Device Attestation [1756729187.818] [4637:4666] [DMG] ICR moving to [AwaitingDe] [1756729187.818] [4637:4666] [IN] SecureSession[0x7f8c013400]: Released - Type:1 LSID:55443 [1756729187.818] [4637:4666] [DL] Long dispatch time: 226 ms, for event type 16391 [1756729187.819] [4637:4666] [DL] HandlePlatformSpecificBLEEvent 16390 [1756729187.819] [4637:4666] [BLE] No endpoint for unsubscribe complete [1756729187.822] [4637:4665] [DL] BLE connection closed: conn=0x559d170f30 [1756729187.823] [4637:4666] [BLE] No endpoint for connection error