root@ubuntu:~# ./chip-tool pairing ble-thread 0x11820 hex:0e08000000000001000035060004001fffe00708fd73e2cb9793c0920410f20cc5c53745d578603d629135681d6d0c0402a0f7f8000300001901025b3502085b35dead5b35beef030435623335051000112233445566778899aabbccddeeff 88581739 1730 --paa-trust-store-path /paa_roots/ [1755248205.859] [22:22] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1755248205.860] [22:22] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1755248205.866] [22:22] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1755248205.866] [22:22] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1755248205.866] [22:22] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1755248205.874] [22:22] [DL] Wrote settings to /tmp/chip_counters.ini [1755248205.874] [22:22] [DL] NVS set: chip-counters/reboot-count = 2 (0x2) [1755248205.877] [22:22] [DL] Failed to get Ethernet interface [1755248205.879] [22:22] [DL] Failed to reset Ethernet statistic counts [1755248205.880] [22:22] [DL] Got WiFi interface: wlan0 [1755248205.883] [22:22] [DL] Found the primary WiFi interface:wlan0 [1755248205.884] [22:22] [IN] UDP::Init bind&listen port=0 [1755248205.884] [22:22] [IN] UDP::Init bound to port=55186 [1755248205.884] [22:22] [IN] BLEBase::Init - setting/overriding transport [1755248205.884] [22:22] [IN] TransportMgr initialized [1755248205.884] [22:22] [FP] Initializing FabricTable from persistent storage [1755248205.884] [22:22] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1755248205.886] [22:22] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0xB68BFF081E4CC426, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1755248205.889] [22:22] [ZCL] Using ZAP configuration... [1755248205.896] [22:22] [DL] Avahi client registered [1755248205.896] [22:22] [IM] WARNING ┌──────────────────────────────────────────────────── [1755248205.896] [22:22] [IM] WARNING │ Interaction Model Engine running in 'Checked' mode. [1755248205.896] [22:22] [IM] WARNING │ This executes BOTH ember and data-model code paths. [1755248205.896] [22:22] [IM] WARNING │ which is inefficient and consumes more flash space. [1755248205.896] [22:22] [IM] WARNING │ This should be done for testing only. [1755248205.896] [22:22] [IM] WARNING └──────────────────────────────────────────────────── [1755248205.896] [22:22] [CTL] System State Initialized... [1755248206.257] [22:22] [CTL] Setting attestation nonce to random value [1755248206.257] [22:22] [CTL] Setting CSR nonce to random value [1755248206.257] [22:22] [IN] UDP::Init bind&listen port=5550 [1755248206.257] [22:22] [IN] UDP::Init bound to port=5550 [1755248206.257] [22:22] [IN] TransportMgr initialized [1755248206.258] [22:24] [DL] CHIP task running [1755248206.258] [22:24] [DL] HandlePlatformSpecificBLEEvent 32786 [1755248206.258] [22:24] [CTL] Setting attestation nonce to random value [1755248206.259] [22:24] [CTL] Setting CSR nonce to random value [1755248206.260] [22:24] [CTL] Generating NOC [1755248206.261] [22:24] [FP] Validating NOC chain [1755248206.263] [22:24] [FP] NOC chain validation successful [1755248206.263] [22:24] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1755248206.263] [22:24] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1755248206.263] [22:24] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1755248206.263] [22:24] [TS] Retaining current Last Known Good Time [1755248206.266] [22:24] [FP] Metadata for Fabric 0x1 persisted to storage. [1755248206.268] [22:24] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1755248206.270] [22:24] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: B68BFF081E4CC426) [1755248206.270] [22:24] [IN] UDP::Init bind&listen port=5550 [1755248206.270] [22:24] [IN] UDP::Init bound to port=5550 [1755248206.270] [22:24] [IN] TransportMgr initialized [1755248206.286] [22:24] [CTL] Setting thread operational dataset from parameters [1755248206.286] [22:24] [CTL] Setting attestation nonce to random value [1755248206.286] [22:24] [CTL] Setting CSR nonce to random value [1755248206.286] [22:24] [CTL] Commission called for node ID 0x0000000000011820 [1755248206.300] [22:23] [BLE] BLE removing known devices [1755248206.302] [22:23] [BLE] BLE initiating scan [1755248206.306] [22:24] [BLE] ChipDeviceScanner has started scanning! [1755248206.311] [22:23] [BLE] Device 5F:99:6E:1B:6E:1B does not look like a CHIP device. [1755248206.315] [22:23] [BLE] Device 6A:DB:A3:D9:BC:F9 does not look like a CHIP device. [1755248206.317] [22:23] [BLE] Device 69:44:D8:62:4B:2D does not look like a CHIP device. [1755248206.326] [22:23] [BLE] Device 70:C9:12:19:44:48 does not look like a CHIP device. [1755248206.330] [22:23] [BLE] Device 65:17:08:DF:BA:48 does not look like a CHIP device. [1755248206.334] [22:23] [BLE] Device 48:97:72:7E:F9:C9 does not look like a CHIP device. [1755248206.346] [22:23] [BLE] Device C2:8B:2F:1E:BB:EE does not look like a CHIP device. [1755248206.347] [22:23] [BLE] Device 51:F4:52:C2:69:A1 does not look like a CHIP device. [1755248206.349] [22:23] [BLE] Device 70:C9:12:19:44:48 does not look like a CHIP device. [1755248206.351] [22:23] [BLE] Device 51:A8:CF:0E:95:EF does not look like a CHIP device. [1755248206.356] [22:23] [BLE] Device 76:44:27:45:89:A3 does not look like a CHIP device. [1755248206.363] [22:23] [BLE] Device 6C:6B:75:B9:16:CD does not look like a CHIP device. [1755248206.364] [22:23] [BLE] Device 3F:FB:B5:EF:1F:02 does not look like a CHIP device. [1755248206.367] [22:23] [BLE] Device 65:17:08:DF:BA:48 does not look like a CHIP device. [1755248206.384] [22:23] [BLE] Device 70:C9:12:19:44:48 does not look like a CHIP device. [1755248206.385] [22:23] [BLE] Device 06:98:F9:F0:B6:96 does not look like a CHIP device. [1755248206.389] [22:23] [BLE] Device 75:78:28:5D:0F:11 does not look like a CHIP device. [1755248206.391] [22:23] [BLE] Device 70:C9:12:19:44:48 does not look like a CHIP device. [1755248206.395] [22:23] [BLE] Device 02:22:1A:4F:75:60 does not look like a CHIP device. [1755248206.396] [22:23] [BLE] Device 65:17:08:DF:BA:48 does not look like a CHIP device. [1755248206.399] [22:23] [BLE] Device 21:2A:5C:A7:CE:D5 does not look like a CHIP device. [1755248206.401] [22:23] [BLE] Device 7C:E4:A5:89:CF:8B does not look like a CHIP device. [1755248206.411] [22:23] [BLE] Device 5F:99:6E:1B:6E:1B does not look like a CHIP device. [1755248206.413] [22:23] [BLE] Device 0F:D1:20:CB:B0:EB does not look like a CHIP device. [1755248206.420] [22:23] [BLE] Device 55:A8:C4:A3:45:87 does not look like a CHIP device. [1755248206.425] [22:23] [BLE] Device 67:6C:F0:4D:0D:AD does not look like a CHIP device. [1755248206.428] [22:23] [BLE] Device 65:17:08:DF:BA:48 does not look like a CHIP device. [1755248206.442] [22:23] [BLE] Device 76:90:18:57:D6:F8 does not look like a CHIP device. [1755248206.446] [22:23] [BLE] Device 7F:4C:0E:E1:39:EC does not look like a CHIP device. [1755248206.452] [22:23] [BLE] Device 52:F0:FF:EA:6E:C2 does not look like a CHIP device. [1755248206.458] [22:23] [BLE] Device 5F:99:6E:1B:6E:1B does not look like a CHIP device. [1755248206.464] [22:23] [BLE] Device D6:9E:E2:4C:99:FE does not look like a CHIP device. [1755248206.464] [22:23] [BLE] Device 65:17:08:DF:BA:48 does not look like a CHIP device. [1755248206.469] [22:23] [BLE] Device 49:B1:7B:5E:47:E2 does not look like a CHIP device. [1755248206.470] [22:23] [BLE] Device 0F:D1:20:CB:B0:EB does not look like a CHIP device. [1755248206.476] [22:23] [BLE] Device 0F:D1:20:CB:B0:EB does not look like a CHIP device. [1755248206.481] [22:23] [BLE] New device scanned: D4:7F:13:69:82:B9 [1755248206.481] [22:23] [BLE] Device discriminator match. Attempting to connect. [1755248206.485] [22:23] [BLE] ChipDeviceScanner has stopped scanning! [1755248207.008] [22:23] [DL] ConnectDevice complete [1755248207.008] [22:23] [BLE] New device connected: D4:7F:13:69:82:B9 [1755248208.119] [22:23] [DL] CHIP service found [1755248208.119] [22:23] [DL] Valid C2 characteristic found [1755248208.119] [22:23] [DL] Valid C1 characteristic found [1755248208.120] [22:23] [DL] New BLE connection: conn=0xffffa403bf60 device=D4:7F:13:69:82:B9 path=/org/bluez/hci0/dev_D4_7F_13_69_82_B9 [1755248208.120] [22:24] [DL] HandlePlatformSpecificBLEEvent 16387 [1755248208.120] [22:24] [DIS] Closing all BLE connections [1755248208.120] [22:24] [IN] BleConnectionComplete: endPoint 0xaaaac8cfcb88 [1755248208.121] [22:24] [IN] SecureSession[0xffff9c00b440]: Allocated Type:1 LSID:41856 [1755248208.122] [22:24] [SC] Assigned local session key ID 41856 [1755248208.122] [22:24] [EM] <<< [E:19052i S:0 M:19214308] (U) Msg TX from 662C17875FCADACA to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98) [1755248208.122] [22:24] [IN] Message appended to BLE send queue [1755248208.122] [22:24] [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms) [1755248208.467] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248208.558] [22:24] [DL] HandlePlatformSpecificBLEEvent 16390 [1755248208.559] [22:24] [BLE] subscribe complete, ep = 0xaaaac8cfcb88 [1755248208.559] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248208.559] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248208.559] [22:24] [BLE] peripheral chose BTP version 4; central expected between 4 and 4 [1755248208.559] [22:24] [BLE] using BTP fragment sizes rx 244 / tx 244. [1755248208.559] [22:24] [BLE] local and remote recv window size = 5 [1755248208.560] [22:24] [IN] BLE EndPoint 0xaaaac8cfcb88 Connection Complete [1755248208.693] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248208.783] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248208.784] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248208.784] [22:24] [EM] >>> [E:19052i S:0 M:41364432] (U) Msg RX from 0:0000000000000000 [0000] to 662C17875FCADACA --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:154) [1755248208.784] [22:24] [EM] Found matching exchange: 19052i, Delegate: 0xffff9c00b828 [1755248208.785] [22:24] [SC] Received PBKDF param response [1755248208.785] [22:24] [SC] Peer assigned session ID 24528 [1755248208.785] [22:24] [SC] Found MRP parameters in the message [1755248208.796] [22:24] [EM] <<< [E:19052i S:0 M:19214309] (U) Msg TX from 662C17875FCADACA to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92) [1755248208.797] [22:24] [SC] Sent spake2p msg1 [1755248208.873] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248208.963] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248208.963] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248208.964] [22:24] [EM] >>> [E:19052i S:0 M:41364433] (U) Msg RX from 0:0000000000000000 [0000] to 662C17875FCADACA --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127) [1755248208.964] [22:24] [EM] Found matching exchange: 19052i, Delegate: 0xffff9c00b828 [1755248208.964] [22:24] [SC] Received spake2p msg2 [1755248208.967] [22:24] [EM] <<< [E:19052i S:0 M:19214310] (U) Msg TX from 662C17875FCADACA to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59) [1755248208.967] [22:24] [SC] Sent spake2p msg3 [1755248209.053] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248209.099] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248209.099] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248209.099] [22:24] [EM] >>> [E:19052i S:0 M:41364434] (U) Msg RX from 0:0000000000000000 [0000] to 662C17875FCADACA --- Type 0000:40 (SecureChannel:StatusReport) (B:30) [1755248209.099] [22:24] [EM] Found matching exchange: 19052i, Delegate: 0xffff9c00b828 [1755248209.100] [22:24] [SC] SecureSession[0xffff9c00b440, LSID:41856]: State change 'kEstablishing' --> 'kActive' [1755248209.100] [22:24] [IN] SecureSession[0xffff9c00b440]: Activated - Type:1 LSID:41856 [1755248209.100] [22:24] [IN] New secure session activated for device , LSID:41856 PSID:24528! [1755248209.100] [22:24] [CTL] Remote device completed SPAKE2+ handshake [1755248209.100] [22:24] [TOO] Pairing Success [1755248209.100] [22:24] [TOO] PASE establishment successful [1755248209.100] [22:24] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1755248209.100] [22:24] [CTL] Performing next commissioning step 'ReadCommissioningInfo' [1755248209.100] [22:24] [CTL] Sending read request for commissioning information [1755248209.100] [22:24] [DMG] SendReadRequest ReadClient[0xffff9c009d50]: Sending Read Request [1755248209.101] [22:24] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1755248209.101] [22:24] [EM] <<< [E:19053i S:41856 M:248780860] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:131) [1755248209.102] [22:24] [DMG] MoveToState ReadClient[0xffff9c009d50]: Moving to [AwaitingIn] [1755248209.102] [22:24] [DL] HandlePlatformSpecificBLEEvent 32792 [1755248209.233] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248209.326] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248209.326] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248209.462] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248209.462] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248209.596] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248209.597] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248209.684] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248209.684] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248209.685] [22:24] [EM] >>> [E:19053i S:41856 M:101708475] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:740) [1755248209.686] [22:24] [EM] Found matching exchange: 19053i, Delegate: 0xffff9c009d60 [1755248209.687] [22:24] [DMG] ReportDataMessage = [1755248209.687] [22:24] [DMG] { [1755248209.687] [22:24] [DMG] AttributeReportIBs = [1755248209.687] [22:24] [DMG] [ [1755248209.687] [22:24] [DMG] AttributeReportIB = [1755248209.687] [22:24] [DMG] { [1755248209.687] [22:24] [DMG] AttributeDataIB = [1755248209.687] [22:24] [DMG] { [1755248209.687] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.687] [22:24] [DMG] AttributePathIB = [1755248209.687] [22:24] [DMG] { [1755248209.688] [22:24] [DMG] Endpoint = 0x0, [1755248209.688] [22:24] [DMG] Cluster = 0x38, [1755248209.688] [22:24] [DMG] Attribute = 0x0000_0000, [1755248209.688] [22:24] [DMG] } [1755248209.688] [22:24] [DMG] [1755248209.688] [22:24] [DMG] Data = 808563409132878 (unsigned), [1755248209.688] [22:24] [DMG] }, [1755248209.688] [22:24] [DMG] [1755248209.688] [22:24] [DMG] }, [1755248209.688] [22:24] [DMG] [1755248209.688] [22:24] [DMG] AttributeReportIB = [1755248209.688] [22:24] [DMG] { [1755248209.688] [22:24] [DMG] AttributeDataIB = [1755248209.688] [22:24] [DMG] { [1755248209.688] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.688] [22:24] [DMG] AttributePathIB = [1755248209.688] [22:24] [DMG] { [1755248209.688] [22:24] [DMG] Endpoint = 0x0, [1755248209.689] [22:24] [DMG] Cluster = 0x38, [1755248209.689] [22:24] [DMG] Attribute = 0x0000_0001, [1755248209.689] [22:24] [DMG] } [1755248209.689] [22:24] [DMG] [1755248209.689] [22:24] [DMG] Data = 2 (unsigned), [1755248209.689] [22:24] [DMG] }, [1755248209.689] [22:24] [DMG] [1755248209.689] [22:24] [DMG] }, [1755248209.689] [22:24] [DMG] [1755248209.689] [22:24] [DMG] AttributeReportIB = [1755248209.689] [22:24] [DMG] { [1755248209.689] [22:24] [DMG] AttributeDataIB = [1755248209.689] [22:24] [DMG] { [1755248209.689] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.689] [22:24] [DMG] AttributePathIB = [1755248209.689] [22:24] [DMG] { [1755248209.689] [22:24] [DMG] Endpoint = 0x0, [1755248209.689] [22:24] [DMG] Cluster = 0x38, [1755248209.689] [22:24] [DMG] Attribute = 0x0000_0002, [1755248209.690] [22:24] [DMG] } [1755248209.690] [22:24] [DMG] [1755248209.690] [22:24] [DMG] Data = 2 (unsigned), [1755248209.690] [22:24] [DMG] }, [1755248209.690] [22:24] [DMG] [1755248209.690] [22:24] [DMG] }, [1755248209.690] [22:24] [DMG] [1755248209.690] [22:24] [DMG] AttributeReportIB = [1755248209.690] [22:24] [DMG] { [1755248209.690] [22:24] [DMG] AttributeDataIB = [1755248209.690] [22:24] [DMG] { [1755248209.690] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.690] [22:24] [DMG] AttributePathIB = [1755248209.690] [22:24] [DMG] { [1755248209.690] [22:24] [DMG] Endpoint = 0x0, [1755248209.690] [22:24] [DMG] Cluster = 0x38, [1755248209.690] [22:24] [DMG] Attribute = 0x0000_0003, [1755248209.691] [22:24] [DMG] } [1755248209.691] [22:24] [DMG] [1755248209.691] [22:24] [DMG] Data = NULL [1755248209.691] [22:24] [DMG] }, [1755248209.691] [22:24] [DMG] [1755248209.691] [22:24] [DMG] }, [1755248209.691] [22:24] [DMG] [1755248209.691] [22:24] [DMG] AttributeReportIB = [1755248209.691] [22:24] [DMG] { [1755248209.691] [22:24] [DMG] AttributeDataIB = [1755248209.691] [22:24] [DMG] { [1755248209.691] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.691] [22:24] [DMG] AttributePathIB = [1755248209.691] [22:24] [DMG] { [1755248209.691] [22:24] [DMG] Endpoint = 0x0, [1755248209.691] [22:24] [DMG] Cluster = 0x38, [1755248209.692] [22:24] [DMG] Attribute = 0x0000_0004, [1755248209.692] [22:24] [DMG] } [1755248209.692] [22:24] [DMG] [1755248209.692] [22:24] [DMG] Data = NULL [1755248209.692] [22:24] [DMG] }, [1755248209.692] [22:24] [DMG] [1755248209.692] [22:24] [DMG] }, [1755248209.692] [22:24] [DMG] [1755248209.692] [22:24] [DMG] AttributeReportIB = [1755248209.692] [22:24] [DMG] { [1755248209.692] [22:24] [DMG] AttributeDataIB = [1755248209.692] [22:24] [DMG] { [1755248209.692] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.692] [22:24] [DMG] AttributePathIB = [1755248209.692] [22:24] [DMG] { [1755248209.692] [22:24] [DMG] Endpoint = 0x0, [1755248209.692] [22:24] [DMG] Cluster = 0x38, [1755248209.692] [22:24] [DMG] Attribute = 0x0000_0005, [1755248209.692] [22:24] [DMG] } [1755248209.693] [22:24] [DMG] [1755248209.693] [22:24] [DMG] Data = [ [1755248209.693] [22:24] [DMG] [1755248209.693] [22:24] [DMG] { [1755248209.693] [22:24] [DMG] 0x0 = 0 (signed), [1755248209.693] [22:24] [DMG] 0x1 = 0 (unsigned), [1755248209.693] [22:24] [DMG] }, [1755248209.693] [22:24] [DMG] ], [1755248209.693] [22:24] [DMG] }, [1755248209.693] [22:24] [DMG] [1755248209.693] [22:24] [DMG] }, [1755248209.693] [22:24] [DMG] [1755248209.693] [22:24] [DMG] AttributeReportIB = [1755248209.693] [22:24] [DMG] { [1755248209.693] [22:24] [DMG] AttributeDataIB = [1755248209.693] [22:24] [DMG] { [1755248209.693] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.693] [22:24] [DMG] AttributePathIB = [1755248209.693] [22:24] [DMG] { [1755248209.694] [22:24] [DMG] Endpoint = 0x0, [1755248209.694] [22:24] [DMG] Cluster = 0x38, [1755248209.694] [22:24] [DMG] Attribute = 0x0000_0006, [1755248209.694] [22:24] [DMG] } [1755248209.694] [22:24] [DMG] [1755248209.694] [22:24] [DMG] Data = [ [1755248209.694] [22:24] [DMG] [1755248209.694] [22:24] [DMG] ], [1755248209.694] [22:24] [DMG] }, [1755248209.694] [22:24] [DMG] [1755248209.694] [22:24] [DMG] }, [1755248209.694] [22:24] [DMG] [1755248209.694] [22:24] [DMG] AttributeReportIB = [1755248209.694] [22:24] [DMG] { [1755248209.694] [22:24] [DMG] AttributeDataIB = [1755248209.694] [22:24] [DMG] { [1755248209.694] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.694] [22:24] [DMG] AttributePathIB = [1755248209.694] [22:24] [DMG] { [1755248209.694] [22:24] [DMG] Endpoint = 0x0, [1755248209.694] [22:24] [DMG] Cluster = 0x38, [1755248209.695] [22:24] [DMG] Attribute = 0x0000_0007, [1755248209.695] [22:24] [DMG] } [1755248209.695] [22:24] [DMG] [1755248209.695] [22:24] [DMG] Data = NULL [1755248209.695] [22:24] [DMG] }, [1755248209.695] [22:24] [DMG] [1755248209.695] [22:24] [DMG] }, [1755248209.695] [22:24] [DMG] [1755248209.695] [22:24] [DMG] AttributeReportIB = [1755248209.695] [22:24] [DMG] { [1755248209.695] [22:24] [DMG] AttributeDataIB = [1755248209.695] [22:24] [DMG] { [1755248209.695] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.695] [22:24] [DMG] AttributePathIB = [1755248209.695] [22:24] [DMG] { [1755248209.695] [22:24] [DMG] Endpoint = 0x0, [1755248209.695] [22:24] [DMG] Cluster = 0x38, [1755248209.695] [22:24] [DMG] Attribute = 0x0000_0008, [1755248209.695] [22:24] [DMG] } [1755248209.695] [22:24] [DMG] [1755248209.695] [22:24] [DMG] Data = 2 (unsigned), [1755248209.696] [22:24] [DMG] }, [1755248209.696] [22:24] [DMG] [1755248209.696] [22:24] [DMG] }, [1755248209.696] [22:24] [DMG] [1755248209.696] [22:24] [DMG] AttributeReportIB = [1755248209.696] [22:24] [DMG] { [1755248209.696] [22:24] [DMG] AttributeDataIB = [1755248209.696] [22:24] [DMG] { [1755248209.696] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.696] [22:24] [DMG] AttributePathIB = [1755248209.696] [22:24] [DMG] { [1755248209.696] [22:24] [DMG] Endpoint = 0x0, [1755248209.696] [22:24] [DMG] Cluster = 0x38, [1755248209.696] [22:24] [DMG] Attribute = 0x0000_000A, [1755248209.696] [22:24] [DMG] } [1755248209.696] [22:24] [DMG] [1755248209.696] [22:24] [DMG] Data = 2 (unsigned), [1755248209.696] [22:24] [DMG] }, [1755248209.697] [22:24] [DMG] [1755248209.697] [22:24] [DMG] }, [1755248209.697] [22:24] [DMG] [1755248209.697] [22:24] [DMG] AttributeReportIB = [1755248209.697] [22:24] [DMG] { [1755248209.697] [22:24] [DMG] AttributeDataIB = [1755248209.697] [22:24] [DMG] { [1755248209.697] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.697] [22:24] [DMG] AttributePathIB = [1755248209.697] [22:24] [DMG] { [1755248209.697] [22:24] [DMG] Endpoint = 0x0, [1755248209.697] [22:24] [DMG] Cluster = 0x38, [1755248209.697] [22:24] [DMG] Attribute = 0x0000_000B, [1755248209.697] [22:24] [DMG] } [1755248209.697] [22:24] [DMG] [1755248209.697] [22:24] [DMG] Data = 2 (unsigned), [1755248209.697] [22:24] [DMG] }, [1755248209.697] [22:24] [DMG] [1755248209.698] [22:24] [DMG] }, [1755248209.698] [22:24] [DMG] [1755248209.698] [22:24] [DMG] AttributeReportIB = [1755248209.698] [22:24] [DMG] { [1755248209.698] [22:24] [DMG] AttributeDataIB = [1755248209.698] [22:24] [DMG] { [1755248209.698] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.698] [22:24] [DMG] AttributePathIB = [1755248209.698] [22:24] [DMG] { [1755248209.698] [22:24] [DMG] Endpoint = 0x0, [1755248209.698] [22:24] [DMG] Cluster = 0x38, [1755248209.698] [22:24] [DMG] Attribute = 0x0000_FFFC, [1755248209.698] [22:24] [DMG] } [1755248209.698] [22:24] [DMG] [1755248209.698] [22:24] [DMG] Data = 9 (unsigned), [1755248209.698] [22:24] [DMG] }, [1755248209.698] [22:24] [DMG] [1755248209.698] [22:24] [DMG] }, [1755248209.699] [22:24] [DMG] [1755248209.699] [22:24] [DMG] AttributeReportIB = [1755248209.699] [22:24] [DMG] { [1755248209.699] [22:24] [DMG] AttributeDataIB = [1755248209.699] [22:24] [DMG] { [1755248209.699] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.699] [22:24] [DMG] AttributePathIB = [1755248209.699] [22:24] [DMG] { [1755248209.699] [22:24] [DMG] Endpoint = 0x0, [1755248209.699] [22:24] [DMG] Cluster = 0x38, [1755248209.699] [22:24] [DMG] Attribute = 0x0000_FFFD, [1755248209.699] [22:24] [DMG] } [1755248209.699] [22:24] [DMG] [1755248209.699] [22:24] [DMG] Data = 2 (unsigned), [1755248209.699] [22:24] [DMG] }, [1755248209.699] [22:24] [DMG] [1755248209.699] [22:24] [DMG] }, [1755248209.699] [22:24] [DMG] [1755248209.699] [22:24] [DMG] AttributeReportIB = [1755248209.700] [22:24] [DMG] { [1755248209.700] [22:24] [DMG] AttributeDataIB = [1755248209.700] [22:24] [DMG] { [1755248209.700] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.700] [22:24] [DMG] AttributePathIB = [1755248209.700] [22:24] [DMG] { [1755248209.700] [22:24] [DMG] Endpoint = 0x0, [1755248209.700] [22:24] [DMG] Cluster = 0x38, [1755248209.700] [22:24] [DMG] Attribute = 0x0000_FFF8, [1755248209.700] [22:24] [DMG] } [1755248209.700] [22:24] [DMG] [1755248209.700] [22:24] [DMG] Data = [ [1755248209.700] [22:24] [DMG] 3 (unsigned), [1755248209.700] [22:24] [DMG] ], [1755248209.700] [22:24] [DMG] }, [1755248209.701] [22:24] [DMG] [1755248209.701] [22:24] [DMG] }, [1755248209.701] [22:24] [DMG] [1755248209.701] [22:24] [DMG] AttributeReportIB = [1755248209.701] [22:24] [DMG] { [1755248209.701] [22:24] [DMG] AttributeDataIB = [1755248209.702] [22:24] [DMG] { [1755248209.702] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.702] [22:24] [DMG] AttributePathIB = [1755248209.702] [22:24] [DMG] { [1755248209.702] [22:24] [DMG] Endpoint = 0x0, [1755248209.703] [22:24] [DMG] Cluster = 0x38, [1755248209.703] [22:24] [DMG] Attribute = 0x0000_FFF9, [1755248209.703] [22:24] [DMG] } [1755248209.703] [22:24] [DMG] [1755248209.703] [22:24] [DMG] Data = [ [1755248209.703] [22:24] [DMG] 0 (unsigned), 1 (unsigned), 2 (unsigned), 4 (unsigned), 5 (unsigned), [1755248209.704] [22:24] [DMG] ], [1755248209.704] [22:24] [DMG] }, [1755248209.704] [22:24] [DMG] [1755248209.704] [22:24] [DMG] }, [1755248209.704] [22:24] [DMG] [1755248209.704] [22:24] [DMG] AttributeReportIB = [1755248209.704] [22:24] [DMG] { [1755248209.704] [22:24] [DMG] AttributeDataIB = [1755248209.704] [22:24] [DMG] { [1755248209.704] [22:24] [DMG] DataVersion = 0x3ec01e8a, [1755248209.705] [22:24] [DMG] AttributePathIB = [1755248209.705] [22:24] [DMG] { [1755248209.705] [22:24] [DMG] Endpoint = 0x0, [1755248209.705] [22:24] [DMG] Cluster = 0x38, [1755248209.705] [22:24] [DMG] Attribute = 0x0000_FFFB, [1755248209.705] [22:24] [DMG] } [1755248209.705] [22:24] [DMG] [1755248209.705] [22:24] [DMG] Data = [ [1755248209.705] [22:24] [DMG] 0 (unsigned), 1 (unsigned), 2 (unsigned), 3 (unsigned), 4 (unsigned), 5 (unsigned), 6 (unsigned), 7 (unsigned), 8 (unsigned), 10 (unsigned), 11 (unsigned), 65528 (unsigned), 65529 (unsigned), 65531 (unsigned), 65532 (unsigned), 65533 (unsigned), [1755248209.705] [22:24] [DMG] ], [1755248209.706] [22:24] [DMG] }, [1755248209.706] [22:24] [DMG] [1755248209.706] [22:24] [DMG] }, [1755248209.706] [22:24] [DMG] [1755248209.706] [22:24] [DMG] AttributeReportIB = [1755248209.706] [22:24] [DMG] { [1755248209.706] [22:24] [DMG] AttributeDataIB = [1755248209.706] [22:24] [DMG] { [1755248209.706] [22:24] [DMG] DataVersion = 0xbe9e4cee, [1755248209.706] [22:24] [DMG] AttributePathIB = [1755248209.707] [22:24] [DMG] { [1755248209.707] [22:24] [DMG] Endpoint = 0x0, [1755248209.707] [22:24] [DMG] Cluster = 0x31, [1755248209.707] [22:24] [DMG] Attribute = 0x0000_0003, [1755248209.707] [22:24] [DMG] } [1755248209.707] [22:24] [DMG] [1755248209.707] [22:24] [DMG] Data = 20 (unsigned), [1755248209.707] [22:24] [DMG] }, [1755248209.707] [22:24] [DMG] [1755248209.707] [22:24] [DMG] }, [1755248209.707] [22:24] [DMG] [1755248209.707] [22:24] [DMG] AttributeReportIB = [1755248209.707] [22:24] [DMG] { [1755248209.707] [22:24] [DMG] AttributeDataIB = [1755248209.707] [22:24] [DMG] { [1755248209.707] [22:24] [DMG] DataVersion = 0xab3ff48e, [1755248209.707] [22:24] [DMG] AttributePathIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] Endpoint = 0x0, [1755248209.708] [22:24] [DMG] Cluster = 0x28, [1755248209.708] [22:24] [DMG] Attribute = 0x0000_0004, [1755248209.708] [22:24] [DMG] } [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] Data = 12289 (unsigned), [1755248209.708] [22:24] [DMG] }, [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] }, [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] AttributeReportIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] AttributeDataIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] DataVersion = 0xab3ff48e, [1755248209.708] [22:24] [DMG] AttributePathIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] Endpoint = 0x0, [1755248209.708] [22:24] [DMG] Cluster = 0x28, [1755248209.708] [22:24] [DMG] Attribute = 0x0000_0002, [1755248209.708] [22:24] [DMG] } [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] Data = 5181 (unsigned), [1755248209.708] [22:24] [DMG] }, [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] }, [1755248209.708] [22:24] [DMG] [1755248209.708] [22:24] [DMG] AttributeReportIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] AttributeDataIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] DataVersion = 0xeb55a7b1, [1755248209.708] [22:24] [DMG] AttributePathIB = [1755248209.708] [22:24] [DMG] { [1755248209.708] [22:24] [DMG] Endpoint = 0x0, [1755248209.708] [22:24] [DMG] Cluster = 0x30, [1755248209.708] [22:24] [DMG] Attribute = 0x0000_0003, [1755248209.708] [22:24] [DMG] } [1755248209.708] [22:24] [DMG] [1755248209.709] [22:24] [DMG] Data = 0 (unsigned), [1755248209.709] [22:24] [DMG] }, [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] }, [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] AttributeReportIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] AttributeDataIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] DataVersion = 0xeb55a7b1, [1755248209.709] [22:24] [DMG] AttributePathIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] Endpoint = 0x0, [1755248209.709] [22:24] [DMG] Cluster = 0x30, [1755248209.709] [22:24] [DMG] Attribute = 0x0000_0002, [1755248209.709] [22:24] [DMG] } [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] Data = 0 (unsigned), [1755248209.709] [22:24] [DMG] }, [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] }, [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] AttributeReportIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] AttributeDataIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] DataVersion = 0xeb55a7b1, [1755248209.709] [22:24] [DMG] AttributePathIB = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] Endpoint = 0x0, [1755248209.709] [22:24] [DMG] Cluster = 0x30, [1755248209.709] [22:24] [DMG] Attribute = 0x0000_0001, [1755248209.709] [22:24] [DMG] } [1755248209.709] [22:24] [DMG] [1755248209.709] [22:24] [DMG] Data = [1755248209.709] [22:24] [DMG] { [1755248209.709] [22:24] [DMG] 0x0 = 60 (unsigned), [1755248209.709] [22:24] [DMG] 0x1 = 900 (unsigned), [1755248209.709] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] AttributeReportIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] AttributeDataIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] DataVersion = 0xeb55a7b1, [1755248209.710] [22:24] [DMG] AttributePathIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] Endpoint = 0x0, [1755248209.710] [22:24] [DMG] Cluster = 0x30, [1755248209.710] [22:24] [DMG] Attribute = 0x0000_0000, [1755248209.710] [22:24] [DMG] } [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] Data = 0 (unsigned), [1755248209.710] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] AttributeReportIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] AttributeDataIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] DataVersion = 0xbe9e4cee, [1755248209.710] [22:24] [DMG] AttributePathIB = [1755248209.710] [22:24] [DMG] { [1755248209.710] [22:24] [DMG] Endpoint = 0x0, [1755248209.710] [22:24] [DMG] Cluster = 0x31, [1755248209.710] [22:24] [DMG] Attribute = 0x0000_FFFC, [1755248209.710] [22:24] [DMG] } [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] Data = 2 (unsigned), [1755248209.710] [22:24] [DMG] }, [1755248209.710] [22:24] [DMG] [1755248209.710] [22:24] [DMG] }, [1755248209.711] [22:24] [DMG] [1755248209.711] [22:24] [DMG] ], [1755248209.711] [22:24] [DMG] [1755248209.711] [22:24] [DMG] SuppressResponse = true, [1755248209.711] [22:24] [DMG] InteractionModelRevision = 12 [1755248209.711] [22:24] [DMG] } [1755248209.713] [22:24] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo' [1755248209.713] [22:24] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1755248209.713] [22:24] [CTL] Performing next commissioning step 'ReadCommissioningInfo2' [1755248209.713] [22:24] [DMG] SendReadRequest ReadClient[0xffff9c009d50]: Sending Read Request [1755248209.713] [22:24] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1755248209.713] [22:24] [EM] <<< [E:19054i S:41856 M:248780861] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:106) [1755248209.713] [22:24] [DMG] MoveToState ReadClient[0xffff9c009d50]: Moving to [AwaitingIn] [1755248209.773] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248209.863] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248210.000] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.001] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.001] [22:24] [EM] >>> [E:19054i S:41856 M:101708476] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:192) [1755248210.001] [22:24] [EM] Found matching exchange: 19054i, Delegate: 0xffff9c009d60 [1755248210.001] [22:24] [DMG] ReportDataMessage = [1755248210.001] [22:24] [DMG] { [1755248210.001] [22:24] [DMG] AttributeReportIBs = [1755248210.001] [22:24] [DMG] [ [1755248210.002] [22:24] [DMG] AttributeReportIB = [1755248210.002] [22:24] [DMG] { [1755248210.002] [22:24] [DMG] AttributeDataIB = [1755248210.002] [22:24] [DMG] { [1755248210.002] [22:24] [DMG] DataVersion = 0x25348dbf, [1755248210.002] [22:24] [DMG] AttributePathIB = [1755248210.002] [22:24] [DMG] { [1755248210.002] [22:24] [DMG] Endpoint = 0x0, [1755248210.002] [22:24] [DMG] Cluster = 0x46, [1755248210.002] [22:24] [DMG] Attribute = 0x0000_0002, [1755248210.002] [22:24] [DMG] } [1755248210.002] [22:24] [DMG] [1755248210.002] [22:24] [DMG] Data = 5000 (unsigned), [1755248210.002] [22:24] [DMG] }, [1755248210.002] [22:24] [DMG] [1755248210.003] [22:24] [DMG] }, [1755248210.003] [22:24] [DMG] [1755248210.003] [22:24] [DMG] AttributeReportIB = [1755248210.003] [22:24] [DMG] { [1755248210.003] [22:24] [DMG] AttributeDataIB = [1755248210.003] [22:24] [DMG] { [1755248210.003] [22:24] [DMG] DataVersion = 0x25348dbf, [1755248210.003] [22:24] [DMG] AttributePathIB = [1755248210.003] [22:24] [DMG] { [1755248210.003] [22:24] [DMG] Endpoint = 0x0, [1755248210.003] [22:24] [DMG] Cluster = 0x46, [1755248210.003] [22:24] [DMG] Attribute = 0x0000_0001, [1755248210.003] [22:24] [DMG] } [1755248210.003] [22:24] [DMG] [1755248210.004] [22:24] [DMG] Data = 300 (unsigned), [1755248210.004] [22:24] [DMG] }, [1755248210.004] [22:24] [DMG] [1755248210.004] [22:24] [DMG] }, [1755248210.004] [22:24] [DMG] [1755248210.004] [22:24] [DMG] AttributeReportIB = [1755248210.004] [22:24] [DMG] { [1755248210.004] [22:24] [DMG] AttributeDataIB = [1755248210.004] [22:24] [DMG] { [1755248210.004] [22:24] [DMG] DataVersion = 0x25348dbf, [1755248210.004] [22:24] [DMG] AttributePathIB = [1755248210.004] [22:24] [DMG] { [1755248210.004] [22:24] [DMG] Endpoint = 0x0, [1755248210.004] [22:24] [DMG] Cluster = 0x46, [1755248210.005] [22:24] [DMG] Attribute = 0x0000_0000, [1755248210.005] [22:24] [DMG] } [1755248210.005] [22:24] [DMG] [1755248210.005] [22:24] [DMG] Data = 300 (unsigned), [1755248210.005] [22:24] [DMG] }, [1755248210.005] [22:24] [DMG] [1755248210.005] [22:24] [DMG] }, [1755248210.005] [22:24] [DMG] [1755248210.005] [22:24] [DMG] AttributeReportIB = [1755248210.005] [22:24] [DMG] { [1755248210.005] [22:24] [DMG] AttributeStatusIB = [1755248210.006] [22:24] [DMG] { [1755248210.006] [22:24] [DMG] AttributePathIB = [1755248210.006] [22:24] [DMG] { [1755248210.006] [22:24] [DMG] Endpoint = 0x0, [1755248210.006] [22:24] [DMG] Cluster = 0x46, [1755248210.006] [22:24] [DMG] Attribute = 0x0000_0007, [1755248210.006] [22:24] [DMG] } [1755248210.006] [22:24] [DMG] [1755248210.006] [22:24] [DMG] StatusIB = [1755248210.006] [22:24] [DMG] { [1755248210.006] [22:24] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1755248210.006] [22:24] [DMG] }, [1755248210.006] [22:24] [DMG] [1755248210.006] [22:24] [DMG] }, [1755248210.006] [22:24] [DMG] [1755248210.006] [22:24] [DMG] }, [1755248210.007] [22:24] [DMG] [1755248210.007] [22:24] [DMG] AttributeReportIB = [1755248210.007] [22:24] [DMG] { [1755248210.007] [22:24] [DMG] AttributeStatusIB = [1755248210.007] [22:24] [DMG] { [1755248210.007] [22:24] [DMG] AttributePathIB = [1755248210.007] [22:24] [DMG] { [1755248210.007] [22:24] [DMG] Endpoint = 0x0, [1755248210.007] [22:24] [DMG] Cluster = 0x46, [1755248210.007] [22:24] [DMG] Attribute = 0x0000_0006, [1755248210.007] [22:24] [DMG] } [1755248210.007] [22:24] [DMG] [1755248210.007] [22:24] [DMG] StatusIB = [1755248210.007] [22:24] [DMG] { [1755248210.008] [22:24] [DMG] status = 0x86 (UNSUPPORTED_ATTRIBUTE), [1755248210.008] [22:24] [DMG] }, [1755248210.008] [22:24] [DMG] [1755248210.008] [22:24] [DMG] }, [1755248210.008] [22:24] [DMG] [1755248210.008] [22:24] [DMG] }, [1755248210.008] [22:24] [DMG] [1755248210.008] [22:24] [DMG] AttributeReportIB = [1755248210.008] [22:24] [DMG] { [1755248210.008] [22:24] [DMG] AttributeDataIB = [1755248210.008] [22:24] [DMG] { [1755248210.008] [22:24] [DMG] DataVersion = 0xeb55a7b1, [1755248210.008] [22:24] [DMG] AttributePathIB = [1755248210.008] [22:24] [DMG] { [1755248210.008] [22:24] [DMG] Endpoint = 0x0, [1755248210.009] [22:24] [DMG] Cluster = 0x30, [1755248210.009] [22:24] [DMG] Attribute = 0x0000_0004, [1755248210.009] [22:24] [DMG] } [1755248210.009] [22:24] [DMG] [1755248210.010] [22:24] [DMG] Data = true, [1755248210.010] [22:24] [DMG] }, [1755248210.010] [22:24] [DMG] [1755248210.010] [22:24] [DMG] }, [1755248210.010] [22:24] [DMG] [1755248210.010] [22:24] [DMG] ], [1755248210.010] [22:24] [DMG] [1755248210.011] [22:24] [DMG] SuppressResponse = true, [1755248210.011] [22:24] [DMG] InteractionModelRevision = 12 [1755248210.011] [22:24] [DMG] } [1755248210.012] [22:24] [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0 [1755248210.012] [22:24] [SVR] OnReadCommissioningInfo - vendorId=0x143D productId=0x3001 [1755248210.012] [22:24] [SVR] OnReadCommissioningInfo ICD - IdleModeDuration=0 activeModeDuration=0 activeModeThreshold=0 [1755248210.012] [22:24] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo2' [1755248210.012] [22:24] [CTL] Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1755248210.012] [22:24] [CTL] Performing next commissioning step 'ArmFailSafe' [1755248210.012] [22:24] [CTL] Arming failsafe (60 seconds) [1755248210.012] [22:24] [DMG] ICR moving to [AddingComm] [1755248210.013] [22:24] [DMG] ICR moving to [AddedComma] [1755248210.013] [22:24] [EM] <<< [E:19055i S:41856 M:248780862] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1755248210.014] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248210.088] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248210.135] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.135] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.136] [22:24] [EM] >>> [E:19055i S:41856 M:101708477] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1755248210.136] [22:24] [EM] Found matching exchange: 19055i, Delegate: 0xffff9c0081c8 [1755248210.136] [22:24] [DMG] ICR moving to [ResponseRe] [1755248210.136] [22:24] [DMG] InvokeResponseMessage = [1755248210.136] [22:24] [DMG] { [1755248210.136] [22:24] [DMG] suppressResponse = false, [1755248210.136] [22:24] [DMG] InvokeResponseIBs = [1755248210.136] [22:24] [DMG] [ [1755248210.136] [22:24] [DMG] InvokeResponseIB = [1755248210.137] [22:24] [DMG] { [1755248210.137] [22:24] [DMG] CommandDataIB = [1755248210.137] [22:24] [DMG] { [1755248210.137] [22:24] [DMG] CommandPathIB = [1755248210.137] [22:24] [DMG] { [1755248210.138] [22:24] [DMG] EndpointId = 0x0, [1755248210.138] [22:24] [DMG] ClusterId = 0x30, [1755248210.138] [22:24] [DMG] CommandId = 0x1, [1755248210.138] [22:24] [DMG] }, [1755248210.138] [22:24] [DMG] [1755248210.138] [22:24] [DMG] CommandFields = [1755248210.138] [22:24] [DMG] { [1755248210.138] [22:24] [DMG] 0x0 = 0 (unsigned), [1755248210.138] [22:24] [DMG] 0x1 = "" (0 chars), [1755248210.138] [22:24] [DMG] }, [1755248210.138] [22:24] [DMG] }, [1755248210.139] [22:24] [DMG] [1755248210.139] [22:24] [DMG] }, [1755248210.139] [22:24] [DMG] [1755248210.139] [22:24] [DMG] ], [1755248210.139] [22:24] [DMG] [1755248210.139] [22:24] [DMG] InteractionModelRevision = 12 [1755248210.139] [22:24] [DMG] }, [1755248210.139] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1755248210.139] [22:24] [CTL] Received ArmFailSafe response errorCode=0 [1755248210.139] [22:24] [CTL] Successfully finished commissioning step 'ArmFailSafe' [1755248210.139] [22:24] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1755248210.139] [22:24] [CTL] Performing next commissioning step 'ConfigRegulatory' [1755248210.139] [22:24] [CTL] Setting Regulatory Config [1755248210.140] [22:24] [CTL] Device does not support configurable regulatory location [1755248210.140] [22:24] [DMG] ICR moving to [AddingComm] [1755248210.140] [22:24] [DMG] ICR moving to [AddedComma] [1755248210.140] [22:24] [EM] <<< [E:19056i S:41856 M:248780863] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:70) [1755248210.142] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248210.142] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248210.222] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248210.270] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.271] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.271] [22:24] [EM] >>> [E:19056i S:41856 M:101708478] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1755248210.271] [22:24] [EM] Found matching exchange: 19056i, Delegate: 0xffff9c008378 [1755248210.271] [22:24] [DMG] ICR moving to [ResponseRe] [1755248210.271] [22:24] [DMG] InvokeResponseMessage = [1755248210.271] [22:24] [DMG] { [1755248210.271] [22:24] [DMG] suppressResponse = false, [1755248210.271] [22:24] [DMG] InvokeResponseIBs = [1755248210.272] [22:24] [DMG] [ [1755248210.272] [22:24] [DMG] InvokeResponseIB = [1755248210.272] [22:24] [DMG] { [1755248210.272] [22:24] [DMG] CommandDataIB = [1755248210.272] [22:24] [DMG] { [1755248210.272] [22:24] [DMG] CommandPathIB = [1755248210.272] [22:24] [DMG] { [1755248210.272] [22:24] [DMG] EndpointId = 0x0, [1755248210.272] [22:24] [DMG] ClusterId = 0x30, [1755248210.272] [22:24] [DMG] CommandId = 0x3, [1755248210.272] [22:24] [DMG] }, [1755248210.272] [22:24] [DMG] [1755248210.272] [22:24] [DMG] CommandFields = [1755248210.272] [22:24] [DMG] { [1755248210.273] [22:24] [DMG] 0x0 = 0 (unsigned), [1755248210.273] [22:24] [DMG] 0x1 = "" (0 chars), [1755248210.273] [22:24] [DMG] }, [1755248210.273] [22:24] [DMG] }, [1755248210.273] [22:24] [DMG] [1755248210.273] [22:24] [DMG] }, [1755248210.273] [22:24] [DMG] [1755248210.273] [22:24] [DMG] ], [1755248210.273] [22:24] [DMG] [1755248210.273] [22:24] [DMG] InteractionModelRevision = 12 [1755248210.273] [22:24] [DMG] }, [1755248210.273] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1755248210.273] [22:24] [CTL] Received SetRegulatoryConfig response errorCode=0 [1755248210.273] [22:24] [CTL] Successfully finished commissioning step 'ConfigRegulatory' [1755248210.273] [22:24] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'ConfigureTCAcknowledgments' [1755248210.273] [22:24] [CTL] Performing next commissioning step 'ConfigureTCAcknowledgments' [1755248210.274] [22:24] [CTL] Setting Terms and Conditions [1755248210.274] [22:24] [CTL] Setting Terms and Conditions: Skipped [1755248210.274] [22:24] [CTL] Successfully finished commissioning step 'ConfigureTCAcknowledgments' [1755248210.274] [22:24] [CTL] Commissioning stage next step: 'ConfigureTCAcknowledgments' -> 'ConfigureUTCTime' [1755248210.274] [22:24] [CTL] Performing next commissioning step 'ConfigureUTCTime' [1755248210.274] [22:24] [DMG] ICR moving to [AddingComm] [1755248210.274] [22:24] [DMG] ICR moving to [AddedComma] [1755248210.274] [22:24] [EM] <<< [E:19057i S:41856 M:248780864] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:72) [1755248210.275] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248210.275] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248210.403] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248210.450] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.450] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.450] [22:24] [EM] >>> [E:19057i S:41856 M:101708479] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:63) [1755248210.450] [22:24] [EM] Found matching exchange: 19057i, Delegate: 0xffff9c0081c8 [1755248210.451] [22:24] [DMG] ICR moving to [ResponseRe] [1755248210.451] [22:24] [DMG] InvokeResponseMessage = [1755248210.451] [22:24] [DMG] { [1755248210.451] [22:24] [DMG] suppressResponse = false, [1755248210.451] [22:24] [DMG] InvokeResponseIBs = [1755248210.451] [22:24] [DMG] [ [1755248210.451] [22:24] [DMG] InvokeResponseIB = [1755248210.451] [22:24] [DMG] { [1755248210.451] [22:24] [DMG] CommandStatusIB = [1755248210.451] [22:24] [DMG] { [1755248210.451] [22:24] [DMG] CommandPathIB = [1755248210.451] [22:24] [DMG] { [1755248210.451] [22:24] [DMG] EndpointId = 0x0, [1755248210.451] [22:24] [DMG] ClusterId = 0x38, [1755248210.451] [22:24] [DMG] CommandId = 0x0, [1755248210.452] [22:24] [DMG] }, [1755248210.452] [22:24] [DMG] [1755248210.452] [22:24] [DMG] StatusIB = [1755248210.452] [22:24] [DMG] { [1755248210.452] [22:24] [DMG] status = 0x00 (SUCCESS), [1755248210.452] [22:24] [DMG] }, [1755248210.452] [22:24] [DMG] [1755248210.452] [22:24] [DMG] }, [1755248210.452] [22:24] [DMG] [1755248210.452] [22:24] [DMG] }, [1755248210.452] [22:24] [DMG] [1755248210.452] [22:24] [DMG] ], [1755248210.453] [22:24] [DMG] [1755248210.453] [22:24] [DMG] InteractionModelRevision = 12 [1755248210.453] [22:24] [DMG] }, [1755248210.453] [22:24] [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_0038 Command=0x0000_0000 Status=0x0 [1755248210.453] [22:24] [CTL] Successfully finished commissioning step 'ConfigureUTCTime' [1755248210.453] [22:24] [CTL] Commissioning stage next step: 'ConfigureUTCTime' -> 'SendPAICertificateRequest' [1755248210.454] [22:24] [CTL] Performing next commissioning step 'SendPAICertificateRequest' [1755248210.454] [22:24] [CTL] Sending request for PAI certificate [1755248210.454] [22:24] [CTL] Sending Certificate Chain request to 0xffff9c00b7d0 device [1755248210.454] [22:24] [DMG] ICR moving to [AddingComm] [1755248210.454] [22:24] [DMG] ICR moving to [AddedComma] [1755248210.454] [22:24] [EM] <<< [E:19058i S:41856 M:248780865] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1755248210.455] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248210.455] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248210.538] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248210.631] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.631] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.766] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.766] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.853] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248210.854] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248210.854] [22:24] [EM] >>> [E:19058i S:41856 M:101708480] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:511) [1755248210.854] [22:24] [EM] Found matching exchange: 19058i, Delegate: 0xffff9c008378 [1755248210.854] [22:24] [DMG] ICR moving to [ResponseRe] [1755248210.854] [22:24] [DMG] InvokeResponseMessage = [1755248210.854] [22:24] [DMG] { [1755248210.854] [22:24] [DMG] suppressResponse = false, [1755248210.854] [22:24] [DMG] InvokeResponseIBs = [1755248210.854] [22:24] [DMG] [ [1755248210.854] [22:24] [DMG] InvokeResponseIB = [1755248210.855] [22:24] [DMG] { [1755248210.855] [22:24] [DMG] CommandDataIB = [1755248210.855] [22:24] [DMG] { [1755248210.855] [22:24] [DMG] CommandPathIB = [1755248210.855] [22:24] [DMG] { [1755248210.855] [22:24] [DMG] EndpointId = 0x0, [1755248210.855] [22:24] [DMG] ClusterId = 0x3e, [1755248210.855] [22:24] [DMG] CommandId = 0x3, [1755248210.855] [22:24] [DMG] }, [1755248210.855] [22:24] [DMG] [1755248210.855] [22:24] [DMG] CommandFields = [1755248210.855] [22:24] [DMG] { [1755248210.855] [22:24] [DMG] 0x0 = [ [1755248210.855] [22:24] [DMG] 0x30, 0x82, 0x01, 0xbb, 0x30, 0x82, 0x01, 0x62, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x35, 0xc8, 0xf7, 0x8c, 0xf9, 0xee, 0x08, 0x22, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x2f, 0x31, 0x17, 0x30, 0x15, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0e, 0x43, 0x68, 0x61, 0x6d, 0x70, 0x4f, 0x6e, 0x20, 0x50, 0x41, 0x41, 0x20, 0x43, 0x4e, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x33, 0x44, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x2f, 0x31, 0x17, 0x30, 0x15, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0e, 0x43, 0x68, 0x61, 0x6d, 0x70, 0x4f, 0x6e, 0x20, 0x50, 0x41, 0x49, 0x20, 0x43, 0x4e, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x33, 0x44, 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, 0x34, 0xc2, 0x12, 0xad, 0x84, 0xb5, 0x89, 0x2b, 0x65, 0xa3, 0x82, 0xc1, 0x02, 0x19, 0xc2, 0x21, 0xfa, 0xa3, 0x1e, 0xb5, 0x7a, 0x1b, 0x36, 0xb7, 0x4d, 0x06, 0xdb, 0x5e, 0xfc, 0xc7, 0x47, 0xad, 0xfc, 0xff, 0x5f, 0xb3, 0x1d, 0xc8, 0x8e, 0x8d, 0xdf, 0x35, 0x81, 0x9f, 0x46, 0xde, 0x10, 0xc1, 0xda, 0x65, 0xfe, 0xda, 0x6f, 0x63, 0xe7, 0x64, 0x10, 0x26, 0x68, 0x82, 0x15, 0x8d, 0x88, 0x00, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x1d, 0x13, 0x01, 0x01, 0xff, 0x04, 0x08, 0x30, 0x06, 0x01, 0x01, 0xff, 0x02, 0x01, 0x00, 0x30, 0x [1755248210.856] [22:24] [DMG] ] (447 bytes) [1755248210.856] [22:24] [DMG] }, [1755248210.856] [22:24] [DMG] }, [1755248210.856] [22:24] [DMG] [1755248210.856] [22:24] [DMG] }, [1755248210.856] [22:24] [DMG] [1755248210.856] [22:24] [DMG] ], [1755248210.856] [22:24] [DMG] [1755248210.856] [22:24] [DMG] InteractionModelRevision = 12 [1755248210.856] [22:24] [DMG] }, [1755248210.856] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1755248210.856] [22:24] [CTL] Received certificate chain from the device [1755248210.856] [22:24] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest' [1755248210.856] [22:24] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1755248210.857] [22:24] [CTL] Performing next commissioning step 'SendDACCertificateRequest' [1755248210.857] [22:24] [CTL] Sending request for DAC certificate [1755248210.857] [22:24] [CTL] Sending Certificate Chain request to 0xffff9c00b7d0 device [1755248210.857] [22:24] [DMG] ICR moving to [AddingComm] [1755248210.857] [22:24] [DMG] ICR moving to [AddedComma] [1755248210.857] [22:24] [EM] <<< [E:19059i S:41856 M:248780866] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1755248210.859] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248210.859] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248210.943] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248211.082] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.082] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.217] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.217] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.350] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.350] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.350] [22:24] [EM] >>> [E:19059i S:41856 M:101708481] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:525) [1755248211.351] [22:24] [EM] Found matching exchange: 19059i, Delegate: 0xffff9c0081c8 [1755248211.351] [22:24] [DMG] ICR moving to [ResponseRe] [1755248211.351] [22:24] [DMG] InvokeResponseMessage = [1755248211.351] [22:24] [DMG] { [1755248211.351] [22:24] [DMG] suppressResponse = false, [1755248211.351] [22:24] [DMG] InvokeResponseIBs = [1755248211.351] [22:24] [DMG] [ [1755248211.351] [22:24] [DMG] InvokeResponseIB = [1755248211.351] [22:24] [DMG] { [1755248211.351] [22:24] [DMG] CommandDataIB = [1755248211.351] [22:24] [DMG] { [1755248211.351] [22:24] [DMG] CommandPathIB = [1755248211.351] [22:24] [DMG] { [1755248211.352] [22:24] [DMG] EndpointId = 0x0, [1755248211.352] [22:24] [DMG] ClusterId = 0x3e, [1755248211.352] [22:24] [DMG] CommandId = 0x3, [1755248211.352] [22:24] [DMG] }, [1755248211.352] [22:24] [DMG] [1755248211.352] [22:24] [DMG] CommandFields = [1755248211.352] [22:24] [DMG] { [1755248211.352] [22:24] [DMG] 0x0 = [ [1755248211.352] [22:24] [DMG] 0x30, 0x82, 0x01, 0xc9, 0x30, 0x82, 0x01, 0x70, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x7f, 0x61, 0x00, 0xc7, 0xbf, 0x83, 0x99, 0x80, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x2f, 0x31, 0x17, 0x30, 0x15, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0e, 0x43, 0x68, 0x61, 0x6d, 0x70, 0x4f, 0x6e, 0x20, 0x50, 0x41, 0x49, 0x20, 0x43, 0x4e, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x33, 0x44, 0x30, 0x1e, 0x17, 0x0d, 0x32, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x17, 0x0d, 0x34, 0x31, 0x30, 0x36, 0x32, 0x38, 0x31, 0x34, 0x32, 0x33, 0x34, 0x32, 0x5a, 0x30, 0x45, 0x31, 0x17, 0x30, 0x15, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0e, 0x43, 0x68, 0x61, 0x6d, 0x70, 0x4f, 0x6e, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x34, 0x33, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x33, 0x30, 0x30, 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, 0x54, 0x7b, 0x76, 0xac, 0xf7, 0x97, 0xc0, 0x51, 0x56, 0x71, 0x89, 0x3d, 0x09, 0x79, 0x47, 0xdf, 0x03, 0x43, 0xbb, 0x64, 0x79, 0x73, 0xf0, 0x22, 0x1d, 0x97, 0xec, 0x7d, 0x4a, 0xa2, 0x9b, 0xba, 0x02, 0x15, 0xab, 0x76, 0x26, 0xad, 0xb7, 0x29, 0x5a, 0x86, 0xb2, 0x87, 0xd7, 0x36, 0xc6, 0x2e, 0x3a, 0xf5, 0x32, 0x54, 0x34, 0xff, 0x03, 0x67, 0x61, 0x03, 0x54, 0xae, 0x27, 0x72, 0xb0, 0xc5, 0xa3, 0x60, 0x30, 0x5e, 0x30, 0x [1755248211.353] [22:24] [DMG] ] (461 bytes) [1755248211.353] [22:24] [DMG] }, [1755248211.353] [22:24] [DMG] }, [1755248211.353] [22:24] [DMG] [1755248211.353] [22:24] [DMG] }, [1755248211.353] [22:24] [DMG] [1755248211.353] [22:24] [DMG] ], [1755248211.353] [22:24] [DMG] [1755248211.353] [22:24] [DMG] InteractionModelRevision = 12 [1755248211.353] [22:24] [DMG] }, [1755248211.353] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1755248211.354] [22:24] [CTL] Received certificate chain from the device [1755248211.354] [22:24] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest' [1755248211.354] [22:24] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1755248211.354] [22:24] [CTL] Performing next commissioning step 'SendAttestationRequest' [1755248211.354] [22:24] [CTL] Sending Attestation Request to the device. [1755248211.354] [22:24] [CTL] Sending Attestation request to 0xffff9c00b7d0 device [1755248211.354] [22:24] [DMG] ICR moving to [AddingComm] [1755248211.354] [22:24] [DMG] ICR moving to [AddedComma] [1755248211.354] [22:24] [EM] <<< [E:19060i S:41856 M:248780867] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1755248211.355] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248211.355] [22:24] [CTL] Sent Attestation request, waiting for the Attestation Information [1755248211.355] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248211.438] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248211.532] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.532] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.666] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.666] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.801] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.801] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.801] [22:24] [EM] >>> [E:19060i S:41856 M:101708482] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:716) [1755248211.801] [22:24] [EM] Found matching exchange: 19060i, Delegate: 0xffff9c008378 [1755248211.801] [22:24] [DMG] ICR moving to [ResponseRe] [1755248211.801] [22:24] [DMG] InvokeResponseMessage = [1755248211.801] [22:24] [DMG] { [1755248211.801] [22:24] [DMG] suppressResponse = false, [1755248211.801] [22:24] [DMG] InvokeResponseIBs = [1755248211.802] [22:24] [DMG] [ [1755248211.802] [22:24] [DMG] InvokeResponseIB = [1755248211.802] [22:24] [DMG] { [1755248211.802] [22:24] [DMG] CommandDataIB = [1755248211.802] [22:24] [DMG] { [1755248211.802] [22:24] [DMG] CommandPathIB = [1755248211.802] [22:24] [DMG] { [1755248211.802] [22:24] [DMG] EndpointId = 0x0, [1755248211.802] [22:24] [DMG] ClusterId = 0x3e, [1755248211.803] [22:24] [DMG] CommandId = 0x1, [1755248211.803] [22:24] [DMG] }, [1755248211.803] [22:24] [DMG] [1755248211.803] [22:24] [DMG] CommandFields = [1755248211.803] [22:24] [DMG] { [1755248211.803] [22:24] [DMG] 0x0 = [ [1755248211.803] [22:24] [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 [1755248211.803] [22:24] [DMG] ] (585 bytes) [1755248211.803] [22:24] [DMG] 0x1 = [ [1755248211.803] [22:24] [DMG] 0xd3, 0x05, 0x5b, 0x61, 0xfa, 0x0f, 0xa6, 0x1b, 0xf2, 0xc7, 0x2b, 0x23, 0xe1, 0x17, 0xd1, 0x36, 0x28, 0x71, 0x99, 0x9d, 0xde, 0x00, 0x7a, 0x7b, 0x4b, 0xee, 0x16, 0xcf, 0x88, 0x93, 0xf1, 0x92, 0x61, 0xaf, 0x11, 0x1f, 0x71, 0x39, 0x8a, 0x67, 0x1f, 0xb5, 0xba, 0xb0, 0x7c, 0xca, 0xdc, 0x9a, 0x5d, 0x06, 0x09, 0xeb, 0x1a, 0xec, 0x53, 0x46, 0x23, 0xaf, 0x59, 0x70, 0x8e, 0xc9, 0x2b, 0x57, [1755248211.803] [22:24] [DMG] ] (64 bytes) [1755248211.803] [22:24] [DMG] }, [1755248211.803] [22:24] [DMG] }, [1755248211.803] [22:24] [DMG] [1755248211.804] [22:24] [DMG] }, [1755248211.804] [22:24] [DMG] [1755248211.804] [22:24] [DMG] ], [1755248211.804] [22:24] [DMG] [1755248211.804] [22:24] [DMG] InteractionModelRevision = 12 [1755248211.804] [22:24] [DMG] }, [1755248211.804] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1755248211.804] [22:24] [CTL] Received Attestation Information from the device [1755248211.804] [22:24] [CTL] Successfully finished commissioning step 'SendAttestationRequest' [1755248211.804] [22:24] [CTL] AutoCommissioner setting attestationElements buffer size 585/585 [1755248211.804] [22:24] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1755248211.804] [22:24] [CTL] Performing next commissioning step 'AttestationVerification' [1755248211.804] [22:24] [CTL] Verifying attestation [1755248211.817] [22:24] [CTL] Error on commissioning step 'AttestationVerification': 'src/controller/CHIPDeviceController.cpp:1283: CHIP Error 0x000000AC: Internal error' [1755248211.817] [22:24] [CTL] Failed verifying attestation information. Now checking DAC chain revoked status. [1755248211.817] [22:24] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck' [1755248211.817] [22:24] [CTL] Performing next commissioning step 'AttestationRevocationCheck' with completion status = 'src/controller/CHIPDeviceController.cpp:1283: CHIP Error 0x000000AC: Internal error' [1755248211.817] [22:24] [CTL] Verifying device's DAC chain revocation status [1755248211.817] [22:24] [CTL] Failed in verifying 'Attestation Information' command received from the device: err 500. Look at AttestationVerificationResult enum to understand the errors [1755248211.817] [22:24] [CTL] Error on commissioning step 'AttestationRevocationCheck': 'src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error' [1755248211.817] [22:24] [CTL] Going from commissioning step 'AttestationRevocationCheck' with lastErr = 'src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error' -> 'Cleanup' [1755248211.818] [22:24] [CTL] Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error' [1755248211.818] [22:24] [CTL] Disarming failsafe on device 0xffff9c00b7d0 [1755248211.818] [22:24] [DMG] ICR moving to [AddingComm] [1755248211.818] [22:24] [DMG] ICR moving to [AddedComma] [1755248211.818] [22:24] [EM] <<< [E:19061i S:41856 M:248780868] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1755248211.819] [22:24] [DMG] ICR moving to [AwaitingRe] [1755248211.819] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248211.888] [22:24] [DL] HandlePlatformSpecificBLEEvent 16389 [1755248211.935] [22:23] [DL] Indication received, conn = 0xffffa403bf60 [1755248211.935] [22:24] [DL] HandlePlatformSpecificBLEEvent 16391 [1755248211.936] [22:24] [EM] >>> [E:19061i S:41856 M:101708483] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1755248211.936] [22:24] [EM] Found matching exchange: 19061i, Delegate: 0xffff9c0081c8 [1755248211.936] [22:24] [DMG] ICR moving to [ResponseRe] [1755248211.936] [22:24] [DMG] InvokeResponseMessage = [1755248211.936] [22:24] [DMG] { [1755248211.936] [22:24] [DMG] suppressResponse = false, [1755248211.936] [22:24] [DMG] InvokeResponseIBs = [1755248211.937] [22:24] [DMG] [ [1755248211.937] [22:24] [DMG] InvokeResponseIB = [1755248211.937] [22:24] [DMG] { [1755248211.937] [22:24] [DMG] CommandDataIB = [1755248211.937] [22:24] [DMG] { [1755248211.937] [22:24] [DMG] CommandPathIB = [1755248211.937] [22:24] [DMG] { [1755248211.937] [22:24] [DMG] EndpointId = 0x0, [1755248211.938] [22:24] [DMG] ClusterId = 0x30, [1755248211.938] [22:24] [DMG] CommandId = 0x1, [1755248211.938] [22:24] [DMG] }, [1755248211.938] [22:24] [DMG] [1755248211.938] [22:24] [DMG] CommandFields = [1755248211.938] [22:24] [DMG] { [1755248211.938] [22:24] [DMG] 0x0 = 0 (unsigned), [1755248211.938] [22:24] [DMG] 0x1 = "" (0 chars), [1755248211.938] [22:24] [DMG] }, [1755248211.938] [22:24] [DMG] }, [1755248211.938] [22:24] [DMG] [1755248211.938] [22:24] [DMG] }, [1755248211.938] [22:24] [DMG] [1755248211.938] [22:24] [DMG] ], [1755248211.938] [22:24] [DMG] [1755248211.938] [22:24] [DMG] InteractionModelRevision = 12 [1755248211.938] [22:24] [DMG] }, [1755248211.939] [22:24] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1755248211.939] [22:24] [CTL] Failsafe disarmed [1755248211.939] [22:24] [CTL] Successfully finished commissioning step 'Cleanup' [1755248211.939] [22:24] [DIS] Closing all BLE connections [1755248211.939] [22:24] [IN] Clearing BLE pending packets. [1755248211.940] [22:24] [BLE] Auto-closing end point's BLE connection. [1755248211.940] [22:24] [DL] Closing BLE GATT connection (con 0xffffa403bf60) [1755248211.940] [22:23] [DL] Close BLE connection: peer=D4:7F:13:69:82:B9 [1755248212.250] [22:24] [IN] SecureSession[0xffff9c00b440]: MarkForEviction Type:1 LSID:41856 [1755248212.250] [22:24] [SC] SecureSession[0xffff9c00b440, LSID:41856]: State change 'kActive' --> 'kPendingEviction' [1755248212.250] [22:24] [CTL] Commissioning complete for node ID 0x0000000000011820: src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error [1755248212.250] [22:24] [TOO] Device commissioning Failure: src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error [1755248212.250] [22:23] [DL] BLE connection closed: conn=0xffffa403bf60 [1755248212.250] [22:24] [DMG] ICR moving to [AwaitingDe] [1755248212.251] [22:24] [IN] SecureSession[0xffff9c00b440]: Released - Type:1 LSID:41856 [1755248212.252] [22:24] [DL] Long dispatch time: 316 ms, for event type 16391 [1755248212.252] [22:24] [DL] HandlePlatformSpecificBLEEvent 16390 [1755248212.252] [22:24] [BLE] No endpoint for unsubscribe complete [1755248212.252] [22:24] [BLE] No endpoint for connection error [1755248212.253] [22:22] [CTL] Shutting down the commissioner [1755248212.253] [22:22] [CTL] Shutting down the controller [1755248212.253] [22:22] [IN] Expiring all sessions for fabric 0x1!! [1755248212.253] [22:22] [FP] Forgetting fabric 0x1 [1755248212.253] [22:22] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1755248212.253] [22:22] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1755248212.253] [22:22] [TS] Reverted Last Known Good Time to previous value [1755248212.254] [22:22] [CTL] Shutting down the commissioner [1755248212.254] [22:22] [CTL] Shutting down the controller [1755248212.254] [22:22] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1755248212.255] [22:22] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1755248212.255] [22:22] [FP] Shutting down FabricTable [1755248212.256] [22:22] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1755248212.256] [22:22] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1755248212.256] [22:22] [TS] Reverted Last Known Good Time to previous value [1755248212.272] [22:22] [DL] Wrote settings to /tmp/chip_counters.ini [1755248212.272] [22:22] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1755248212.272] [22:22] [DL] Inet Layer shutdown [1755248212.272] [22:22] [DL] BLE Layer shutdown [1755248212.274] [22:22] [DL] System Layer shutdown [1755248212.274] [22:22] [TOO] Run command failure: src/controller/CHIPDeviceController.cpp:1332: CHIP Error 0x000000AC: Internal error root@ubuntu:~#