=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2025.09.18 16:09:13 =~=~=~=~=~=~=~=~=~=~=~= ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ sudo ./chip-tool pairing ble-thread 1 hex:0e08000000000001000035060004001fffe00708fd46843da9be2ae704103ab1879f9a437fb5f3e7239296bd23240c0402a0f7f8000300001101025b1702085b17dead5b17beef030435623137051000112233445566778899aabbccddeeff 20202026 3590 --paa-trust-store-path /var/paa-root-certs/ sudo ./chip-tool pairing ble-thread 1 hex:0e08000000000001000035060004001fffe00708fd46843da9be2ae704103ab1879f9a437fb5f3e7239296bd23240c0402a0f7f8000300001101025b1702085b17dead5b17beef030435623137051000112233445566778899aabbccddeeff 20202026 3590 --paa-trust-store-path /var/paa-root-certs/ [1758182999.451] [25004:25004] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1758182999.462] [25004:25004] [DL] Wrote settings to /tmp/chip_tool_kvs [1758182999.463] [25004:25004] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1758182999.477] [25004:25004] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1758182999.489] [25004:25004] [DL] Wrote settings to /tmp/chip_factory.ini [1758182999.490] [25004:25004] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1758182999.493] [25004:25004] [DL] Wrote settings to /tmp/chip_config.ini [1758182999.494] [25004:25004] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1758182999.499] [25004:25004] [DL] Wrote settings to /tmp/chip_counters.ini [1758182999.508] [25004:25004] [DL] Wrote settings to /tmp/chip_factory.ini [1758182999.508] [25004:25004] [DL] NVS set: chip-factory/unique-id = "B56D92D5C43C2732" [1758182999.515] [25004:25004] [DL] Wrote settings to /tmp/chip_factory.ini [1758182999.515] [25004:25004] [DL] NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1758182999.521] [25004:25004] [DL] Wrote settings to /tmp/chip_factory.ini [1758182999.521] [25004:25004] [DL] NVS set: chip-factory/product-id = 32769 (0x8001) [1758182999.529] [25004:25004] [DL] Wrote settings to /tmp/chip_counters.ini [1758182999.529] [25004:25004] [DL] NVS set: chip-counters/reboot-count = 1 (0x1) [1758182999.537] [25004:25004] [DL] Wrote settings to /tmp/chip_counters.ini [1758182999.537] [25004:25004] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1758182999.546] [25004:25004] [DL] Wrote settings to /tmp/chip_counters.ini [1758182999.546] [25004:25004] [DL] NVS set: chip-counters/boot-reason = 0 (0x0) [1758182999.553] [25004:25004] [DL] Wrote settings to /tmp/chip_config.ini [1758182999.553] [25004:25004] [DL] NVS set: chip-config/regulatory-location = 0 (0x0) [1758182999.559] [25004:25004] [DL] Wrote settings to /tmp/chip_config.ini [1758182999.559] [25004:25004] [DL] NVS set: chip-config/location-capability = 2 (0x2) [1758182999.561] [25004:25004] [DL] Got Ethernet interface: eth0 [1758182999.562] [25004:25004] [DL] Found the primary Ethernet interface:eth0 [1758182999.564] [25004:25004] [DL] Got WiFi interface: wlan0 [1758182999.564] [25004:25004] [DL] Failed to reset WiFi statistic counts [1758182999.564] [25004:25004] [IN] UDP::Init bind&listen port=0 [1758182999.564] [25004:25004] [IN] UDP::Init bound to port=36107 [1758182999.564] [25004:25004] [IN] BLEBase::Init - setting/overriding transport [1758182999.564] [25004:25004] [IN] TransportMgr initialized [1758182999.564] [25004:25004] [FP] Initializing FabricTable from persistent storage [1758182999.564] [25004:25004] [TS] Last Known Good Time: [unknown] [1758182999.564] [25004:25004] [TS] Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48 [1758182999.569] [25004:25004] [ZCL] Using ZAP configuration... [1758182999.576] [25004:25004] [DL] Avahi client registered [1758182999.576] [25004:25004] [IM] WARNING [1758182999.576] [25004:25004] [IM] WARNING Interaction Model Engine running in 'Checked' mode. [1758182999.576] [25004:25004] [IM] WARNING This executes BOTH ember and data-model code paths. [1758182999.576] [25004:25004] [IM] WARNING which is inefficient and consumes more flash space. [1758182999.576] [25004:25004] [IM] WARNING This should be done for testing only. [1758182999.576] [25004:25004] [IM] WARNING [1758182999.576] [25004:25004] [CTL] System State Initialized... [1758182999.933] [25004:25004] [CTL] Setting attestation nonce to random value [1758182999.933] [25004:25004] [CTL] Setting CSR nonce to random value [1758182999.933] [25004:25004] [IN] UDP::Init bind&listen port=5550 [1758182999.934] [25004:25004] [IN] UDP::Init bound to port=5550 [1758182999.934] [25004:25004] [IN] TransportMgr initialized [1758182999.935] [25004:25006] [DL] CHIP task running [1758182999.935] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 32786 [1758182999.935] [25004:25006] [CTL] Setting attestation nonce to random value [1758182999.935] [25004:25006] [CTL] Setting CSR nonce to random value [1758182999.936] [25004:25006] [CTL] Couldn't get ExampleOpCredsCAKey from storage: src/controller/ExamplePersistentStorage.cpp:109: CHIP Error 0x000000A0: Value not found in the persisted storage [1758182999.941] [25004:25006] [CTL] Couldn't get ExampleOpCredsICAKey from storage: src/controller/ExamplePersistentStorage.cpp:109: CHIP Error 0x000000A0: Value not found in the persisted storage [1758182999.942] [25004:25006] [CTL] Generating RCAC [1758182999.943] [25004:25006] [CTL] Generating ICAC [1758182999.945] [25004:25006] [CTL] Generating NOC [1758182999.946] [25004:25006] [FP] Validating NOC chain [1758182999.949] [25004:25006] [FP] NOC chain validation successful [1758182999.949] [25004:25006] [FP] Added new fabric at index: 0x1 [1758182999.949] [25004:25006] [FP] Assigned compressed fabric ID: 0x5687372BCD45D144, node ID: 0x000000000001B669 [1758182999.949] [25004:25006] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1758182999.949] [25004:25006] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1758182999.949] [25004:25006] [TS] Retaining current Last Known Good Time [1758182999.951] [25004:25006] [FP] Metadata for Fabric 0x1 persisted to storage. [1758182999.955] [25004:25006] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1758182999.958] [25004:25006] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 5687372BCD45D144) [1758182999.958] [25004:25006] [IN] UDP::Init bind&listen port=5550 [1758182999.958] [25004:25006] [IN] UDP::Init bound to port=5550 [1758182999.958] [25004:25006] [IN] TransportMgr initialized [1758183000.017] [25004:25006] [CTL] Setting thread operational dataset from parameters [1758183000.017] [25004:25006] [CTL] Setting attestation nonce to random value [1758183000.017] [25004:25006] [CTL] Setting CSR nonce to random value [1758183000.017] [25004:25006] [CTL] Commission called for node ID 0x0000000000000001 [1758183000.033] [25004:25005] [BLE] BLE removing known devices [1758183000.035] [25004:25005] [BLE] BLE initiating scan [1758183000.038] [25004:25006] [BLE] ChipDeviceScanner has started scanning! [1758183000.059] [25004:25005] [BLE] Device F9:16:16:E8:23:03 does not look like a CHIP device. [1758183000.064] [25004:25005] [BLE] Device F3:80:02:B3:C9:FD does not look like a CHIP device. [1758183000.074] [25004:25005] [BLE] New device scanned: CC:CB:F9:A7:68:6A [1758183000.074] [25004:25005] [BLE] Device discriminator match. Attempting to connect. [1758183000.077] [25004:25005] [BLE] ChipDeviceScanner has stopped scanning! [1758183000.217] [25004:25005] [DL] ConnectDevice complete [1758183000.217] [25004:25005] [BLE] New device connected: CC:CB:F9:A7:68:6A [1758183001.472] [25004:25005] [DL] CHIP service found [1758183001.472] [25004:25005] [DL] Valid C1 characteristic found [1758183001.472] [25004:25005] [DL] Valid C2 characteristic found [1758183001.472] [25004:25005] [DL] New BLE connection: conn=0xffff880261a0 device=CC:CB:F9:A7:68:6A path=/org/bluez/hci0/dev_CC_CB_F9_A7_68_6A [1758183001.472] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16387 [1758183001.472] [25004:25006] [DIS] Closing all BLE connections [1758183001.472] [25004:25006] [IN] BleConnectionComplete: endPoint 0xaaaaccb2cb38 [1758183001.473] [25004:25006] [IN] SecureSession[0xffff80014e90]: Allocated Type:1 LSID:50268 [1758183001.473] [25004:25006] [SC] Assigned local session key ID 50268 [1758183001.473] [25004:25006] [EM] <<< [E:60635i S:0 M:79882750] (U) Msg TX from 750FDDB5D212F640 to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) (B:98) [1758183001.473] [25004:25006] [IN] Message appended to BLE send queue [1758183001.473] [25004:25006] [SC] Sent PBKDF param request [II:500ms AI:300ms AT:4000ms) [1758183001.825] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183001.915] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16390 [1758183001.915] [25004:25006] [BLE] subscribe complete, ep = 0xaaaaccb2cb38 [1758183001.915] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183001.915] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183001.916] [25004:25006] [BLE] peripheral chose BTP version 4; central expected between 4 and 4 [1758183001.916] [25004:25006] [BLE] using BTP fragment sizes rx 244 / tx 244. [1758183001.916] [25004:25006] [BLE] local and remote recv window size = 5 [1758183001.916] [25004:25006] [IN] BLE EndPoint 0xaaaaccb2cb38 Connection Complete [1758183002.050] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.096] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.097] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.097] [25004:25006] [EM] >>> [E:60635i S:0 M:54858967] (U) Msg RX from 0:0000000000000000 [0000] to 750FDDB5D212F640 --- Type 0000:21 (SecureChannel:PBKDFParamResponse) (B:154) [1758183002.097] [25004:25006] [EM] Found matching exchange: 60635i, Delegate: 0xffff800146f8 [1758183002.097] [25004:25006] [SC] Received PBKDF param response [1758183002.097] [25004:25006] [SC] Peer assigned session ID 37797 [1758183002.097] [25004:25006] [SC] Found MRP parameters in the message [1758183002.110] [25004:25006] [EM] <<< [E:60635i S:0 M:79882751] (U) Msg TX from 750FDDB5D212F640 to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) (B:92) [1758183002.111] [25004:25006] [SC] Sent spake2p msg1 [1758183002.184] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.231] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.231] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.232] [25004:25006] [EM] >>> [E:60635i S:0 M:54858968] (U) Msg RX from 0:0000000000000000 [0000] to 750FDDB5D212F640 --- Type 0000:23 (SecureChannel:PASE_Pake2) (B:127) [1758183002.232] [25004:25006] [EM] Found matching exchange: 60635i, Delegate: 0xffff800146f8 [1758183002.232] [25004:25006] [SC] Received spake2p msg2 [1758183002.236] [25004:25006] [EM] <<< [E:60635i S:0 M:79882752] (U) Msg TX from 750FDDB5D212F640 to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) (B:59) [1758183002.236] [25004:25006] [SC] Sent spake2p msg3 [1758183002.320] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.321] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.322] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.322] [25004:25006] [EM] >>> [E:60635i S:0 M:54858969] (U) Msg RX from 0:0000000000000000 [0000] to 750FDDB5D212F640 --- Type 0000:40 (SecureChannel:StatusReport) (B:30) [1758183002.322] [25004:25006] [EM] Found matching exchange: 60635i, Delegate: 0xffff800146f8 [1758183002.323] [25004:25006] [SC] SecureSession[0xffff80014e90, LSID:50268]: State change 'kEstablishing' --> 'kActive' [1758183002.323] [25004:25006] [IN] SecureSession[0xffff80014e90]: Activated - Type:1 LSID:50268 [1758183002.323] [25004:25006] [IN] New secure session activated for device , LSID:50268 PSID:37797! [1758183002.323] [25004:25006] [CTL] Remote device completed SPAKE2+ handshake [1758183002.323] [25004:25006] [TOO] Pairing Success [1758183002.323] [25004:25006] [TOO] PASE establishment successful [1758183002.323] [25004:25006] [CTL] Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1758183002.323] [25004:25006] [CTL] Performing next commissioning step 'ReadCommissioningInfo' [1758183002.323] [25004:25006] [CTL] Sending read request for commissioning information [1758183002.323] [25004:25006] [DMG] SendReadRequest ReadClient[0xffff8000ff80]: Sending Read Request [1758183002.323] [25004:25006] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1758183002.324] [25004:25006] [EM] <<< [E:60636i S:50268 M:176269975] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:131) [1758183002.325] [25004:25006] [DMG] MoveToState ReadClient[0xffff8000ff80]: Moving to [AwaitingIn] [1758183002.325] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 32792 [1758183002.454] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.545] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.545] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.637] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.637] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.637] [25004:25006] [EM] >>> [E:60636i S:50268 M:112433855] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:258) [1758183002.637] [25004:25006] [EM] Found matching exchange: 60636i, Delegate: 0xffff8000ff90 [1758183002.637] [25004:25006] [DMG] ReportDataMessage = [1758183002.638] [25004:25006] [DMG] { [1758183002.638] [25004:25006] [DMG] AttributeReportIBs = [1758183002.638] [25004:25006] [DMG] [ [1758183002.638] [25004:25006] [DMG] AttributeReportIB = [1758183002.638] [25004:25006] [DMG] { [1758183002.638] [25004:25006] [DMG] AttributeDataIB = [1758183002.638] [25004:25006] [DMG] { [1758183002.638] [25004:25006] [DMG] DataVersion = 0xa3537a57, [1758183002.638] [25004:25006] [DMG] AttributePathIB = [1758183002.638] [25004:25006] [DMG] { [1758183002.639] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.639] [25004:25006] [DMG] Cluster = 0x31, [1758183002.639] [25004:25006] [DMG] Attribute = 0x0000_0003, [1758183002.639] [25004:25006] [DMG] } [1758183002.639] [25004:25006] [DMG] [1758183002.639] [25004:25006] [DMG] Data = 20 (unsigned), [1758183002.639] [25004:25006] [DMG] }, [1758183002.639] [25004:25006] [DMG] [1758183002.640] [25004:25006] [DMG] }, [1758183002.640] [25004:25006] [DMG] [1758183002.640] [25004:25006] [DMG] AttributeReportIB = [1758183002.640] [25004:25006] [DMG] { [1758183002.640] [25004:25006] [DMG] AttributeDataIB = [1758183002.640] [25004:25006] [DMG] { [1758183002.640] [25004:25006] [DMG] DataVersion = 0x7c862532, [1758183002.640] [25004:25006] [DMG] AttributePathIB = [1758183002.640] [25004:25006] [DMG] { [1758183002.641] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.641] [25004:25006] [DMG] Cluster = 0x28, [1758183002.641] [25004:25006] [DMG] Attribute = 0x0000_0004, [1758183002.641] [25004:25006] [DMG] } [1758183002.641] [25004:25006] [DMG] [1758183002.641] [25004:25006] [DMG] Data = 4112 (unsigned), [1758183002.641] [25004:25006] [DMG] }, [1758183002.641] [25004:25006] [DMG] [1758183002.642] [25004:25006] [DMG] }, [1758183002.642] [25004:25006] [DMG] [1758183002.642] [25004:25006] [DMG] AttributeReportIB = [1758183002.642] [25004:25006] [DMG] { [1758183002.642] [25004:25006] [DMG] AttributeDataIB = [1758183002.642] [25004:25006] [DMG] { [1758183002.642] [25004:25006] [DMG] DataVersion = 0x7c862532, [1758183002.642] [25004:25006] [DMG] AttributePathIB = [1758183002.642] [25004:25006] [DMG] { [1758183002.643] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.643] [25004:25006] [DMG] Cluster = 0x28, [1758183002.643] [25004:25006] [DMG] Attribute = 0x0000_0002, [1758183002.643] [25004:25006] [DMG] } [1758183002.643] [25004:25006] [DMG] [1758183002.643] [25004:25006] [DMG] Data = 5615 (unsigned), [1758183002.643] [25004:25006] [DMG] }, [1758183002.643] [25004:25006] [DMG] [1758183002.643] [25004:25006] [DMG] }, [1758183002.644] [25004:25006] [DMG] [1758183002.644] [25004:25006] [DMG] AttributeReportIB = [1758183002.644] [25004:25006] [DMG] { [1758183002.644] [25004:25006] [DMG] AttributeDataIB = [1758183002.644] [25004:25006] [DMG] { [1758183002.644] [25004:25006] [DMG] DataVersion = 0xfbdaf629, [1758183002.644] [25004:25006] [DMG] AttributePathIB = [1758183002.644] [25004:25006] [DMG] { [1758183002.644] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.645] [25004:25006] [DMG] Cluster = 0x30, [1758183002.645] [25004:25006] [DMG] Attribute = 0x0000_0003, [1758183002.645] [25004:25006] [DMG] } [1758183002.645] [25004:25006] [DMG] [1758183002.645] [25004:25006] [DMG] Data = 0 (unsigned), [1758183002.645] [25004:25006] [DMG] }, [1758183002.645] [25004:25006] [DMG] [1758183002.645] [25004:25006] [DMG] }, [1758183002.646] [25004:25006] [DMG] [1758183002.646] [25004:25006] [DMG] AttributeReportIB = [1758183002.646] [25004:25006] [DMG] { [1758183002.646] [25004:25006] [DMG] AttributeDataIB = [1758183002.646] [25004:25006] [DMG] { [1758183002.646] [25004:25006] [DMG] DataVersion = 0xfbdaf629, [1758183002.646] [25004:25006] [DMG] AttributePathIB = [1758183002.646] [25004:25006] [DMG] { [1758183002.647] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.647] [25004:25006] [DMG] Cluster = 0x30, [1758183002.647] [25004:25006] [DMG] Attribute = 0x0000_0002, [1758183002.647] [25004:25006] [DMG] } [1758183002.647] [25004:25006] [DMG] [1758183002.647] [25004:25006] [DMG] Data = 0 (unsigned), [1758183002.647] [25004:25006] [DMG] }, [1758183002.647] [25004:25006] [DMG] [1758183002.647] [25004:25006] [DMG] }, [1758183002.647] [25004:25006] [DMG] [1758183002.647] [25004:25006] [DMG] AttributeReportIB = [1758183002.647] [25004:25006] [DMG] { [1758183002.647] [25004:25006] [DMG] AttributeDataIB = [1758183002.647] [25004:25006] [DMG] { [1758183002.647] [25004:25006] [DMG] DataVersion = 0xfbdaf629, [1758183002.647] [25004:25006] [DMG] AttributePathIB = [1758183002.647] [25004:25006] [DMG] { [1758183002.647] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.647] [25004:25006] [DMG] Cluster = 0x30, [1758183002.647] [25004:25006] [DMG] Attribute = 0x0000_0001, [1758183002.647] [25004:25006] [DMG] } [1758183002.647] [25004:25006] [DMG] [1758183002.647] [25004:25006] [DMG] Data = [1758183002.647] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] 0x0 = 60 (unsigned), [1758183002.648] [25004:25006] [DMG] 0x1 = 900 (unsigned), [1758183002.648] [25004:25006] [DMG] }, [1758183002.648] [25004:25006] [DMG] }, [1758183002.648] [25004:25006] [DMG] [1758183002.648] [25004:25006] [DMG] }, [1758183002.648] [25004:25006] [DMG] [1758183002.648] [25004:25006] [DMG] AttributeReportIB = [1758183002.648] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] AttributeDataIB = [1758183002.648] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] DataVersion = 0xfbdaf629, [1758183002.648] [25004:25006] [DMG] AttributePathIB = [1758183002.648] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.648] [25004:25006] [DMG] Cluster = 0x30, [1758183002.648] [25004:25006] [DMG] Attribute = 0x0000_0000, [1758183002.648] [25004:25006] [DMG] } [1758183002.648] [25004:25006] [DMG] [1758183002.648] [25004:25006] [DMG] Data = 0 (unsigned), [1758183002.648] [25004:25006] [DMG] }, [1758183002.648] [25004:25006] [DMG] [1758183002.648] [25004:25006] [DMG] }, [1758183002.648] [25004:25006] [DMG] [1758183002.648] [25004:25006] [DMG] AttributeReportIB = [1758183002.648] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] AttributeDataIB = [1758183002.648] [25004:25006] [DMG] { [1758183002.648] [25004:25006] [DMG] DataVersion = 0xa3537a57, [1758183002.648] [25004:25006] [DMG] AttributePathIB = [1758183002.649] [25004:25006] [DMG] { [1758183002.649] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.649] [25004:25006] [DMG] Cluster = 0x31, [1758183002.649] [25004:25006] [DMG] Attribute = 0x0000_FFFC, [1758183002.649] [25004:25006] [DMG] } [1758183002.649] [25004:25006] [DMG] [1758183002.649] [25004:25006] [DMG] Data = 2 (unsigned), [1758183002.649] [25004:25006] [DMG] }, [1758183002.649] [25004:25006] [DMG] [1758183002.649] [25004:25006] [DMG] }, [1758183002.649] [25004:25006] [DMG] [1758183002.649] [25004:25006] [DMG] ], [1758183002.649] [25004:25006] [DMG] [1758183002.649] [25004:25006] [DMG] SuppressResponse = true, [1758183002.649] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183002.649] [25004:25006] [DMG] } [1758183002.650] [25004:25006] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo' [1758183002.650] [25004:25006] [CTL] Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1758183002.650] [25004:25006] [CTL] Performing next commissioning step 'ReadCommissioningInfo2' [1758183002.650] [25004:25006] [DMG] SendReadRequest ReadClient[0xffff8000ff80]: Sending Read Request [1758183002.650] [25004:25006] [DMG] 0 data version filters provided, 0 not relevant, 0 encoded, 0 skipped due to lack of space [1758183002.650] [25004:25006] [EM] <<< [E:60637i S:50268 M:176269976] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) (B:106) [1758183002.651] [25004:25006] [DMG] MoveToState ReadClient[0xffff8000ff80]: Moving to [AwaitingIn] [1758183002.769] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.817] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.817] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.818] [25004:25006] [EM] >>> [E:60637i S:50268 M:112433856] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:05 (IM:ReportData) (B:180) [1758183002.818] [25004:25006] [EM] Found matching exchange: 60637i, Delegate: 0xffff8000ff90 [1758183002.818] [25004:25006] [DMG] ReportDataMessage = [1758183002.818] [25004:25006] [DMG] { [1758183002.818] [25004:25006] [DMG] AttributeReportIBs = [1758183002.818] [25004:25006] [DMG] [ [1758183002.818] [25004:25006] [DMG] AttributeReportIB = [1758183002.818] [25004:25006] [DMG] { [1758183002.818] [25004:25006] [DMG] AttributeStatusIB = [1758183002.818] [25004:25006] [DMG] { [1758183002.818] [25004:25006] [DMG] AttributePathIB = [1758183002.819] [25004:25006] [DMG] { [1758183002.819] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.819] [25004:25006] [DMG] Cluster = 0x46, [1758183002.819] [25004:25006] [DMG] Attribute = 0x0000_0002, [1758183002.819] [25004:25006] [DMG] } [1758183002.819] [25004:25006] [DMG] [1758183002.819] [25004:25006] [DMG] StatusIB = [1758183002.819] [25004:25006] [DMG] { [1758183002.819] [25004:25006] [DMG] status = 0xc3 (UNSUPPORTED_CLUSTER), [1758183002.819] [25004:25006] [DMG] }, [1758183002.819] [25004:25006] [DMG] [1758183002.819] [25004:25006] [DMG] }, [1758183002.819] [25004:25006] [DMG] [1758183002.819] [25004:25006] [DMG] }, [1758183002.820] [25004:25006] [DMG] [1758183002.820] [25004:25006] [DMG] AttributeReportIB = [1758183002.820] [25004:25006] [DMG] { [1758183002.820] [25004:25006] [DMG] AttributeStatusIB = [1758183002.820] [25004:25006] [DMG] { [1758183002.820] [25004:25006] [DMG] AttributePathIB = [1758183002.820] [25004:25006] [DMG] { [1758183002.820] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.820] [25004:25006] [DMG] Cluster = 0x46, [1758183002.820] [25004:25006] [DMG] Attribute = 0x0000_0001, [1758183002.820] [25004:25006] [DMG] } [1758183002.820] [25004:25006] [DMG] [1758183002.820] [25004:25006] [DMG] StatusIB = [1758183002.820] [25004:25006] [DMG] { [1758183002.820] [25004:25006] [DMG] status = 0xc3 (UNSUPPORTED_CLUSTER), [1758183002.820] [25004:25006] [DMG] }, [1758183002.821] [25004:25006] [DMG] [1758183002.821] [25004:25006] [DMG] }, [1758183002.821] [25004:25006] [DMG] [1758183002.821] [25004:25006] [DMG] }, [1758183002.821] [25004:25006] [DMG] [1758183002.821] [25004:25006] [DMG] AttributeReportIB = [1758183002.821] [25004:25006] [DMG] { [1758183002.821] [25004:25006] [DMG] AttributeStatusIB = [1758183002.821] [25004:25006] [DMG] { [1758183002.821] [25004:25006] [DMG] AttributePathIB = [1758183002.821] [25004:25006] [DMG] { [1758183002.821] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.821] [25004:25006] [DMG] Cluster = 0x46, [1758183002.821] [25004:25006] [DMG] Attribute = 0x0000_0000, [1758183002.821] [25004:25006] [DMG] } [1758183002.821] [25004:25006] [DMG] [1758183002.821] [25004:25006] [DMG] StatusIB = [1758183002.821] [25004:25006] [DMG] { [1758183002.822] [25004:25006] [DMG] status = 0xc3 (UNSUPPORTED_CLUSTER), [1758183002.822] [25004:25006] [DMG] }, [1758183002.822] [25004:25006] [DMG] [1758183002.822] [25004:25006] [DMG] }, [1758183002.822] [25004:25006] [DMG] [1758183002.822] [25004:25006] [DMG] }, [1758183002.822] [25004:25006] [DMG] [1758183002.822] [25004:25006] [DMG] AttributeReportIB = [1758183002.822] [25004:25006] [DMG] { [1758183002.822] [25004:25006] [DMG] AttributeStatusIB = [1758183002.822] [25004:25006] [DMG] { [1758183002.822] [25004:25006] [DMG] AttributePathIB = [1758183002.822] [25004:25006] [DMG] { [1758183002.822] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.822] [25004:25006] [DMG] Cluster = 0x46, [1758183002.822] [25004:25006] [DMG] Attribute = 0x0000_0007, [1758183002.822] [25004:25006] [DMG] } [1758183002.823] [25004:25006] [DMG] [1758183002.823] [25004:25006] [DMG] StatusIB = [1758183002.823] [25004:25006] [DMG] { [1758183002.823] [25004:25006] [DMG] status = 0xc3 (UNSUPPORTED_CLUSTER), [1758183002.823] [25004:25006] [DMG] }, [1758183002.823] [25004:25006] [DMG] [1758183002.823] [25004:25006] [DMG] }, [1758183002.823] [25004:25006] [DMG] [1758183002.823] [25004:25006] [DMG] }, [1758183002.823] [25004:25006] [DMG] [1758183002.823] [25004:25006] [DMG] AttributeReportIB = [1758183002.823] [25004:25006] [DMG] { [1758183002.823] [25004:25006] [DMG] AttributeStatusIB = [1758183002.823] [25004:25006] [DMG] { [1758183002.823] [25004:25006] [DMG] AttributePathIB = [1758183002.823] [25004:25006] [DMG] { [1758183002.823] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.823] [25004:25006] [DMG] Cluster = 0x46, [1758183002.823] [25004:25006] [DMG] Attribute = 0x0000_0006, [1758183002.824] [25004:25006] [DMG] } [1758183002.824] [25004:25006] [DMG] [1758183002.824] [25004:25006] [DMG] StatusIB = [1758183002.824] [25004:25006] [DMG] { [1758183002.824] [25004:25006] [DMG] status = 0xc3 (UNSUPPORTED_CLUSTER), [1758183002.824] [25004:25006] [DMG] }, [1758183002.824] [25004:25006] [DMG] [1758183002.824] [25004:25006] [DMG] }, [1758183002.824] [25004:25006] [DMG] [1758183002.824] [25004:25006] [DMG] }, [1758183002.824] [25004:25006] [DMG] [1758183002.824] [25004:25006] [DMG] AttributeReportIB = [1758183002.824] [25004:25006] [DMG] { [1758183002.824] [25004:25006] [DMG] AttributeDataIB = [1758183002.824] [25004:25006] [DMG] { [1758183002.824] [25004:25006] [DMG] DataVersion = 0xfbdaf629, [1758183002.824] [25004:25006] [DMG] AttributePathIB = [1758183002.824] [25004:25006] [DMG] { [1758183002.824] [25004:25006] [DMG] Endpoint = 0x0, [1758183002.825] [25004:25006] [DMG] Cluster = 0x30, [1758183002.825] [25004:25006] [DMG] Attribute = 0x0000_0004, [1758183002.825] [25004:25006] [DMG] } [1758183002.825] [25004:25006] [DMG] [1758183002.825] [25004:25006] [DMG] Data = true, [1758183002.825] [25004:25006] [DMG] }, [1758183002.825] [25004:25006] [DMG] [1758183002.825] [25004:25006] [DMG] }, [1758183002.825] [25004:25006] [DMG] [1758183002.825] [25004:25006] [DMG] ], [1758183002.825] [25004:25006] [DMG] [1758183002.825] [25004:25006] [DMG] SuppressResponse = true, [1758183002.825] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183002.825] [25004:25006] [DMG] } [1758183002.826] [25004:25006] [CTL] ----- NetworkCommissioning Features: has Thread. endpointid = 0 [1758183002.826] [25004:25006] [SVR] OnReadCommissioningInfo - vendorId=0x15EF productId=0x1010 [1758183002.827] [25004:25006] [SVR] OnReadCommissioningInfo ICD - IdleModeDuration=0 activeModeDuration=0 activeModeThreshold=0 [1758183002.827] [25004:25006] [CTL] Successfully finished commissioning step 'ReadCommissioningInfo2' [1758183002.827] [25004:25006] [CTL] Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1758183002.827] [25004:25006] [CTL] Performing next commissioning step 'ArmFailSafe' [1758183002.827] [25004:25006] [CTL] Arming failsafe (60 seconds) [1758183002.827] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183002.827] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183002.827] [25004:25006] [EM] <<< [E:60638i S:50268 M:176269977] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1758183002.828] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183002.905] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.907] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.908] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.908] [25004:25006] [EM] >>> [E:60638i S:50268 M:112433857] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1758183002.908] [25004:25006] [EM] Found matching exchange: 60638i, Delegate: 0xffff80007388 [1758183002.908] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183002.909] [25004:25006] [DMG] InvokeResponseMessage = [1758183002.909] [25004:25006] [DMG] { [1758183002.909] [25004:25006] [DMG] suppressResponse = false, [1758183002.909] [25004:25006] [DMG] InvokeResponseIBs = [1758183002.909] [25004:25006] [DMG] [ [1758183002.909] [25004:25006] [DMG] InvokeResponseIB = [1758183002.909] [25004:25006] [DMG] { [1758183002.909] [25004:25006] [DMG] CommandDataIB = [1758183002.909] [25004:25006] [DMG] { [1758183002.909] [25004:25006] [DMG] CommandPathIB = [1758183002.909] [25004:25006] [DMG] { [1758183002.910] [25004:25006] [DMG] EndpointId = 0x0, [1758183002.910] [25004:25006] [DMG] ClusterId = 0x30, [1758183002.910] [25004:25006] [DMG] CommandId = 0x1, [1758183002.910] [25004:25006] [DMG] }, [1758183002.910] [25004:25006] [DMG] [1758183002.910] [25004:25006] [DMG] CommandFields = [1758183002.910] [25004:25006] [DMG] { [1758183002.910] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183002.910] [25004:25006] [DMG] 0x1 = "" (0 chars), [1758183002.910] [25004:25006] [DMG] }, [1758183002.910] [25004:25006] [DMG] }, [1758183002.911] [25004:25006] [DMG] [1758183002.911] [25004:25006] [DMG] }, [1758183002.911] [25004:25006] [DMG] [1758183002.911] [25004:25006] [DMG] ], [1758183002.911] [25004:25006] [DMG] [1758183002.911] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183002.911] [25004:25006] [DMG] }, [1758183002.911] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1758183002.911] [25004:25006] [CTL] Received ArmFailSafe response errorCode=0 [1758183002.911] [25004:25006] [CTL] Successfully finished commissioning step 'ArmFailSafe' [1758183002.911] [25004:25006] [CTL] Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1758183002.912] [25004:25006] [CTL] Performing next commissioning step 'ConfigRegulatory' [1758183002.912] [25004:25006] [CTL] Setting Regulatory Config [1758183002.912] [25004:25006] [CTL] Device does not support configurable regulatory location [1758183002.912] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183002.912] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183002.912] [25004:25006] [EM] <<< [E:60639i S:50268 M:176269978] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:70) [1758183002.913] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183002.913] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183002.994] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183002.997] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183002.998] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183002.998] [25004:25006] [EM] >>> [E:60639i S:50268 M:112433858] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1758183002.998] [25004:25006] [EM] Found matching exchange: 60639i, Delegate: 0xffff80017688 [1758183002.998] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183002.998] [25004:25006] [DMG] InvokeResponseMessage = [1758183002.998] [25004:25006] [DMG] { [1758183002.998] [25004:25006] [DMG] suppressResponse = false, [1758183002.999] [25004:25006] [DMG] InvokeResponseIBs = [1758183002.999] [25004:25006] [DMG] [ [1758183002.999] [25004:25006] [DMG] InvokeResponseIB = [1758183002.999] [25004:25006] [DMG] { [1758183002.999] [25004:25006] [DMG] CommandDataIB = [1758183002.999] [25004:25006] [DMG] { [1758183002.999] [25004:25006] [DMG] CommandPathIB = [1758183002.999] [25004:25006] [DMG] { [1758183002.999] [25004:25006] [DMG] EndpointId = 0x0, [1758183002.999] [25004:25006] [DMG] ClusterId = 0x30, [1758183003.000] [25004:25006] [DMG] CommandId = 0x3, [1758183003.000] [25004:25006] [DMG] }, [1758183003.000] [25004:25006] [DMG] [1758183003.000] [25004:25006] [DMG] CommandFields = [1758183003.000] [25004:25006] [DMG] { [1758183003.000] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183003.000] [25004:25006] [DMG] 0x1 = "" (0 chars), [1758183003.000] [25004:25006] [DMG] }, [1758183003.000] [25004:25006] [DMG] }, [1758183003.001] [25004:25006] [DMG] [1758183003.001] [25004:25006] [DMG] }, [1758183003.001] [25004:25006] [DMG] [1758183003.001] [25004:25006] [DMG] ], [1758183003.001] [25004:25006] [DMG] [1758183003.001] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183003.001] [25004:25006] [DMG] }, [1758183003.001] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1758183003.001] [25004:25006] [CTL] Received SetRegulatoryConfig response errorCode=0 [1758183003.001] [25004:25006] [CTL] Successfully finished commissioning step 'ConfigRegulatory' [1758183003.002] [25004:25006] [CTL] Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1758183003.002] [25004:25006] [CTL] Performing next commissioning step 'SendPAICertificateRequest' [1758183003.002] [25004:25006] [CTL] Sending request for PAI certificate [1758183003.002] [25004:25006] [CTL] Sending Certificate Chain request to 0xffff800146a0 device [1758183003.002] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183003.002] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183003.002] [25004:25006] [EM] <<< [E:60640i S:50268 M:176269979] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1758183003.003] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183003.004] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183003.085] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183003.175] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.176] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.314] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.314] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.401] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.402] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.402] [25004:25006] [EM] >>> [E:60640i S:50268 M:112433859] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:528) [1758183003.402] [25004:25006] [EM] Found matching exchange: 60640i, Delegate: 0xffff80007388 [1758183003.402] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183003.402] [25004:25006] [DMG] InvokeResponseMessage = [1758183003.402] [25004:25006] [DMG] { [1758183003.402] [25004:25006] [DMG] suppressResponse = false, [1758183003.402] [25004:25006] [DMG] InvokeResponseIBs = [1758183003.402] [25004:25006] [DMG] [ [1758183003.403] [25004:25006] [DMG] InvokeResponseIB = [1758183003.403] [25004:25006] [DMG] { [1758183003.403] [25004:25006] [DMG] CommandDataIB = [1758183003.403] [25004:25006] [DMG] { [1758183003.403] [25004:25006] [DMG] CommandPathIB = [1758183003.403] [25004:25006] [DMG] { [1758183003.403] [25004:25006] [DMG] EndpointId = 0x0, [1758183003.403] [25004:25006] [DMG] ClusterId = 0x3e, [1758183003.403] [25004:25006] [DMG] CommandId = 0x3, [1758183003.403] [25004:25006] [DMG] }, [1758183003.403] [25004:25006] [DMG] [1758183003.403] [25004:25006] [DMG] CommandFields = [1758183003.404] [25004:25006] [DMG] { [1758183003.404] [25004:25006] [DMG] 0x0 = [ [1758183003.404] [25004:25006] [DMG] 0x30, 0x82, 0x01, 0xcc, 0x30, 0x82, 0x01, 0x72, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x50, 0x69, 0x91, 0x2b, 0x01, 0xd0, 0x72, 0x9a, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x2d, 0x31, 0x2b, 0x30, 0x29, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x22, 0x41, 0x63, 0x54, 0x45, 0x43, 0x20, 0x6f, 0x66, 0x20, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x41, 0x20, 0x30, 0x31, 0x30, 0x1e, 0x17, 0x0d, 0x32, 0x30, 0x31, 0x30, 0x31, 0x35, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x17, 0x0d, 0x34, 0x30, 0x31, 0x30, 0x31, 0x35, 0x31, 0x34, 0x32, 0x33, 0x34, 0x32, 0x5a, 0x30, 0x43, 0x31, 0x2b, 0x30, 0x29, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x22, 0x41, 0x63, 0x54, 0x45, 0x43, 0x20, 0x6f, 0x66, 0x20, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x35, 0x45, 0x46, 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, 0x43, 0xf1, 0x58, 0x50, 0xbd, 0x96, 0x2e, 0x90, 0x35, 0xb2, 0x9b, 0x16, 0xab, 0x1d, 0x07, 0x17, 0xfd, 0x12, 0x65, 0x00, 0x90, 0xf6, 0xb4, 0x43, 0x4a, 0xc2, 0xd9, 0x4d, 0xa9, 0x3b, 0xc2, 0xf4, 0xd2, 0x0c, 0xfd, 0x66, 0xd7, 0xfb, 0x4b, 0xda, 0xaf, 0x08, 0x62, 0x39, 0x50, 0x3d, 0x1e, 0xb7, 0xa2, 0x31, 0x6c, 0xd4, 0xf3, 0x1e, 0x94, 0xd3, 0x72, 0x2d, 0x78, 0x3d, 0x4e, 0x2e, 0x83, 0x0f, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x [1758183003.404] [25004:25006] [DMG] ] (464 bytes) [1758183003.404] [25004:25006] [DMG] }, [1758183003.404] [25004:25006] [DMG] }, [1758183003.404] [25004:25006] [DMG] [1758183003.404] [25004:25006] [DMG] }, [1758183003.404] [25004:25006] [DMG] [1758183003.405] [25004:25006] [DMG] ], [1758183003.405] [25004:25006] [DMG] [1758183003.405] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183003.405] [25004:25006] [DMG] }, [1758183003.405] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1758183003.405] [25004:25006] [CTL] Received certificate chain from the device [1758183003.405] [25004:25006] [CTL] Successfully finished commissioning step 'SendPAICertificateRequest' [1758183003.405] [25004:25006] [CTL] Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1758183003.405] [25004:25006] [CTL] Performing next commissioning step 'SendDACCertificateRequest' [1758183003.405] [25004:25006] [CTL] Sending request for DAC certificate [1758183003.405] [25004:25006] [CTL] Sending Certificate Chain request to 0xffff800146a0 device [1758183003.406] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183003.406] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183003.406] [25004:25006] [EM] <<< [E:60641i S:50268 M:176269980] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:62) [1758183003.407] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183003.407] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183003.489] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183003.580] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.580] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.719] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.719] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.807] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.807] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183003.807] [25004:25006] [EM] >>> [E:60641i S:50268 M:112433860] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:566) [1758183003.807] [25004:25006] [EM] Found matching exchange: 60641i, Delegate: 0xffff80017688 [1758183003.807] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183003.807] [25004:25006] [DMG] InvokeResponseMessage = [1758183003.807] [25004:25006] [DMG] { [1758183003.807] [25004:25006] [DMG] suppressResponse = false, [1758183003.807] [25004:25006] [DMG] InvokeResponseIBs = [1758183003.807] [25004:25006] [DMG] [ [1758183003.807] [25004:25006] [DMG] InvokeResponseIB = [1758183003.807] [25004:25006] [DMG] { [1758183003.807] [25004:25006] [DMG] CommandDataIB = [1758183003.808] [25004:25006] [DMG] { [1758183003.808] [25004:25006] [DMG] CommandPathIB = [1758183003.808] [25004:25006] [DMG] { [1758183003.808] [25004:25006] [DMG] EndpointId = 0x0, [1758183003.808] [25004:25006] [DMG] ClusterId = 0x3e, [1758183003.808] [25004:25006] [DMG] CommandId = 0x3, [1758183003.808] [25004:25006] [DMG] }, [1758183003.808] [25004:25006] [DMG] [1758183003.808] [25004:25006] [DMG] CommandFields = [1758183003.808] [25004:25006] [DMG] { [1758183003.808] [25004:25006] [DMG] 0x0 = [ [1758183003.808] [25004:25006] [DMG] 0x30, 0x82, 0x01, 0xf2, 0x30, 0x82, 0x01, 0x98, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x61, 0x04, 0x40, 0x4f, 0x00, 0x66, 0x75, 0x45, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x43, 0x31, 0x2b, 0x30, 0x29, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x22, 0x41, 0x63, 0x54, 0x45, 0x43, 0x20, 0x6f, 0x66, 0x20, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x31, 0x35, 0x45, 0x46, 0x30, 0x1e, 0x17, 0x0d, 0x32, 0x30, 0x31, 0x30, 0x31, 0x35, 0x31, 0x34, 0x32, 0x33, 0x34, 0x33, 0x5a, 0x17, 0x0d, 0x34, 0x30, 0x31, 0x30, 0x31, 0x35, 0x31, 0x34, 0x32, 0x33, 0x34, 0x32, 0x5a, 0x30, 0x59, 0x31, 0x2b, 0x30, 0x29, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x22, 0x41, 0x63, 0x54, 0x45, 0x43, 0x20, 0x6f, 0x66, 0x20, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x65, 0x6c, 0x6f, 0x70, 0x6d, 0x65, 0x6e, 0x74, 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, 0x35, 0x45, 0x46, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x31, 0x30, 0x31, 0x30, 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, 0xca, 0x15, 0x41, 0x15, 0x37, 0x98, 0x9d, 0xf2, 0x6f, 0xf3, 0x60, 0x4e, 0xd3, 0xd1, 0x96, 0x6e, 0xa1, 0x49, 0xd9, 0x8f, 0xe0, 0xec, 0x8b, 0x95, 0x1e, 0xd2, 0x59, 0x3d, 0xf5, 0x [1758183003.808] [25004:25006] [DMG] ] (502 bytes) [1758183003.808] [25004:25006] [DMG] }, [1758183003.808] [25004:25006] [DMG] }, [1758183003.808] [25004:25006] [DMG] [1758183003.808] [25004:25006] [DMG] }, [1758183003.808] [25004:25006] [DMG] [1758183003.808] [25004:25006] [DMG] ], [1758183003.808] [25004:25006] [DMG] [1758183003.808] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183003.808] [25004:25006] [DMG] }, [1758183003.808] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1758183003.809] [25004:25006] [CTL] Received certificate chain from the device [1758183003.809] [25004:25006] [CTL] Successfully finished commissioning step 'SendDACCertificateRequest' [1758183003.809] [25004:25006] [CTL] Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1758183003.809] [25004:25006] [CTL] Performing next commissioning step 'SendAttestationRequest' [1758183003.809] [25004:25006] [CTL] Sending Attestation Request to the device. [1758183003.809] [25004:25006] [CTL] Sending Attestation request to 0xffff800146a0 device [1758183003.809] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183003.809] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183003.809] [25004:25006] [EM] <<< [E:60642i S:50268 M:176269981] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1758183003.809] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183003.809] [25004:25006] [CTL] Sent Attestation request, waiting for the Attestation Information [1758183003.810] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183003.895] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183003.985] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183003.986] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183004.122] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183004.122] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183004.122] [25004:25006] [EM] >>> [E:60642i S:50268 M:112433861] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:409) [1758183004.122] [25004:25006] [EM] Found matching exchange: 60642i, Delegate: 0xffff80007388 [1758183004.122] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183004.123] [25004:25006] [DMG] InvokeResponseMessage = [1758183004.123] [25004:25006] [DMG] { [1758183004.123] [25004:25006] [DMG] suppressResponse = false, [1758183004.123] [25004:25006] [DMG] InvokeResponseIBs = [1758183004.123] [25004:25006] [DMG] [ [1758183004.123] [25004:25006] [DMG] InvokeResponseIB = [1758183004.123] [25004:25006] [DMG] { [1758183004.123] [25004:25006] [DMG] CommandDataIB = [1758183004.123] [25004:25006] [DMG] { [1758183004.123] [25004:25006] [DMG] CommandPathIB = [1758183004.123] [25004:25006] [DMG] { [1758183004.123] [25004:25006] [DMG] EndpointId = 0x0, [1758183004.123] [25004:25006] [DMG] ClusterId = 0x3e, [1758183004.123] [25004:25006] [DMG] CommandId = 0x1, [1758183004.124] [25004:25006] [DMG] }, [1758183004.124] [25004:25006] [DMG] [1758183004.124] [25004:25006] [DMG] CommandFields = [1758183004.124] [25004:25006] [DMG] { [1758183004.124] [25004:25006] [DMG] 0x0 = [ [1758183004.124] [25004:25006] [DMG] 0x15, 0x30, 0x01, 0xeb, 0x30, 0x81, 0xe8, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x81, 0xda, 0x30, 0x81, 0xd7, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x45, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x38, 0x04, 0x36, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xef, 0x15, 0x36, 0x02, 0x05, 0x10, 0x10, 0x18, 0x25, 0x03, 0x01, 0x01, 0x2c, 0x04, 0x13, 0x5a, 0x49, 0x47, 0x32, 0x30, 0x31, 0x34, 0x32, 0x5a, 0x42, 0x33, 0x33, 0x30, 0x30, 0x30, 0x33, 0x2d, 0x32, 0x34, 0x24, 0x05, 0x00, 0x24, 0x06, 0x00, 0x25, 0x07, 0x94, 0x26, 0x24, 0x08, 0x01, 0x18, 0x31, 0x7c, 0x30, 0x7a, 0x02, 0x01, 0x03, 0x80, 0x14, 0x62, 0xfa, 0x82, 0x33, 0x59, 0xac, 0xfa, 0xa9, 0x96, 0x3e, 0x1c, 0xfa, 0x14, 0x0a, 0xdd, 0xf5, 0x04, 0xf3, 0x71, 0x60, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x04, 0x46, 0x30, 0x44, 0x02, 0x20, 0x37, 0xa0, 0x28, 0xf4, 0x3d, 0xfc, 0xaf, 0xe8, 0xd4, 0x4b, 0x4d, 0x9d, 0x2e, 0x02, 0x61, 0x89, 0x15, 0xb0, 0x7b, 0x39, 0x4e, 0xc6, 0x40, 0x30, 0x0c, 0x0c, 0xb0, 0xa8, 0x21, 0x83, 0xb4, 0x0d, 0x02, 0x20, 0x30, 0x32, 0x96, 0xc5, 0x52, 0xf6, 0xd1, 0xfb, 0xe7, 0xec, 0x7c, 0xde, 0x56, 0x69, 0x8d, 0x96, 0x31, 0x0b, 0x6b, 0x85, 0x7f, 0x06, 0x95, 0xe9, 0xca, 0x73, 0xfb, 0x7a, 0x6d, 0xb7, 0x82, 0x92, 0x30, 0x02, 0x20, 0x51, 0x18, 0x2f, 0x58, 0xc3, 0x04, 0x65, 0xa5, 0x34, 0x9f, 0x9f, 0x05, 0x23, 0xe8, 0xe4, 0xfc, 0x20, 0xb1, 0xa4, 0x19, 0x9e, 0x38, 0x5c, 0x6c, 0xc4, 0x60, 0x88, 0x57, 0xbe, 0x17, 0xcc, 0x43, 0x24, 0x03, 0x00, 0x18, [1758183004.124] [25004:25006] [DMG] ] (278 bytes) [1758183004.124] [25004:25006] [DMG] 0x1 = [ [1758183004.124] [25004:25006] [DMG] 0x1b, 0x9b, 0x79, 0x4e, 0xbc, 0xa6, 0x00, 0xeb, 0x16, 0xb2, 0xd9, 0x22, 0xc7, 0xa7, 0x59, 0xf6, 0xa0, 0xfe, 0xcc, 0x74, 0xce, 0x13, 0x90, 0x2b, 0x28, 0x90, 0xb2, 0x0f, 0xc4, 0x55, 0x6c, 0xf7, 0xf0, 0x7f, 0x64, 0x0f, 0x3d, 0x72, 0xf9, 0x88, 0x29, 0x86, 0x37, 0x90, 0x20, 0xf4, 0x62, 0x30, 0x01, 0xae, 0x59, 0xca, 0x89, 0x67, 0x1d, 0x76, 0xfc, 0x4e, 0x17, 0x7f, 0x9d, 0x61, 0x68, 0x3f, [1758183004.125] [25004:25006] [DMG] ] (64 bytes) [1758183004.125] [25004:25006] [DMG] }, [1758183004.125] [25004:25006] [DMG] }, [1758183004.125] [25004:25006] [DMG] [1758183004.125] [25004:25006] [DMG] }, [1758183004.125] [25004:25006] [DMG] [1758183004.125] [25004:25006] [DMG] ], [1758183004.125] [25004:25006] [DMG] [1758183004.125] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183004.125] [25004:25006] [DMG] }, [1758183004.125] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1758183004.125] [25004:25006] [CTL] Received Attestation Information from the device [1758183004.125] [25004:25006] [CTL] Successfully finished commissioning step 'SendAttestationRequest' [1758183004.126] [25004:25006] [CTL] AutoCommissioner setting attestationElements buffer size 278/278 [1758183004.126] [25004:25006] [CTL] Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1758183004.126] [25004:25006] [CTL] Performing next commissioning step 'AttestationVerification' [1758183004.126] [25004:25006] [CTL] Verifying attestation [1758183004.173] [25004:25006] [CTL] Successfully finished commissioning step 'AttestationVerification' [1758183004.173] [25004:25006] [CTL] Commissioning stage next step: 'AttestationVerification' -> 'AttestationRevocationCheck' [1758183004.173] [25004:25006] [CTL] Performing next commissioning step 'AttestationRevocationCheck' [1758183004.173] [25004:25006] [CTL] Verifying device's DAC chain revocation status [1758183004.173] [25004:25006] [CTL] Successfully validated 'Attestation Information' command received from the device. [1758183004.173] [25004:25006] [CTL] Successfully finished commissioning step 'AttestationRevocationCheck' [1758183004.173] [25004:25006] [CTL] Commissioning stage next step: 'AttestationRevocationCheck' -> 'SendOpCertSigningRequest' [1758183004.173] [25004:25006] [CTL] Performing next commissioning step 'SendOpCertSigningRequest' [1758183004.173] [25004:25006] [CTL] Sending CSR request to 0xffff800146a0 device [1758183004.173] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183004.173] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183004.173] [25004:25006] [EM] <<< [E:60643i S:50268 M:176269982] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:94) [1758183004.174] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183004.174] [25004:25006] [CTL] Sent CSR request, waiting for the CSR [1758183004.174] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183004.255] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183004.345] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183004.345] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183004.481] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183004.481] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183004.482] [25004:25006] [EM] >>> [E:60643i S:50268 M:112433862] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:393) [1758183004.482] [25004:25006] [EM] Found matching exchange: 60643i, Delegate: 0xffff80017688 [1758183004.482] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183004.482] [25004:25006] [DMG] InvokeResponseMessage = [1758183004.482] [25004:25006] [DMG] { [1758183004.482] [25004:25006] [DMG] suppressResponse = false, [1758183004.482] [25004:25006] [DMG] InvokeResponseIBs = [1758183004.482] [25004:25006] [DMG] [ [1758183004.482] [25004:25006] [DMG] InvokeResponseIB = [1758183004.482] [25004:25006] [DMG] { [1758183004.482] [25004:25006] [DMG] CommandDataIB = [1758183004.483] [25004:25006] [DMG] { [1758183004.483] [25004:25006] [DMG] CommandPathIB = [1758183004.483] [25004:25006] [DMG] { [1758183004.483] [25004:25006] [DMG] EndpointId = 0x0, [1758183004.483] [25004:25006] [DMG] ClusterId = 0x3e, [1758183004.483] [25004:25006] [DMG] CommandId = 0x5, [1758183004.483] [25004:25006] [DMG] }, [1758183004.483] [25004:25006] [DMG] [1758183004.483] [25004:25006] [DMG] CommandFields = [1758183004.483] [25004:25006] [DMG] { [1758183004.484] [25004:25006] [DMG] 0x0 = [ [1758183004.484] [25004:25006] [DMG] 0x15, 0x30, 0x01, 0xde, 0x30, 0x81, 0xdb, 0x30, 0x81, 0x81, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0b, 0x0c, 0x03, 0x43, 0x53, 0x41, 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, 0x91, 0x4f, 0x9d, 0x45, 0x32, 0xaf, 0xf4, 0x3a, 0xa5, 0xc1, 0x28, 0x01, 0x8f, 0x34, 0x22, 0x3b, 0x02, 0x7c, 0xe7, 0xb6, 0xa4, 0x7c, 0xbc, 0x26, 0x42, 0x0f, 0xc0, 0xaf, 0xcd, 0x42, 0x94, 0xf5, 0x06, 0xd5, 0x18, 0x1d, 0x3b, 0xbd, 0xc0, 0x78, 0xa1, 0xdc, 0xb6, 0xe1, 0xf0, 0xbc, 0x46, 0x59, 0xab, 0xb6, 0x5b, 0xcd, 0x88, 0x31, 0xcf, 0xee, 0x96, 0x6e, 0xa0, 0xa7, 0xc5, 0x7a, 0x99, 0xf2, 0xa0, 0x11, 0x30, 0x0f, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x09, 0x0e, 0x31, 0x02, 0x30, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x49, 0x00, 0x30, 0x46, 0x02, 0x21, 0x00, 0xe7, 0x1e, 0xb7, 0x48, 0x20, 0x97, 0x1e, 0xe2, 0x30, 0x53, 0x93, 0x09, 0x6d, 0x38, 0x1d, 0xc4, 0xeb, 0xbc, 0x80, 0xbe, 0xaf, 0xd3, 0x6f, 0xee, 0x92, 0x37, 0xe8, 0x93, 0x67, 0x65, 0x47, 0xdc, 0x02, 0x21, 0x00, 0x91, 0xcd, 0x8f, 0x78, 0x6d, 0xf6, 0xe0, 0x0a, 0x0d, 0xf3, 0x28, 0x29, 0x2e, 0x4f, 0x1c, 0x10, 0xe3, 0x4e, 0xda, 0x1b, 0xe6, 0x24, 0xaa, 0xb8, 0xb9, 0xa4, 0x6e, 0xd9, 0x85, 0x39, 0x7f, 0x7f, 0x30, 0x02, 0x20, 0xb9, 0xab, 0xc8, 0xd4, 0x84, 0x0e, 0x76, 0x33, 0x2f, 0x81, 0x81, 0x7b, 0x60, 0xb8, 0x49, 0x3e, 0x03, 0x66, 0xca, 0x69, 0x01, 0x07, 0x3f, 0x70, 0x96, 0xa4, 0x3d, 0x1b, 0x99, 0x0e, 0xa7, 0xb5, 0x18, [1758183004.484] [25004:25006] [DMG] ] (262 bytes) [1758183004.484] [25004:25006] [DMG] 0x1 = [ [1758183004.484] [25004:25006] [DMG] 0xb4, 0xa6, 0x07, 0x9b, 0xdf, 0x7e, 0x3d, 0x8c, 0x75, 0x58, 0x94, 0x97, 0xad, 0x28, 0x7d, 0x89, 0x72, 0x0c, 0xb7, 0xf6, 0x88, 0xf0, 0xa3, 0xb7, 0xbf, 0x20, 0x64, 0xb8, 0xc0, 0x21, 0x17, 0x8d, 0xc5, 0xe5, 0x13, 0x44, 0x2e, 0x65, 0xe4, 0xf7, 0x6a, 0x90, 0x9b, 0xd6, 0x8a, 0x16, 0x68, 0x38, 0x4b, 0x50, 0x14, 0xa8, 0xf0, 0x48, 0xf8, 0x08, 0x06, 0xb6, 0xb9, 0xe8, 0x8b, 0xea, 0x83, 0x90, [1758183004.484] [25004:25006] [DMG] ] (64 bytes) [1758183004.484] [25004:25006] [DMG] }, [1758183004.484] [25004:25006] [DMG] }, [1758183004.485] [25004:25006] [DMG] [1758183004.485] [25004:25006] [DMG] }, [1758183004.485] [25004:25006] [DMG] [1758183004.485] [25004:25006] [DMG] ], [1758183004.485] [25004:25006] [DMG] [1758183004.485] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183004.485] [25004:25006] [DMG] }, [1758183004.485] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1758183004.485] [25004:25006] [CTL] Received certificate signing request from the device [1758183004.485] [25004:25006] [CTL] Successfully finished commissioning step 'SendOpCertSigningRequest' [1758183004.485] [25004:25006] [CTL] Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1758183004.486] [25004:25006] [CTL] Performing next commissioning step 'ValidateCSR' [1758183004.493] [25004:25006] [CTL] Successfully finished commissioning step 'ValidateCSR' [1758183004.494] [25004:25006] [CTL] Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1758183004.494] [25004:25006] [CTL] Performing next commissioning step 'GenerateNOCChain' [1758183004.494] [25004:25006] [CTL] Getting certificate chain for the device from the issuer [1758183004.497] [25004:25006] [CTL] Verifying Certificate Signing Request [1758183004.499] [25004:25006] [CTL] Generating NOC [1758183004.499] [25004:25006] [CTL] Providing certificate chain to the commissioner [1758183004.499] [25004:25006] [CTL] Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success [1758183004.499] [25004:25006] [CTL] Successfully finished commissioning step 'GenerateNOCChain' [1758183004.499] [25004:25006] [CTL] Performing next commissioning step 'SendTrustedRootCert' [1758183004.499] [25004:25006] [CTL] Sending root certificate to the device [1758183004.499] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183004.499] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183004.499] [25004:25006] [EM] <<< [E:60644i S:50268 M:176269983] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:293) [1758183004.500] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183004.500] [25004:25006] [CTL] Sent root certificate to the device [1758183004.500] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183004.705] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183004.794] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183004.797] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183004.798] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183004.798] [25004:25006] [EM] >>> [E:60644i S:50268 M:112433863] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:63) [1758183004.798] [25004:25006] [EM] Found matching exchange: 60644i, Delegate: 0xffff80007388 [1758183004.798] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183004.798] [25004:25006] [DMG] InvokeResponseMessage = [1758183004.798] [25004:25006] [DMG] { [1758183004.798] [25004:25006] [DMG] suppressResponse = false, [1758183004.799] [25004:25006] [DMG] InvokeResponseIBs = [1758183004.799] [25004:25006] [DMG] [ [1758183004.799] [25004:25006] [DMG] InvokeResponseIB = [1758183004.799] [25004:25006] [DMG] { [1758183004.799] [25004:25006] [DMG] CommandStatusIB = [1758183004.799] [25004:25006] [DMG] { [1758183004.799] [25004:25006] [DMG] CommandPathIB = [1758183004.799] [25004:25006] [DMG] { [1758183004.799] [25004:25006] [DMG] EndpointId = 0x0, [1758183004.799] [25004:25006] [DMG] ClusterId = 0x3e, [1758183004.799] [25004:25006] [DMG] CommandId = 0xb, [1758183004.800] [25004:25006] [DMG] }, [1758183004.800] [25004:25006] [DMG] [1758183004.800] [25004:25006] [DMG] StatusIB = [1758183004.800] [25004:25006] [DMG] { [1758183004.800] [25004:25006] [DMG] status = 0x00 (SUCCESS), [1758183004.800] [25004:25006] [DMG] }, [1758183004.800] [25004:25006] [DMG] [1758183004.800] [25004:25006] [DMG] }, [1758183004.800] [25004:25006] [DMG] [1758183004.800] [25004:25006] [DMG] }, [1758183004.800] [25004:25006] [DMG] [1758183004.800] [25004:25006] [DMG] ], [1758183004.801] [25004:25006] [DMG] [1758183004.801] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183004.801] [25004:25006] [DMG] }, [1758183004.801] [25004:25006] [DMG] Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1758183004.801] [25004:25006] [CTL] Device confirmed that it has received the root certificate [1758183004.801] [25004:25006] [CTL] Successfully finished commissioning step 'SendTrustedRootCert' [1758183004.801] [25004:25006] [CTL] Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1758183004.801] [25004:25006] [CTL] Performing next commissioning step 'SendNOC' [1758183004.801] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183004.801] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183004.802] [25004:25006] [EM] <<< [E:60645i S:50268 M:176269984] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:566) [1758183004.803] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183004.803] [25004:25006] [CTL] Sent operational certificate to the device [1758183004.803] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183004.975] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183005.155] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183005.245] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183005.247] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183005.247] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183005.248] [25004:25006] [EM] >>> [E:60645i S:50268 M:112433864] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1758183005.248] [25004:25006] [EM] Found matching exchange: 60645i, Delegate: 0xffff80017688 [1758183005.248] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183005.248] [25004:25006] [DMG] InvokeResponseMessage = [1758183005.248] [25004:25006] [DMG] { [1758183005.248] [25004:25006] [DMG] suppressResponse = false, [1758183005.248] [25004:25006] [DMG] InvokeResponseIBs = [1758183005.248] [25004:25006] [DMG] [ [1758183005.248] [25004:25006] [DMG] InvokeResponseIB = [1758183005.248] [25004:25006] [DMG] { [1758183005.248] [25004:25006] [DMG] CommandDataIB = [1758183005.249] [25004:25006] [DMG] { [1758183005.249] [25004:25006] [DMG] CommandPathIB = [1758183005.249] [25004:25006] [DMG] { [1758183005.249] [25004:25006] [DMG] EndpointId = 0x0, [1758183005.249] [25004:25006] [DMG] ClusterId = 0x3e, [1758183005.249] [25004:25006] [DMG] CommandId = 0x8, [1758183005.249] [25004:25006] [DMG] }, [1758183005.249] [25004:25006] [DMG] [1758183005.249] [25004:25006] [DMG] CommandFields = [1758183005.249] [25004:25006] [DMG] { [1758183005.249] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183005.249] [25004:25006] [DMG] 0x1 = 1 (unsigned), [1758183005.250] [25004:25006] [DMG] }, [1758183005.250] [25004:25006] [DMG] }, [1758183005.250] [25004:25006] [DMG] [1758183005.250] [25004:25006] [DMG] }, [1758183005.250] [25004:25006] [DMG] [1758183005.250] [25004:25006] [DMG] ], [1758183005.250] [25004:25006] [DMG] [1758183005.250] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183005.250] [25004:25006] [DMG] }, [1758183005.250] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1758183005.250] [25004:25006] [CTL] Device returned status 0 on receiving the NOC [1758183005.250] [25004:25006] [CTL] Operational credentials provisioned on device 0xffff800146a0 [1758183005.250] [25004:25006] [TOO] Secure Pairing Success [1758183005.251] [25004:25006] [TOO] CASE establishment successful [1758183005.251] [25004:25006] [CTL] Successfully finished commissioning step 'SendNOC' [1758183005.251] [25004:25006] [CTL] No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1758183005.251] [25004:25006] [CTL] Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup' [1758183005.251] [25004:25006] [CTL] Performing next commissioning step 'ThreadNetworkSetup' [1758183005.251] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183005.251] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183005.251] [25004:25006] [EM] <<< [E:60646i S:50268 M:176269985] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:160) [1758183005.252] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183005.252] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183005.379] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183005.381] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183005.381] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183005.381] [25004:25006] [EM] >>> [E:60646i S:50268 M:112433865] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1758183005.381] [25004:25006] [EM] Found matching exchange: 60646i, Delegate: 0xffff80007388 [1758183005.381] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183005.381] [25004:25006] [DMG] InvokeResponseMessage = [1758183005.382] [25004:25006] [DMG] { [1758183005.382] [25004:25006] [DMG] suppressResponse = false, [1758183005.382] [25004:25006] [DMG] InvokeResponseIBs = [1758183005.382] [25004:25006] [DMG] [ [1758183005.382] [25004:25006] [DMG] InvokeResponseIB = [1758183005.382] [25004:25006] [DMG] { [1758183005.382] [25004:25006] [DMG] CommandDataIB = [1758183005.382] [25004:25006] [DMG] { [1758183005.382] [25004:25006] [DMG] CommandPathIB = [1758183005.382] [25004:25006] [DMG] { [1758183005.382] [25004:25006] [DMG] EndpointId = 0x0, [1758183005.382] [25004:25006] [DMG] ClusterId = 0x31, [1758183005.382] [25004:25006] [DMG] CommandId = 0x5, [1758183005.382] [25004:25006] [DMG] }, [1758183005.382] [25004:25006] [DMG] [1758183005.382] [25004:25006] [DMG] CommandFields = [1758183005.382] [25004:25006] [DMG] { [1758183005.382] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183005.382] [25004:25006] [DMG] 0x2 = 0 (unsigned), [1758183005.382] [25004:25006] [DMG] }, [1758183005.382] [25004:25006] [DMG] }, [1758183005.382] [25004:25006] [DMG] [1758183005.382] [25004:25006] [DMG] }, [1758183005.382] [25004:25006] [DMG] [1758183005.382] [25004:25006] [DMG] ], [1758183005.382] [25004:25006] [DMG] [1758183005.382] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183005.382] [25004:25006] [DMG] }, [1758183005.382] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1758183005.382] [25004:25006] [CTL] Received NetworkConfig response, networkingStatus=0 [1758183005.382] [25004:25006] [CTL] Successfully finished commissioning step 'ThreadNetworkSetup' [1758183005.382] [25004:25006] [CTL] Commissioning stage next step: 'ThreadNetworkSetup' -> 'FailsafeBeforeThreadEnable' [1758183005.382] [25004:25006] [CTL] Performing next commissioning step 'FailsafeBeforeThreadEnable' [1758183005.383] [25004:25006] [CTL] Arming failsafe (94 seconds) [1758183005.383] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183005.383] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183005.383] [25004:25006] [EM] <<< [E:60647i S:50268 M:176269986] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:65) [1758183005.383] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183005.383] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183005.470] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183005.472] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183005.472] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183005.473] [25004:25006] [EM] >>> [E:60647i S:50268 M:112433866] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:66) [1758183005.473] [25004:25006] [EM] Found matching exchange: 60647i, Delegate: 0xffff80017688 [1758183005.473] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183005.473] [25004:25006] [DMG] InvokeResponseMessage = [1758183005.473] [25004:25006] [DMG] { [1758183005.473] [25004:25006] [DMG] suppressResponse = false, [1758183005.473] [25004:25006] [DMG] InvokeResponseIBs = [1758183005.473] [25004:25006] [DMG] [ [1758183005.473] [25004:25006] [DMG] InvokeResponseIB = [1758183005.473] [25004:25006] [DMG] { [1758183005.473] [25004:25006] [DMG] CommandDataIB = [1758183005.474] [25004:25006] [DMG] { [1758183005.474] [25004:25006] [DMG] CommandPathIB = [1758183005.474] [25004:25006] [DMG] { [1758183005.474] [25004:25006] [DMG] EndpointId = 0x0, [1758183005.474] [25004:25006] [DMG] ClusterId = 0x30, [1758183005.474] [25004:25006] [DMG] CommandId = 0x1, [1758183005.474] [25004:25006] [DMG] }, [1758183005.474] [25004:25006] [DMG] [1758183005.474] [25004:25006] [DMG] CommandFields = [1758183005.474] [25004:25006] [DMG] { [1758183005.474] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183005.474] [25004:25006] [DMG] 0x1 = "" (0 chars), [1758183005.474] [25004:25006] [DMG] }, [1758183005.475] [25004:25006] [DMG] }, [1758183005.475] [25004:25006] [DMG] [1758183005.475] [25004:25006] [DMG] }, [1758183005.475] [25004:25006] [DMG] [1758183005.475] [25004:25006] [DMG] ], [1758183005.475] [25004:25006] [DMG] [1758183005.475] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183005.475] [25004:25006] [DMG] }, [1758183005.475] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1758183005.475] [25004:25006] [CTL] Received ArmFailSafe response errorCode=0 [1758183005.475] [25004:25006] [CTL] Successfully finished commissioning step 'FailsafeBeforeThreadEnable' [1758183005.475] [25004:25006] [CTL] Commissioning stage next step: 'FailsafeBeforeThreadEnable' -> 'ThreadNetworkEnable' [1758183005.475] [25004:25006] [CTL] Performing next commissioning step 'ThreadNetworkEnable' [1758183005.476] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183005.476] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183005.476] [25004:25006] [EM] <<< [E:60648i S:50268 M:176269987] (S) Msg TX from 0000000000000000 to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) (B:73) [1758183005.477] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183005.477] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183005.560] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183007.227] [25004:25005] [DL] Indication received, conn = 0xffff880261a0 [1758183007.227] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16391 [1758183007.227] [25004:25006] [EM] >>> [E:60648i S:50268 M:112433867] (S) Msg RX from 0:FFFFFFFB00000000 [0000] to 0000000000000000 --- Type 0001:09 (IM:InvokeCommandResponse) (B:65) [1758183007.227] [25004:25006] [EM] Found matching exchange: 60648i, Delegate: 0xffff80007388 [1758183007.227] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183007.227] [25004:25006] [DMG] InvokeResponseMessage = [1758183007.227] [25004:25006] [DMG] { [1758183007.227] [25004:25006] [DMG] suppressResponse = false, [1758183007.227] [25004:25006] [DMG] InvokeResponseIBs = [1758183007.228] [25004:25006] [DMG] [ [1758183007.228] [25004:25006] [DMG] InvokeResponseIB = [1758183007.228] [25004:25006] [DMG] { [1758183007.228] [25004:25006] [DMG] CommandDataIB = [1758183007.228] [25004:25006] [DMG] { [1758183007.228] [25004:25006] [DMG] CommandPathIB = [1758183007.228] [25004:25006] [DMG] { [1758183007.228] [25004:25006] [DMG] EndpointId = 0x0, [1758183007.228] [25004:25006] [DMG] ClusterId = 0x31, [1758183007.228] [25004:25006] [DMG] CommandId = 0x7, [1758183007.228] [25004:25006] [DMG] }, [1758183007.228] [25004:25006] [DMG] [1758183007.228] [25004:25006] [DMG] CommandFields = [1758183007.228] [25004:25006] [DMG] { [1758183007.228] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183007.228] [25004:25006] [DMG] 0x2 = NULL [1758183007.228] [25004:25006] [DMG] }, [1758183007.228] [25004:25006] [DMG] }, [1758183007.228] [25004:25006] [DMG] [1758183007.228] [25004:25006] [DMG] }, [1758183007.228] [25004:25006] [DMG] [1758183007.228] [25004:25006] [DMG] ], [1758183007.228] [25004:25006] [DMG] [1758183007.228] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183007.228] [25004:25006] [DMG] }, [1758183007.228] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1758183007.229] [25004:25006] [CTL] Received ConnectNetwork response, networkingStatus=0 [1758183007.229] [25004:25006] [CTL] Successfully finished commissioning step 'ThreadNetworkEnable' [1758183007.229] [25004:25006] [CTL] Commissioning stage next step: 'ThreadNetworkEnable' -> 'kEvictPreviousCaseSessions' [1758183007.229] [25004:25006] [CTL] Performing next commissioning step 'kEvictPreviousCaseSessions' [1758183007.229] [25004:25006] [IN] Expiring all sessions for node <0000000000000001, 1>!! [1758183007.229] [25004:25006] [CTL] Successfully finished commissioning step 'kEvictPreviousCaseSessions' [1758183007.229] [25004:25006] [CTL] Commissioning stage next step: 'kEvictPreviousCaseSessions' -> 'kFindOperationalForStayActive' [1758183007.229] [25004:25006] [CTL] Performing next commissioning step 'kFindOperationalForStayActive' [1758183007.229] [25004:25006] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000001] [1758183007.229] [25004:25006] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1758183007.229] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1758183007.229] [25004:25006] [DIS] Resolving 5687372BCD45D144:0000000000000001 ... [1758183007.230] [25004:25006] [DIS] Lookup started for 5687372BCD45D144-0000000000000001 [1758183007.230] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183007.430] [25004:25006] [DIS] Checking node lookup status for 5687372BCD45D144-0000000000000001 after 201 ms [1758183008.733] [25004:25006] [DL] Avahi resolve found [1758183008.734] [25004:25006] [DIS] Node ID resolved for 5687372BCD45D144-0000000000000001 [1758183008.734] [25004:25006] [DIS] Hostname: 4A7D4F62235339F5 [1758183008.734] [25004:25006] [DIS] IP Address #1: fd11:17::1f50:eff:5de9:e2c7 [1758183008.734] [25004:25006] [DIS] Port: 5540 [1758183008.734] [25004:25006] [DIS] Mrp Interval idle: 2000 ms [1758183008.734] [25004:25006] [DIS] Mrp Interval active: 2000 ms [1758183008.734] [25004:25006] [DIS] Mrp Active Threshold: 4000 ms [1758183008.734] [25004:25006] [DIS] TCP Client Supported: 0 [1758183008.734] [25004:25006] [DIS] TCP Server Supported: 0 [1758183008.734] [25004:25006] [DIS] ICD: not present [1758183008.736] [25004:25006] [DIS] Lookup clearing interface for non LL address [1758183008.736] [25004:25006] [DIS] UDP:[fd11:17::1f50:eff:5de9:e2c7%eth0]:5540: new best score: 5 (for 5687372BCD45D144-0000000000000001) [1758183008.737] [25004:25006] [DIS] Checking node lookup status for 5687372BCD45D144-0000000000000001 after 1507 ms [1758183008.740] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540 while in state 2 [1758183008.740] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1758183008.740] [25004:25006] [IN] SecureSession[0xffff800189e0]: Allocated Type:2 LSID:50269 [1758183008.740] [25004:25006] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1758183008.742] [25004:25006] [EM] <<< [E:60649i S:0 M:79882753] (U) Msg TX from 5EA08F5945E7A676 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1758183008.743] [25004:25006] [EM] ??1 [E:60649i S:0 M:79882753] (U) Msg Retransmission to 0:0000000000000000 in 2338ms [State:Idle II:2000 AI:2000 AT:4000] [1758183008.743] [25004:25006] [SC] Sent Sigma1 msg to <0000000000000001, 1> [II:500ms AI:300ms AT:4000ms] [1758183008.743] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1758183008.795] [25004:25006] [EM] >>> [E:60649i S:0 M:54858970 (Ack:79882753)] (U) Msg RX from 0:0000000000000000 [0000] to 5EA08F5945E7A676 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183008.795] [25004:25006] [EM] Found matching exchange: 60649i, Delegate: 0xffff80017e38 [1758183008.795] [25004:25006] [EM] Rxd Ack; Removing MessageCounter:79882753 from Retrans Table on exchange 60649i [1758183008.869] [25004:25006] [EM] >>> [E:60649i S:0 M:54858971 (Ack:79882753)] (U) Msg RX from 0:0000000000000000 [0000] to 5EA08F5945E7A676 --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1758183008.869] [25004:25006] [EM] Found matching exchange: 60649i, Delegate: 0xffff80017e38 [1758183008.869] [25004:25006] [EM] CHIP MessageCounter:79882753 not in RetransTable on exchange 60649i [1758183008.869] [25004:25006] [SC] Received Sigma2 msg [1758183008.870] [25004:25006] [SC] Peer assigned session session ID 37798 [1758183008.879] [25004:25006] [SC] Found MRP parameters in the message [1758183008.879] [25004:25006] [SC] Sending Sigma3 [1758183008.880] [25004:25006] [EM] <<< [E:60649i S:0 M:79882754 (Ack:54858971)] (U) Msg TX from 5EA08F5945E7A676 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1758183008.880] [25004:25006] [EM] ??1 [E:60649i S:0 M:79882754] (U) Msg Retransmission to 0:0000000000000000 in 2652ms [State:Active II:2000 AI:2000 AT:4000] [1758183008.880] [25004:25006] [SC] Sent Sigma3 msg [1758183008.968] [25004:25006] [EM] >>> [E:60649i S:0 M:54858972 (Ack:79882754)] (U) Msg RX from 0:0000000000000000 [0000] to 5EA08F5945E7A676 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183008.968] [25004:25006] [EM] Found matching exchange: 60649i, Delegate: 0xffff80017e38 [1758183008.968] [25004:25006] [EM] Rxd Ack; Removing MessageCounter:79882754 from Retrans Table on exchange 60649i [1758183009.268] [25004:25006] [EM] >>> [E:60649i S:0 M:54858973 (Ack:79882754)] (U) Msg RX from 0:0000000000000000 [0000] to 5EA08F5945E7A676 --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1758183009.268] [25004:25006] [EM] Found matching exchange: 60649i, Delegate: 0xffff80017e38 [1758183009.268] [25004:25006] [EM] CHIP MessageCounter:79882754 not in RetransTable on exchange 60649i [1758183009.268] [25004:25006] [SC] Success status report received. Session was established [1758183009.278] [25004:25006] [SC] SecureSession[0xffff800189e0, LSID:50269]: State change 'kEstablishing' --> 'kActive' [1758183009.278] [25004:25006] [IN] SecureSession[0xffff800189e0]: Activated - Type:2 LSID:50269 [1758183009.278] [25004:25006] [IN] New secure session activated for device <0000000000000001, 1>, LSID:50269 PSID:37798! [1758183009.278] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1758183009.279] [25004:25006] [CTL] Successfully finished commissioning step 'kFindOperationalForStayActive' [1758183009.279] [25004:25006] [CTL] Commissioning stage next step: 'kFindOperationalForStayActive' -> 'ICDSendStayActive' [1758183009.279] [25004:25006] [CTL] Performing next commissioning step 'ICDSendStayActive' [1758183009.279] [25004:25006] [CTL] Skipping kICDSendStayActive [1758183009.279] [25004:25006] [CTL] Successfully finished commissioning step 'ICDSendStayActive' [1758183009.279] [25004:25006] [CTL] Commissioning stage next step: 'ICDSendStayActive' -> 'kFindOperationalForCommissioningComplete' [1758183009.279] [25004:25006] [CTL] Performing next commissioning step 'kFindOperationalForCommissioningComplete' [1758183009.279] [25004:25006] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000001] [1758183009.279] [25004:25006] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1758183009.279] [25004:25006] [DIS] Found an existing secure session to [1:0000000000000001]! [1758183009.279] [25004:25006] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 1 --> 5 [1758183009.279] [25004:25006] [CTL] Successfully finished commissioning step 'kFindOperationalForCommissioningComplete' [1758183009.279] [25004:25006] [CTL] Commissioning stage next step: 'kFindOperationalForCommissioningComplete' -> 'SendComplete' [1758183009.279] [25004:25006] [CTL] Performing next commissioning step 'SendComplete' [1758183009.280] [25004:25006] [DMG] ICR moving to [AddingComm] [1758183009.280] [25004:25006] [DMG] ICR moving to [AddedComma] [1758183009.280] [25004:25006] [EM] <<< [E:60650i S:50269 M:175891282] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [D144] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:59) [1758183009.280] [25004:25006] [EM] ??1 [E:60650i S:50269 M:175891282] (S) Msg Retransmission to 1:0000000000000001 in 2536ms [State:Active II:2000 AI:2000 AT:4000] [1758183009.280] [25004:25006] [DMG] ICR moving to [AwaitingRe] [1758183009.280] [25004:25006] [EM] <<< [E:60649i S:0 M:79882755 (Ack:54858973)] (U) Msg TX from 5EA08F5945E7A676 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183009.281] [25004:25006] [EM] Flushed pending ack for MessageCounter:54858973 on exchange 60649i [1758183009.281] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 32792 [1758183009.789] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16389 [1758183010.112] [25004:25006] [EM] >>> [E:60650i S:50269 M:30958719 (Ack:175891282)] (S) Msg RX from 1:0000000000000001 [D144] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:70) [1758183010.112] [25004:25006] [EM] Found matching exchange: 60650i, Delegate: 0xffff80007388 [1758183010.113] [25004:25006] [EM] Rxd Ack; Removing MessageCounter:175891282 from Retrans Table on exchange 60650i [1758183010.113] [25004:25006] [DMG] ICR moving to [ResponseRe] [1758183010.113] [25004:25006] [DMG] InvokeResponseMessage = [1758183010.113] [25004:25006] [DMG] { [1758183010.113] [25004:25006] [DMG] suppressResponse = false, [1758183010.113] [25004:25006] [DMG] InvokeResponseIBs = [1758183010.113] [25004:25006] [DMG] [ [1758183010.113] [25004:25006] [DMG] InvokeResponseIB = [1758183010.113] [25004:25006] [DMG] { [1758183010.113] [25004:25006] [DMG] CommandDataIB = [1758183010.113] [25004:25006] [DMG] { [1758183010.113] [25004:25006] [DMG] CommandPathIB = [1758183010.114] [25004:25006] [DMG] { [1758183010.114] [25004:25006] [DMG] EndpointId = 0x0, [1758183010.114] [25004:25006] [DMG] ClusterId = 0x30, [1758183010.114] [25004:25006] [DMG] CommandId = 0x5, [1758183010.114] [25004:25006] [DMG] }, [1758183010.114] [25004:25006] [DMG] [1758183010.114] [25004:25006] [DMG] CommandFields = [1758183010.114] [25004:25006] [DMG] { [1758183010.114] [25004:25006] [DMG] 0x0 = 0 (unsigned), [1758183010.114] [25004:25006] [DMG] 0x1 = "" (0 chars), [1758183010.115] [25004:25006] [DMG] }, [1758183010.115] [25004:25006] [DMG] }, [1758183010.115] [25004:25006] [DMG] [1758183010.115] [25004:25006] [DMG] }, [1758183010.115] [25004:25006] [DMG] [1758183010.115] [25004:25006] [DMG] ], [1758183010.115] [25004:25006] [DMG] [1758183010.115] [25004:25006] [DMG] InteractionModelRevision = 12 [1758183010.115] [25004:25006] [DMG] }, [1758183010.115] [25004:25006] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005 [1758183010.115] [25004:25006] [CTL] Received CommissioningComplete response, errorCode=0 [1758183010.115] [25004:25006] [CTL] Successfully finished commissioning step 'SendComplete' [1758183010.116] [25004:25006] [CTL] Commissioning stage next step: 'SendComplete' -> 'Cleanup' [1758183010.116] [25004:25006] [CTL] Performing next commissioning step 'Cleanup' [1758183010.116] [25004:25006] [CTL] Successfully finished commissioning step 'Cleanup' [1758183010.116] [25004:25006] [DIS] Closing all BLE connections [1758183010.116] [25004:25006] [IN] Clearing BLE pending packets. [1758183010.117] [25004:25006] [BLE] Auto-closing end point's BLE connection. [1758183010.117] [25004:25006] [DL] Closing BLE GATT connection (con 0xffff880261a0) [1758183010.117] [25004:25005] [DL] Close BLE connection: peer=CC:CB:F9:A7:68:6A [1758183010.422] [25004:25006] [IN] SecureSession[0xffff80014e90]: MarkForEviction Type:1 LSID:50268 [1758183010.422] [25004:25006] [SC] SecureSession[0xffff80014e90, LSID:50268]: State change 'kActive' --> 'kPendingEviction' [1758183010.423] [25004:25006] [IN] SecureSession[0xffff80014e90]: Released - Type:1 LSID:50268 [1758183010.423] [25004:25006] [CTL] Commissioning complete for node ID 0x0000000000000001: success [1758183010.423] [25004:25006] [TOO] Device commissioning completed with success [1758183010.423] [25004:25005] [DL] BLE connection closed: conn=0xffff880261a0 [1758183010.423] [25004:25006] [DMG] ICR moving to [AwaitingDe] [1758183010.423] [25004:25006] [EM] <<< [E:60650i S:50269 M:175891283 (Ack:30958719)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [D144] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1758183010.424] [25004:25006] [EM] Flushed pending ack for MessageCounter:30958719 on exchange 60650i [1758183010.424] [25004:25006] [DL] HandlePlatformSpecificBLEEvent 16390 [1758183010.424] [25004:25006] [BLE] No endpoint for unsubscribe complete [1758183010.424] [25004:25006] [BLE] No endpoint for connection error [1758183010.425] [25004:25004] [CTL] Shutting down the commissioner [1758183010.425] [25004:25004] [CTL] Shutting down the controller [1758183010.425] [25004:25004] [IN] Expiring all sessions for fabric 0x1!! [1758183010.425] [25004:25004] [IN] SecureSession[0xffff800189e0]: MarkForEviction Type:2 LSID:50269 [1758183010.425] [25004:25004] [SC] SecureSession[0xffff800189e0, LSID:50269]: State change 'kActive' --> 'kPendingEviction' [1758183010.426] [25004:25004] [IN] SecureSession[0xffff800189e0]: Released - Type:2 LSID:50269 [1758183010.426] [25004:25004] [FP] Forgetting fabric 0x1 [1758183010.426] [25004:25004] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1758183010.426] [25004:25004] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1758183010.426] [25004:25004] [TS] Reverted Last Known Good Time to previous value [1758183010.426] [25004:25004] [CTL] Shutting down the commissioner [1758183010.426] [25004:25004] [CTL] Shutting down the controller [1758183010.427] [25004:25004] [CTL] Shutting down the System State, this will teardown the CHIP Stack [1758183010.427] [25004:25004] [DMG] All ReadHandler-s are clean, clear GlobalDirtySet [1758183010.427] [25004:25004] [FP] Shutting down FabricTable [1758183010.427] [25004:25004] [TS] Pending Last Known Good Time: 2023-10-14T01:16:48 [1758183010.427] [25004:25004] [TS] Previous Last Known Good Time: 2023-10-14T01:16:48 [1758183010.427] [25004:25004] [TS] Reverted Last Known Good Time to previous value [1758183010.441] [25004:25004] [DL] Wrote settings to /tmp/chip_counters.ini [1758183010.441] [25004:25004] [DL] NVS set: chip-counters/total-operational-hours = 0 (0x0) [1758183010.441] [25004:25004] [DL] Inet Layer shutdown [1758183010.441] [25004:25004] [DL] BLE Layer shutdown [1758183010.443] [25004:25004] [DL] System Layer shutdown ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ ubuntu@ubuntu:~/apps$ sudo ./chip-tool interactive start sudo ./chip-tool interactive start [1758183191.736] [25031:25031] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1758183191.738] [25031:25031] [DL] ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1758183191.743] [25031:25031] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1758183191.743] [25031:25031] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1758183191.743] [25031:25031] [DL] ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1758183191.757] [25031:25031] [DL] Wrote settings to /tmp/chip_counters.ini [1758183191.757] [25031:25031] [DL] NVS set: chip-counters/reboot-count = 2 (0x2) [1758183191.758] [25031:25031] [DL] Got Ethernet interface: eth0 [1758183191.759] [25031:25031] [DL] Found the primary Ethernet interface:eth0 [1758183191.761] [25031:25031] [DL] Got WiFi interface: wlan0 [1758183191.761] [25031:25031] [DL] Failed to reset WiFi statistic counts [1758183191.761] [25031:25031] [IN] UDP::Init bind&listen port=0 [1758183191.761] [25031:25031] [IN] UDP::Init bound to port=33137 [1758183191.762] [25031:25031] [IN] BLEBase::Init - setting/overriding transport [1758183191.762] [25031:25031] [IN] TransportMgr initialized [1758183191.762] [25031:25031] [FP] Initializing FabricTable from persistent storage [1758183191.762] [25031:25031] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1758183191.765] [25031:25031] [FP] Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x5687372BCD45D144, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1758183191.776] [25031:25031] [ZCL] Using ZAP configuration... [1758183191.782] [25031:25031] [DL] Avahi client registered [1758183191.783] [25031:25031] [IN] CASE Server enabling CASE session setups [1758183191.783] [25031:25031] [IN] SecureSession[0xaaab0a963af0]: Allocated Type:2 LSID:16437 [1758183191.783] [25031:25031] [SC] Allocated SecureSession (0xaaab0a963af0) - waiting for Sigma1 msg [1758183191.783] [25031:25031] [IM] WARNING [1758183191.783] [25031:25031] [IM] WARNING Interaction Model Engine running in 'Checked' mode. [1758183191.783] [25031:25031] [IM] WARNING This executes BOTH ember and data-model code paths. [1758183191.783] [25031:25031] [IM] WARNING which is inefficient and consumes more flash space. [1758183191.783] [25031:25031] [IM] WARNING This should be done for testing only. [1758183191.783] [25031:25031] [IM] WARNING [1758183191.783] [25031:25031] [CTL] System State Initialized... [1758183191.784] [25031:25031] [CTL] Setting attestation nonce to random value [1758183191.784] [25031:25031] [CTL] Setting CSR nonce to random value [1758183191.784] [25031:25031] [IN] UDP::Init bind&listen port=5550 [1758183191.784] [25031:25031] [IN] UDP::Init bound to port=5550 [1758183191.784] [25031:25031] [IN] TransportMgr initialized [1758183191.785] [25031:25033] [DL] CHIP task running >>> [1758183191.785] [25031:25033] [DL] HandlePlatformSpecificBLEEvent 32786 diagnosticlogs retrieve-logs-request 0 1 1 0 --TransferFileDesignator Length_123456789123456789123.txt [1758183217.426] [25031:25031] [TOO] Command: diagnosticlogs retrieve-logs-request 0 1 1 0 --TransferFileDesignator Length_123456789123456789123.txt [1758183217.427] [25031:25033] [CTL] Setting attestation nonce to random value [1758183217.427] [25031:25033] [CTL] Setting CSR nonce to random value [1758183217.430] [25031:25033] [CTL] Generating NOC [1758183217.432] [25031:25033] [FP] Validating NOC chain [1758183217.437] [25031:25033] [FP] NOC chain validation successful [1758183217.437] [25031:25033] [FP] Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1758183217.437] [25031:25033] [TS] Last Known Good Time: 2023-10-14T01:16:48 [1758183217.437] [25031:25033] [TS] New proposed Last Known Good Time: 2021-01-01T00:00:00 [1758183217.437] [25031:25033] [TS] Retaining current Last Known Good Time [1758183217.456] [25031:25033] [FP] Metadata for Fabric 0x1 persisted to storage. [1758183217.459] [25031:25033] [TS] Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1758183217.464] [25031:25033] [CTL] Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 5687372BCD45D144) [1758183217.465] [25031:25033] [IN] UDP::Init bind&listen port=5550 [1758183217.465] [25031:25033] [IN] UDP::Init bound to port=5550 [1758183217.465] [25031:25033] [IN] TransportMgr initialized [1758183217.465] [25031:25033] [DIS] Updating services using commissioning mode 0 [1758183217.466] [25031:25033] [DL] Using WiFi MAC for hostname [1758183217.466] [25031:25033] [DIS] Advertise operational node 5687372BCD45D144-000000000001B669 [1758183217.466] [25031:25033] [DL] PublishService 5687372BCD45D144-000000000001B669._matter._tcp33137.-1 [1758183217.470] [25031:25033] [DL] Using addresses from interface id=0 name= [1758183217.494] [25031:25033] [DIS] mDNS service published: _matter._tcp; instance name: 5687372BCD45D144-000000000001B669 [1758183217.495] [25031:25033] [DL] Using WiFi MAC for hostname [1758183217.495] [25031:25033] [DIS] Advertise commission parameter vendorID=65521 productID=32769 discriminator=0000/00 cm=0 cp=0 [1758183217.495] [25031:25033] [DL] PublishService 9770EAD196699821._matterd._udp5550.-1 [1758183217.497] [25031:25033] [DL] Using addresses from interface id=0 name= [1758183217.521] [25031:25033] [DIS] mDNS service published: _matterd._udp; instance name: 9770EAD196699821 [1758183217.547] [25031:25033] [TOO] Sending command to node 0x1 [1758183217.548] [25031:25033] [CSM] FindOrEstablishSession: PeerId = [1:0000000000000001] [1758183217.548] [25031:25033] [CSM] FindOrEstablishSession: No existing OperationalSessionSetup instance found [1758183217.548] [25031:25033] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1758183217.548] [25031:25033] [DIS] Resolving 5687372BCD45D144:0000000000000001 ... [1758183217.549] [25031:25033] [DIS] Lookup started for 5687372BCD45D144-0000000000000001 [1758183217.549] [25031:25033] [DL] Long dispatch time: 123 ms, for event type 2 [1758183217.550] [25031:25033] [DL] Avahi resolve found [1758183217.550] [25031:25033] [DIS] Node ID resolved for 5687372BCD45D144-0000000000000001 [1758183217.550] [25031:25033] [DIS] Hostname: 4A7D4F62235339F5 [1758183217.550] [25031:25033] [DIS] IP Address #1: fd11:17::1f50:eff:5de9:e2c7 [1758183217.550] [25031:25033] [DIS] Port: 5540 [1758183217.550] [25031:25033] [DIS] Mrp Interval idle: 2000 ms [1758183217.550] [25031:25033] [DIS] Mrp Interval active: 2000 ms [1758183217.550] [25031:25033] [DIS] Mrp Active Threshold: 4000 ms [1758183217.551] [25031:25033] [DIS] TCP Client Supported: 0 [1758183217.551] [25031:25033] [DIS] TCP Server Supported: 0 [1758183217.551] [25031:25033] [DIS] ICD: not present [1758183217.551] [25031:25033] [DIS] Lookup clearing interface for non LL address [1758183217.551] [25031:25033] [DIS] UDP:[fd11:17::1f50:eff:5de9:e2c7%veth8fdaa24]:5540: new best score: 5 (for 5687372BCD45D144-0000000000000001) [1758183217.551] [25031:25033] [DIS] Checking node lookup status for 5687372BCD45D144-0000000000000001 after 3 ms [1758183217.551] [25031:25033] [DIS] Keeping DNSSD lookup active [1758183217.748] [25031:25033] [DIS] Checking node lookup status for 5687372BCD45D144-0000000000000001 after 200 ms [1758183217.748] [25031:25033] [DIS] OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540 while in state 2 [1758183217.748] [25031:25033] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3 [1758183217.749] [25031:25033] [IN] SecureSession[0xffff7000e0e0]: Allocated Type:2 LSID:16438 [1758183217.749] [25031:25033] [SC] Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001 [1758183217.751] [25031:25033] [EM] <<< [E:53821i S:0 M:173134616] (U) Msg TX from 05A33ECABE0EE605 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) (B:196) [1758183217.752] [25031:25033] [EM] ??1 [E:53821i S:0 M:173134616] (U) Msg Retransmission to 0:0000000000000000 in 2484ms [State:Idle II:2000 AI:2000 AT:4000] [1758183217.752] [25031:25033] [SC] Sent Sigma1 msg to <0000000000000001, 1> [II:500ms AI:300ms AT:4000ms] [1758183217.752] [25031:25033] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4 [1758183217.818] [25031:25033] [EM] >>> [E:53821i S:0 M:54858974 (Ack:173134616)] (U) Msg RX from 0:0000000000000000 [0000] to 05A33ECABE0EE605 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183217.819] [25031:25033] [EM] Found matching exchange: 53821i, Delegate: 0xffff7000ddf8 [1758183217.819] [25031:25033] [EM] Rxd Ack; Removing MessageCounter:173134616 from Retrans Table on exchange 53821i [1758183217.888] [25031:25033] [EM] >>> [E:53821i S:0 M:54858975 (Ack:173134616)] (U) Msg RX from 0:0000000000000000 [0000] to 05A33ECABE0EE605 --- Type 0000:31 (SecureChannel:CASE_Sigma2) (B:751) [1758183217.888] [25031:25033] [EM] Found matching exchange: 53821i, Delegate: 0xffff7000ddf8 [1758183217.888] [25031:25033] [EM] CHIP MessageCounter:173134616 not in RetransTable on exchange 53821i [1758183217.888] [25031:25033] [SC] Received Sigma2 msg [1758183217.888] [25031:25033] [SC] Peer assigned session session ID 37799 [1758183217.897] [25031:25033] [SC] Found MRP parameters in the message [1758183217.897] [25031:25033] [SC] Sending Sigma3 [1758183217.898] [25031:25033] [EM] <<< [E:53821i S:0 M:173134617 (Ack:54858975)] (U) Msg TX from 05A33ECABE0EE605 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) (B:598) [1758183217.898] [25031:25033] [EM] ??1 [E:53821i S:0 M:173134617] (U) Msg Retransmission to 0:0000000000000000 in 2347ms [State:Active II:2000 AI:2000 AT:4000] [1758183217.898] [25031:25033] [SC] Sent Sigma3 msg [1758183217.964] [25031:25033] [EM] >>> [E:53821i S:0 M:54858976 (Ack:173134617)] (U) Msg RX from 0:0000000000000000 [0000] to 05A33ECABE0EE605 --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183217.964] [25031:25033] [EM] Found matching exchange: 53821i, Delegate: 0xffff7000ddf8 [1758183217.964] [25031:25033] [EM] Rxd Ack; Removing MessageCounter:173134617 from Retrans Table on exchange 53821i [1758183218.166] [25031:25033] [EM] >>> [E:53821i S:0 M:54858977 (Ack:173134617)] (U) Msg RX from 0:0000000000000000 [0000] to 05A33ECABE0EE605 --- Type 0000:40 (SecureChannel:StatusReport) (B:34) [1758183218.166] [25031:25033] [EM] Found matching exchange: 53821i, Delegate: 0xffff7000ddf8 [1758183218.166] [25031:25033] [EM] CHIP MessageCounter:173134617 not in RetransTable on exchange 53821i [1758183218.166] [25031:25033] [SC] Success status report received. Session was established [1758183218.180] [25031:25033] [SC] SecureSession[0xffff7000e0e0, LSID:16438]: State change 'kEstablishing' --> 'kActive' [1758183218.180] [25031:25033] [IN] SecureSession[0xffff7000e0e0]: Activated - Type:2 LSID:16438 [1758183218.180] [25031:25033] [IN] New secure session activated for device <0000000000000001, 1>, LSID:16438 PSID:37799! [1758183218.180] [25031:25033] [DIS] OperationalSessionSetup[1:0000000000000001]: State change 4 --> 5 [1758183218.180] [25031:25033] [TOO] Sending cluster (0x00000032) command (0x00000000) on endpoint 0 [1758183218.180] [25031:25033] [DMG] ICR moving to [AddingComm] [1758183218.181] [25031:25033] [DMG] ICR moving to [AddedComma] [1758183218.181] [25031:25033] [EM] <<< [E:53822i S:16438 M:100833485] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [D144] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) (B:100) [1758183218.181] [25031:25033] [EM] ??1 [E:53822i S:16438 M:100833485] (S) Msg Retransmission to 1:0000000000000001 in 2637ms [State:Active II:2000 AI:2000 AT:4000] [1758183218.181] [25031:25033] [DMG] ICR moving to [AwaitingRe] [1758183218.181] [25031:25033] [EM] <<< [E:53821i S:0 M:173134618 (Ack:54858977)] (U) Msg TX from 05A33ECABE0EE605 to 0:0000000000000000 [0000] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:26) [1758183218.181] [25031:25033] [EM] Flushed pending ack for MessageCounter:54858977 on exchange 53821i [1758183218.182] [25031:25033] [DL] HandlePlatformSpecificBLEEvent 32792 [1758183218.311] [25031:25033] [EM] >>> [E:53822i S:16438 M:109499124 (Ack:100833485)] (S) Msg RX from 1:0000000000000001 [D144] to 000000000001B669 --- Type 0001:09 (IM:InvokeCommandResponse) (B:1083) [1758183218.311] [25031:25033] [EM] Found matching exchange: 53822i, Delegate: 0xffff700081b8 [1758183218.311] [25031:25033] [EM] Rxd Ack; Removing MessageCounter:100833485 from Retrans Table on exchange 53822i [1758183218.311] [25031:25033] [DMG] ICR moving to [ResponseRe] [1758183218.311] [25031:25033] [DMG] InvokeResponseMessage = [1758183218.312] [25031:25033] [DMG] { [1758183218.312] [25031:25033] [DMG] suppressResponse = false, [1758183218.312] [25031:25033] [DMG] InvokeResponseIBs = [1758183218.312] [25031:25033] [DMG] [ [1758183218.312] [25031:25033] [DMG] InvokeResponseIB = [1758183218.312] [25031:25033] [DMG] { [1758183218.312] [25031:25033] [DMG] CommandDataIB = [1758183218.312] [25031:25033] [DMG] { [1758183218.312] [25031:25033] [DMG] CommandPathIB = [1758183218.312] [25031:25033] [DMG] { [1758183218.312] [25031:25033] [DMG] EndpointId = 0x0, [1758183218.312] [25031:25033] [DMG] ClusterId = 0x32, [1758183218.312] [25031:25033] [DMG] CommandId = 0x1, [1758183218.312] [25031:25033] [DMG] }, [1758183218.312] [25031:25033] [DMG] [1758183218.312] [25031:25033] [DMG] CommandFields = [1758183218.312] [25031:25033] [DMG] { [1758183218.312] [25031:25033] [DMG] 0x0 = 1 (unsigned), [1758183218.313] [25031:25033] [DMG] 0x1 = [ [1758183218.313] [25031:25033] [DMG] 0x70, 0x3a, 0x20, 0x62, 0x6c, 0x65, 0x20, 0x63, 0x6f, 0x6e, 0x6e, 0x65, 0x63, 0x74, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x35, 0x31, 0x38, 0x2c, 0x31, 0x36, 0x35, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x61, 0x70, 0x70, 0x3a, 0x20, 0x6d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x70, 0x72, 0x6f, 0x76, 0x69, 0x73, 0x69, 0x6f, 0x6e, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x35, 0x32, 0x30, 0x2c, 0x38, 0x35, 0x36, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x61, 0x70, 0x70, 0x3a, 0x20, 0x62, 0x6c, 0x65, 0x20, 0x63, 0x6f, 0x6e, 0x6e, 0x65, 0x63, 0x74, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x35, 0x32, 0x30, 0x2c, 0x38, 0x36, 0x33, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x61, 0x70, 0x70, 0x3a, 0x20, 0x6d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x70, 0x72, 0x6f, 0x76, 0x69, 0x73, 0x69, 0x6f, 0x6e, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x35, 0x32, 0x32, 0x2c, 0x34, 0x32, 0x35, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x61, 0x70, 0x70, 0x3a, 0x20, 0x62, 0x6c, 0x65, 0x20, 0x63, 0x6f, 0x6e, 0x6e, 0x65, 0x63, 0x74, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x35, 0x32, 0x32, 0x2c, 0x34, 0x33, 0x30, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x61, 0x70, 0x70, 0x3a, 0x20, 0x6d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x70, 0x72, 0x6f, 0x76, 0x69, 0x73, 0x69, 0x6f, 0x6e, 0x65, 0x64, 0x0d, 0x0a, 0x5b, 0x30, 0x30, 0x3a, 0x30, 0x34, 0x3a, 0x30, 0x37, 0x2e, 0x38, 0x37, 0x38, 0x2c, 0x38, 0x33, 0x38, 0x5d, 0x20, 0x3c, 0x69, 0x6e, 0x66, 0x3e, 0x20, 0x [1758183218.313] [25031:25033] [DMG] ] (1012 bytes) [1758183218.313] [25031:25033] [DMG] }, [1758183218.313] [25031:25033] [DMG] }, [1758183218.313] [25031:25033] [DMG] [1758183218.313] [25031:25033] [DMG] }, [1758183218.313] [25031:25033] [DMG] [1758183218.313] [25031:25033] [DMG] ], [1758183218.313] [25031:25033] [DMG] [1758183218.313] [25031:25033] [DMG] InteractionModelRevision = 12 [1758183218.313] [25031:25033] [DMG] }, [1758183218.313] [25031:25033] [DMG] Received Command Response Data, Endpoint=0 Cluster=0x0000_0032 Command=0x0000_0001 [1758183218.313] [25031:25033] [TOO] Endpoint: 0 Cluster: 0x0000_0032 Command 0x0000_0001 [1758183218.314] [25031:25033] [TOO] RetrieveLogsResponse: { [1758183218.314] [25031:25033] [TOO] status: 1 [1758183218.314] [25031:25033] [TOO] logContent: 703A20626C6520636F6E6E65637465640D0A5B30303A30343A30372E3531382C3136355D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A30372E3532302C3835365D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A30372E3532302C3836335D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A30372E3532322C3432355D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A30372E3532322C3433305D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A30372E3837382C3833385D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A30372E3837382C3834355D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A30372E3838322C3139375D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A30372E3838322C3230335D203C69 [1758183218.314] [25031:25033] [TOO] ..........: 6E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A30392E3536392C3137305D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A30392E3536392C3137365D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A31302E3437392C3038385D203C696E663E206170703A20436F6D6D697373696F6E696E6720636F6D706C65746564207375636365737366756C6C790A0D0A5B30303A30343A31302E3437392C3039365D203C696E663E206170703A206E6F74696679206D637520636F6D6D6973696F6E696E6720737563636573730A0D0A5B30303A30343A31302E3437392C3130315D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A31302E3437392C3130375D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30343A31302E3534332C3331375D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30343A31302E3534332C3332345D203C696E [1758183218.314] [25031:25033] [TOO] ..........: 663E206170703A206D61747465722070726F766973696F6E65640D0A5B30303A30353A33312E3539312C3037365D203C696E663E206170703A20746872656164207374617465206368616E67650D0A5B30303A30353A33312E3539312C3038335D203C696E663E206170703A20626C6520636F6E6E65637465640D0A5B30303A30353A33312E3539312C3038395D203C696E663E206170703A206D61747465722070726F766973696F6E65640D0A [1758183218.314] [25031:25033] [TOO] } [1758183218.314] [25031:25033] [DMG] ICR moving to [AwaitingDe] [1758183218.314] [25031:25033] [EM] <<< [E:53822i S:16438 M:100833486 (Ack:109499124)] (S) Msg TX from 000000000001B669 to 1:0000000000000001 [D144] [UDP:[fd11:17::1f50:eff:5de9:e2c7]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) (B:34) [1758183218.315] [25031:25033] [EM] Flushed pending ack for MessageCounter:109499124 on exchange 53822i >>> [1758183218.359] [25031:25033] [DL] Avahi group established [1758183218.386] [25031:25033] [DL] Avahi group established