Hi,
I want to test Matter over Thread, with 'light bulb' example and Border Router and controller In the same device.
To do this I need to configure CHIP, but the configuration failed.
Environment:
- PCA10056 with light bulb
- PCA10059 with radio co-proccesor
- Linux virtual machine (using VirtualBox Oracle) in a Windows 11 PC, to run Docker and CHIP.
Test steps:
- Run light bulb in PCA10056 and radio co-processor In PCA10059
- Running OTBR using Docker, following: https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/nrf/protocols/thread/tools.html#id10
- Build Matter, https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/matter/BUILDING.html
git clone --recurse-submodules [email protected]:project-chip/connectedhomeip.git git submodule update --init sudo apt-get install git gcc g++ pkg-config libssl-dev libdbus-1-dev \ libglib2.0-dev libavahi-client-dev ninja-build python3-venv python3-dev \ python3-pip unzip libgirepository1.0-dev libcairo2-dev libreadline-dev sudo apt-get install libsdl2-dev source scripts/activate.sh gn gen out/hostninja -C out/host ninja -C out/host check ./scripts/examples/gn_build_example.sh examples/chip-tool BUILD_PATH./chip-tool
- Using CHIP Tool for Matter device testing, following the steps in https://developer.nordicsemi.com/nRF_Connect_SDK/doc/latest/matter/chip_tool_guide.html
./chip-tool pairing ble-thread 1 hex: <HEX GENERATED> 20202021 3840 /chip-tool onoff toggle 1 1
But the commissioning failed:
[1685017302.407046][7164:7164] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1685017302.408518][7164:7164] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1685017302.408608][7164:7164] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1685017302.408621][7164:7164] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1685017302.408734][7164:7164] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-wC3EQR)
[1685017302.408958][7164:7164] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1685017302.408976][7164:7164] CHIP:DL: NVS set: chip-counters/reboot-count = 10 (0xA)
[1685017302.409574][7164:7164] CHIP:DL: Got Ethernet interface: enp0s8
[1685017302.409830][7164:7164] CHIP:DL: Found the primary Ethernet interface:enp0s8
[1685017302.410741][7164:7164] CHIP:DL: Failed to get WiFi interface
[1685017302.410749][7164:7164] CHIP:DL: Failed to reset WiFi statistic counts
[1685017302.410773][7164:7164] CHIP:IN: UDP::Init bind&listen port=0
[1685017302.410789][7164:7164] CHIP:IN: UDP::Init bound to port=54312
[1685017302.410793][7164:7164] CHIP:IN: UDP::Init bind&listen port=0
[1685017302.410803][7164:7164] CHIP:IN: UDP::Init bound to port=58876
[1685017302.410807][7164:7164] CHIP:IN: BLEBase::Init - setting/overriding transport
[1685017302.410811][7164:7164] CHIP:IN: TransportMgr initialized
[1685017302.410834][7164:7164] CHIP:FP: Initializing FabricTable from persistent storage
[1685017302.410877][7164:7164] CHIP:TS: Last Known Good Time: 2023-05-25T11:03:16
[1685017302.411231][7164:7164] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x466BD5809740C9A4, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
[1685017302.418134][7164:7164] CHIP:ZCL: Using ZAP configuration...
[1685017302.420776][7164:7164] CHIP:CTL: System State Initialized...
[1685017302.420855][7164:7164] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1685017302.420902][7164:7164] CHIP:CTL: Setting attestation nonce to random value
[1685017302.420935][7164:7164] CHIP:CTL: Setting CSR nonce to random value
[1685017302.420960][7164:7164] CHIP:IN: UDP::Init bind&listen port=5550
[1685017302.420984][7164:7164] CHIP:IN: UDP::Init bound to port=5550
[1685017302.420999][7164:7164] CHIP:IN: UDP::Init bind&listen port=5550
[1685017302.421021][7164:7164] CHIP:IN: UDP::Init bound to port=5550
[1685017302.421036][7164:7164] CHIP:IN: TransportMgr initialized
[1685017302.421358][7164:7166] CHIP:DL: CHIP task running
[1685017302.421461][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 32784
[1685017302.421577][7164:7166] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1685017302.421652][7164:7166] CHIP:CTL: Setting attestation nonce to random value
[1685017302.421691][7164:7166] CHIP:CTL: Setting CSR nonce to random value
[1685017302.422007][7164:7166] CHIP:CTL: Generating NOC
[1685017302.422252][7164:7166] CHIP:FP: Validating NOC chain
[1685017302.422546][7164:7166] CHIP:FP: NOC chain validation successful
[1685017302.422617][7164:7166] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669
[1685017302.422625][7164:7166] CHIP:TS: Last Known Good Time: 2023-05-25T11:03:16
[1685017302.422629][7164:7166] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
[1685017302.422633][7164:7166] CHIP:TS: Retaining current Last Known Good Time
[1685017302.423523][7164:7166] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
[1685017302.425626][7164:7166] CHIP:TS: Committing Last Known Good Time to storage: 2023-05-25T11:03:16
[1685017302.426678][7164:7166] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 466BD5809740C9A4)
[1685017302.426716][7164:7166] CHIP:IN: UDP::Init bind&listen port=5550
[1685017302.426730][7164:7166] CHIP:IN: UDP::Init bound to port=5550
[1685017302.426732][7164:7166] CHIP:IN: UDP::Init bind&listen port=5550
[1685017302.426738][7164:7166] CHIP:IN: UDP::Init bound to port=5550
[1685017302.426739][7164:7166] CHIP:IN: TransportMgr initialized
[1685017302.431445][7164:7166] CHIP:CTL: Setting thread operational dataset from parameters
[1685017302.431559][7164:7166] CHIP:CTL: Setting attestation nonce to random value
[1685017302.431568][7164:7166] CHIP:CTL: Setting CSR nonce to random value
[1685017302.431571][7164:7166] CHIP:CTL: Commission called for node ID 0x0000000000000001
[1685017302.433957][7164:7165] CHIP:DL: TRACE: Bus acquired for name C-1bfc
[1685017302.436276][7164:7166] CHIP:DL: PlatformBlueZInit init success
[1685017302.438096][7164:7165] CHIP:BLE: BLE removing known devices.
[1685017302.440852][7164:7165] CHIP:BLE: BLE initiating scan.
[1685017302.569612][7164:7165] CHIP:BLE: Device 71:89:01:DF:D4:0F does not look like a CHIP device.
[1685017302.569797][7164:7165] CHIP:BLE: Device 49:90:D7:F0:BF:AF does not look like a CHIP device.
[1685017302.569930][7164:7165] CHIP:BLE: Device 61:FB:36:A9:E4:8B does not look like a CHIP device.
[1685017302.603234][7164:7165] CHIP:BLE: Device DA:C7:D8:31:75:72 does not look like a CHIP device.
[1685017302.768769][7164:7165] CHIP:BLE: Device 46:BC:14:42:F8:9C does not look like a CHIP device.
[1685017302.909510][7164:7165] CHIP:BLE: Device F8:04:2E:BF:12:6F does not look like a CHIP device.
[1685017302.909682][7164:7165] CHIP:BLE: Device 31:98:21:A2:32:FE does not look like a CHIP device.
[1685017303.046906][7164:7165] CHIP:BLE: Device 06:23:7C:C9:86:E4 does not look like a CHIP device.
[1685017303.072700][7164:7165] CHIP:BLE: Device 5C:88:E7:CC:A1:23 does not look like a CHIP device.
[1685017303.072979][7164:7165] CHIP:BLE: Device 6D:56:CB:65:1F:BB does not look like a CHIP device.
[1685017303.103993][7164:7165] CHIP:BLE: Device 71:89:01:DF:D4:0F does not look like a CHIP device.
[1685017303.165368][7164:7165] CHIP:BLE: Device 47:96:20:DB:E4:DA does not look like a CHIP device.
[1685017303.165602][7164:7165] CHIP:BLE: Device 17:54:E4:00:B8:4A does not look like a CHIP device.
[1685017303.194150][7164:7165] CHIP:BLE: Device C0:97:27:4E:83:C3 does not look like a CHIP device.
[1685017303.194377][7164:7165] CHIP:BLE: Device F4:FE:FB:75:E4:0D does not look like a CHIP device.
[1685017303.389456][7164:7165] CHIP:BLE: Device A8:B1:3B:3F:69:16 does not look like a CHIP device.
[1685017303.389770][7164:7165] CHIP:BLE: Device 7C:1C:4E:8A:6F:79 does not look like a CHIP device.
[1685017303.389929][7164:7165] CHIP:BLE: Device 40:24:B2:29:7B:55 does not look like a CHIP device.
[1685017303.447756][7164:7165] CHIP:BLE: Device 47:26:BA:38:28:12 does not look like a CHIP device.
[1685017303.542549][7164:7165] CHIP:BLE: Device A0:9E:1A:1C:06:5B does not look like a CHIP device.
[1685017303.542668][7164:7165] CHIP:BLE: Device 5C:88:E7:CC:A1:23 does not look like a CHIP device.
[1685017303.570851][7164:7165] CHIP:BLE: Device F8:04:2E:BF:12:6F does not look like a CHIP device.
[1685017303.624924][7164:7165] CHIP:BLE: Device 49:90:D7:F0:BF:AF does not look like a CHIP device.
[1685017303.626276][7164:7165] CHIP:BLE: Device 70:09:71:AB:3C:FD does not look like a CHIP device.
[1685017303.626440][7164:7165] CHIP:BLE: Device 54:5D:D9:75:63:0B does not look like a CHIP device.
[1685017303.626570][7164:7165] CHIP:BLE: Device 4C:F0:DC:80:76:A2 does not look like a CHIP device.
[1685017303.694486][7164:7165] CHIP:BLE: Device A8:B1:3B:3F:69:16 does not look like a CHIP device.
[1685017303.694850][7164:7165] CHIP:BLE: Device F8:04:2E:BF:12:6F does not look like a CHIP device.
[1685017303.695269][7164:7165] CHIP:BLE: Device 1D:CE:55:AE:22:02 does not look like a CHIP device.
[1685017303.789196][7164:7165] CHIP:BLE: Device 4D:DB:70:50:F4:A3 does not look like a CHIP device.
[1685017303.814071][7164:7165] CHIP:BLE: New device scanned: FA:FE:EA:D2:27:71
[1685017303.814206][7164:7165] CHIP:BLE: Device discriminator match. Attempting to connect.
[1685017306.193297][7164:7165] CHIP:DL: ConnectDevice complete
[1685017307.304886][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304944][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304949][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304950][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304951][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304952][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304955][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304956][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304958][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304959][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304961][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304962][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304963][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304964][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304967][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304968][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304969][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304971][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304972][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0001
[1685017307.304973][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304975][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304976][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304978][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304979][7164:7165] CHIP:DL: Char1 /org/bluez/hci0/dev_FA_FE_EA_D2_27_71/service0010
[1685017307.304982][7164:7165] CHIP:DL: New BLE connection 0x7fccb40710e0, device FA:FE:EA:D2:27:71, path /org/bluez/hci0/dev_FA_FE_EA_D2_27_71
[1685017307.305094][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16385
[1685017307.305139][7164:7166] CHIP:DIS: Closing all BLE connections
[1685017307.305155][7164:7166] CHIP:IN: BleConnectionComplete: endPoint 0x55cfca5ecda0
[1685017307.305363][7164:7166] CHIP:IN: SecureSession[0x7fccac00acf0]: Allocated Type:1 LSID:31218
[1685017307.305402][7164:7166] CHIP:SC: Assigned local session key ID 31218
[1685017307.305446][7164:7166] CHIP:EM: <<< [E:24724i S:0 M:116781971] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1685017307.305454][7164:7166] CHIP:IN: (U) Sending msg 116781971 to IP address 'BLE'
[1685017307.305458][7164:7166] CHIP:IN: Message appended to BLE send queue
[1685017307.305460][7164:7166] CHIP:SC: Sent PBKDF param request
[1685017307.723724][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017307.814464][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16388
[1685017307.814503][7164:7166] CHIP:BLE: subscribe complete, ep = 0x55cfca5ecda0
[1685017307.815203][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017307.815430][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017307.815441][7164:7166] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4
[1685017307.815446][7164:7166] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244.
[1685017307.815449][7164:7166] CHIP:BLE: local and remote recv window size = 5
[1685017307.815936][7164:7166] CHIP:IN: BLE EndPoint 0x55cfca5ecda0 Connection Complete
[1685017307.901863][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017307.903674][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017307.903854][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017307.903932][7164:7166] CHIP:EM: >>> [E:24724i S:0 M:149081705] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1685017307.903941][7164:7166] CHIP:EM: Found matching exchange: 24724i, Delegate: 0x7fccac00d9a0
[1685017307.903949][7164:7166] CHIP:SC: Received PBKDF param response
[1685017307.903961][7164:7166] CHIP:SC: Peer assigned session ID 13409
[1685017307.904203][7164:7166] CHIP:SC: Found MRP parameters in the message
[1685017307.905587][7164:7166] CHIP:EM: <<< [E:24724i S:0 M:116781972] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1685017307.905637][7164:7166] CHIP:IN: (U) Sending msg 116781972 to IP address 'BLE'
[1685017307.905988][7164:7166] CHIP:SC: Sent spake2p msg1
[1685017307.994929][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017309.928189][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017309.928328][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017309.928358][7164:7166] CHIP:EM: >>> [E:24724i S:0 M:149081706] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1685017309.928363][7164:7166] CHIP:EM: Found matching exchange: 24724i, Delegate: 0x7fccac00d9a0
[1685017309.928368][7164:7166] CHIP:SC: Received spake2p msg2
[1685017309.928599][7164:7166] CHIP:EM: <<< [E:24724i S:0 M:116781973] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1685017309.928676][7164:7166] CHIP:IN: (U) Sending msg 116781973 to IP address 'BLE'
[1685017309.928944][7164:7166] CHIP:SC: Sent spake2p msg3
[1685017310.017477][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.017572][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.017614][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.017644][7164:7166] CHIP:EM: >>> [E:24724i S:0 M:149081707] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1685017310.017649][7164:7166] CHIP:EM: Found matching exchange: 24724i, Delegate: 0x7fccac00d9a0
[1685017310.017710][7164:7166] CHIP:SC: SecureSession[0x7fccac00acf0, LSID:31218]: State change 'kEstablishing' --> 'kActive'
[1685017310.017747][7164:7166] CHIP:IN: SecureSession[0x7fccac00acf0]: Activated - Type:1 LSID:31218
[1685017310.017750][7164:7166] CHIP:IN: New secure session activated for device <FFFFFFFB00000000, 0>, LSID:31218 PSID:13409!
[1685017310.017755][7164:7166] CHIP:CTL: Remote device completed SPAKE2+ handshake
[1685017310.017758][7164:7166] CHIP:TOO: Pairing Success
[1685017310.017759][7164:7166] CHIP:TOO: PASE establishment successful
[1685017310.017761][7164:7166] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1685017310.017764][7164:7166] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo'
[1685017310.017766][7164:7166] CHIP:CTL: Sending request for commissioning information
[1685017310.017800][7164:7166] CHIP:DMG: SendReadRequest ReadClient[0x7fccac015b70]: Sending Read Request
[1685017310.017890][7164:7166] CHIP:EM: <<< [E:24725i S:31218 M:130712331] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
[1685017310.017928][7164:7166] CHIP:IN: (S) Sending msg 130712331 on secure session with LSID: 31218
[1685017310.018186][7164:7166] CHIP:DMG: MoveToState ReadClient[0x7fccac015b70]: Moving to [AwaitingIn]
[1685017310.108711][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.154036][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.154127][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.242736][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.242830][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.243070][7164:7166] CHIP:EM: >>> [E:24725i S:31218 M:110006082] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1685017310.243076][7164:7166] CHIP:EM: Found matching exchange: 24725i, Delegate: 0x7fccac015b80
[1685017310.243116][7164:7166] CHIP:DMG: ReportDataMessage =
[1685017310.243164][7164:7166] CHIP:DMG: {
[1685017310.243167][7164:7166] CHIP:DMG: AttributeReportIBs =
[1685017310.243170][7164:7166] CHIP:DMG: [
[1685017310.243171][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243174][7164:7166] CHIP:DMG: {
[1685017310.243175][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243177][7164:7166] CHIP:DMG: {
[1685017310.243198][7164:7166] CHIP:DMG: DataVersion = 0x4bee5a87,
[1685017310.243231][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243235][7164:7166] CHIP:DMG: {
[1685017310.243237][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243239][7164:7166] CHIP:DMG: Cluster = 0x31,
[1685017310.243241][7164:7166] CHIP:DMG: Attribute = 0x0000_0003,
[1685017310.243243][7164:7166] CHIP:DMG: }
[1685017310.243245][7164:7166] CHIP:DMG:
[1685017310.243247][7164:7166] CHIP:DMG: Data = 20,
[1685017310.243248][7164:7166] CHIP:DMG: },
[1685017310.243251][7164:7166] CHIP:DMG:
[1685017310.243252][7164:7166] CHIP:DMG: },
[1685017310.243255][7164:7166] CHIP:DMG:
[1685017310.243257][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243259][7164:7166] CHIP:DMG: {
[1685017310.243260][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243262][7164:7166] CHIP:DMG: {
[1685017310.243264][7164:7166] CHIP:DMG: DataVersion = 0xb3a20987,
[1685017310.243265][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243267][7164:7166] CHIP:DMG: {
[1685017310.243268][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243270][7164:7166] CHIP:DMG: Cluster = 0x28,
[1685017310.243272][7164:7166] CHIP:DMG: Attribute = 0x0000_0004,
[1685017310.243274][7164:7166] CHIP:DMG: }
[1685017310.243276][7164:7166] CHIP:DMG:
[1685017310.243277][7164:7166] CHIP:DMG: Data = 32773,
[1685017310.243279][7164:7166] CHIP:DMG: },
[1685017310.243281][7164:7166] CHIP:DMG:
[1685017310.243282][7164:7166] CHIP:DMG: },
[1685017310.243285][7164:7166] CHIP:DMG:
[1685017310.243287][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243289][7164:7166] CHIP:DMG: {
[1685017310.243320][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243324][7164:7166] CHIP:DMG: {
[1685017310.243326][7164:7166] CHIP:DMG: DataVersion = 0xb3a20987,
[1685017310.243328][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243330][7164:7166] CHIP:DMG: {
[1685017310.243332][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243333][7164:7166] CHIP:DMG: Cluster = 0x28,
[1685017310.243335][7164:7166] CHIP:DMG: Attribute = 0x0000_0002,
[1685017310.243337][7164:7166] CHIP:DMG: }
[1685017310.243339][7164:7166] CHIP:DMG:
[1685017310.243341][7164:7166] CHIP:DMG: Data = 65521,
[1685017310.243342][7164:7166] CHIP:DMG: },
[1685017310.243345][7164:7166] CHIP:DMG:
[1685017310.243346][7164:7166] CHIP:DMG: },
[1685017310.243349][7164:7166] CHIP:DMG:
[1685017310.243350][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243353][7164:7166] CHIP:DMG: {
[1685017310.243354][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243355][7164:7166] CHIP:DMG: {
[1685017310.243357][7164:7166] CHIP:DMG: DataVersion = 0xfbcc58e9,
[1685017310.243359][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243360][7164:7166] CHIP:DMG: {
[1685017310.243362][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243364][7164:7166] CHIP:DMG: Cluster = 0x30,
[1685017310.243365][7164:7166] CHIP:DMG: Attribute = 0x0000_0003,
[1685017310.243367][7164:7166] CHIP:DMG: }
[1685017310.243369][7164:7166] CHIP:DMG:
[1685017310.243371][7164:7166] CHIP:DMG: Data = 0,
[1685017310.243402][7164:7166] CHIP:DMG: },
[1685017310.243406][7164:7166] CHIP:DMG:
[1685017310.243408][7164:7166] CHIP:DMG: },
[1685017310.243411][7164:7166] CHIP:DMG:
[1685017310.243413][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243415][7164:7166] CHIP:DMG: {
[1685017310.243416][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243418][7164:7166] CHIP:DMG: {
[1685017310.243420][7164:7166] CHIP:DMG: DataVersion = 0xfbcc58e9,
[1685017310.243421][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243423][7164:7166] CHIP:DMG: {
[1685017310.243425][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243427][7164:7166] CHIP:DMG: Cluster = 0x30,
[1685017310.243428][7164:7166] CHIP:DMG: Attribute = 0x0000_0002,
[1685017310.243430][7164:7166] CHIP:DMG: }
[1685017310.243432][7164:7166] CHIP:DMG:
[1685017310.243434][7164:7166] CHIP:DMG: Data = 0,
[1685017310.243435][7164:7166] CHIP:DMG: },
[1685017310.243437][7164:7166] CHIP:DMG:
[1685017310.243439][7164:7166] CHIP:DMG: },
[1685017310.243442][7164:7166] CHIP:DMG:
[1685017310.243443][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243446][7164:7166] CHIP:DMG: {
[1685017310.243447][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243449][7164:7166] CHIP:DMG: {
[1685017310.243450][7164:7166] CHIP:DMG: DataVersion = 0xfbcc58e9,
[1685017310.243482][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243485][7164:7166] CHIP:DMG: {
[1685017310.243487][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243489][7164:7166] CHIP:DMG: Cluster = 0x30,
[1685017310.243491][7164:7166] CHIP:DMG: Attribute = 0x0000_0001,
[1685017310.243493][7164:7166] CHIP:DMG: }
[1685017310.243495][7164:7166] CHIP:DMG:
[1685017310.243496][7164:7166] CHIP:DMG: Data =
[1685017310.243498][7164:7166] CHIP:DMG: {
[1685017310.243500][7164:7166] CHIP:DMG: 0x0 = 60,
[1685017310.243502][7164:7166] CHIP:DMG: 0x1 = 900,
[1685017310.243504][7164:7166] CHIP:DMG: },
[1685017310.243505][7164:7166] CHIP:DMG: },
[1685017310.243508][7164:7166] CHIP:DMG:
[1685017310.243509][7164:7166] CHIP:DMG: },
[1685017310.243513][7164:7166] CHIP:DMG:
[1685017310.243514][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243516][7164:7166] CHIP:DMG: {
[1685017310.243518][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243519][7164:7166] CHIP:DMG: {
[1685017310.243521][7164:7166] CHIP:DMG: DataVersion = 0xfbcc58e9,
[1685017310.243522][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243524][7164:7166] CHIP:DMG: {
[1685017310.243526][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243527][7164:7166] CHIP:DMG: Cluster = 0x30,
[1685017310.243530][7164:7166] CHIP:DMG: Attribute = 0x0000_0000,
[1685017310.243561][7164:7166] CHIP:DMG: }
[1685017310.243565][7164:7166] CHIP:DMG:
[1685017310.243567][7164:7166] CHIP:DMG: Data = 0,
[1685017310.243569][7164:7166] CHIP:DMG: },
[1685017310.243572][7164:7166] CHIP:DMG:
[1685017310.243573][7164:7166] CHIP:DMG: },
[1685017310.243576][7164:7166] CHIP:DMG:
[1685017310.243578][7164:7166] CHIP:DMG: AttributeReportIB =
[1685017310.243580][7164:7166] CHIP:DMG: {
[1685017310.243582][7164:7166] CHIP:DMG: AttributeDataIB =
[1685017310.243583][7164:7166] CHIP:DMG: {
[1685017310.243585][7164:7166] CHIP:DMG: DataVersion = 0x4bee5a87,
[1685017310.243586][7164:7166] CHIP:DMG: AttributePathIB =
[1685017310.243588][7164:7166] CHIP:DMG: {
[1685017310.243590][7164:7166] CHIP:DMG: Endpoint = 0x0,
[1685017310.243592][7164:7166] CHIP:DMG: Cluster = 0x31,
[1685017310.243594][7164:7166] CHIP:DMG: Attribute = 0x0000_FFFC,
[1685017310.243595][7164:7166] CHIP:DMG: }
[1685017310.243597][7164:7166] CHIP:DMG:
[1685017310.243599][7164:7166] CHIP:DMG: Data = 2,
[1685017310.243601][7164:7166] CHIP:DMG: },
[1685017310.243603][7164:7166] CHIP:DMG:
[1685017310.243604][7164:7166] CHIP:DMG: },
[1685017310.243606][7164:7166] CHIP:DMG:
[1685017310.243607][7164:7166] CHIP:DMG: ],
[1685017310.243614][7164:7166] CHIP:DMG:
[1685017310.243646][7164:7166] CHIP:DMG: SuppressResponse = true,
[1685017310.243649][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017310.243651][7164:7166] CHIP:DMG: }
[1685017310.243802][7164:7166] CHIP:CTL: ----- NetworkCommissioning Features: has Thread. endpointid = 0
[1685017310.243861][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo'
[1685017310.243866][7164:7166] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe'
[1685017310.243869][7164:7166] CHIP:CTL: Performing next commissioning step 'ArmFailSafe'
[1685017310.243872][7164:7166] CHIP:CTL: Arming failsafe (60 seconds)
[1685017310.243893][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017310.244009][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017310.244028][7164:7166] CHIP:EM: <<< [E:24726i S:31218 M:130712332] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017310.244032][7164:7166] CHIP:IN: (S) Sending msg 130712332 on secure session with LSID: 31218
[1685017310.244322][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017310.333490][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.335663][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.335931][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.336011][7164:7166] CHIP:EM: >>> [E:24726i S:31218 M:110006083] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017310.336017][7164:7166] CHIP:EM: Found matching exchange: 24726i, Delegate: 0x7fccb4035aa8
[1685017310.336021][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017310.336030][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017310.336031][7164:7166] CHIP:DMG: {
[1685017310.336033][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017310.336034][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017310.336037][7164:7166] CHIP:DMG: [
[1685017310.336070][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017310.336075][7164:7166] CHIP:DMG: {
[1685017310.336077][7164:7166] CHIP:DMG: CommandDataIB =
[1685017310.336079][7164:7166] CHIP:DMG: {
[1685017310.336081][7164:7166] CHIP:DMG: CommandPathIB =
[1685017310.336083][7164:7166] CHIP:DMG: {
[1685017310.336085][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017310.336087][7164:7166] CHIP:DMG: ClusterId = 0x30,
[1685017310.336088][7164:7166] CHIP:DMG: CommandId = 0x1,
[1685017310.336090][7164:7166] CHIP:DMG: },
[1685017310.336092][7164:7166] CHIP:DMG:
[1685017310.336094][7164:7166] CHIP:DMG: CommandFields =
[1685017310.336095][7164:7166] CHIP:DMG: {
[1685017310.336097][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017310.336100][7164:7166] CHIP:DMG: 0x1 = "" (0 chars),
[1685017310.336102][7164:7166] CHIP:DMG: },
[1685017310.336103][7164:7166] CHIP:DMG: },
[1685017310.336106][7164:7166] CHIP:DMG:
[1685017310.336107][7164:7166] CHIP:DMG: },
[1685017310.336109][7164:7166] CHIP:DMG:
[1685017310.336110][7164:7166] CHIP:DMG: ],
[1685017310.336113][7164:7166] CHIP:DMG:
[1685017310.336114][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017310.336116][7164:7166] CHIP:DMG: },
[1685017310.336125][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1685017310.336243][7164:7166] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1685017310.336251][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe'
[1685017310.336253][7164:7166] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1685017310.336256][7164:7166] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory'
[1685017310.336257][7164:7166] CHIP:CTL: Setting Regulatory Config
[1685017310.336259][7164:7166] CHIP:CTL: Device does not support configurable regulatory location
[1685017310.336295][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017310.336324][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017310.336370][7164:7166] CHIP:EM: <<< [E:24727i S:31218 M:130712333] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017310.336375][7164:7166] CHIP:IN: (S) Sending msg 130712333 on secure session with LSID: 31218
[1685017310.336794][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017310.336806][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017310.431189][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.431281][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.431494][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.431545][7164:7166] CHIP:EM: >>> [E:24727i S:31218 M:110006084] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017310.431551][7164:7166] CHIP:EM: Found matching exchange: 24727i, Delegate: 0x7fccac009fe8
[1685017310.431555][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017310.431564][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017310.431598][7164:7166] CHIP:DMG: {
[1685017310.431602][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017310.431603][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017310.431607][7164:7166] CHIP:DMG: [
[1685017310.431608][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017310.431611][7164:7166] CHIP:DMG: {
[1685017310.431612][7164:7166] CHIP:DMG: CommandDataIB =
[1685017310.431614][7164:7166] CHIP:DMG: {
[1685017310.431616][7164:7166] CHIP:DMG: CommandPathIB =
[1685017310.431618][7164:7166] CHIP:DMG: {
[1685017310.431620][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017310.431622][7164:7166] CHIP:DMG: ClusterId = 0x30,
[1685017310.431624][7164:7166] CHIP:DMG: CommandId = 0x3,
[1685017310.431625][7164:7166] CHIP:DMG: },
[1685017310.431627][7164:7166] CHIP:DMG:
[1685017310.431629][7164:7166] CHIP:DMG: CommandFields =
[1685017310.431631][7164:7166] CHIP:DMG: {
[1685017310.431633][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017310.431635][7164:7166] CHIP:DMG: 0x1 = "" (0 chars),
[1685017310.431637][7164:7166] CHIP:DMG: },
[1685017310.431639][7164:7166] CHIP:DMG: },
[1685017310.431641][7164:7166] CHIP:DMG:
[1685017310.431642][7164:7166] CHIP:DMG: },
[1685017310.431645][7164:7166] CHIP:DMG:
[1685017310.431646][7164:7166] CHIP:DMG: ],
[1685017310.431648][7164:7166] CHIP:DMG:
[1685017310.431650][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017310.431651][7164:7166] CHIP:DMG: },
[1685017310.431660][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1685017310.431706][7164:7166] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0
[1685017310.431714][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory'
[1685017310.431717][7164:7166] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest'
[1685017310.431720][7164:7166] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest'
[1685017310.431722][7164:7166] CHIP:CTL: Sending request for PAI certificate
[1685017310.431723][7164:7166] CHIP:CTL: Sending Certificate Chain request to 0x7fccac00d950 device
[1685017310.431735][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017310.431742][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017310.431758][7164:7166] CHIP:EM: <<< [E:24728i S:31218 M:130712334] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017310.431761][7164:7166] CHIP:IN: (S) Sending msg 130712334 on secure session with LSID: 31218
[1685017310.432103][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017310.432501][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017310.511569][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.516104][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.516424][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.604113][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.604250][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.692352][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.692469][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.692547][7164:7166] CHIP:EM: >>> [E:24728i S:31218 M:110006085] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017310.692557][7164:7166] CHIP:EM: Found matching exchange: 24728i, Delegate: 0x7fccb4035aa8
[1685017310.692571][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017310.692582][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017310.692587][7164:7166] CHIP:DMG: {
[1685017310.692591][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017310.692598][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017310.692606][7164:7166] CHIP:DMG: [
[1685017310.692627][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017310.692636][7164:7166] CHIP:DMG: {
[1685017310.692643][7164:7166] CHIP:DMG: CommandDataIB =
[1685017310.692653][7164:7166] CHIP:DMG: {
[1685017310.692661][7164:7166] CHIP:DMG: CommandPathIB =
[1685017310.692672][7164:7166] CHIP:DMG: {
[1685017310.692682][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017310.692694][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017310.692705][7164:7166] CHIP:DMG: CommandId = 0x3,
[1685017310.692716][7164:7166] CHIP:DMG: },
[1685017310.692727][7164:7166] CHIP:DMG:
[1685017310.692801][7164:7166] CHIP:DMG: CommandFields =
[1685017310.692846][7164:7166] CHIP:DMG: {
[1685017310.692858][7164:7166] CHIP:DMG: 0x0 = [
[1685017310.692882][7164:7166] CHIP:DMG: 0x30, 0x82, 0x01, 0xcb, 0x30, 0x82, 0x01, 0x71, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x56, 0xad, 0x82, 0x22, 0xad, 0x94, 0x5b, 0x64, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x30, 0x31, 0x18, 0x30, 0x16, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x0f, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x54, 0x65, 0x73, 0x74, 0x20, 0x50, 0x41, 0x41, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x41, 0x9a, 0x93, 0x15, 0xc2, 0x17, 0x3e, 0x0c, 0x8c, 0x87, 0x6d, 0x03, 0xcc, 0xfc, 0x94, 0x48, 0x52, 0x64, 0x7f, 0x7f, 0xec, 0x5e, 0x50, 0x82, 0xf4, 0x05, 0x99, 0x28, 0xec, 0xa8, 0x94, 0xc5, 0x94, 0x15, 0x13, 0x09, 0xac, 0x63, 0x1e, 0x4c, 0xb0, 0x33, 0x92, 0xaf, 0x68, 0x4b, 0x0b, 0xaf, 0xb7, 0xe6, 0x5b, 0x3b, 0x81, 0x62, 0xc2, 0xf5, 0x2b, 0xf9, 0x31, 0xb8, 0xe7, 0x7a, 0xaa, 0x82, 0xa3, 0x66, 0x30, 0x64, 0x30, 0x12, 0x06, 0x03, 0x55, 0x1d, 0x
[1685017310.692903][7164:7166] CHIP:DMG: ] (463 bytes)
[1685017310.692914][7164:7166] CHIP:DMG: },
[1685017310.692923][7164:7166] CHIP:DMG: },
[1685017310.692933][7164:7166] CHIP:DMG:
[1685017310.692940][7164:7166] CHIP:DMG: },
[1685017310.692948][7164:7166] CHIP:DMG:
[1685017310.692954][7164:7166] CHIP:DMG: ],
[1685017310.692962][7164:7166] CHIP:DMG:
[1685017310.692968][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017310.692974][7164:7166] CHIP:DMG: },
[1685017310.692987][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1685017310.692996][7164:7166] CHIP:CTL: Received certificate chain from the device
[1685017310.693006][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest'
[1685017310.693011][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1685017310.693018][7164:7166] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest'
[1685017310.693022][7164:7166] CHIP:CTL: Sending request for DAC certificate
[1685017310.693026][7164:7166] CHIP:CTL: Sending Certificate Chain request to 0x7fccac00d950 device
[1685017310.693040][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017310.693046][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017310.693068][7164:7166] CHIP:EM: <<< [E:24729i S:31218 M:130712335] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017310.693075][7164:7166] CHIP:IN: (S) Sending msg 130712335 on secure session with LSID: 31218
[1685017310.693205][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017310.693221][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017310.781735][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017310.785334][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.785430][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.874522][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.874625][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.962398][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017310.962504][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017310.962557][7164:7166] CHIP:EM: >>> [E:24729i S:31218 M:110006086] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017310.962566][7164:7166] CHIP:EM: Found matching exchange: 24729i, Delegate: 0x7fccb4072f48
[1685017310.962575][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017310.962586][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017310.962591][7164:7166] CHIP:DMG: {
[1685017310.962596][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017310.962731][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017310.962743][7164:7166] CHIP:DMG: [
[1685017310.962749][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017310.962758][7164:7166] CHIP:DMG: {
[1685017310.962766][7164:7166] CHIP:DMG: CommandDataIB =
[1685017310.962775][7164:7166] CHIP:DMG: {
[1685017310.962784][7164:7166] CHIP:DMG: CommandPathIB =
[1685017310.962795][7164:7166] CHIP:DMG: {
[1685017310.962834][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017310.962848][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017310.962859][7164:7166] CHIP:DMG: CommandId = 0x3,
[1685017310.962870][7164:7166] CHIP:DMG: },
[1685017310.962881][7164:7166] CHIP:DMG:
[1685017310.962890][7164:7166] CHIP:DMG: CommandFields =
[1685017310.962900][7164:7166] CHIP:DMG: {
[1685017310.962911][7164:7166] CHIP:DMG: 0x0 = [
[1685017310.962935][7164:7166] CHIP:DMG: 0x30, 0x82, 0x01, 0xe8, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x6f, 0xdc, 0xb6, 0xed, 0x06, 0xf3, 0x58, 0xf9, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x35, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x35, 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, 0x7e, 0xf2, 0xf1, 0x08, 0x2b, 0x2a, 0x56, 0x4a, 0x05, 0x44, 0xc0, 0xa6, 0x13, 0xd2, 0x1b, 0x15, 0x75, 0x00, 0x55, 0x2d, 0x03, 0x5f, 0x23, 0x02, 0x25, 0x2f, 0xce, 0xe8, 0x1d, 0x6e, 0xc6, 0x74, 0x17, 0x34, 0x18, 0xa9, 0x4a, 0x88, 0xdb, 0x
[1685017310.962957][7164:7166] CHIP:DMG: ] (492 bytes)
[1685017310.962997][7164:7166] CHIP:DMG: },
[1685017310.963008][7164:7166] CHIP:DMG: },
[1685017310.963018][7164:7166] CHIP:DMG:
[1685017310.963026][7164:7166] CHIP:DMG: },
[1685017310.963034][7164:7166] CHIP:DMG:
[1685017310.963041][7164:7166] CHIP:DMG: ],
[1685017310.963048][7164:7166] CHIP:DMG:
[1685017310.963054][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017310.963060][7164:7166] CHIP:DMG: },
[1685017310.963072][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1685017310.963082][7164:7166] CHIP:CTL: Received certificate chain from the device
[1685017310.963091][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest'
[1685017310.963186][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1685017310.963242][7164:7166] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest'
[1685017310.963247][7164:7166] CHIP:CTL: Sending Attestation Request to the device.
[1685017310.963252][7164:7166] CHIP:CTL: Sending Attestation request to 0x7fccac00d950 device
[1685017310.963268][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017310.963274][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017310.963297][7164:7166] CHIP:EM: <<< [E:24730i S:31218 M:130712336] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017310.963304][7164:7166] CHIP:IN: (S) Sending msg 130712336 on secure session with LSID: 31218
[1685017310.963398][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017310.963404][7164:7166] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
[1685017310.963412][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017311.051408][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017311.098884][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.099097][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.189119][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.189328][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.279367][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.279467][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.279515][7164:7166] CHIP:EM: >>> [E:24730i S:31218 M:110006087] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017311.279522][7164:7166] CHIP:EM: Found matching exchange: 24730i, Delegate: 0x7fccb4073208
[1685017311.279530][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017311.279542][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017311.279644][7164:7166] CHIP:DMG: {
[1685017311.279651][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017311.279657][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017311.279666][7164:7166] CHIP:DMG: [
[1685017311.279672][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017311.279681][7164:7166] CHIP:DMG: {
[1685017311.279688][7164:7166] CHIP:DMG: CommandDataIB =
[1685017311.279697][7164:7166] CHIP:DMG: {
[1685017311.279705][7164:7166] CHIP:DMG: CommandPathIB =
[1685017311.279715][7164:7166] CHIP:DMG: {
[1685017311.279725][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017311.279736][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017311.279747][7164:7166] CHIP:DMG: CommandId = 0x1,
[1685017311.279886][7164:7166] CHIP:DMG: },
[1685017311.279898][7164:7166] CHIP:DMG:
[1685017311.279907][7164:7166] CHIP:DMG: CommandFields =
[1685017311.279917][7164:7166] CHIP:DMG: {
[1685017311.279927][7164:7166] CHIP:DMG: 0x0 = [
[1685017311.279952][7164:7166] CHIP:DMG: 0x15, 0x31, 0x01, 0x1b, 0x02, 0x30, 0x82, 0x02, 0x17, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x08, 0x30, 0x82, 0x02, 0x04, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x70, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x61, 0x04, 0x82, 0x01, 0x5d, 0x15, 0x24, 0x00, 0x01, 0x25, 0x01, 0xf1, 0xff, 0x36, 0x02, 0x05, 0x00, 0x80, 0x05, 0x01, 0x80, 0x05, 0x02, 0x80, 0x05, 0x03, 0x80, 0x05, 0x04, 0x80, 0x05, 0x05, 0x80, 0x05, 0x06, 0x80, 0x05, 0x07, 0x80, 0x05, 0x08, 0x80, 0x05, 0x09, 0x80, 0x05, 0x0a, 0x80, 0x05, 0x0b, 0x80, 0x05, 0x0c, 0x80, 0x05, 0x0d, 0x80, 0x05, 0x0e, 0x80, 0x05, 0x0f, 0x80, 0x05, 0x10, 0x80, 0x05, 0x11, 0x80, 0x05, 0x12, 0x80, 0x05, 0x13, 0x80, 0x05, 0x14, 0x80, 0x05, 0x15, 0x80, 0x05, 0x16, 0x80, 0x05, 0x17, 0x80, 0x05, 0x18, 0x80, 0x05, 0x19, 0x80, 0x05, 0x1a, 0x80, 0x05, 0x1b, 0x80, 0x05, 0x1c, 0x80, 0x05, 0x1d, 0x80, 0x05, 0x1e, 0x80, 0x05, 0x1f, 0x80, 0x05, 0x20, 0x80, 0x05, 0x21, 0x80, 0x05, 0x22, 0x80, 0x05, 0x23, 0x80, 0x05, 0x24, 0x80, 0x05, 0x25, 0x80, 0x05, 0x26, 0x80, 0x05, 0x27, 0x80, 0x05, 0x28, 0x80, 0x05, 0x29, 0x80, 0x05, 0x2a, 0x80, 0x05, 0x2b, 0x80, 0x05, 0x2c, 0x80, 0x05, 0x2d, 0x80, 0x05, 0x2e, 0x80, 0x05, 0x2f, 0x80, 0x05, 0x30, 0x80, 0x05, 0x31, 0x80, 0x05, 0x32, 0x80, 0x05, 0x33, 0x80, 0x05, 0x34, 0x80, 0x05, 0x35, 0x80, 0x05, 0x36, 0x80, 0x05, 0x37, 0x80, 0x05, 0x38, 0x80, 0x05, 0x39, 0x80, 0x05, 0x3a, 0x80, 0x05, 0x3b, 0x80, 0x05, 0x3c, 0x80, 0x05, 0x3d, 0x80, 0x05, 0x3e, 0x80, 0x05, 0x3f, 0x80, 0x05, 0x40, 0x80, 0x05, 0x41, 0x80, 0x05, 0x42, 0x80, 0x05, 0x43, 0x80, 0x
[1685017311.279972][7164:7166] CHIP:DMG: ] (583 bytes)
[1685017311.279983][7164:7166] CHIP:DMG: 0x1 = [
[1685017311.279997][7164:7166] CHIP:DMG: 0xd2, 0xc4, 0xed, 0xaf, 0xcd, 0xaf, 0x59, 0x6e, 0xa9, 0x65, 0xe2, 0xac, 0x60, 0x31, 0xfa, 0x7b, 0x11, 0x91, 0x0c, 0x86, 0xa3, 0xf9, 0x2d, 0xce, 0xfe, 0x37, 0xe2, 0xc2, 0x01, 0x2f, 0x8f, 0x73, 0xc2, 0x20, 0x6f, 0xd8, 0x76, 0xfa, 0x20, 0xcd, 0x14, 0x0c, 0x6c, 0xc4, 0x4a, 0xc8, 0x19, 0x08, 0x97, 0x52, 0x5b, 0x7a, 0x98, 0x59, 0xfd, 0x9a, 0x2e, 0x11, 0xde, 0x7f, 0x6e, 0x23, 0x63, 0x60,
[1685017311.280196][7164:7166] CHIP:DMG: ] (64 bytes)
[1685017311.280209][7164:7166] CHIP:DMG: },
[1685017311.280218][7164:7166] CHIP:DMG: },
[1685017311.280228][7164:7166] CHIP:DMG:
[1685017311.280236][7164:7166] CHIP:DMG: },
[1685017311.280244][7164:7166] CHIP:DMG:
[1685017311.280250][7164:7166] CHIP:DMG: ],
[1685017311.280258][7164:7166] CHIP:DMG:
[1685017311.280263][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017311.280269][7164:7166] CHIP:DMG: },
[1685017311.280282][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001
[1685017311.280290][7164:7166] CHIP:CTL: Received Attestation Information from the device
[1685017311.280299][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest'
[1685017311.280305][7164:7166] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583
[1685017311.280319][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1685017311.280325][7164:7166] CHIP:CTL: Performing next commissioning step 'AttestationVerification'
[1685017311.280330][7164:7166] CHIP:CTL: Verifying attestation
[1685017311.287305][7164:7166] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
[1685017311.287394][7164:7166] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification'
[1685017311.287402][7164:7166] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1685017311.287411][7164:7166] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest'
[1685017311.287436][7164:7166] CHIP:CTL: Sending CSR request to 0x7fccac00d950 device
[1685017311.287463][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017311.287470][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017311.287504][7164:7166] CHIP:EM: <<< [E:24731i S:31218 M:130712337] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017311.287548][7164:7166] CHIP:IN: (S) Sending msg 130712337 on secure session with LSID: 31218
[1685017311.287816][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017311.287854][7164:7166] CHIP:CTL: Sent CSR request, waiting for the CSR
[1685017311.287868][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017311.366301][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017311.639922][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.640318][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.728019][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.728367][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.728428][7164:7166] CHIP:EM: >>> [E:24731i S:31218 M:110006088] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017311.728436][7164:7166] CHIP:EM: Found matching exchange: 24731i, Delegate: 0x7fccb4073368
[1685017311.728445][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017311.728457][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017311.728462][7164:7166] CHIP:DMG: {
[1685017311.728466][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017311.728473][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017311.728481][7164:7166] CHIP:DMG: [
[1685017311.728487][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017311.728496][7164:7166] CHIP:DMG: {
[1685017311.728503][7164:7166] CHIP:DMG: CommandDataIB =
[1685017311.728513][7164:7166] CHIP:DMG: {
[1685017311.728521][7164:7166] CHIP:DMG: CommandPathIB =
[1685017311.728532][7164:7166] CHIP:DMG: {
[1685017311.728542][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017311.728554][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017311.728565][7164:7166] CHIP:DMG: CommandId = 0x5,
[1685017311.728576][7164:7166] CHIP:DMG: },
[1685017311.728587][7164:7166] CHIP:DMG:
[1685017311.728596][7164:7166] CHIP:DMG: CommandFields =
[1685017311.728607][7164:7166] CHIP:DMG: {
[1685017311.728617][7164:7166] CHIP:DMG: 0x0 = [
[1685017311.728639][7164:7166] CHIP:DMG: 0x15, 0x30, 0x01, 0xcd, 0x30, 0x81, 0xca, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 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, 0x40, 0xa6, 0x94, 0x50, 0xe9, 0xf3, 0xc2, 0xe5, 0x4d, 0x0d, 0x58, 0xab, 0xaf, 0x8b, 0x4d, 0xcf, 0xa1, 0x2c, 0x61, 0x82, 0x4a, 0x72, 0x58, 0xf1, 0x1a, 0x7b, 0xdc, 0x10, 0xe0, 0x75, 0xee, 0xe5, 0x2f, 0x4c, 0x6d, 0x7e, 0x5d, 0x06, 0x2b, 0x0b, 0x86, 0xc3, 0xa3, 0x3b, 0xeb, 0x32, 0xac, 0x2a, 0xbe, 0xdf, 0x91, 0xf5, 0x3f, 0x84, 0x26, 0x16, 0xd8, 0x9f, 0x73, 0x47, 0xa9, 0x17, 0x51, 0x3c, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x20, 0x6e, 0x1e, 0x09, 0x11, 0x9f, 0x75, 0xa4, 0xb7, 0xb9, 0x2c, 0xc7, 0x5c, 0xd3, 0x23, 0xc6, 0xf9, 0x85, 0x7e, 0x61, 0x9c, 0x78, 0xcb, 0x36, 0x50, 0x4c, 0x35, 0xae, 0xea, 0x06, 0x83, 0xca, 0x3b, 0x02, 0x21, 0x00, 0x8a, 0x26, 0x43, 0xdc, 0x5a, 0x66, 0x10, 0x6c, 0x1d, 0xbc, 0x4c, 0x81, 0x15, 0x19, 0xce, 0xe4, 0x8c, 0xee, 0x72, 0x81, 0x67, 0xcf, 0x4c, 0xd7, 0x88, 0xf6, 0x19, 0x58, 0xbf, 0xa0, 0x1d, 0x8f, 0x30, 0x02, 0x20, 0xb9, 0xae, 0x11, 0x87, 0x3f, 0x34, 0x99, 0x0a, 0xa6, 0x11, 0x85, 0x6f, 0x1c, 0x6f, 0x89, 0xfa, 0xdd, 0xc0, 0x2f, 0x30, 0xd8, 0x69, 0xe5, 0xfc, 0x47, 0x8a, 0x3d, 0xc2, 0x88, 0x18, 0x11, 0x4c, 0x18,
[1685017311.728659][7164:7166] CHIP:DMG: ] (245 bytes)
[1685017311.728670][7164:7166] CHIP:DMG: 0x1 = [
[1685017311.728856][7164:7166] CHIP:DMG: 0x8d, 0x26, 0x45, 0xad, 0x84, 0x7d, 0x23, 0x72, 0xaf, 0xbc, 0x8e, 0x77, 0x0e, 0x56, 0xb8, 0x2d, 0x01, 0x98, 0xb2, 0xa2, 0xc8, 0x49, 0x0e, 0x58, 0x84, 0xe6, 0x61, 0xd1, 0x2e, 0xdb, 0x9d, 0xc3, 0x63, 0xb4, 0x35, 0x5d, 0xbf, 0xc9, 0xa1, 0x14, 0x7b, 0x78, 0x8a, 0x2c, 0xd5, 0xa2, 0x78, 0xfe, 0xeb, 0x2e, 0x29, 0x9f, 0xab, 0x57, 0xd6, 0x78, 0x80, 0x0a, 0xc6, 0x81, 0x12, 0xc7, 0x14, 0xdc,
[1685017311.728873][7164:7166] CHIP:DMG: ] (64 bytes)
[1685017311.728884][7164:7166] CHIP:DMG: },
[1685017311.728894][7164:7166] CHIP:DMG: },
[1685017311.728905][7164:7166] CHIP:DMG:
[1685017311.728912][7164:7166] CHIP:DMG: },
[1685017311.728920][7164:7166] CHIP:DMG:
[1685017311.728927][7164:7166] CHIP:DMG: ],
[1685017311.728934][7164:7166] CHIP:DMG:
[1685017311.728940][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017311.728946][7164:7166] CHIP:DMG: },
[1685017311.728959][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1685017311.728987][7164:7166] CHIP:CTL: Received certificate signing request from the device
[1685017311.728996][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest'
[1685017311.729013][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1685017311.729020][7164:7166] CHIP:CTL: Performing next commissioning step 'ValidateCSR'
[1685017311.730977][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR'
[1685017311.731014][7164:7166] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1685017311.731033][7164:7166] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain'
[1685017311.731049][7164:7166] CHIP:CTL: Getting certificate chain for the device from the issuer
[1685017311.732106][7164:7166] CHIP:CTL: Verifying Certificate Signing Request
[1685017311.732670][7164:7166] CHIP:CTL: Generating NOC
[1685017311.732765][7164:7166] CHIP:CTL: Providing certificate chain to the commissioner
[1685017311.732785][7164:7166] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success
[1685017311.732806][7164:7166] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain'
[1685017311.732845][7164:7166] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert'
[1685017311.732863][7164:7166] CHIP:CTL: Sending root certificate to the device
[1685017311.732895][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017311.732914][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017311.732955][7164:7166] CHIP:EM: <<< [E:24732i S:31218 M:130712338] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017311.732975][7164:7166] CHIP:IN: (S) Sending msg 130712338 on secure session with LSID: 31218
[1685017311.733212][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017311.733244][7164:7166] CHIP:CTL: Sent root certificate to the device
[1685017311.733331][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017311.824914][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017311.906982][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017311.997275][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017311.997375][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017311.997434][7164:7166] CHIP:EM: >>> [E:24732i S:31218 M:110006089] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017311.997443][7164:7166] CHIP:EM: Found matching exchange: 24732i, Delegate: 0x7fccb4073208
[1685017311.997450][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017311.997461][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017311.997466][7164:7166] CHIP:DMG: {
[1685017311.997470][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017311.997477][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017311.997485][7164:7166] CHIP:DMG: [
[1685017311.997491][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017311.997500][7164:7166] CHIP:DMG: {
[1685017311.997508][7164:7166] CHIP:DMG: CommandStatusIB =
[1685017311.997524][7164:7166] CHIP:DMG: {
[1685017311.997533][7164:7166] CHIP:DMG: CommandPathIB =
[1685017311.997544][7164:7166] CHIP:DMG: {
[1685017311.997699][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017311.997721][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017311.997791][7164:7166] CHIP:DMG: CommandId = 0xb,
[1685017311.997803][7164:7166] CHIP:DMG: },
[1685017311.997880][7164:7166] CHIP:DMG:
[1685017311.997891][7164:7166] CHIP:DMG: StatusIB =
[1685017311.997902][7164:7166] CHIP:DMG: {
[1685017311.997912][7164:7166] CHIP:DMG: status = 0x00 (SUCCESS),
[1685017311.997960][7164:7166] CHIP:DMG: },
[1685017311.997972][7164:7166] CHIP:DMG:
[1685017311.997981][7164:7166] CHIP:DMG: },
[1685017311.997992][7164:7166] CHIP:DMG:
[1685017311.997999][7164:7166] CHIP:DMG: },
[1685017311.998008][7164:7166] CHIP:DMG:
[1685017311.998047][7164:7166] CHIP:DMG: ],
[1685017311.998057][7164:7166] CHIP:DMG:
[1685017311.998063][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017311.998069][7164:7166] CHIP:DMG: },
[1685017311.998081][7164:7166] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1685017311.998089][7164:7166] CHIP:CTL: Device confirmed that it has received the root certificate
[1685017311.998098][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert'
[1685017311.998135][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1685017311.998144][7164:7166] CHIP:CTL: Performing next commissioning step 'SendNOC'
[1685017311.998166][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017311.998174][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017311.998204][7164:7166] CHIP:EM: <<< [E:24733i S:31218 M:130712339] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017311.998246][7164:7166] CHIP:IN: (S) Sending msg 130712339 on secure session with LSID: 31218
[1685017311.998375][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017311.998384][7164:7166] CHIP:CTL: Sent operational certificate to the device
[1685017311.998395][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017312.086583][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017312.180195][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017312.266677][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017312.468639][7164:7166] CHIP:BLE: BLE scan error: src/platform/Linux/bluez/ChipDeviceScanner.cpp:174: CHIP Error 0x00000032: Timeout
[1685017312.627303][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017312.627408][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017312.627592][7164:7166] CHIP:EM: >>> [E:24733i S:31218 M:110006090] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017312.627637][7164:7166] CHIP:EM: Found matching exchange: 24733i, Delegate: 0x7fccb4073368
[1685017312.627647][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017312.627659][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017312.627663][7164:7166] CHIP:DMG: {
[1685017312.627667][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017312.627674][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017312.627682][7164:7166] CHIP:DMG: [
[1685017312.627688][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017312.627697][7164:7166] CHIP:DMG: {
[1685017312.627704][7164:7166] CHIP:DMG: CommandDataIB =
[1685017312.627713][7164:7166] CHIP:DMG: {
[1685017312.627721][7164:7166] CHIP:DMG: CommandPathIB =
[1685017312.627732][7164:7166] CHIP:DMG: {
[1685017312.627742][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017312.627754][7164:7166] CHIP:DMG: ClusterId = 0x3e,
[1685017312.627764][7164:7166] CHIP:DMG: CommandId = 0x8,
[1685017312.627775][7164:7166] CHIP:DMG: },
[1685017312.627786][7164:7166] CHIP:DMG:
[1685017312.627827][7164:7166] CHIP:DMG: CommandFields =
[1685017312.627839][7164:7166] CHIP:DMG: {
[1685017312.627850][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017312.627861][7164:7166] CHIP:DMG: 0x1 = 1,
[1685017312.627873][7164:7166] CHIP:DMG: },
[1685017312.627882][7164:7166] CHIP:DMG: },
[1685017312.627892][7164:7166] CHIP:DMG:
[1685017312.627899][7164:7166] CHIP:DMG: },
[1685017312.627908][7164:7166] CHIP:DMG:
[1685017312.628224][7164:7166] CHIP:DMG: ],
[1685017312.628234][7164:7166] CHIP:DMG:
[1685017312.628240][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017312.628246][7164:7166] CHIP:DMG: },
[1685017312.628258][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1685017312.628270][7164:7166] CHIP:CTL: Device returned status 0 on receiving the NOC
[1685017312.628275][7164:7166] CHIP:CTL: Operational credentials provisioned on device 0x7fccac00d950
[1685017312.628280][7164:7166] CHIP:TOO: Secure Pairing Success
[1685017312.628284][7164:7166] CHIP:TOO: CASE establishment successful
[1685017312.628292][7164:7166] CHIP:CTL: Successfully finished commissioning step 'SendNOC'
[1685017312.628299][7164:7166] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
[1685017312.628331][7164:7166] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'ThreadNetworkSetup'
[1685017312.628337][7164:7166] CHIP:CTL: Performing next commissioning step 'ThreadNetworkSetup'
[1685017312.628352][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017312.628367][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017312.628387][7164:7166] CHIP:EM: <<< [E:24734i S:31218 M:130712340] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017312.628391][7164:7166] CHIP:IN: (S) Sending msg 130712340 on secure session with LSID: 31218
[1685017312.628551][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017312.628562][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017312.716228][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017312.717697][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017312.717742][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017312.717927][7164:7166] CHIP:EM: >>> [E:24734i S:31218 M:110006091] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017312.717973][7164:7166] CHIP:EM: Found matching exchange: 24734i, Delegate: 0x7fccb4073208
[1685017312.717983][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017312.717994][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017312.717999][7164:7166] CHIP:DMG: {
[1685017312.718003][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017312.718009][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017312.718182][7164:7166] CHIP:DMG: [
[1685017312.718191][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017312.718200][7164:7166] CHIP:DMG: {
[1685017312.718208][7164:7166] CHIP:DMG: CommandDataIB =
[1685017312.718217][7164:7166] CHIP:DMG: {
[1685017312.718225][7164:7166] CHIP:DMG: CommandPathIB =
[1685017312.718235][7164:7166] CHIP:DMG: {
[1685017312.718281][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017312.718294][7164:7166] CHIP:DMG: ClusterId = 0x31,
[1685017312.718305][7164:7166] CHIP:DMG: CommandId = 0x5,
[1685017312.718316][7164:7166] CHIP:DMG: },
[1685017312.718326][7164:7166] CHIP:DMG:
[1685017312.718335][7164:7166] CHIP:DMG: CommandFields =
[1685017312.718345][7164:7166] CHIP:DMG: {
[1685017312.718356][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017312.718367][7164:7166] CHIP:DMG: 0x2 = 0,
[1685017312.718378][7164:7166] CHIP:DMG: },
[1685017312.718387][7164:7166] CHIP:DMG: },
[1685017312.718397][7164:7166] CHIP:DMG:
[1685017312.718404][7164:7166] CHIP:DMG: },
[1685017312.718443][7164:7166] CHIP:DMG:
[1685017312.718446][7164:7166] CHIP:DMG: ],
[1685017312.718449][7164:7166] CHIP:DMG:
[1685017312.718451][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017312.718452][7164:7166] CHIP:DMG: },
[1685017312.718461][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1685017312.718512][7164:7166] CHIP:CTL: Received NetworkConfig response, networkingStatus=0
[1685017312.718520][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkSetup'
[1685017312.718523][7164:7166] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkSetup' -> 'FailsafeBeforeThreadEnable'
[1685017312.718528][7164:7166] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeThreadEnable'
[1685017312.718534][7164:7166] CHIP:CTL: Arming failsafe (76 seconds)
[1685017312.718545][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017312.718548][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017312.718569][7164:7166] CHIP:EM: <<< [E:24735i S:31218 M:130712341] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017312.718574][7164:7166] CHIP:IN: (S) Sending msg 130712341 on secure session with LSID: 31218
[1685017312.718635][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017312.718682][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017312.806149][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017312.808066][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017312.808401][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017312.808514][7164:7166] CHIP:EM: >>> [E:24735i S:31218 M:110006092] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017312.808566][7164:7166] CHIP:EM: Found matching exchange: 24735i, Delegate: 0x7fccb4073368
[1685017312.808573][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017312.808582][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017312.808584][7164:7166] CHIP:DMG: {
[1685017312.808585][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017312.808587][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017312.808590][7164:7166] CHIP:DMG: [
[1685017312.808591][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017312.808594][7164:7166] CHIP:DMG: {
[1685017312.808595][7164:7166] CHIP:DMG: CommandDataIB =
[1685017312.808597][7164:7166] CHIP:DMG: {
[1685017312.808598][7164:7166] CHIP:DMG: CommandPathIB =
[1685017312.808601][7164:7166] CHIP:DMG: {
[1685017312.808603][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017312.808605][7164:7166] CHIP:DMG: ClusterId = 0x30,
[1685017312.808606][7164:7166] CHIP:DMG: CommandId = 0x1,
[1685017312.808608][7164:7166] CHIP:DMG: },
[1685017312.808678][7164:7166] CHIP:DMG:
[1685017312.808721][7164:7166] CHIP:DMG: CommandFields =
[1685017312.808771][7164:7166] CHIP:DMG: {
[1685017312.808821][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017312.808851][7164:7166] CHIP:DMG: 0x1 = "" (0 chars),
[1685017312.808869][7164:7166] CHIP:DMG: },
[1685017312.808885][7164:7166] CHIP:DMG: },
[1685017312.808901][7164:7166] CHIP:DMG:
[1685017312.808914][7164:7166] CHIP:DMG: },
[1685017312.808929][7164:7166] CHIP:DMG:
[1685017312.808941][7164:7166] CHIP:DMG: ],
[1685017312.808954][7164:7166] CHIP:DMG:
[1685017312.808959][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017312.808972][7164:7166] CHIP:DMG: },
[1685017312.808990][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1685017312.809011][7164:7166] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1685017312.809032][7164:7166] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeThreadEnable'
[1685017312.809044][7164:7166] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeThreadEnable' -> 'ThreadNetworkEnable'
[1685017312.809057][7164:7166] CHIP:CTL: Performing next commissioning step 'ThreadNetworkEnable'
[1685017312.809080][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017312.809098][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017312.809127][7164:7166] CHIP:EM: <<< [E:24736i S:31218 M:130712342] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017312.809146][7164:7166] CHIP:IN: (S) Sending msg 130712342 on secure session with LSID: 31218
[1685017312.809278][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017312.809340][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017312.896675][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017315.372319][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017315.372510][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017317.982254][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017320.457437][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017320.457673][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017323.069568][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017325.542355][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017325.542595][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017325.992748][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017325.992839][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017325.992889][7164:7166] CHIP:EM: >>> [E:24736i S:31218 M:110006093] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017325.992894][7164:7166] CHIP:EM: Found matching exchange: 24736i, Delegate: 0x7fccb4073208
[1685017325.992900][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017325.992909][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017325.992911][7164:7166] CHIP:DMG: {
[1685017325.992912][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017325.992914][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017325.992922][7164:7166] CHIP:DMG: [
[1685017325.992957][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017325.992962][7164:7166] CHIP:DMG: {
[1685017325.992964][7164:7166] CHIP:DMG: CommandDataIB =
[1685017325.992966][7164:7166] CHIP:DMG: {
[1685017325.992968][7164:7166] CHIP:DMG: CommandPathIB =
[1685017325.992970][7164:7166] CHIP:DMG: {
[1685017325.992972][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017325.992974][7164:7166] CHIP:DMG: ClusterId = 0x31,
[1685017325.992976][7164:7166] CHIP:DMG: CommandId = 0x7,
[1685017325.992977][7164:7166] CHIP:DMG: },
[1685017325.992979][7164:7166] CHIP:DMG:
[1685017325.992981][7164:7166] CHIP:DMG: CommandFields =
[1685017325.992983][7164:7166] CHIP:DMG: {
[1685017325.992985][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017325.992987][7164:7166] CHIP:DMG: 0x2 = NULL
[1685017325.992989][7164:7166] CHIP:DMG: },
[1685017325.992990][7164:7166] CHIP:DMG: },
[1685017325.992993][7164:7166] CHIP:DMG:
[1685017325.993024][7164:7166] CHIP:DMG: },
[1685017325.993028][7164:7166] CHIP:DMG:
[1685017325.993030][7164:7166] CHIP:DMG: ],
[1685017325.993033][7164:7166] CHIP:DMG:
[1685017325.993034][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017325.993036][7164:7166] CHIP:DMG: },
[1685017325.993045][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007
[1685017325.993054][7164:7166] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0
[1685017325.993060][7164:7166] CHIP:CTL: Successfully finished commissioning step 'ThreadNetworkEnable'
[1685017325.993063][7164:7166] CHIP:CTL: Commissioning stage next step: 'ThreadNetworkEnable' -> 'FindOperational'
[1685017325.993066][7164:7166] CHIP:CTL: Performing next commissioning step 'FindOperational'
[1685017325.993068][7164:7166] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001]
[1685017325.993070][7164:7166] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found
[1685017325.993076][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2
[1685017325.993748][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017325.994394][7164:7166] CHIP:DIS: Lookup clearing interface for non LL address
[1685017325.994448][7164:7166] CHIP:DIS: UDP:[fd11:22::add3:c4f7:a475:2c77%otbr0]:5540: new best score: 4
[1685017325.994451][7164:7166] CHIP:DIS: Checking node lookup status after 2 ms
[1685017325.994452][7164:7166] CHIP:DIS: Keeping DNSSD lookup active
[1685017325.994720][7164:7166] CHIP:DIS: UDP:[fd11:22::add3:c4f7:a475:2c77%otbr0]:5540: score has not improved: 4
[1685017325.994761][7164:7166] CHIP:DIS: Checking node lookup status after 2 ms
[1685017325.994764][7164:7166] CHIP:DIS: Keeping DNSSD lookup active
[1685017325.994786][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017325.994792][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017325.994797][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017325.994810][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017325.994853][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017325.996008][7164:7166] CHIP:DIS: UDP:[fd11:22::add3:c4f7:a475:2c77%otbr0]:5540: score has not improved: 4
[1685017325.996054][7164:7166] CHIP:DIS: Checking node lookup status after 3 ms
[1685017325.996060][7164:7166] CHIP:DIS: Keeping DNSSD lookup active
[1685017326.193508][7164:7166] CHIP:DIS: Checking node lookup status after 201 ms
[1685017326.193691][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Updating device address to UDP:[fd11:22::add3:c4f7:a475:2c77]:5540 while in state 2
[1685017326.193709][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 3
[1685017326.193790][7164:7166] CHIP:IN: SecureSession[0x7fccac013ee0]: Allocated Type:2 LSID:31219
[1685017326.193814][7164:7166] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000000001
[1685017326.194380][7164:7166] CHIP:EM: <<< [E:24737i S:0 M:116781974] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
[1685017326.194570][7164:7166] CHIP:IN: (U) Sending msg 116781974 to IP address 'UDP:[fd11:22::add3:c4f7:a475:2c77]:5540'
[1685017326.194864][7164:7166] CHIP:SC: Sent Sigma1 msg
[1685017326.194890][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 3 --> 4
[1685017327.486801][7164:7166] CHIP:EM: Retransmitting MessageCounter:116781974 on exchange 24737i Send Cnt 1
[1685017327.486992][7164:7166] CHIP:IN: (U) Sending msg 116781974 to IP address 'UDP:[fd11:22::add3:c4f7:a475:2c77]:5540'
[1685017327.567850][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017327.568256][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017327.568268][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017327.568276][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017327.568285][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.109630][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017328.571646][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.571711][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.571721][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.571730][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.571738][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017328.711525][7164:7166] CHIP:EM: Retransmitting MessageCounter:116781974 on exchange 24737i Send Cnt 2
[1685017328.711603][7164:7166] CHIP:IN: (U) Sending msg 116781974 to IP address 'UDP:[fd11:22::add3:c4f7:a475:2c77]:5540'
[1685017330.575386][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017330.575651][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017330.575688][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017330.575723][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017330.575766][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017330.582724][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017330.582998][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017330.772520][7164:7166] CHIP:EM: Retransmitting MessageCounter:116781974 on exchange 24737i Send Cnt 3
[1685017330.772662][7164:7166] CHIP:IN: (U) Sending msg 116781974 to IP address 'UDP:[fd11:22::add3:c4f7:a475:2c77]:5540'
[1685017333.192386][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017334.100934][7164:7166] CHIP:EM: Retransmitting MessageCounter:116781974 on exchange 24737i Send Cnt 4
[1685017334.101021][7164:7166] CHIP:IN: (U) Sending msg 116781974 to IP address 'UDP:[fd11:22::add3:c4f7:a475:2c77]:5540'
[1685017334.588850][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017334.589056][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017334.589085][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017334.589109][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017334.589132][7164:7166] CHIP:DIS: SRV record already actively processed.
[1685017335.667296][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017335.667462][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017338.329580][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017339.465474][7164:7166] CHIP:EM: Failed to Send CHIP MessageCounter:116781974 on exchange 24737i sendCount: 4 max retries: 4
[1685017340.797616][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017340.797860][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017342.300358][7164:7166] CHIP:SC: CASESession timed out while waiting for a response from the peer. Current state was 1
[1685017342.300490][7164:7166] CHIP:IN: SecureSession[0x7fccac013ee0]: Released - Type:2 LSID:31219
[1685017342.300520][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 4 --> 2
[1685017342.300533][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 2 --> 1
[1685017342.300544][7164:7166] CHIP:DIS: OperationalSessionSetup:attempts done: 1, attempts left: 2, retry delay 1s, status src/system/SystemLayerImplSelect.cpp:167: Success
[1685017342.300548][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 6
[1685017342.300561][7164:7166] CHIP:CTL: Session establishment failed for <0000000000000001, 1>, error: src/protocols/secure_channel/CASESession.cpp:496: CHIP Error 0x00000032: Timeout. Next retry expected to get a response to Sigma1 or fail within 8 seconds
[1685017342.300570][7164:7166] CHIP:CTL: Arming failsafe (68 seconds)
[1685017342.300602][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017342.300610][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017342.300669][7164:7166] CHIP:EM: <<< [E:24738i S:31218 M:130712343] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017342.300803][7164:7166] CHIP:IN: (S) Sending msg 130712343 on secure session with LSID: 31218
[1685017342.301897][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017342.372857][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017342.372957][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017342.373193][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017342.373247][7164:7166] CHIP:EM: >>> [E:24738i S:31218 M:110006094] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017342.373291][7164:7166] CHIP:EM: Found matching exchange: 24738i, Delegate: 0x7fccb4073368
[1685017342.373298][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017342.373307][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017342.373309][7164:7166] CHIP:DMG: {
[1685017342.373310][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017342.373312][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017342.373315][7164:7166] CHIP:DMG: [
[1685017342.373316][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017342.373319][7164:7166] CHIP:DMG: {
[1685017342.373320][7164:7166] CHIP:DMG: CommandDataIB =
[1685017342.373322][7164:7166] CHIP:DMG: {
[1685017342.373324][7164:7166] CHIP:DMG: CommandPathIB =
[1685017342.373326][7164:7166] CHIP:DMG: {
[1685017342.373360][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017342.373363][7164:7166] CHIP:DMG: ClusterId = 0x30,
[1685017342.373365][7164:7166] CHIP:DMG: CommandId = 0x1,
[1685017342.373367][7164:7166] CHIP:DMG: },
[1685017342.373369][7164:7166] CHIP:DMG:
[1685017342.373371][7164:7166] CHIP:DMG: CommandFields =
[1685017342.373373][7164:7166] CHIP:DMG: {
[1685017342.373375][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017342.373378][7164:7166] CHIP:DMG: 0x1 = "" (0 chars),
[1685017342.373379][7164:7166] CHIP:DMG: },
[1685017342.373381][7164:7166] CHIP:DMG: },
[1685017342.373383][7164:7166] CHIP:DMG:
[1685017342.373385][7164:7166] CHIP:DMG: },
[1685017342.373387][7164:7166] CHIP:DMG:
[1685017342.373388][7164:7166] CHIP:DMG: ],
[1685017342.373391][7164:7166] CHIP:DMG:
[1685017342.373392][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017342.373393][7164:7166] CHIP:DMG: },
[1685017342.373403][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1685017342.373410][7164:7166] CHIP:CTL: Status of extending fail-safe for CASE retry: 0
[1685017342.373445][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017343.312350][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 6 --> 2
[1685017343.515730][7164:7166] CHIP:DIS: Checking node lookup status after 203 ms
[1685017344.982532][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017347.458241][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017347.458663][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017350.021998][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017352.498963][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017352.499193][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017355.062025][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017357.538064][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017357.538524][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017360.102028][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017362.577348][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017362.577478][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017365.142940][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017367.617372][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017367.617486][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017370.182206][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017372.658197][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017372.658447][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017374.324628][7164:7166] CHIP:DIS: Timeout waiting for mDNS resolution.
[1685017375.230382][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017377.697277][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017377.697436][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017380.262025][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017382.759080][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017382.759205][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017385.347073][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017387.823839][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017387.824377][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017388.314214][7164:7166] CHIP:DIS: Checking node lookup status after 45002 ms
[1685017388.314279][7164:7166] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout
[1685017388.314283][7164:7166] CHIP:DIS: Retrying operational DNS-SD discovery. Attempts remaining: 1
[1685017388.315346][7164:7166] CHIP:CTL: Session establishment failed for <0000000000000001, 1>, error: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout. Next retry expected to get a response to Sigma1 or fail within 60 seconds
[1685017388.315474][7164:7166] CHIP:CTL: Arming failsafe (120 seconds)
[1685017388.315493][7164:7166] CHIP:DMG: ICR moving to [AddingComm]
[1685017388.315496][7164:7166] CHIP:DMG: ICR moving to [AddedComma]
[1685017388.315561][7164:7166] CHIP:EM: <<< [E:24739i S:31218 M:130712344] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
[1685017388.315568][7164:7166] CHIP:IN: (S) Sending msg 130712344 on secure session with LSID: 31218
[1685017388.315800][7164:7166] CHIP:DMG: ICR moving to [CommandSen]
[1685017388.406902][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017388.408503][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017388.408586][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017388.408701][7164:7166] CHIP:EM: >>> [E:24739i S:31218 M:110006095] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1685017388.408739][7164:7166] CHIP:EM: Found matching exchange: 24739i, Delegate: 0x7fccb4073368
[1685017388.408746][7164:7166] CHIP:DMG: ICR moving to [ResponseRe]
[1685017388.408755][7164:7166] CHIP:DMG: InvokeResponseMessage =
[1685017388.408756][7164:7166] CHIP:DMG: {
[1685017388.408758][7164:7166] CHIP:DMG: suppressResponse = false,
[1685017388.408760][7164:7166] CHIP:DMG: InvokeResponseIBs =
[1685017388.408763][7164:7166] CHIP:DMG: [
[1685017388.408764][7164:7166] CHIP:DMG: InvokeResponseIB =
[1685017388.408767][7164:7166] CHIP:DMG: {
[1685017388.408768][7164:7166] CHIP:DMG: CommandDataIB =
[1685017388.408770][7164:7166] CHIP:DMG: {
[1685017388.408772][7164:7166] CHIP:DMG: CommandPathIB =
[1685017388.408774][7164:7166] CHIP:DMG: {
[1685017388.408776][7164:7166] CHIP:DMG: EndpointId = 0x0,
[1685017388.408778][7164:7166] CHIP:DMG: ClusterId = 0x30,
[1685017388.408780][7164:7166] CHIP:DMG: CommandId = 0x1,
[1685017388.408781][7164:7166] CHIP:DMG: },
[1685017388.408783][7164:7166] CHIP:DMG:
[1685017388.408785][7164:7166] CHIP:DMG: CommandFields =
[1685017388.408787][7164:7166] CHIP:DMG: {
[1685017388.408789][7164:7166] CHIP:DMG: 0x0 = 0,
[1685017388.408791][7164:7166] CHIP:DMG: 0x1 = "" (0 chars),
[1685017388.408824][7164:7166] CHIP:DMG: },
[1685017388.408827][7164:7166] CHIP:DMG: },
[1685017388.408830][7164:7166] CHIP:DMG:
[1685017388.408831][7164:7166] CHIP:DMG: },
[1685017388.408834][7164:7166] CHIP:DMG:
[1685017388.408835][7164:7166] CHIP:DMG: ],
[1685017388.408838][7164:7166] CHIP:DMG:
[1685017388.408839][7164:7166] CHIP:DMG: InteractionModelRevision = 1
[1685017388.408840][7164:7166] CHIP:DMG: },
[1685017388.408850][7164:7166] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1685017388.408856][7164:7166] CHIP:CTL: Status of extending fail-safe for CASE retry: 0
[1685017388.408861][7164:7166] CHIP:DMG: ICR moving to [AwaitingDe]
[1685017388.514952][7164:7166] CHIP:DIS: Checking node lookup status after 200 ms
[1685017390.981222][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017393.447371][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017393.447720][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017396.057416][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017398.532978][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017398.533211][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017401.144438][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017403.618599][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017403.618747][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017406.182824][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017408.659082][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017408.659142][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017411.267695][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017413.742923][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017413.743486][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017416.357414][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017418.828777][7164:7165] CHIP:DL: Indication received, conn = 0x7fccb40710e0
[1685017418.829059][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1685017419.321870][7164:7166] CHIP:DIS: Timeout waiting for mDNS resolution.
[1685017421.437699][7164:7166] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1685017422.424051][7164:7164] CHIP:CTL: Shutting down the commissioner
[1685017422.424128][7164:7164] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1685017422.424319][7164:7164] CHIP:DIS: Closing all BLE connections
[1685017422.424362][7164:7164] CHIP:IN: Clearing BLE pending packets.
[1685017422.425129][7164:7164] CHIP:BLE: Auto-closing end point's BLE connection.
[1685017422.425179][7164:7164] CHIP:DL: Closing BLE GATT connection (con 0x7fccb40710e0)
[1685017422.425196][7164:7165] CHIP:DL: BluezDisconnect peer=FA:FE:EA:D2:27:71
[1685017422.608868][7164:7165] CHIP:DL: Bluez disconnected
[1685017422.609036][7164:7165] CHIP:DL: Bluez notify CHIPoBluez connection disconnected
[1685017422.609026][7164:7164] CHIP:IN: SecureSession[0x7fccac00acf0]: MarkForEviction Type:1 LSID:31218
[1685017422.609072][7164:7164] CHIP:SC: SecureSession[0x7fccac00acf0, LSID:31218]: State change 'kActive' --> 'kPendingEviction'
[1685017422.609091][7164:7164] CHIP:IN: SecureSession[0x7fccac00acf0]: Released - Type:1 LSID:31218
[1685017422.609101][7164:7164] CHIP:CTL: Shutting down the controller
[1685017422.609110][7164:7164] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Cancelling incomplete address resolution as device is being deleted.
[1685017422.609128][7164:7164] CHIP:CTL: Device connection failed. Error src/app/OperationalSessionSetup.cpp:450: CHIP Error 0x00000074: The operation has been cancelled
[1685017422.609136][7164:7164] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1685017422.609143][7164:7164] CHIP:FP: Forgetting fabric 0x1
[1685017422.609155][7164:7164] CHIP:TS: Pending Last Known Good Time: 2023-05-25T11:03:16
[1685017422.609219][7164:7164] CHIP:TS: Previous Last Known Good Time: 2023-05-25T11:03:16
[1685017422.609225][7164:7164] CHIP:TS: Reverted Last Known Good Time to previous value
[1685017422.609242][7164:7164] CHIP:CTL: Shutting down the commissioner
[1685017422.609246][7164:7164] CHIP:CTL: Stopping commissioning discovery over DNS-SD
[1685017422.609270][7164:7164] CHIP:CTL: Shutting down the controller
[1685017422.609274][7164:7164] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1685017422.609342][7164:7164] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1685017422.609403][7164:7164] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented.
[1685017422.609416][7164:7164] CHIP:FP: Shutting down FabricTable
[1685017422.609422][7164:7164] CHIP:TS: Pending Last Known Good Time: 2023-05-25T11:03:16
[1685017422.609449][7164:7164] CHIP:TS: Previous Last Known Good Time: 2023-05-25T11:03:16
[1685017422.609453][7164:7164] CHIP:TS: Reverted Last Known Good Time to previous value
[1685017422.609598][7164:7164] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-B9qI0K)
[1685017422.609815][7164:7164] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1685017422.609845][7164:7164] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1685017422.609853][7164:7164] CHIP:DL: Inet Layer shutdown
[1685017422.609860][7164:7164] CHIP:DL: BLE shutdown
[1685017422.610283][7164:7164] CHIP:DL: System Layer shutdown
[1685017422.610428][7164:7164] CHIP:TOO: Run command failure: ../examples/chip-tool/commands/common/CHIPCommand.cpp:537: CHIP Error 0x00000032: Timeout
Can you please help me?
Thanks in advance
Paula.
