: light 프로젝트와 chip-tool 또는 smartThings 앱 사용해 commissioning 시 나오는 로그를
참고자료로 올립니다.
목차
chip-tool Log
$ ./chip-tool pairing ble-wifi 0x2222 [ssid] [passcode] 20202021 3840
[1706582561.832383][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs
[1706582561.832825][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs
[1706582561.832832][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/
chip_kvs
[1706582561.834506][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini
[1706582561.834543][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini
[1706582561.834565][5712:5712] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini
[1706582561.834640][5712:5712] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-RrFa0F)
[1706582561.834848][5712:5712] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1706582561.834859][5712:5712] CHIP:DL: NVS set: chip-counters/reboot-count = 6 (0x6)
[1706582561.835027][5712:5712] CHIP:DL: Got Ethernet interface: enp3s0
[1706582561.835161][5712:5712] CHIP:DL: Found the primary Ethernet interface:enp3s0
[1706582561.835330][5712:5712] CHIP:DL: Failed to get WiFi interface
[1706582561.835335][5712:5712] CHIP:DL: Failed to reset WiFi statistic counts
[1706582561.835362][5712:5712] CHIP:IN: UDP::Init bind&listen port=0
[1706582561.835399][5712:5712] CHIP:IN: UDP::Init bound to port=44862
[1706582561.835404][5712:5712] CHIP:IN: UDP::Init bind&listen port=0
[1706582561.835437][5712:5712] CHIP:IN: UDP::Init bound to port=57982
[1706582561.835455][5712:5712] CHIP:IN: BLEBase::Init - setting/overriding transport
[1706582561.835459][5712:5712] CHIP:IN: TransportMgr initialized
[1706582561.835484][5712:5712] CHIP:FP: Initializing FabricTable from persistent storage
[1706582561.835553][5712:5712] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48
[1706582561.836067][5712:5712] CHIP:FP: Fabric index 0x1 was retrieved from storage.
Compressed FabricId 0x7219618C7FEF6A57, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1
[1706582561.836255][5712:5712] CHIP:FP: Fabric index 0x2 was retrieved from storage.
Compressed FabricId 0xFC9E4ACEBBB01A63, FabricId 0x0000000000000002, NodeId 0x000000000001B669, VendorId 0xFFF1
[1706582561.836464][5712:5712] CHIP:FP: Fabric index 0x3 was retrieved from storage.
Compressed FabricId 0x12872D7B9A851EAE, FabricId 0x0000000000000003, NodeId 0x000000000001B669, VendorId 0xFFF1
[1706582561.837127][5712:5712] CHIP:ZCL: Using ZAP configuration...
[1706582561.837561][5712:5712] CHIP:CTL: System State Initialized...
[1706582561.837644][5712:5712] CHIP:CTL: Setting attestation nonce to random value
[1706582561.837653][5712:5712] CHIP:CTL: Setting CSR nonce to random value
[1706582561.837666][5712:5712] CHIP:IN: UDP::Init bind&listen port=5550
[1706582561.837684][5712:5712] CHIP:IN: UDP::Init bound to port=5550
[1706582561.837689][5712:5712] CHIP:IN: UDP::Init bind&listen port=5550
[1706582561.837705][5712:5712] CHIP:IN: UDP::Init bound to port=5550
[1706582561.837709][5712:5712] CHIP:IN: TransportMgr initialized
[1706582561.837800][5712:5714] CHIP:DL: CHIP task running
[1706582561.837820][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 32786
[1706582561.837927][5712:5714] CHIP:CTL: Setting attestation nonce to random value
[1706582561.837954][5712:5714] CHIP:CTL: Setting CSR nonce to random value
[1706582561.838203][5712:5714] CHIP:CTL: Generating NOC
[1706582561.838501][5712:5714] CHIP:FP: Validating NOC chain
[1706582561.838865][5712:5714] CHIP:FP: NOC chain validation successful
[1706582561.838919][5712:5714] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669
[1706582561.838939][5712:5714] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48
[1706582561.838944][5712:5714] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00
[1706582561.838961][5712:5714] CHIP:TS: Retaining current Last Known Good Time
[1706582561.839551][5712:5714] CHIP:FP: Metadata for Fabric 0x1 persisted to storage.
[1706582561.840126][5712:5714] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-14T01:16:48
[1706582561.840682][5712:5714] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 7219618C7FEF6A57)
[1706582561.840690][5712:5714] CHIP:IN: UDP::Init bind&listen port=5550
[1706582561.840710][5712:5714] CHIP:IN: UDP::Init bound to port=5550
[1706582561.840715][5712:5714] CHIP:IN: UDP::Init bind&listen port=5550
[1706582561.840731][5712:5714] CHIP:IN: UDP::Init bound to port=5550
[1706582561.840736][5712:5714] CHIP:IN: TransportMgr initialized
[1706582561.845799][5712:5714] CHIP:CTL: Setting wifi credentials from parameters
[1706582561.845806][5712:5714] CHIP:CTL: Setting attestation nonce to random value
[1706582561.845813][5712:5714] CHIP:CTL: Setting CSR nonce to random value
[1706582561.845819][5712:5714] CHIP:CTL: Checking ICD registration parameters
[1706582561.845824][5712:5714] CHIP:CTL: Commission called for node ID 0x0000000000002222
[1706582561.847524][5712:5713] CHIP:DL: TRACE: Bus acquired for name C-1650
[1706582561.849218][5712:5714] CHIP:DL: BlueZ integration init success
[1706582561.850595][5712:5713] CHIP:BLE: BLE removing known devices.
[1706582561.851092][5712:5713] CHIP:BLE: BLE initiating scan.
[1706582561.971127][5712:5714] CHIP:DL: Long dispatch time: 125 ms, for event type 2
[1706582562.025218][5712:5713] CHIP:BLE: Device 68:14:CB:5D:BA:3F does not look like a CHIP device.
[1706582562.026395][5712:5713] CHIP:BLE: Device F8:04:2E:F7:5F:B7 does not look like a CHIP device.
[1706582562.027385][5712:5713] CHIP:BLE: Device 39:07:BD:D7:D4:54 does not look like a CHIP device.
[1706582562.146107][5712:5713] CHIP:BLE: Device 67:E6:A2:D8:05:E2 does not look like a CHIP device.
[1706582562.249978][5712:5713] CHIP:BLE: Device 47:B0:97:42:FD:86 does not look like a CHIP device.
[1706582562.438894][5712:5713] CHIP:BLE: Device 75:D4:43:59:CF:D6 does not look like a CHIP device.
[1706582562.439972][5712:5713] CHIP:BLE: Device 14:4E:34:82:CC:3F does not look like a CHIP device.
[1706582562.486189][5712:5713] CHIP:BLE: Device EE:92:58:C2:82:34 does not look like a CHIP device.
[1706582562.728009][5712:5713] CHIP:BLE: Device 47:B0:97:42:FD:86 does not look like a CHIP device.
[1706582562.856254][5712:5713] CHIP:BLE: New device scanned: D1:96:30:DD:8A:29
[1706582562.856264][5712:5713] CHIP:BLE: Device discriminator match. Attempting to connect.
[1706582563.796339][5712:5713] CHIP:DL: ConnectDevice complete
[1706582564.820171][5712:5713] CHIP:DL: Char /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a on service /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a
[1706582564.820183][5712:5713] CHIP:DL: Char /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service0006 on service /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a
[1706582564.820188][5712:5713] CHIP:DL: Char /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service0006 on service /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a
[1706582564.820193][5712:5713] CHIP:DL: Char /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a on service /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a
[1706582564.820198][5712:5713] CHIP:DL: Char /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a on service /org/bluez/hci0/dev_D1_96_30_DD_8A_29/service000a
[1706582564.820209][5712:5713] CHIP:DL: New BLE connection: conn 0x7f19d001eac0, device D1:96:30:DD:8A:29, path /org/bluez/hci0/dev_D1_96_30_DD_8A_29
[1706582564.820266][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 ------> kPlatformESP32BLECentralConnected
[1706582564.820277][5712:5714] CHIP:DIS: Closing all BLE connections
[1706582564.820300][5712:5714] CHIP:IN: BleConnectionComplete: endPoint 0x55cd81859560
[1706582564.820471][5712:5714] CHIP:IN: SecureSession[0x7f19d800f870]: Allocated Type:1 LSID:40863
[1706582564.820482][5712:5714] CHIP:SC: Assigned local session key ID 40863
[1706582564.820507][5712:5714] CHIP:EM: <<< [E:20022i S:0 M:37012638] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
[1706582564.820518][5712:5714] CHIP:IN: Message appended to BLE send queue
[1706582564.820523][5712:5714] CHIP:SC: Sent PBKDF param request
[1706582565.150542][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 ------> kPlatformESP32BLEWriteComplete
[1706582565.239230][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 ------> kPlatformESP32BLESubscribeOpComplete
[1706582565.239284][5712:5714] CHIP:BLE: subscribe complete, ep = 0x55cd81859560
[1706582565.239716][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.239866][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 ------> kPlatformESP32BLEIndicationReceived
[1706582565.239909][5712:5714] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4
[1706582565.239933][5712:5714] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244.
[1706582565.239951][5712:5714] CHIP:BLE: local and remote recv window size = 5
[1706582565.240365][5712:5714] CHIP:IN: BLE EndPoint 0x55cd81859560 Connection Complete
[1706582565.330531][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 ------> kPlatformESP32BLEWriteComplete
[1706582565.378965][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.379107][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 ------> kPlatformESP32BLEIndicationReceived
[1706582565.379224][5712:5714] CHIP:EM: >>> [E:20022i S:0 M:262434376] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
[1706582565.379261][5712:5714] CHIP:EM: Found matching exchange: 20022i, Delegate: 0x7f19d8013740
[1706582565.379315][5712:5714] CHIP:SC: Received PBKDF param response
[1706582565.379365][5712:5714] CHIP:SC: Peer assigned session ID 31019
[1706582565.379405][5712:5714] CHIP:SC: Found MRP parameters in the message
[1706582565.385830][5712:5714] CHIP:EM: <<< [E:20022i S:0 M:37012639] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1)
[1706582565.386020][5712:5714] CHIP:SC: Sent spake2p msg1
[1706582565.466535][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582565.555004][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.555151][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582565.555261][5712:5714] CHIP:EM: >>> [E:20022i S:0 M:262434377] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
[1706582565.555297][5712:5714] CHIP:EM: Found matching exchange: 20022i, Delegate: 0x7f19d8013740
[1706582565.555351][5712:5714] CHIP:SC: Received spake2p msg2
[1706582565.556500][5712:5714] CHIP:EM: <<< [E:20022i S:0 M:37012640] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3)
[1706582565.556940][5712:5714] CHIP:SC: Sent spake2p msg3
[1706582565.646521][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582565.691088][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.691246][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582565.691345][5712:5714] CHIP:EM: >>> [E:20022i S:0 M:262434378] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1706582565.691377][5712:5714] CHIP:EM: Found matching exchange: 20022i, Delegate: 0x7f19d8013740
[1706582565.691534][5712:5714] CHIP:SC: SecureSession[0x7f19d800f870, LSID:40863]: State change 'kEstablishing' --> 'kActive'
[1706582565.691561][5712:5714] CHIP:IN: SecureSession[0x7f19d800f870]: Activated - Type:1 LSID:40863
[1706582565.691589][5712:5714] CHIP:IN: New secure session activated for device <FFFFFFFB00000000, 0>, LSID:40863 PSID:31019!
[1706582565.691615][5712:5714] CHIP:CTL: Remote device completed SPAKE2+ handshake
[1706582565.691636][5712:5714] CHIP:TOO: Pairing Success
[1706582565.691656][5712:5714] CHIP:TOO: PASE establishment successful
[1706582565.691679][5712:5714] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo'
[1706582565.691702][5712:5714] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo'
[1706582565.691723][5712:5714] CHIP:CTL: Sending read request for commissioning information
[1706582565.691763][5712:5714] CHIP:DMG: SendReadRequest ReadClient[0x7f19d800ae10]: Sending Read Request
[1706582565.691900][5712:5714] CHIP:EM: <<< [E:20023i S:40863 M:20622510] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest)
[1706582565.692293][5712:5714] CHIP:DMG: MoveToState ReadClient[0x7f19d800ae10]: Moving to [AwaitingIn]
[1706582565.778524][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 ------> kPlatformESP32BLEWriteComplete
[1706582565.826981][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.827125][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 ------> kPlatformESP32BLEIndicationReceived
[1706582565.914985][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582565.915127][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 ------> kPlatformESP32BLEIndicationReceived
[1706582565.915241][5712:5714] CHIP:EM: >>> [E:20023i S:40863 M:149183754] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1706582565.915280][5712:5714] CHIP:EM: Found matching exchange: 20023i, Delegate: 0x7f19d800ae20
[1706582565.915401][5712:5714] CHIP:DMG: ReportDataMessage =
[1706582565.915431][5712:5714] CHIP:DMG: {
[1706582565.915462][5712:5714] CHIP:DMG: AttributeReportIBs =
[1706582565.915509][5712:5714] CHIP:DMG: [
[1706582565.915536][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.915583][5712:5714] CHIP:DMG: {
[1706582565.915612][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.915650][5712:5714] CHIP:DMG: {
[1706582565.915685][5712:5714] CHIP:DMG: DataVersion = 0x6e34f3bb,
[1706582565.915716][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.915754][5712:5714] CHIP:DMG: {
[1706582565.915790][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.915826][5712:5714] CHIP:DMG: Cluster = 0x31, ----> Network Commissioning
[1706582565.915863][5712:5714] CHIP:DMG: Attribute = 0x0000_0003, ----> Connect-Max-TimeSeconds
[1706582565.915895][5712:5714] CHIP:DMG: }
[1706582565.915937][5712:5714] CHIP:DMG:
[1706582565.915974][5712:5714] CHIP:DMG: Data = 30,
[1706582565.916004][5712:5714] CHIP:DMG: },
[1706582565.916048][5712:5714] CHIP:DMG:
[1706582565.916076][5712:5714] CHIP:DMG: },
[1706582565.916133][5712:5714] CHIP:DMG:
[1706582565.916160][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.916207][5712:5714] CHIP:DMG: {
[1706582565.916235][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.916272][5712:5714] CHIP:DMG: {
[1706582565.916307][5712:5714] CHIP:DMG: DataVersion = 0xac4402ce,
[1706582565.916338][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.916375][5712:5714] CHIP:DMG: {
[1706582565.916411][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.916446][5712:5714] CHIP:DMG: Cluster = 0x28, ----> Basic Information
[1706582565.916484][5712:5714] CHIP:DMG: Attribute = 0x0000_0004, ----> Product ID
[1706582565.916516][5712:5714] CHIP:DMG: }
[1706582565.916559][5712:5714] CHIP:DMG:
[1706582565.916596][5712:5714] CHIP:DMG: Data = 32768,
[1706582565.916625][5712:5714] CHIP:DMG: },
[1706582565.916668][5712:5714] CHIP:DMG:
[1706582565.916696][5712:5714] CHIP:DMG: },
[1706582565.916752][5712:5714] CHIP:DMG:
[1706582565.916779][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.916825][5712:5714] CHIP:DMG: {
[1706582565.916853][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.916889][5712:5714] CHIP:DMG: {
[1706582565.916924][5712:5714] CHIP:DMG: DataVersion = 0xac4402ce,
[1706582565.916955][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.916992][5712:5714] CHIP:DMG: {
[1706582565.917027][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.917063][5712:5714] CHIP:DMG: Cluster = 0x28, ----> Basic Information
[1706582565.917099][5712:5714] CHIP:DMG: Attribute = 0x0000_0002, ----> Vendor ID
[1706582565.917130][5712:5714] CHIP:DMG: }
[1706582565.917172][5712:5714] CHIP:DMG:
[1706582565.917208][5712:5714] CHIP:DMG: Data = 65521,
[1706582565.917237][5712:5714] CHIP:DMG: },
[1706582565.917280][5712:5714] CHIP:DMG:
[1706582565.917307][5712:5714] CHIP:DMG: },
[1706582565.917363][5712:5714] CHIP:DMG:
[1706582565.917390][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.917436][5712:5714] CHIP:DMG: {
[1706582565.917464][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.917500][5712:5714] CHIP:DMG: {
[1706582565.917536][5712:5714] CHIP:DMG: DataVersion = 0x9dd0ad58,
[1706582565.917604][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.917642][5712:5714] CHIP:DMG: {
[1706582565.917677][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.917713][5712:5714] CHIP:DMG: Cluster = 0x30, ----> General Commissioning
[1706582565.917749][5712:5714] CHIP:DMG: Attribute = 0x0000_0003, ---> LocationCapability
[1706582565.917780][5712:5714] CHIP:DMG: }
[1706582565.917822][5712:5714] CHIP:DMG:
[1706582565.917858][5712:5714] CHIP:DMG: Data = 0,
[1706582565.917888][5712:5714] CHIP:DMG: },
[1706582565.917931][5712:5714] CHIP:DMG:
[1706582565.917958][5712:5714] CHIP:DMG: },
[1706582565.918005][5712:5714] CHIP:DMG:
[1706582565.918011][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.918019][5712:5714] CHIP:DMG: {
[1706582565.918024][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.918030][5712:5714] CHIP:DMG: {
[1706582565.918036][5712:5714] CHIP:DMG: DataVersion = 0x9dd0ad58,
[1706582565.918042][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.918048][5712:5714] CHIP:DMG: {
[1706582565.918054][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.918060][5712:5714] CHIP:DMG: Cluster = 0x30, ----> General Commissioning
[1706582565.918066][5712:5714] CHIP:DMG: Attribute = 0x0000_0002, ----> RegulatoryConfig
[1706582565.918072][5712:5714] CHIP:DMG: }
[1706582565.918079][5712:5714] CHIP:DMG:
[1706582565.918085][5712:5714] CHIP:DMG: Data = 0,
[1706582565.918091][5712:5714] CHIP:DMG: },
[1706582565.918098][5712:5714] CHIP:DMG:
[1706582565.918103][5712:5714] CHIP:DMG: },
[1706582565.918114][5712:5714] CHIP:DMG:
[1706582565.918119][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.918128][5712:5714] CHIP:DMG: {
[1706582565.918133][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.918139][5712:5714] CHIP:DMG: {
[1706582565.918144][5712:5714] CHIP:DMG: DataVersion = 0x9dd0ad58,
[1706582565.918150][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.918156][5712:5714] CHIP:DMG: {
[1706582565.918162][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.918169][5712:5714] CHIP:DMG: Cluster = 0x30, ----> General Commissioning
[1706582565.918175][5712:5714] CHIP:DMG: Attribute = 0x0000_0001, ----> BasicCommissioningInfo
[1706582565.918181][5712:5714] CHIP:DMG: }
[1706582565.918187][5712:5714] CHIP:DMG:
[1706582565.918193][5712:5714] CHIP:DMG: Data =
[1706582565.918199][5712:5714] CHIP:DMG: {
[1706582565.918206][5712:5714] CHIP:DMG: 0x0 = 60,
[1706582565.918212][5712:5714] CHIP:DMG: 0x1 = 900,
[1706582565.918219][5712:5714] CHIP:DMG: },
[1706582565.918224][5712:5714] CHIP:DMG: },
[1706582565.918232][5712:5714] CHIP:DMG:
[1706582565.918237][5712:5714] CHIP:DMG: },
[1706582565.918248][5712:5714] CHIP:DMG:
[1706582565.918253][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.918261][5712:5714] CHIP:DMG: {
[1706582565.918266][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.918272][5712:5714] CHIP:DMG: {
[1706582565.918278][5712:5714] CHIP:DMG: DataVersion = 0x9dd0ad58,
[1706582565.918283][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.918289][5712:5714] CHIP:DMG: {
[1706582565.918295][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.918302][5712:5714] CHIP:DMG: Cluster = 0x30, ----> General Commissioning
[1706582565.918308][5712:5714] CHIP:DMG: Attribute = 0x0000_0000, ---> Breadcrumb
[1706582565.918314][5712:5714] CHIP:DMG: }
[1706582565.918320][5712:5714] CHIP:DMG:
[1706582565.918327][5712:5714] CHIP:DMG: Data = 0,
[1706582565.918332][5712:5714] CHIP:DMG: },
[1706582565.918339][5712:5714] CHIP:DMG:
[1706582565.918345][5712:5714] CHIP:DMG: },
[1706582565.918355][5712:5714] CHIP:DMG:
[1706582565.918360][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582565.918368][5712:5714] CHIP:DMG: {
[1706582565.918373][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582565.918379][5712:5714] CHIP:DMG: {
[1706582565.918384][5712:5714] CHIP:DMG: DataVersion = 0x6e34f3bb,
[1706582565.918390][5712:5714] CHIP:DMG: AttributePathIB =
[1706582565.918396][5712:5714] CHIP:DMG: {
[1706582565.918402][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582565.918408][5712:5714] CHIP:DMG: Cluster = 0x31, ----> Network Commissioning
[1706582565.918415][5712:5714] CHIP:DMG: Attribute = 0x0000_FFFC, ----> FeatureMap
[1706582565.918420][5712:5714] CHIP:DMG: }
[1706582565.918427][5712:5714] CHIP:DMG:
[1706582565.918434][5712:5714] CHIP:DMG: Data = 1,
[1706582565.918439][5712:5714] CHIP:DMG: },
[1706582565.918447][5712:5714] CHIP:DMG:
[1706582565.918452][5712:5714] CHIP:DMG: },
[1706582565.918459][5712:5714] CHIP:DMG:
[1706582565.918464][5712:5714] CHIP:DMG: ],
[1706582565.918488][5712:5714] CHIP:DMG:
[1706582565.918493][5712:5714] CHIP:DMG: SuppressResponse = true,
[1706582565.918499][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582565.918504][5712:5714] CHIP:DMG: }
[1706582565.918793][5712:5714] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo'
[1706582565.918800][5712:5714] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2'
[1706582565.918806][5712:5714] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo2'
[1706582565.918814][5712:5714] CHIP:DMG: SendReadRequest ReadClient[0x7f19d800ae10]: Sending Read Request
[1706582565.918842][5712:5714] CHIP:EM: <<< [E:20024i S:40863 M:20622511] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest)
[1706582565.918945][5712:5714] CHIP:DMG: MoveToState ReadClient[0x7f19d800ae10]: Moving to [AwaitingIn]
[1706582566.006516][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582566.007076][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.007236][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.007403][5712:5714] CHIP:EM: >>> [E:20024i S:40863 M:149183755] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
[1706582566.007457][5712:5714] CHIP:EM: Found matching exchange: 20024i, Delegate: 0x7f19d800ae20
[1706582566.007575][5712:5714] CHIP:DMG: ReportDataMessage =
[1706582566.007615][5712:5714] CHIP:DMG: {
[1706582566.007643][5712:5714] CHIP:DMG: AttributeReportIBs =
[1706582566.007691][5712:5714] CHIP:DMG: [
[1706582566.007724][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582566.007773][5712:5714] CHIP:DMG: {
[1706582566.007804][5712:5714] CHIP:DMG: AttributeStatusIB =
[1706582566.007842][5712:5714] CHIP:DMG: {
[1706582566.007877][5712:5714] CHIP:DMG: AttributePathIB =
[1706582566.007917][5712:5714] CHIP:DMG: {
[1706582566.007954][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582566.007993][5712:5714] CHIP:DMG: Cluster = 0x46, ----> ICDManagement
[1706582566.008034][5712:5714] CHIP:DMG: Attribute = 0x0000_0007,
[1706582566.008074][5712:5714] CHIP:DMG: }
[1706582566.008125][5712:5714] CHIP:DMG:
[1706582566.008162][5712:5714] CHIP:DMG: StatusIB =
[1706582566.008198][5712:5714] CHIP:DMG: {
[1706582566.008237][5712:5714] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER),
[1706582566.008273][5712:5714] CHIP:DMG: },
[1706582566.008311][5712:5714] CHIP:DMG:
[1706582566.008345][5712:5714] CHIP:DMG: },
[1706582566.008394][5712:5714] CHIP:DMG:
[1706582566.008427][5712:5714] CHIP:DMG: },
[1706582566.008484][5712:5714] CHIP:DMG:
[1706582566.008517][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582566.008574][5712:5714] CHIP:DMG: {
[1706582566.008606][5712:5714] CHIP:DMG: AttributeStatusIB =
[1706582566.008647][5712:5714] CHIP:DMG: {
[1706582566.008682][5712:5714] CHIP:DMG: AttributePathIB =
[1706582566.008724][5712:5714] CHIP:DMG: {
[1706582566.008767][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582566.008813][5712:5714] CHIP:DMG: Cluster = 0x46, ----> ICDManagement
[1706582566.008859][5712:5714] CHIP:DMG: Attribute = 0x0000_0006,
[1706582566.008897][5712:5714] CHIP:DMG: }
[1706582566.008950][5712:5714] CHIP:DMG:
[1706582566.008987][5712:5714] CHIP:DMG: StatusIB =
[1706582566.009029][5712:5714] CHIP:DMG: {
[1706582566.009073][5712:5714] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER),
[1706582566.009116][5712:5714] CHIP:DMG: },
[1706582566.009161][5712:5714] CHIP:DMG:
[1706582566.009195][5712:5714] CHIP:DMG: },
[1706582566.009244][5712:5714] CHIP:DMG:
[1706582566.009279][5712:5714] CHIP:DMG: },
[1706582566.009345][5712:5714] CHIP:DMG:
[1706582566.009379][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582566.009426][5712:5714] CHIP:DMG: {
[1706582566.009461][5712:5714] CHIP:DMG: AttributeStatusIB =
[1706582566.009500][5712:5714] CHIP:DMG: {
[1706582566.009538][5712:5714] CHIP:DMG: AttributePathIB =
[1706582566.009609][5712:5714] CHIP:DMG: {
[1706582566.009648][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582566.009687][5712:5714] CHIP:DMG: Cluster = 0x46, ----> ICDManagement
[1706582566.009730][5712:5714] CHIP:DMG: Attribute = 0x0000_FFFC, ----> FeatureMap
[1706582566.009774][5712:5714] CHIP:DMG: }
[1706582566.009821][5712:5714] CHIP:DMG:
[1706582566.009869][5712:5714] CHIP:DMG: StatusIB =
[1706582566.009908][5712:5714] CHIP:DMG: {
[1706582566.009948][5712:5714] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER),
[1706582566.009989][5712:5714] CHIP:DMG: },
[1706582566.010030][5712:5714] CHIP:DMG:
[1706582566.010067][5712:5714] CHIP:DMG: },
[1706582566.010111][5712:5714] CHIP:DMG:
[1706582566.010143][5712:5714] CHIP:DMG: },
[1706582566.010202][5712:5714] CHIP:DMG:
[1706582566.010239][5712:5714] CHIP:DMG: AttributeReportIB =
[1706582566.010286][5712:5714] CHIP:DMG: {
[1706582566.010318][5712:5714] CHIP:DMG: AttributeDataIB =
[1706582566.010357][5712:5714] CHIP:DMG: {
[1706582566.010396][5712:5714] CHIP:DMG: DataVersion = 0x9dd0ad58,
[1706582566.010436][5712:5714] CHIP:DMG: AttributePathIB =
[1706582566.010473][5712:5714] CHIP:DMG: {
[1706582566.010510][5712:5714] CHIP:DMG: Endpoint = 0x0,
[1706582566.010554][5712:5714] CHIP:DMG: Cluster = 0x30, ----> General Commissioning
[1706582566.010598][5712:5714] CHIP:DMG: Attribute = 0x0000_0004, ----> SupportConcurrentConnection
[1706582566.010638][5712:5714] CHIP:DMG: }
[1706582566.010686][5712:5714] CHIP:DMG:
[1706582566.010726][5712:5714] CHIP:DMG: Data = true,
[1706582566.010764][5712:5714] CHIP:DMG: },
[1706582566.010807][5712:5714] CHIP:DMG:
[1706582566.010845][5712:5714] CHIP:DMG: },
[1706582566.010889][5712:5714] CHIP:DMG:
[1706582566.010919][5712:5714] CHIP:DMG: ],
[1706582566.011005][5712:5714] CHIP:DMG:
[1706582566.011041][5712:5714] CHIP:DMG: SuppressResponse = true,
[1706582566.011076][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582566.011106][5712:5714] CHIP:DMG: }
[1706582566.011810][5712:5714] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid = 0
[1706582566.011874][5712:5714] CHIP:SVR: OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8000
[1706582566.011921][5712:5714] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo2'
[1706582566.011959][5712:5714] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe'
[1706582566.011982][5712:5714] CHIP:CTL: Performing next commissioning step 'ArmFailSafe'
[1706582566.011989][5712:5714] CHIP:CTL: Arming failsafe (60 seconds)
[1706582566.012015][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582566.012025][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582566.012054][5712:5714] CHIP:EM: <<< [E:20025i S:40863 M:20622512] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582566.012207][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582566.094533][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582566.143049][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.143218][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.143399][5712:5714] CHIP:EM: >>> [E:20025i S:40863 M:149183756] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582566.143451][5712:5714] CHIP:EM: Found matching exchange: 20025i, Delegate: 0x7f19d8019218
[1706582566.143490][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582566.143558][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582566.143588][5712:5714] CHIP:DMG: {
[1706582566.143618][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582566.143654][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582566.143707][5712:5714] CHIP:DMG: [
[1706582566.143741][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582566.143790][5712:5714] CHIP:DMG: {
[1706582566.143822][5712:5714] CHIP:DMG: CommandDataIB =
[1706582566.143857][5712:5714] CHIP:DMG: {
[1706582566.143889][5712:5714] CHIP:DMG: CommandPathIB =
[1706582566.143929][5712:5714] CHIP:DMG: {
[1706582566.143967][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582566.144005][5712:5714] CHIP:DMG: ClusterId = 0x30, ----> General Commissioning
[1706582566.144041][5712:5714] CHIP:DMG: CommandId = 0x1, ----> ArmFailSafeResponse
[1706582566.144073][5712:5714] CHIP:DMG: },
[1706582566.144114][5712:5714] CHIP:DMG:
[1706582566.144149][5712:5714] CHIP:DMG: CommandFields =
[1706582566.144188][5712:5714] CHIP:DMG: {
[1706582566.144229][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582566.144276][5712:5714] CHIP:DMG: 0x1 = "" (0 chars),
[1706582566.144318][5712:5714] CHIP:DMG: },
[1706582566.144354][5712:5714] CHIP:DMG: },
[1706582566.144398][5712:5714] CHIP:DMG:
[1706582566.144431][5712:5714] CHIP:DMG: },
[1706582566.144481][5712:5714] CHIP:DMG:
[1706582566.144512][5712:5714] CHIP:DMG: ],
[1706582566.144561][5712:5714] CHIP:DMG:
[1706582566.144593][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582566.144623][5712:5714] CHIP:DMG: },
[1706582566.144753][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=
0x0000_0001
[1706582566.144850][5712:5714] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1706582566.144894][5712:5714] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe'
[1706582566.144927][5712:5714] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory'
[1706582566.144965][5712:5714] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory'
[1706582566.144995][5712:5714] CHIP:CTL: Setting Regulatory Config
[1706582566.145023][5712:5714] CHIP:CTL: Device does not support configurable regulatory location
[1706582566.145101][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582566.145152][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582566.145282][5712:5714] CHIP:EM: <<< [E:20026i S:40863 M:20622513] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582566.145819][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582566.145926][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582566.230538][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582566.275026][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.275190][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.275362][5712:5714] CHIP:EM: >>> [E:20026i S:40863 M:149183757] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582566.275415][5712:5714] CHIP:EM: Found matching exchange: 20026i, Delegate: 0x7f19d8019388
[1706582566.275457][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582566.275524][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582566.275560][5712:5714] CHIP:DMG: {
[1706582566.275591][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582566.275625][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582566.275680][5712:5714] CHIP:DMG: [
[1706582566.275707][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582566.275758][5712:5714] CHIP:DMG: {
[1706582566.275791][5712:5714] CHIP:DMG: CommandDataIB =
[1706582566.275825][5712:5714] CHIP:DMG: {
[1706582566.275860][5712:5714] CHIP:DMG: CommandPathIB =
[1706582566.275900][5712:5714] CHIP:DMG: {
[1706582566.275936][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582566.275979][5712:5714] CHIP:DMG: ClusterId = 0x30, ----> General Commissioning
[1706582566.276020][5712:5714] CHIP:DMG: CommandId = 0x3, ----> SetRegulatoryConfigResponse
[1706582566.276055][5712:5714] CHIP:DMG: },
[1706582566.276100][5712:5714] CHIP:DMG:
[1706582566.276134][5712:5714] CHIP:DMG: CommandFields =
[1706582566.276167][5712:5714] CHIP:DMG: {
[1706582566.276212][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582566.276252][5712:5714] CHIP:DMG: 0x1 = "" (0 chars),
[1706582566.276291][5712:5714] CHIP:DMG: },
[1706582566.276326][5712:5714] CHIP:DMG: },
[1706582566.276372][5712:5714] CHIP:DMG:
[1706582566.276405][5712:5714] CHIP:DMG: },
[1706582566.276452][5712:5714] CHIP:DMG:
[1706582566.276484][5712:5714] CHIP:DMG: ],
[1706582566.276531][5712:5714] CHIP:DMG:
[1706582566.276566][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582566.276598][5712:5714] CHIP:DMG: },
[1706582566.276731][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003
[1706582566.276795][5712:5714] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0
[1706582566.276835][5712:5714] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory'
[1706582566.276868][5712:5714] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> ' SendPAICertificateRequest'
[1706582566.276906][5712:5714] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest'
[1706582566.276936][5712:5714] CHIP:CTL: Sending request for PAI certificate
[1706582566.276964][5712:5714] CHIP:CTL: Sending Certificate Chain request to 0x7f19d80136f0 device
[1706582566.277040][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582566.277081][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582566.277212][5712:5714] CHIP:EM: <<< [E:20027i S:40863 M:20622514] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582566.277740][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582566.277852][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582566.366542][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582566.415053][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.415211][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.503050][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.503209][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.635112][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.635280][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.635425][5712:5714] CHIP:EM: >>> [E:20027i S:40863 M:149183758] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582566.635478][5712:5714] CHIP:EM: Found matching exchange: 20027i, Delegate: 0x7f19d8019218
[1706582566.635528][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582566.635604][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582566.635634][5712:5714] CHIP:DMG: {
[1706582566.635664][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582566.635689][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582566.635742][5712:5714] CHIP:DMG: [
[1706582566.635775][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582566.635817][5712:5714] CHIP:DMG: {
[1706582566.635851][5712:5714] CHIP:DMG: CommandDataIB =
[1706582566.635888][5712:5714] CHIP:DMG: {
[1706582566.635923][5712:5714] CHIP:DMG: CommandPathIB =
[1706582566.635960][5712:5714] CHIP:DMG: {
[1706582566.636001][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582566.636045][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582566.636084][5712:5714] CHIP:DMG: CommandId = 0x3, ----> CertificateChainResponse
[1706582566.636121][5712:5714] CHIP:DMG: },
[1706582566.636162][5712:5714] CHIP:DMG:
[1706582566.636197][5712:5714] CHIP:DMG: CommandFields =
[1706582566.636239][5712:5714] CHIP:DMG: {
[1706582566.636276][5712:5714] CHIP:DMG: 0x0 = [
[1706582566.636559][5712:5714] 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
[1706582566.636634][5712:5714] CHIP:DMG: ] (463 bytes)
[1706582566.636674][5712:5714] CHIP:DMG: },
[1706582566.636707][5712:5714] CHIP:DMG: },
[1706582566.636748][5712:5714] CHIP:DMG:
[1706582566.636774][5712:5714] CHIP:DMG: },
[1706582566.636820][5712:5714] CHIP:DMG:
[1706582566.636853][5712:5714] CHIP:DMG: ],
[1706582566.636902][5712:5714] CHIP:DMG:
[1706582566.636933][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582566.636966][5712:5714] CHIP:DMG: },
[1706582566.637093][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=
0x0000_0003
[1706582566.637153][5712:5714] CHIP:CTL: Received certificate chain from the device
[1706582566.637195][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest'
[1706582566.637231][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest'
[1706582566.637270][5712:5714] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest'
[1706582566.637301][5712:5714] CHIP:CTL: Sending request for DAC certificate
[1706582566.637331][5712:5714] CHIP:CTL: Sending Certificate Chain request to 0x7f19d80136f0 device
[1706582566.637408][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582566.637449][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582566.637615][5712:5714] CHIP:EM: <<< [E:20028i S:40863 M:20622515] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582566.638107][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582566.638215][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582566.726539][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582566.775044][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.775202][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.863050][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.863200][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.951035][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582566.951191][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582566.951324][5712:5714] CHIP:EM: >>> [E:20028i S:40863 M:149183759] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582566.951377][5712:5714] CHIP:EM: Found matching exchange: 20028i, Delegate: 0x7f19d8019388
[1706582566.951427][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582566.951500][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582566.951534][5712:5714] CHIP:DMG: {
[1706582566.951571][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582566.951604][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582566.951654][5712:5714] CHIP:DMG: [
[1706582566.951686][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582566.951735][5712:5714] CHIP:DMG: {
[1706582566.951772][5712:5714] CHIP:DMG: CommandDataIB =
[1706582566.951809][5712:5714] CHIP:DMG: {
[1706582566.951845][5712:5714] CHIP:DMG: CommandPathIB =
[1706582566.951885][5712:5714] CHIP:DMG: {
[1706582566.951925][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582566.951965][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582566.952004][5712:5714] CHIP:DMG: CommandId = 0x3, ----> CertificateChainResponse
[1706582566.952038][5712:5714] CHIP:DMG: },
[1706582566.952085][5712:5714] CHIP:DMG:
[1706582566.952117][5712:5714] CHIP:DMG: CommandFields =
[1706582566.952155][5712:5714] CHIP:DMG: {
[1706582566.952194][5712:5714] CHIP:DMG: 0x0 = [
[1706582566.952446][5712:5714] CHIP:DMG: 0x30, 0x82, 0x01, 0xe9
, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x23, 0x8a, 0x64, 0x7b, 0xbc, 0x4c, 0x30, 0xdd,
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, 0x30, 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, 0x30, 0x30,
0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03
, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x62, 0xdb, 0x16, 0xba, 0xde, 0xa3, 0x26, 0xa6, 0xdb, 0x84, 0x81, 0x4a, 0x06,
0x3f, 0xc6, 0xc7, 0xe9, 0xe2, 0xb1, 0x01, 0xb7, 0x21, 0x64, 0x8e, 0xba, 0x4e, 0x5a, 0xc8, 0x40, 0xf5, 0xda, 0x30, 0x1e
, 0xe6, 0x18, 0x12, 0x4e, 0xb4, 0x18, 0x
[1706582566.952525][5712:5714] CHIP:DMG: ] (493 bytes)
[1706582566.952564][5712:5714] CHIP:DMG: },
[1706582566.952598][5712:5714] CHIP:DMG: },
[1706582566.952642][5712:5714] CHIP:DMG:
[1706582566.952669][5712:5714] CHIP:DMG: },
[1706582566.952711][5712:5714] CHIP:DMG:
[1706582566.952742][5712:5714] CHIP:DMG: ],
[1706582566.952791][5712:5714] CHIP:DMG:
[1706582566.952825][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582566.952854][5712:5714] CHIP:DMG: },
[1706582566.952985][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003
[1706582566.953046][5712:5714] CHIP:CTL: Received certificate chain from the device
[1706582566.953084][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest'
[1706582566.953124][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest'
[1706582566.953163][5712:5714] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest'
[1706582566.953194][5712:5714] CHIP:CTL: Sending Attestation Request to the device.
[1706582566.953223][5712:5714] CHIP:CTL: Sending Attestation request to 0x7f19d80136f0 device
[1706582566.953298][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582566.953346][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582566.953476][5712:5714] CHIP:EM: <<< [E:20029i S:40863 M:20622516] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582566.953981][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582566.954026][5712:5714] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information
[1706582566.954115][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582567.130537][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582567.131090][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.131255][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.267019][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.267175][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.359071][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.359225][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.359340][5712:5714] CHIP:EM: >>> [E:20029i S:40863 M:149183760] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582567.359375][5712:5714] CHIP:EM: Found matching exchange: 20029i, Delegate: 0x7f19d8019218
[1706582567.359411][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582567.359478][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582567.359507][5712:5714] CHIP:DMG: {
[1706582567.359544][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582567.359575][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582567.359630][5712:5714] CHIP:DMG: [
[1706582567.359655][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582567.359707][5712:5714] CHIP:DMG: {
[1706582567.359734][5712:5714] CHIP:DMG: CommandDataIB =
[1706582567.359773][5712:5714] CHIP:DMG: {
[1706582567.359803][5712:5714] CHIP:DMG: CommandPathIB =
[1706582567.359840][5712:5714] CHIP:DMG: {
[1706582567.359876][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582567.359912][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582567.359947][5712:5714] CHIP:DMG: CommandId = 0x1, ----> AttestationRequest
[1706582567.359979][5712:5714] CHIP:DMG: },
[1706582567.360019][5712:5714] CHIP:DMG:
[1706582567.360050][5712:5714] CHIP:DMG: CommandFields =
[1706582567.360089][5712:5714] CHIP:DMG: {
[1706582567.360124][5712:5714] CHIP:DMG: 0x0 = [
[1706582567.360421][5712:5714] 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
[1706582567.360549][5712:5714] CHIP:DMG: ] (583 bytes)
[1706582567.360587][5712:5714] CHIP:DMG: 0x1 = [
[1706582567.360675][5712:5714] CHIP:DMG: 0xd4, 0x1f, 0x2c, 0x4a
, 0x46, 0xbe, 0x00, 0x1e, 0x86, 0x51, 0xf3, 0x0a, 0xb6, 0x3e, 0x0b, 0xf9, 0xe9, 0xec, 0xac, 0xab, 0x8e, 0x16, 0x50,
0x7a, 0x9a, 0xf5, 0xea, 0x27, 0xa0, 0xfd, 0xce, 0xca, 0x68, 0x70, 0x17, 0x86, 0x7a, 0x85, 0x2b, 0xa6, 0x67, 0x64, 0xc8
, 0x54, 0xba, 0x19, 0xdc, 0x1a, 0xf4, 0x82, 0x9b, 0x02, 0x5b, 0x31, 0x05, 0xdb, 0xf6, 0xb9, 0xa1, 0x83, 0xb4, 0x07,
0x9a, 0xe8,
[1706582567.360724][5712:5714] CHIP:DMG: ] (64 bytes)
[1706582567.360763][5712:5714] CHIP:DMG: },
[1706582567.360792][5712:5714] CHIP:DMG: },
[1706582567.360838][5712:5714] CHIP:DMG:
[1706582567.360866][5712:5714] CHIP:DMG: },
[1706582567.360913][5712:5714] CHIP:DMG:
[1706582567.360939][5712:5714] CHIP:DMG: ],
[1706582567.360987][5712:5714] CHIP:DMG:
[1706582567.361015][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582567.361044][5712:5714] CHIP:DMG: },
[1706582567.361162][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=
0x0000_0001
[1706582567.361212][5712:5714] CHIP:CTL: Received Attestation Information from the device
[1706582567.361245][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest'
[1706582567.361278][5712:5714] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583
[1706582567.361338][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification'
[1706582567.361367][5712:5714] CHIP:CTL: Performing next commissioning step 'AttestationVerification'
[1706582567.361389][5712:5714] CHIP:CTL: Verifying attestation
[1706582567.371552][5712:5714] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device.
[1706582567.371562][5712:5714] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification'
[1706582567.371569][5712:5714] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest'
[1706582567.371576][5712:5714] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest'
[1706582567.371583][5712:5714] CHIP:CTL: Sending CSR request to 0x7f19d80136f0 device
[1706582567.371603][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582567.371611][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582567.371635][5712:5714] CHIP:EM: <<< [E:20030i S:40863 M:20622517] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582567.371766][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582567.371791][5712:5714] CHIP:CTL: Sent CSR request, waiting for the CSR
[1706582567.371814][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582567.446508][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582567.539055][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.539224][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.627061][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.627186][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.627329][5712:5714] CHIP:EM: >>> [E:20030i S:40863 M:149183761] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582567.627384][5712:5714] CHIP:EM: Found matching exchange: 20030i, Delegate: 0x7f19d8019388
[1706582567.627436][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582567.627507][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582567.627544][5712:5714] CHIP:DMG: {
[1706582567.627578][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582567.627611][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582567.627663][5712:5714] CHIP:DMG: [
[1706582567.627696][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582567.627746][5712:5714] CHIP:DMG: {
[1706582567.627780][5712:5714] CHIP:DMG: CommandDataIB =
[1706582567.627820][5712:5714] CHIP:DMG: {
[1706582567.627855][5712:5714] CHIP:DMG: CommandPathIB =
[1706582567.627892][5712:5714] CHIP:DMG: {
[1706582567.627933][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582567.627978][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582567.628021][5712:5714] CHIP:DMG: CommandId = 0x5, ----> CSRResponse
[1706582567.628058][5712:5714] CHIP:DMG: },
[1706582567.628101][5712:5714] CHIP:DMG:
[1706582567.628135][5712:5714] CHIP:DMG: CommandFields =
[1706582567.628174][5712:5714] CHIP:DMG: {
[1706582567.628212][5712:5714] CHIP:DMG: 0x0 = [
[1706582567.628484][5712:5714] CHIP:DMG: 0x15, 0x30, 0x01, 0xcb
, 0x30, 0x81, 0xc8, 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, 0x37, 0x4b, 0x12, 0xbf, 0x99, 0x58, 0xe5,
0x5d, 0xee, 0xb7, 0xf5, 0x92, 0x57, 0xfa, 0x4b, 0x83, 0x45, 0xe7, 0x6c, 0x79, 0x60, 0x7e, 0x2c, 0x71, 0x39, 0x2c, 0x3c
, 0xf7, 0x92, 0xdf, 0x5e, 0x44, 0x38, 0xbc, 0x47, 0x9c, 0xe8, 0xfb, 0x25, 0x63, 0xc0, 0x9e, 0xeb, 0xcd, 0x59, 0xc1,
0xf8, 0x2a, 0xd4, 0x24, 0xbe, 0xbc, 0x59, 0xa1, 0x7b, 0x7a, 0xed, 0xe1, 0xcc, 0x81, 0xd4, 0xbe, 0x2b, 0xe3, 0xa0, 0x00
, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21,
0x00, 0xca, 0x22, 0x55, 0xae, 0x33, 0xd6, 0x78, 0x8d, 0x0d, 0x25, 0xb3, 0x26, 0x62, 0xdc, 0xff, 0x38, 0xc7, 0xc3, 0x15
, 0x45, 0x8e, 0xe5, 0xf3, 0xe2, 0xf9, 0xaf, 0x0d, 0x41, 0xdc, 0xbd, 0x69, 0xf5, 0x02, 0x20, 0x56, 0x84, 0x37, 0x60,
0xf9, 0xcd, 0xa3, 0x78, 0xc5, 0xda, 0xfa, 0xec, 0xc1, 0x47, 0xa5, 0x00, 0x66, 0xac, 0x13, 0x06, 0xef, 0x69, 0x9f, 0xf0
, 0xf4, 0xd5, 0xec, 0xaa, 0x66, 0xd6, 0xf1, 0xb6, 0x30, 0x02, 0x20, 0x31, 0x6a, 0xc8, 0x42, 0x47, 0xa1, 0x3b, 0x26,
0xf4, 0x92, 0x59, 0x3d, 0x7c, 0xf4, 0x56, 0x2a, 0xe7, 0x57, 0x55, 0x5c, 0xea, 0xf3, 0xdf, 0x59, 0x41, 0xb9, 0x76, 0x5f
, 0xc3, 0xcc, 0x58, 0x53, 0x18,
[1706582567.628558][5712:5714] CHIP:DMG: ] (243 bytes)
[1706582567.628603][5712:5714] CHIP:DMG: 0x1 = [
[1706582567.628698][5712:5714] CHIP:DMG: 0xab, 0x05, 0x64, 0x21
, 0x98, 0x14, 0xc6, 0x3b, 0xd1, 0x68, 0x69, 0x02, 0x10, 0xd0, 0x7c, 0x37, 0xec, 0xd1, 0xa6, 0xb0, 0xfe, 0x7a, 0xf0,
0x85, 0x6f, 0x59, 0x7c, 0x7e, 0xaf, 0x90, 0x46, 0x8f, 0x54, 0x5d, 0x0f, 0xfb, 0xa8, 0x90, 0x18, 0x6d, 0xff, 0x18, 0xde
, 0x66, 0x9e, 0xca, 0x0a, 0x6f, 0x2a, 0x14, 0xe2, 0xb0, 0xc1, 0xe3, 0x67, 0xe3, 0xc4, 0x23, 0xe1, 0xdf, 0x10, 0x78,
0xb8, 0x62,
[1706582567.628746][5712:5714] CHIP:DMG: ] (64 bytes)
[1706582567.628786][5712:5714] CHIP:DMG: },
[1706582567.628821][5712:5714] CHIP:DMG: },
[1706582567.628868][5712:5714] CHIP:DMG:
[1706582567.628903][5712:5714] CHIP:DMG: },
[1706582567.628947][5712:5714] CHIP:DMG:
[1706582567.628980][5712:5714] CHIP:DMG: ],
[1706582567.629029][5712:5714] CHIP:DMG:
[1706582567.629063][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582567.629095][5712:5714] CHIP:DMG: },
[1706582567.629226][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005
[1706582567.629287][5712:5714] CHIP:CTL: Received certificate signing request from the device
[1706582567.629331][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest'
[1706582567.629366][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR'
[1706582567.629404][5712:5714] CHIP:CTL: Performing next commissioning step 'ValidateCSR'
[1706582567.632636][5712:5714] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR'
[1706582567.632644][5712:5714] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain'
[1706582567.632652][5712:5714] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain'
[1706582567.632659][5712:5714] CHIP:CTL: Getting certificate chain for the device from the issuer
[1706582567.633706][5712:5714] CHIP:CTL: Verifying Certificate Signing Request
[1706582567.634286][5712:5714] CHIP:CTL: Generating NOC
[1706582567.634376][5712:5714] CHIP:CTL: Providing certificate chain to the commissioner
[1706582567.634385][5712:5714] CHIP:CTL: Received callback from the CA for NOC Chain generation.
Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success
[1706582567.634391][5712:5714] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain'
[1706582567.634435][5712:5714] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert'
[1706582567.634443][5712:5714] CHIP:CTL: Sending root certificate to the device
[1706582567.634461][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582567.634467][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582567.634491][5712:5714] CHIP:EM: <<< [E:20031i S:40863 M:20622518] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582567.634636][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582567.634645][5712:5714] CHIP:CTL: Sent root certificate to the device
[1706582567.634767][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582567.806534][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582567.894534][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582567.987055][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582567.987175][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582567.987349][5712:5714] CHIP:EM: >>> [E:20031i S:40863 M:149183762] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582567.987402][5712:5714] CHIP:EM: Found matching exchange: 20031i, Delegate: 0x7f19d8043dd8
[1706582567.987446][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582567.987510][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582567.987545][5712:5714] CHIP:DMG: {
[1706582567.987572][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582567.987612][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582567.987662][5712:5714] CHIP:DMG: [
[1706582567.987693][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582567.987741][5712:5714] CHIP:DMG: {
[1706582567.987771][5712:5714] CHIP:DMG: CommandStatusIB =
[1706582567.987808][5712:5714] CHIP:DMG: {
[1706582567.987841][5712:5714] CHIP:DMG: CommandPathIB =
[1706582567.987876][5712:5714] CHIP:DMG: {
[1706582567.987916][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582567.987956][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582567.987992][5712:5714] CHIP:DMG: CommandId = 0xb, ----> AddTrustedRootCertificate
[1706582567.988034][5712:5714] CHIP:DMG: },
[1706582567.988081][5712:5714] CHIP:DMG:
[1706582567.988116][5712:5714] CHIP:DMG: StatusIB =
[1706582567.988152][5712:5714] CHIP:DMG: {
[1706582567.988194][5712:5714] CHIP:DMG: status = 0x00 (SUCCESS),
[1706582567.988232][5712:5714] CHIP:DMG: },
[1706582567.988274][5712:5714] CHIP:DMG:
[1706582567.988308][5712:5714] CHIP:DMG: },
[1706582567.988359][5712:5714] CHIP:DMG:
[1706582567.988390][5712:5714] CHIP:DMG: },
[1706582567.988435][5712:5714] CHIP:DMG:
[1706582567.988466][5712:5714] CHIP:DMG: ],
[1706582567.988512][5712:5714] CHIP:DMG:
[1706582567.988548][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582567.988580][5712:5714] CHIP:DMG: },
[1706582567.988700][5712:5714] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0
[1706582567.988743][5712:5714] CHIP:CTL: Device confirmed that it has received the root certificate
[1706582567.988784][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert'
[1706582567.988819][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC'
[1706582567.988857][5712:5714] CHIP:CTL: Performing next commissioning step 'SendNOC'
[1706582567.988942][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582567.989026][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582567.989164][5712:5714] CHIP:EM: <<< [E:20032i S:40863 M:20622519] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582567.989614][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582567.989659][5712:5714] CHIP:CTL: Sent operational certificate to the device
[1706582567.989727][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582568.118530][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582568.254499][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582568.346500][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582568.527013][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582568.527138][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582568.527313][5712:5714] CHIP:EM: >>> [E:20032i S:40863 M:149183763] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582568.527367][5712:5714] CHIP:EM: Found matching exchange: 20032i, Delegate: 0x7f19d8019388
[1706582568.527417][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582568.527485][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582568.527520][5712:5714] CHIP:DMG: {
[1706582568.527555][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582568.527584][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582568.527633][5712:5714] CHIP:DMG: [
[1706582568.527667][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582568.527717][5712:5714] CHIP:DMG: {
[1706582568.527750][5712:5714] CHIP:DMG: CommandDataIB =
[1706582568.527784][5712:5714] CHIP:DMG: {
[1706582568.527817][5712:5714] CHIP:DMG: CommandPathIB =
[1706582568.527858][5712:5714] CHIP:DMG: {
[1706582568.527895][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582568.527941][5712:5714] CHIP:DMG: ClusterId = 0x3e, ----> Node Operational Credentials
[1706582568.527980][5712:5714] CHIP:DMG: CommandId = 0x8, ----> NOCResponse
[1706582568.528017][5712:5714] CHIP:DMG: },
[1706582568.528063][5712:5714] CHIP:DMG:
[1706582568.528096][5712:5714] CHIP:DMG: CommandFields =
[1706582568.528131][5712:5714] CHIP:DMG: {
[1706582568.528178][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582568.528217][5712:5714] CHIP:DMG: 0x1 = 1,
[1706582568.528253][5712:5714] CHIP:DMG: },
[1706582568.528286][5712:5714] CHIP:DMG: },
[1706582568.528333][5712:5714] CHIP:DMG:
[1706582568.528366][5712:5714] CHIP:DMG: },
[1706582568.528415][5712:5714] CHIP:DMG:
[1706582568.528446][5712:5714] CHIP:DMG: ],
[1706582568.528496][5712:5714] CHIP:DMG:
[1706582568.528527][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582568.528560][5712:5714] CHIP:DMG: },
[1706582568.528687][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008
[1706582568.528775][5712:5714] CHIP:CTL: Device returned status 0 on receiving the NOC
[1706582568.528814][5712:5714] CHIP:CTL: Operational credentials provisioned on device 0x7f19d80136f0
[1706582568.528846][5712:5714] CHIP:TOO: Secure Pairing Success
[1706582568.528871][5712:5714] CHIP:TOO: CASE establishment successful
[1706582568.528911][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendNOC'
[1706582568.528945][5712:5714] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks
[1706582568.528975][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'WiFiNetworkSetup'
[1706582568.529012][5712:5714] CHIP:CTL: Performing next commissioning step 'WiFiNetworkSetup'
[1706582568.529092][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582568.529141][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582568.529270][5712:5714] CHIP:EM: <<< [E:20033i S:40863 M:20622520] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582568.529827][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582568.529927][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582568.658530][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582568.659067][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582568.659201][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582568.659364][5712:5714] CHIP:EM: >>> [E:20033i S:40863 M:149183764] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582568.659416][5712:5714] CHIP:EM: Found matching exchange: 20033i, Delegate: 0x7f19d8043dd8
[1706582568.659463][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582568.659536][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582568.659572][5712:5714] CHIP:DMG: {
[1706582568.659603][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582568.659634][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582568.659687][5712:5714] CHIP:DMG: [
[1706582568.659715][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582568.659762][5712:5714] CHIP:DMG: {
[1706582568.659796][5712:5714] CHIP:DMG: CommandDataIB =
[1706582568.659832][5712:5714] CHIP:DMG: {
[1706582568.659863][5712:5714] CHIP:DMG: CommandPathIB =
[1706582568.659905][5712:5714] CHIP:DMG: {
[1706582568.659946][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582568.659990][5712:5714] CHIP:DMG: ClusterId = 0x31, ----> Network Commissioning
[1706582568.660030][5712:5714] CHIP:DMG: CommandId = 0x5, ----> LastNetworkingStatus
[1706582568.660066][5712:5714] CHIP:DMG: },
[1706582568.660110][5712:5714] CHIP:DMG:
[1706582568.660145][5712:5714] CHIP:DMG: CommandFields =
[1706582568.660179][5712:5714] CHIP:DMG: {
[1706582568.660218][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582568.660260][5712:5714] CHIP:DMG: 0x2 = 0,
[1706582568.660299][5712:5714] CHIP:DMG: },
[1706582568.660332][5712:5714] CHIP:DMG: },
[1706582568.660381][5712:5714] CHIP:DMG:
[1706582568.660414][5712:5714] CHIP:DMG: },
[1706582568.660459][5712:5714] CHIP:DMG:
[1706582568.660495][5712:5714] CHIP:DMG: ],
[1706582568.660544][5712:5714] CHIP:DMG:
[1706582568.660578][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582568.660610][5712:5714] CHIP:DMG: },
[1706582568.660739][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005
[1706582568.660834][5712:5714] CHIP:CTL: Received NetworkConfig response, networkingStatus=0
[1706582568.660876][5712:5714] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkSetup'
[1706582568.660911][5712:5714] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkSetup' -> 'FailsafeBeforeWiFiEnable'
[1706582568.660947][5712:5714] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeWiFiEnable'
[1706582568.660994][5712:5714] CHIP:CTL: Arming failsafe (66 seconds)
[1706582568.661070][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582568.661113][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582568.661245][5712:5714] CHIP:EM: <<< [E:20034i S:40863 M:20622521] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582568.661704][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582568.661796][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582568.750497][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582568.794994][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582568.795123][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582568.795255][5712:5714] CHIP:EM: >>> [E:20034i S:40863 M:149183765] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582568.795291][5712:5714] CHIP:EM: Found matching exchange: 20034i, Delegate: 0x7f19d8019388
[1706582568.795322][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582568.795385][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582568.795413][5712:5714] CHIP:DMG: {
[1706582568.795449][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582568.795479][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582568.795528][5712:5714] CHIP:DMG: [
[1706582568.795554][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582568.795603][5712:5714] CHIP:DMG: {
[1706582568.795632][5712:5714] CHIP:DMG: CommandDataIB =
[1706582568.795669][5712:5714] CHIP:DMG: {
[1706582568.795699][5712:5714] CHIP:DMG: CommandPathIB =
[1706582568.795736][5712:5714] CHIP:DMG: {
[1706582568.795772][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582568.795808][5712:5714] CHIP:DMG: ClusterId = 0x30, ----> General Commissioning
[1706582568.795843][5712:5714] CHIP:DMG: CommandId = 0x1, ----> ArmFailSafeResponse
[1706582568.795875][5712:5714] CHIP:DMG: },
[1706582568.795916][5712:5714] CHIP:DMG:
[1706582568.795947][5712:5714] CHIP:DMG: CommandFields =
[1706582568.795985][5712:5714] CHIP:DMG: {
[1706582568.796022][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582568.796060][5712:5714] CHIP:DMG: 0x1 = "" (0 chars),
[1706582568.796096][5712:5714] CHIP:DMG: },
[1706582568.796126][5712:5714] CHIP:DMG: },
[1706582568.796170][5712:5714] CHIP:DMG:
[1706582568.796198][5712:5714] CHIP:DMG: },
[1706582568.796245][5712:5714] CHIP:DMG:
[1706582568.796271][5712:5714] CHIP:DMG: ],
[1706582568.796319][5712:5714] CHIP:DMG:
[1706582568.796347][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582568.796376][5712:5714] CHIP:DMG: },
[1706582568.796490][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001
[1706582568.796539][5712:5714] CHIP:CTL: Received ArmFailSafe response errorCode=0
[1706582568.796573][5712:5714] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeWiFiEnable'
[1706582568.796602][5712:5714] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeWiFiEnable' -> 'WiFiNetworkEnable'
[1706582568.796631][5712:5714] CHIP:CTL: Setting wifi connection time min = 30
[1706582568.796664][5712:5714] CHIP:CTL: Performing next commissioning step 'WiFiNetworkEnable'
[1706582568.796693][5712:5714] CHIP:CTL: SendCommand kWiFiNetworkEnable, supportsConcurrentConnection=1
[1706582568.796760][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582568.796796][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582568.796904][5712:5714] CHIP:EM: <<< [E:20035i S:40863 M:20622522] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582568.797312][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582568.797425][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582568.930526][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16387
[1706582571.046980][5712:5713] CHIP:DL: Indication received, conn = 0x7f19d001eac0
[1706582571.047118][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16389
[1706582571.047252][5712:5714] CHIP:EM: >>> [E:20035i S:40863 M:149183766] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582571.047287][5712:5714] CHIP:EM: Found matching exchange: 20035i, Delegate: 0x7f19d8043dd8
[1706582571.047320][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582571.047383][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582571.047411][5712:5714] CHIP:DMG: {
[1706582571.047446][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582571.047478][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582571.047528][5712:5714] CHIP:DMG: [
[1706582571.047553][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582571.047602][5712:5714] CHIP:DMG: {
[1706582571.047632][5712:5714] CHIP:DMG: CommandDataIB =
[1706582571.047669][5712:5714] CHIP:DMG: {
[1706582571.047699][5712:5714] CHIP:DMG: CommandPathIB =
[1706582571.047736][5712:5714] CHIP:DMG: {
[1706582571.047773][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582571.047809][5712:5714] CHIP:DMG: ClusterId = 0x31, ----> Network Commissioning
[1706582571.047844][5712:5714] CHIP:DMG: CommandId = 0x7, ----> LastConnectErrorValue
[1706582571.047876][5712:5714] CHIP:DMG: },
[1706582571.047917][5712:5714] CHIP:DMG:
[1706582571.047949][5712:5714] CHIP:DMG: CommandFields =
[1706582571.047986][5712:5714] CHIP:DMG: {
[1706582571.048023][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582571.048060][5712:5714] CHIP:DMG: 0x2 = NULL
[1706582571.048095][5712:5714] CHIP:DMG: },
[1706582571.048126][5712:5714] CHIP:DMG: },
[1706582571.048171][5712:5714] CHIP:DMG:
[1706582571.048199][5712:5714] CHIP:DMG: },
[1706582571.048245][5712:5714] CHIP:DMG:
[1706582571.048272][5712:5714] CHIP:DMG: ],
[1706582571.048320][5712:5714] CHIP:DMG:
[1706582571.048348][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582571.048377][5712:5714] CHIP:DMG: },
[1706582571.048491][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command= 0x0000_0007
[1706582571.048564][5712:5714] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0
[1706582571.048599][5712:5714] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkEnable'
[1706582571.048630][5712:5714] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkEnable' -> 'FindOperational'
[1706582571.048665][5712:5714] CHIP:CTL: Performing next commissioning step 'FindOperational'
[1706582571.048692][5712:5714] CHIP:IN: Expiring all sessions for node <0000000000002222, 1>!!
[1706582571.048724][5712:5714] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000002222]
[1706582571.048754][5712:5714] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found
[1706582571.048797][5712:5714] CHIP:DIS: OperationalSessionSetup[1:0000000000002222]: State change 1 --> 2
[1706582571.049077][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582571.249541][5712:5714] CHIP:DIS: Checking node lookup status after 200 ms
[1706582572.635280][5712:5714] CHIP:DIS: UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540: new best score: 7
[1706582572.635314][5712:5714] CHIP:DIS: Checking node lookup status after 1586 ms
[1706582572.635361][5712:5714] CHIP:DIS: OperationalSessionSetup[1:0000000000002222]:
Updating device address to UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540 while in state 2
[1706582572.635382][5712:5714] CHIP:DIS: OperationalSessionSetup[1:0000000000002222]: State change 2 --> 3
[1706582572.635465][5712:5714] CHIP:IN: SecureSession[0x7f19d8009b20]: Allocated Type:2 LSID:40864
[1706582572.635497][5712:5714] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x0000000000002222
[1706582572.636541][5712:5714] CHIP:EM: <<< [E:20036i S:0 M:37012641] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
[1706582572.636647][5712:5714] CHIP:SC: Sent Sigma1 msg
[1706582572.636669][5712:5714] CHIP:DIS: OperationalSessionSetup[1:0000000000002222]: State change 3 --> 4
[1706582572.849579][5712:5714] CHIP:EM: >>> [E:20036i S:0 M:262434380 (Ack:37012641)] (U)
Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
[1706582572.849622][5712:5714] CHIP:EM: Found matching exchange: 20036i, Delegate: 0x7f19d80424c8
[1706582572.849656][5712:5714] CHIP:EM: Rxd Ack; Removing MessageCounter:37012641 from Retrans Table on exchange 20036i
[1706582572.849715][5712:5714] CHIP:SC: Received Sigma2 msg
[1706582572.849763][5712:5714] CHIP:SC: Peer assigned session session ID 31020
[1706582572.853769][5712:5714] CHIP:SC: Found MRP parameters in the message
[1706582572.853833][5712:5714] CHIP:SC: Sending Sigma3
[1706582572.854615][5712:5714] CHIP:EM: <<< [E:20036i S:0 M:37012642 (Ack:262434380)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
[1706582572.854747][5712:5714] CHIP:SC: Sent Sigma3 msg
[1706582572.931207][5712:5714] CHIP:EM: >>> [E:20036i S:0 M:262434381 (Ack:37012642)] (U)
Msg RX from 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1706582572.931253][5712:5714] CHIP:EM: Found matching exchange: 20036i, Delegate: 0x7f19d80424c8
[1706582572.931303][5712:5714] CHIP:EM: Rxd Ack; Removing MessageCounter:37012642 from Retrans Table on exchange 20036i
[1706582573.054384][5712:5714] CHIP:EM: >>> [E:20036i S:0 M:262434382 (Ack:37012642)] (U)
Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
[1706582573.054430][5712:5714] CHIP:EM: Found matching exchange: 20036i, Delegate: 0x7f19d80424c8
[1706582573.054466][5712:5714] CHIP:EM: CHIP MessageCounter:37012642 not in RetransTable on exchange 20036i
[1706582573.054532][5712:5714] CHIP:SC: Success status report received. Session was established
[1706582573.059331][5712:5714] CHIP:SC: SecureSession[0x7f19d8009b20, LSID:40864]: State change 'kEstablishing' --> 'kActive'
[1706582573.059371][5712:5714] CHIP:IN: SecureSession[0x7f19d8009b20]: Activated - Type:2 LSID:40864
[1706582573.059403][5712:5714] CHIP:IN: New secure session activated for device <0000000000002222, 1>, LSID:40864 PSID:31020!
[1706582573.059446][5712:5714] CHIP:DIS: OperationalSessionSetup[1:0000000000002222]: State change 4 --> 5
[1706582573.059532][5712:5714] CHIP:CTL: Successfully finished commissioning step 'FindOperational'
[1706582573.059562][5712:5714] CHIP:CTL: Commissioning stage next step: 'FindOperational' -> 'SendComplete'
[1706582573.059608][5712:5714] CHIP:CTL: Performing next commissioning step 'SendComplete'
[1706582573.059680][5712:5714] CHIP:DMG: ICR moving to [AddingComm]
[1706582573.059711][5712:5714] CHIP:DMG: ICR moving to [AddedComma]
[1706582573.059887][5712:5714] CHIP:EM: <<< [E:20037i S:40864 M:104350266] (S) Msg TX to 1:0000000000002222 [6A57]
[UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540] --- Type 0001:08 (IM:InvokeCommandRequest)
[1706582573.060020][5712:5714] CHIP:DMG: ICR moving to [CommandSen]
[1706582573.060145][5712:5714] CHIP:EM: <<< [E:20036i S:0 M:37012643 (Ack:262434382)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1706582573.060222][5712:5714] CHIP:EM: Flushed pending ack for MessageCounter:262434382 on exchange 20036i
[1706582573.177723][5712:5714] CHIP:EM: >>> [E:20037i S:40864 M:179838610 (Ack:104350266)] (S)
Msg RX from 1:0000000000002222 [6A57] --- Type 0001:09 (IM:InvokeCommandResponse)
[1706582573.177769][5712:5714] CHIP:EM: Found matching exchange: 20037i, Delegate: 0x7f19d8043dd8
[1706582573.177822][5712:5714] CHIP:EM: Rxd Ack; Removing MessageCounter:104350266 from Retrans Table on exchange
20037i
[1706582573.177876][5712:5714] CHIP:DMG: ICR moving to [ResponseRe]
[1706582573.177939][5712:5714] CHIP:DMG: InvokeResponseMessage =
[1706582573.177968][5712:5714] CHIP:DMG: {
[1706582573.178001][5712:5714] CHIP:DMG: suppressResponse = false,
[1706582573.178030][5712:5714] CHIP:DMG: InvokeResponseIBs =
[1706582573.178080][5712:5714] CHIP:DMG: [
[1706582573.178107][5712:5714] CHIP:DMG: InvokeResponseIB =
[1706582573.178155][5712:5714] CHIP:DMG: {
[1706582573.178183][5712:5714] CHIP:DMG: CommandDataIB =
[1706582573.178220][5712:5714] CHIP:DMG: {
[1706582573.178250][5712:5714] CHIP:DMG: CommandPathIB =
[1706582573.178288][5712:5714] CHIP:DMG: {
[1706582573.178324][5712:5714] CHIP:DMG: EndpointId = 0x0,
[1706582573.178360][5712:5714] CHIP:DMG: ClusterId = 0x30, ----> General Commissioning
[1706582573.178395][5712:5714] CHIP:DMG: CommandId = 0x5, ----> CommissioningCompleteResponse
[1706582573.178426][5712:5714] CHIP:DMG: },
[1706582573.178467][5712:5714] CHIP:DMG:
[1706582573.178498][5712:5714] CHIP:DMG: CommandFields =
[1706582573.178536][5712:5714] CHIP:DMG: {
[1706582573.178573][5712:5714] CHIP:DMG: 0x0 = 0,
[1706582573.178613][5712:5714] CHIP:DMG: 0x1 = "" (0 chars),
[1706582573.178648][5712:5714] CHIP:DMG: },
[1706582573.178678][5712:5714] CHIP:DMG: },
[1706582573.178723][5712:5714] CHIP:DMG:
[1706582573.178751][5712:5714] CHIP:DMG: },
[1706582573.178797][5712:5714] CHIP:DMG:
[1706582573.178824][5712:5714] CHIP:DMG: ],
[1706582573.178871][5712:5714] CHIP:DMG:
[1706582573.178899][5712:5714] CHIP:DMG: InteractionModelRevision = 11
[1706582573.178928][5712:5714] CHIP:DMG: },
[1706582573.179044][5712:5714] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005
[1706582573.179093][5712:5714] CHIP:CTL: Received CommissioningComplete response, errorCode=0
[1706582573.179125][5712:5714] CHIP:CTL: Successfully finished commissioning step 'SendComplete'
[1706582573.179153][5712:5714] CHIP:CTL: Commissioning stage next step: 'SendComplete' -> 'Cleanup'
[1706582573.179198][5712:5714] CHIP:CTL: Performing next commissioning step 'Cleanup'
[1706582573.179227][5712:5714] CHIP:DIS: Closing all BLE connections
[1706582573.179257][5712:5714] CHIP:IN: Clearing BLE pending packets.
[1706582573.179670][5712:5714] CHIP:BLE: Auto-closing end point's BLE connection.
[1706582573.179714][5712:5714] CHIP:DL: Closing BLE GATT connection (con 0x7f19d001eac0)
[1706582573.179869][5712:5713] CHIP:DL: BluezDisconnect peer=D1:96:30:DD:8A:29
[1706582575.765665][5712:5714] CHIP:IN: SecureSession[0x7f19d800f870]: MarkForEviction Type:1 LSID:40863
[1706582575.765727][5712:5714] CHIP:SC: SecureSession[0x7f19d800f870, LSID:40863]: State change 'kActive' --> 'kPendingEviction'
[1706582575.765808][5712:5714] CHIP:IN: SecureSession[0x7f19d800f870]: Released - Type:1 LSID:40863
[1706582575.765871][5712:5714] CHIP:CTL: Successfully finished commissioning step 'Cleanup'
[1706582575.765921][5712:5714] CHIP:TOO: Device commissioning completed with success
[1706582575.766012][5712:5714] CHIP:DMG: ICR moving to [AwaitingDe]
[1706582575.766207][5712:5713] CHIP:DL: Bluez disconnected
[1706582575.766272][5712:5713] CHIP:DL: Bluez notify CHIPoBluez connection disconnected
[1706582575.766217][5712:5714] CHIP:EM: <<< [E:20037i S:40864 M:104350267 (Ack:179838610)] (S) Msg TX to 1:0000000000002222 [6A57]
[UDP:[fe80::424c:caff:fe43:1518%enp3s0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck)
[1706582575.766450][5712:5714] CHIP:EM: Flushed pending ack for MessageCounter:179838610 on exchange 20037i
[1706582575.766504][5712:5714] CHIP:DL: HandlePlatformSpecificBLEEvent 16388
[1706582575.766526][5712:5714] CHIP:BLE: no endpoint for unsub complete
[1706582575.766804][5712:5712] CHIP:CTL: Shutting down the commissioner
[1706582575.766915][5712:5712] CHIP:CTL: Shutting down the controller
[1706582575.766949][5712:5712] CHIP:IN: Expiring all sessions for fabric 0x1!!
[1706582575.766982][5712:5712] CHIP:IN: SecureSession[0x7f19d8009b20]: MarkForEviction Type:2 LSID:40864
[1706582575.767030][5712:5712] CHIP:SC: SecureSession[0x7f19d8009b20, LSID:40864]: State change 'kActive' --> 'kPendingEviction'
[1706582575.767091][5712:5712] CHIP:IN: SecureSession[0x7f19d8009b20]: Released - Type:2 LSID:40864
[1706582575.767127][5712:5712] CHIP:FP: Forgetting fabric 0x1
[1706582575.767178][5712:5712] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48
[1706582575.767675][5712:5712] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48
[1706582575.767704][5712:5712] CHIP:TS: Reverted Last Known Good Time to previous value
[1706582575.767749][5712:5712] CHIP:CTL: Shutting down the commissioner
[1706582575.767852][5712:5712] CHIP:CTL: Shutting down the controller
[1706582575.767876][5712:5712] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack
[1706582575.768012][5712:5712] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet
[1706582575.768125][5712:5712] CHIP:FP: Shutting down FabricTable
[1706582575.768158][5712:5712] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48
[1706582575.768580][5712:5712] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48
[1706582575.768608][5712:5712] CHIP:TS: Reverted Last Known Good Time to previous value
[1706582575.768882][5712:5712] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-3TZj2M)
[1706582575.769397][5712:5712] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini)
[1706582575.769460][5712:5712] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0)
[1706582575.769491][5712:5712] CHIP:DL: Inet Layer shutdown
[1706582575.769519][5712:5712] CHIP:DL: BLE shutdown
[1706582575.771166][5712:5712] CHIP:DL: System Layer shutdown
chip-tool commissioning light 장치 Log
I (56136) chip[DL]: BLE GAP connection established (con 1)
I (56146) chip[DL]: CHIPoBLE advertising stopped
I (57516) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (57516) chip[BLE]: local and remote recv window sizes = 5
I (57526) chip[BLE]: selected BTP version 4
I (57526) chip[BLE]: using BTP fragment sizes rx 244 / tx 244.
I (57606) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1
I (57606) chip[DL]: CHIPoBLE subscribe received
I (57606) NimBLE: GATT procedure initiated: indicate;
I (57616) NimBLE: att_handle=14
I (57696) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (57696) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (57706) chip[EM]: >>> [E:20022r S:0 M:37012638] (U) Msg RX from 0:7B1438D51EA34458 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (57726) chip[EM]: <<< [E:20022r S:0 M:262434376] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (57736) NimBLE: GATT procedure initiated: indicate;
I (57746) NimBLE: att_handle=14
I (57746) chip[SVR]: Commissioning session establishment step started
I (57826) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (57836) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (57846) chip[EM]: >>> [E:20022r S:0 M:37012639] (U) Msg RX from 0:7B1438D51EA34458 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (57916) chip[EM]: <<< [E:20022r S:0 M:262434377] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (57926) NimBLE: GATT procedure initiated: indicate;
I (57926) NimBLE: att_handle=14
I (58006) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58016) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58026) chip[EM]: >>> [E:20022r S:0 M:37012640] (U) Msg RX from 0:7B1438D51EA34458 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (58036) chip[EM]: <<< [E:20022r S:0 M:262434378] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport)
I (58046) NimBLE: GATT procedure initiated: indicate;
I (58046) NimBLE: att_handle=14
I (58066) chip[SC]: SecureSession[0x40820790, LSID:31019]: State change 'kEstablishing' --> 'kActive'
I (58076) chip[SVR]: Commissioning completed session establishment step
I (58076) chip[DIS]: Updating services using commissioning mode 0
I (58086) chip[DIS]: CHIP minimal mDNS started advertising.
I (58086) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (58096) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EB57FB697C466A55.
I (58106) chip[DIS]: mDNS service published: _matterc._udp
I (58126) chip[SVR]: Device completed Rendezvous process
I (58126) app_main: Commissioning session started
I (58126) app_main: Commissioning window closed
I (58146) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58146) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58156) chip[EM]: >>> [E:20023r S:31019 M:20622510] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (58176) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (58176) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (58196) chip[EM]: <<< [E:20023r S:31019 M:149183754] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (58206) NimBLE: GATT procedure initiated: indicate;
I (58206) NimBLE: att_handle=14
I (58276) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58286) NimBLE: GATT procedure initiated: indicate;
I (58296) NimBLE: att_handle=14
I (58366) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58376) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58386) chip[EM]: >>> [E:20024r S:31019 M:20622511] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (58396) chip[EM]: <<< [E:20024r S:31019 M:149183755] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (58416) NimBLE: GATT procedure initiated: indicate;
I (58416) NimBLE: att_handle=14
I (58456) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58466) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58476) chip[EM]: >>> [E:20025r S:31019 M:20622512] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (58486) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (58496) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s)
I (58496) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 **********
I (58516) chip[EM]: <<< [E:20025r S:31019 M:149183756] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (58526) NimBLE: GATT procedure initiated: indicate;
I (58536) NimBLE: att_handle=14
I (58596) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58596) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58606) chip[EM]: >>> [E:20026r S:31019 M:20622513] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (58626) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (58636) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (58646) chip[DL]: NVS set: chip-config/country-code = "XX"
I (58646) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 5 **********
I (58656) chip[EM]: <<< [E:20026r S:31019 M:149183757] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (58666) NimBLE: GATT procedure initiated: indicate;
I (58676) NimBLE: att_handle=14
I (58726) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58736) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (58746) chip[EM]: >>> [E:20027r S:31019 M:20622514] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (58756) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (58766) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (58776) chip[EM]: <<< [E:20027r S:31019 M:149183758] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (58786) NimBLE: GATT procedure initiated: indicate;
I (58796) NimBLE: att_handle=14
I (58866) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58866) NimBLE: GATT procedure initiated: indicate;
I (58876) NimBLE: att_handle=14
I (58956) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (58956) NimBLE: GATT procedure initiated: indicate;
I (58966) NimBLE: att_handle=14
I (59086) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59096) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (59106) chip[EM]: >>> [E:20028r S:31019 M:20622515] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (59116) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (59126) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (59136) chip[EM]: <<< [E:20028r S:31019 M:149183759] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (59146) NimBLE: GATT procedure initiated: indicate;
I (59156) NimBLE: att_handle=14
I (59226) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59226) NimBLE: GATT procedure initiated: indicate;
I (59236) NimBLE: att_handle=14
I (59316) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59316) NimBLE: GATT procedure initiated: indicate;
I (59326) NimBLE: att_handle=14
I (59406) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59406) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (59416) chip[EM]: >>> [E:20029r S:31019 M:20622516] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (59436) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (59446) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (59466) chip[ZCL]: OpCreds: AttestationRequest successful.
I (59466) chip[EM]: <<< [E:20029r S:31019 M:149183760] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (59476) NimBLE: GATT procedure initiated: indicate;
I (59476) NimBLE: att_handle=14
I (59626) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59636) NimBLE: GATT procedure initiated: indicate;
I (59646) NimBLE: att_handle=14
I (59716) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59716) NimBLE: GATT procedure initiated: indicate;
I (59736) NimBLE: att_handle=14
I (59806) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59816) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (59826) chip[EM]: >>> [E:20030r S:31019 M:20622517] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (59836) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (59846) chip[ZCL]: OpCreds: Received a CSRRequest command
I (59876) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (59896) chip[ZCL]: OpCreds: CSRRequest successful.
I (59896) chip[EM]: <<< [E:20030r S:31019 M:149183761] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (59916) NimBLE: GATT procedure initiated: indicate;
I (59916) NimBLE: att_handle=14
I (59986) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (59996) NimBLE: GATT procedure initiated: indicate;
I (60006) NimBLE: att_handle=14
I (60076) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (60166) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60256) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60266) chip[EM]: >>> [E:20031r S:31019 M:20622518] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (60276) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (60286) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (60316) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (60326) chip[EM]: <<< [E:20031r S:31019 M:149183762] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (60336) NimBLE: GATT procedure initiated: indicate;
I (60346) NimBLE: att_handle=14
I (60436) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (60486) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60616) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60706) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60716) chip[EM]: >>> [E:20032r S:31019 M:20622519] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (60726) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (60736) chip[ZCL]: OpCreds: Received an AddNOC command
I (60746) chip[FP]: Validating NOC chain
I (60806) chip[FP]: NOC chain validation successful
I (60806) chip[FP]: Added new fabric at index: 0x1
I (60806) chip[FP]: Assigned compressed fabric ID: 0x7219618C7FEF6A57, node ID: 0x0000000000002222
I (60816) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (60826) chip[TS]: New proposed Last Known Good Time: 2021-01-01T00:00:00
I (60826) chip[TS]: Retaining current Last Known Good Time
I (60846) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669
I (60846) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (60856) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (60866) chip[DIS]: mDNS service published: _matter._tcp
I (60876) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC
I (60886) chip[EM]: <<< [E:20032r S:31019 M:149183763] (S) Msg TX to 1:FFFFFFFB00000000 [6A57] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (60896) NimBLE: GATT procedure initiated: indicate;
I (60906) NimBLE: att_handle=14
I (60906) app_main: Fabric is updated
I (60976) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (60986) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (60996) chip[EM]: >>> [E:20033r S:31019 M:20622520] (S) Msg RX from 1:FFFFFFFB00000000 [6A57] --- Type 0001:08 (IM:InvokeCommandRequest)
I (61006) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 23 **********
I (61026) chip[EM]: <<< [E:20033r S:31019 M:149183764] (S) Msg TX to 1:FFFFFFFB00000000 [6A57] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (61036) NimBLE: GATT procedure initiated: indicate;
I (61036) NimBLE: att_handle=14
I (61116) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (61116) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (61126) chip[EM]: >>> [E:20034r S:31019 M:20622521] (S) Msg RX from 1:FFFFFFFB00000000 [6A57] --- Type 0001:08 (IM:InvokeCommandRequest)
I (61146) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (61156) chip[FS]: GeneralCommissioning: Received ArmFailSafe (66s)
I (61156) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 25
**********
I (61166) chip[EM]: <<< [E:20034r S:31019 M:149183765] (S) Msg TX to 1:FFFFFFFB00000000 [6A57] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (61176) NimBLE: GATT procedure initiated: indicate;
I (61196) NimBLE: att_handle=14
I (61246) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (61296) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (61296) chip[EM]: >>> [E:20035r S:31019 M:20622522] (S) Msg RX from 1:FFFFFFFB00000000 [6A57] --- Type 0001:08 (IM:InvokeCommandRequest)
I (61306) chip[NP]: ESP NetworkCommissioningDelegate: SSID: NETGEAR
W (61326) wifi:Haven't to connect to a suitable AP now!
I (61326) chip[DL]: WiFi station mode change: Enabled -> Disabled
I (61356) chip[DL]: WiFi station mode change: Disabled -> Enabled
W (61356) wifi:Haven't to connect to a suitable AP now!
I (61356) chip[DL]: Attempting to connect WiFi station interface
I (61366) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (61376) chip[DL]: Done driving station state, nothing else to do...
W (61386) wifi:Haven't to connect to a suitable AP now!
I (61396) chip[DL]: Attempting to connect WiFi station interface
E (61396) wifi:sta is connecting, return error
E (61396) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (61406) wifi:Haven't to connect to a suitable AP now!
I (61406) chip[DL]: Attempting to connect WiFi station interface
E (61416) wifi:sta is connecting, return error
E (61416) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (61426) wifi:Haven't to connect to a suitable AP now!
E (61426) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (62816) wifi:new:<7,0>, old:<1,0>, ap:<255,255>, sta:<7,0>, prof:1
I (62816) wifi:(connect)dot11_authmode:0x3, pairwise_cipher:0x3, group_cipher:0x3
I (63206) wifi:state: init -> auth (b0)
I (63206) wifi:state: auth -> assoc (0)
I (63216) wifi:state: assoc -> run (10)
I (63216) wifi:(trc)phytype:CBW20-SGI, snr:37, maxRate:144, highestRateIdx:0
I (63216) wifi:(trc)rate(S-MCS7, schedIdx:0), ampdu(rate:S-MCS7, schedIdx(0, stop:8)), snr:37, ampduState:wait operational
I (63226) wifi:ifidx:0, rssi:-58, nf:-95, phytype(0x3, CBW20-SGI), phymode(0x3, 11bgn), max_rate:1440, he:0
I (63236) wifi:max ampdu length exponent:2(32767 bytes), mmss:7(16 us)
I (63256) wifi:(extcap)mbssid:0, enhanced_mbssid_advertise:0, complete_nontxbssid_profiles:0, twt_responder: 0
I (63256) wifi:connected with NETGEAR, aid = 2, channel 7, BW20, bssid = 70:5d:cc:e7:65:26
I (63266) wifi:cipher(pairwise:0x3, group:0x3), pmf:0, security:WPA2-PSK, phy:11bgn, rssi:-58
I (63296) wifi:pm start, type: 1, itwt_start:0
I (63296) wifi:pm start, type:1, aid:0x2, trans-BSSID:70:5d:cc:e7:65:26, BSSID[5]:0x26, mbssid(max-indicator:0, index:0), he:0
I (63306) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us
I (63306) wifi:set rx beacon pti, rx_bcn_pti: 10, bcn_timeout: 25000, mt_pti: 10, mt_time: 10000
I (63316) wifi:[ADDBA]TX addba request, tid:0, dialogtoken:1, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0)
I (63336) wifi:[ADDBA]TX addba request, tid:7, dialogtoken:2, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x20)
I (63346) wifi:[ADDBA]TX addba request, tid:5, dialogtoken:3, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0)
I (63356) wifi:AP's beacon interval = 102400 us, DTIM period = 3
I (63366) wifi:[ADDBA]RX addba response, status:0, tid:0/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (63366) wifi:[ADDBA]RX addba response, status:0, tid:7/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (63376) wifi:[ADDBA]RX addba response, status:0, tid:5/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (63356) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (63386) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (63406) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (63416) chip[DL]: WiFi station interface connected
I (63416) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 27 **********
I (63426) chip[EM]: <<< [E:20035r S:31019 M:149183766] (S) Msg TX to 1:FFFFFFFB00000000 [6A57] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (63436) NimBLE: GATT procedure initiated: indicate;
I (63446) NimBLE: att_handle=14
I (63456) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (63456) chip[DL]: Done driving station state, nothing else to do...
W (63436) wifi:<ba-add>idx:0, ifx:0, tid:0, TAHI:0x1002665, TALO:0xe7cc5d70, (ssn:2, win:64, cur_ssn:2), CONF:
0xc0000005
I (63476) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (63486) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (63496) chip[DIS]: mDNS service published: _matter._tcp
I (63496) chip[SVR]: Operational advertising enabled
I (63516) chip[DL]: Updating advertising data
I (63506) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (64976) chip[DL]: IP_EVENT_GOT_IP6
I (64976) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:424c:caff:fe43:1518
I (64986) app_main: Interface IP Address changed
I (64986) chip[DIS]: Updating services using commissioning mode 0
I (64996) chip[DIS]: CHIP minimal mDNS started advertising.
I (65006) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (65006) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (65016) chip[DIS]: mDNS service published: _matter._tcp
I (65026) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (65036) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EB57FB697C466A55.
I (65056) chip[DIS]: mDNS service published: _matterc._udp
I (65056) chip[SVR]: Server initialization complete
I (65056) chip[DIS]: Updating services using commissioning mode 0
I (65066) chip[DIS]: CHIP minimal mDNS started advertising.
I (65086) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (65086) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (65096) chip[DIS]: mDNS service published: _matter._tcp
I (65106) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (65116) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EB57FB697C466A55.
I (65126) chip[DIS]: mDNS service published: _matterc._udp
I (65136) chip[SWU]: Stopping the watchdog timer
I (65136) chip[SWU]: Starting the periodic query timer, timeout: 86400 seconds
I (65156) chip[IM]: No subscriptions to resume
I (65156) chip[EM]: >>> [E:20036r S:0 M:37012641] (U) Msg RX from 0:0055865591928F62 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (65166) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x40822140
I (65176) chip[EM]: <<< [E:20036r S:0 M:262434379 (Ack:37012641)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[FE80::19B:3C64:95E:519C%st1]:44862] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (65186) chip[SC]: Received Sigma1 msg
I (65206) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000002222
I (65246) chip[EM]: <<< [E:20036r S:0 M:262434380 (Ack:37012641)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[FE80::19B:3C64:95E:519C%st1]:44862] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (65256) chip[SC]: Sent Sigma2 msg
I (65256) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000002 is 0 **********
I (65266) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000002 is 1 **********
I (65286) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000003 is 0 **********
I (65296) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000003 is null **********
I (65306) chip[EM]: >>> [E:20036r S:0 M:37012642 (Ack:262434380)] (U) Msg RX from 0:0055865591928F62 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (65316) chip[EM]: <<< [E:20036r S:0 M:262434381 (Ack:37012642)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[FE80::19B:3C64:95E:519C%st1]:44862] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (65336) chip[SC]: Received Sigma3 msg
E (65346) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time
I (65456) chip[EM]: <<< [E:20036r S:0 M:262434382 (Ack:37012642)] (U) Msg TX to 0:0000000000000000 [0000]
[UDP:[FE80::19B:3C64:95E:519C%st1]:44862] --- Type 0000:40 (SecureChannel:StatusReport)
I (65466) chip[SC]: SecureSession[0x40820860, LSID:31020]: State change 'kEstablishing' --> 'kActive'
I (65476) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (65486) chip[EM]: >>> [E:20037r S:31020 M:104350266] (S) Msg RX from 1:000000000001B669 [6A57] --- Type 0001:08 (IM:InvokeCommandRequest)
I (65496) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (65506) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (65516) chip[FP]: Metadata for Fabric 0x1 persisted to storage.
I (65536) chip[TS]: Committing Last Known Good Time to storage: 2023-10-14T01:16:48
I (65536) chip[ZCL]: OpCreds: Fabric index 0x1 was committed to storage.
Compressed Fabric Id 0x7219618C7FEF6A57, FabricId 0000000000000001, NodeId 0000000000002222, VendorId 0xFFF1
I (65556) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (65566) chip[FS]: Fail-safe cleanly disarmed
I (65566) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (65576) chip[EM]: <<< [E:20037r S:31020 M:179838610 (Ack:104350266)] (S) Msg TX to 1:000000000001B669 [6A57]
[UDP:[FE80::19B:3C64:95E:519C%st1]:44862] --- Type 0001:09 (IM:InvokeCommandResponse)
I (65596) chip[EM]: >>> [E:20036r S:0 M:37012643 (Ack:262434382)] (U) Msg RX from 0:0055865591928F62 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (65606) app_main: Fabric is committed
I (65616) chip[SWU]: Device commissioned, schedule a default provider query
I (65626) chip[SVR]: Commissioning completed successfully
I (65626) chip[DIS]: Updating services using commissioning mode 0
I (65636) chip[DIS]: CHIP minimal mDNS started advertising.
I (65646) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (65646) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (65656) chip[DIS]: mDNS service published: _matter._tcp
I (65666) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (65686) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EB57FB697C466A55.
I (65686) chip[DIS]: mDNS service published: _matterc._udp
I (65696) chip[SC]: SecureSession[0x40820790, LSID:31019]: State change 'kActive' --> 'kPendingEviction'
I (65706) chip[BLE]: Releasing end point's BLE connection back to application.
I (65716) app_main: Commissioning complete
I (65716) esp_matter_core: Commissioning Complete
I (65726) app_main: Commissioning window closed
I (65616) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (65736) chip[DL]: CHIPoBLE unsubscribe received
E (65736) chip[BLE]: no endpoint for unsub recvd
I (65856) esp_netif_handlers: sta ip: 192.168.19.35, mask: 255.255.255.0, gw: 192.168.19.1
I (65856) chip[DL]: IP_EVENT_STA_GOT_IP
I (65866) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.19.35/255.255.255.0 gateway 192.168.19.1
I (65876) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (65876) app_main: Interface IP Address changed
I (65886) chip[DIS]: Updating services using commissioning mode 0
I (65886) chip[DIS]: CHIP minimal mDNS started advertising.
I (65906) chip[DIS]: Advertise operational node 7219618C7FEF6A57-0000000000002222
I (65916) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7219618C7FEF6A57-0000000000002222.
I (65926) chip[DIS]: mDNS service published: _matter._tcp
I (65926) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (65936) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: EB57FB697C466A55.
I (65956) chip[DIS]: mDNS service published: _matterc._udp
I (65956) chip[EM]: Retransmitting MessageCounter:179838610 on exchange 20037r Send Cnt 1
I (66356) chip[EM]: Retransmitting MessageCounter:179838610 on exchange 20037r Send Cnt 2
I (66986) chip[EM]: Retransmitting MessageCounter:179838610 on exchange 20037r Send Cnt 3
I (67866) chip[EM]: Retransmitting MessageCounter:179838610 on exchange 20037r Send Cnt 4
I (68226) chip[EM]: >>> [E:20037r S:31020 M:104350267 (Ack:179838610)] (S) Msg RX from 1:000000000001B669 [6A57] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68226) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (68236) esp_matter_core: BLE Disconnected
SmartThings 앱 commissioning light 장치 Log
I (24792) chip[DL]: BLE GAP connection established (con 1)
I (24792) chip[DL]: CHIPoBLE advertising stopped
I (25592) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (25592) chip[BLE]: local and remote recv window sizes = 5
I (25602) chip[BLE]: selected BTP version 4
I (25602) chip[BLE]: using BTP fragment sizes rx 244 / tx 244.
I (25602) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1
I (25632) chip[DL]: CHIPoBLE subscribe received
I (25632) NimBLE: GATT procedure initiated: indicate;
I (25632) NimBLE: att_handle=14
I (25772) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (25772) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (25792) chip[EM]: >>> [E:27129r S:0 M:84176254] (U) Msg RX from 0:A5AFF05C181B4487 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (25802) chip[EM]: <<< [E:27129r S:0 M:72589256] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (25812) NimBLE: GATT procedure initiated: indicate;
I (25812) NimBLE: att_handle=14
I (25822) chip[SVR]: Commissioning session establishment step started
I (25922) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (25922) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (25932) chip[EM]: >>> [E:27129r S:0 M:84176255] (U) Msg RX from 0:A5AFF05C181B4487 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (26002) chip[EM]: <<< [E:27129r S:0 M:72589257] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (26012) NimBLE: GATT procedure initiated: indicate;
I (26012) NimBLE: att_handle=14
I (26112) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26112) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26132) chip[EM]: >>> [E:27129r S:0 M:84176256] (U) Msg RX from 0:A5AFF05C181B4487 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (26142) chip[EM]: <<< [E:27129r S:0 M:72589258] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport)
I (26152) NimBLE: GATT procedure initiated: indicate;
I (26162) NimBLE: att_handle=14
I (26162) chip[SC]: SecureSession[0x408207b0, LSID:25879]: State change 'kEstablishing' --> 'kActive'
I (26182) chip[SVR]: Commissioning completed session establishment step
I (26182) chip[DIS]: Updating services using commissioning mode 0
I (26192) chip[DIS]: CHIP minimal mDNS started advertising.
I (26192) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (26202) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (26212) chip[DIS]: mDNS service published: _matterc._udp
I (26222) chip[SVR]: Device completed Rendezvous process
I (26222) app_main: Commissioning session started
I (26242) app_main: Commissioning window closed
I (26262) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26262) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26272) chip[EM]: >>> [E:27130r S:25879 M:209483510] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (26292) chip[EM]: <<< [E:27130r S:25879 M:231227172] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (26302) NimBLE: GATT procedure initiated: indicate;
I (26302) NimBLE: att_handle=14
I (26412) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26412) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26422) chip[EM]: >>> [E:27131r S:25879 M:209483511] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (26432) chip[EM]: <<< [E:27131r S:25879 M:231227173] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (26452) NimBLE: GATT procedure initiated: indicate;
I (26452) NimBLE: att_handle=14
I (26552) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26552) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26572) chip[EM]: >>> [E:27132r S:25879 M:209483512] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (26582) chip[EM]: <<< [E:27132r S:25879 M:231227174] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (26592) NimBLE: GATT procedure initiated: indicate;
I (26592) NimBLE: att_handle=14
I (26702) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26752) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26752) chip[EM]: >>> [E:27133r S:25879 M:209483513] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (26762) chip[EM]: <<< [E:27133r S:25879 M:231227175] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (26782) NimBLE: GATT procedure initiated: indicate;
I (26782) NimBLE: att_handle=14
I (26842) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26852) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26862) chip[EM]: >>> [E:27134r S:25879 M:209483514] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (26872) chip[EM]: <<< [E:27134r S:25879 M:231227176] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (26892) NimBLE: GATT procedure initiated: indicate;
I (26892) NimBLE: att_handle=14
I (26942) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (26942) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (26962) chip[EM]: >>> [E:27135r S:25879 M:209483515] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (26972) chip[EM]: <<< [E:27135r S:25879 M:231227177] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (26982) NimBLE: GATT procedure initiated: indicate;
I (26982) NimBLE: att_handle=14
I (27092) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27092) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (27102) chip[EM]: >>> [E:27135r S:25879 M:209483516] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (27122) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (27132) chip[FS]: GeneralCommissioning: Received ArmFailSafe (900s)
I (27132) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (27142) chip[EM]: <<< [E:27135r S:25879 M:231227178] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (27152) NimBLE: GATT procedure initiated: indicate;
I (27162) NimBLE: att_handle=14
I (27232) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27242) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (27252) chip[EM]: >>> [E:27136r S:25879 M:209483517] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (27262) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (27272) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (27282) chip[EM]: <<< [E:27136r S:25879 M:231227179] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (27292) NimBLE: GATT procedure initiated: indicate;
I (27302) NimBLE: att_handle=14
I (27382) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27382) NimBLE: GATT procedure initiated: indicate;
I (27392) NimBLE: att_handle=14
I (27532) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27532) NimBLE: GATT procedure initiated: indicate;
I (27542) NimBLE: att_handle=14
I (27622) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27632) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (27642) chip[EM]: >>> [E:27137r S:25879 M:209483518] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (27652) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (27662) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (27672) chip[EM]: <<< [E:27137r S:25879 M:231227180] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (27682) NimBLE: GATT procedure initiated: indicate;
I (27692) NimBLE: att_handle=14
I (27772) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27772) NimBLE: GATT procedure initiated: indicate;
I (27782) NimBLE: att_handle=14
I (27872) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (27872) NimBLE: GATT procedure initiated: indicate;
I (27882) NimBLE: att_handle=14
I (27962) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (30012) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (30022) chip[EM]: >>> [E:27138r S:25879 M:209483519] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (30032) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (30042) chip[ZCL]: OpCreds: Received a CSRRequest command
I (30072) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (30092) chip[ZCL]: OpCreds: CSRRequest successful.
I (30092) chip[EM]: <<< [E:27138r S:25879 M:231227181] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (30102) NimBLE: GATT procedure initiated: indicate;
I (30102) NimBLE: att_handle=14
I (30262) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (30262) NimBLE: GATT procedure initiated: indicate;
I (30272) NimBLE: att_handle=14
I (30352) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (32692) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34262) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34352) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34362) chip[EM]: >>> [E:27139r S:25879 M:209483520] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (34372) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (34382) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (34412) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (34412) chip[EM]: <<< [E:27139r S:25879 M:231227182] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (34432) NimBLE: GATT procedure initiated: indicate;
I (34442) NimBLE: att_handle=14
I (34502) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (34502) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34602) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34692) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (34702) chip[EM]: >>> [E:27140r S:25879 M:209483521] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (34712) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (34722) chip[ZCL]: OpCreds: Received an AddNOC command
I (34732) chip[FP]: Validating NOC chain
I (34782) chip[FP]: NOC chain validation successful
I (34792) chip[FP]: Added new fabric at index: 0x1
I (34792) chip[FP]: Assigned compressed fabric ID: 0x98B08F9B983BF989, node ID: 0xE58D79EBA566B125
I (34802) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (34812) chip[TS]: New proposed Last Known Good Time: 2024-02-01T00:50:01
I (34812) chip[TS]: Updating pending Last Known Good Time to 2024-02-01T00:50:01
I (34832) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00000001
I (34832) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (34842) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (34852) chip[DIS]: mDNS service published: _matter._tcp
I (34862) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC
I (34872) chip[EM]: <<< [E:27140r S:25879 M:231227183] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (34882) NimBLE: GATT procedure initiated: indicate;
I (34892) NimBLE: att_handle=14
I (34892) app_main: Fabric is updated
I (34992) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (34992) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35002) chip[EM]: >>> [E:27141r S:25879 M:209483522] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35012) chip[EM]: <<< [E:27141r S:25879 M:231227184] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35032) NimBLE: GATT procedure initiated: indicate;
I (35032) NimBLE: att_handle=14
I (35132) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35132) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35152) chip[EM]: >>> [E:27142r S:25879 M:209483523] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35162) chip[EM]: <<< [E:27142r S:25879 M:231227185] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35172) NimBLE: GATT procedure initiated: indicate;
I (35172) NimBLE: att_handle=14
I (35282) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35282) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35292) chip[EM]: >>> [E:27143r S:25879 M:209483524] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35312) chip[EM]: <<< [E:27143r S:25879 M:231227186] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35322) NimBLE: GATT procedure initiated: indicate;
I (35322) NimBLE: att_handle=14
I (35382) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35382) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35392) chip[EM]: >>> [E:27144r S:25879 M:209483525] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35402) chip[EM]: <<< [E:27144r S:25879 M:231227187] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35422) NimBLE: GATT procedure initiated: indicate;
I (35422) NimBLE: att_handle=14
I (35522) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35522) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35542) chip[EM]: >>> [E:27145r S:25879 M:209483526] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35552) chip[EM]: <<< [E:27145r S:25879 M:231227188] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35562) NimBLE: GATT procedure initiated: indicate;
I (35562) NimBLE: att_handle=14
I (35622) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35622) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35632) chip[EM]: >>> [E:27146r S:25879 M:209483527] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35652) chip[EM]: <<< [E:27146r S:25879 M:231227189] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35662) NimBLE: GATT procedure initiated: indicate;
I (35662) NimBLE: att_handle=14
I (35722) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35722) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35732) chip[EM]: >>> [E:27147r S:25879 M:209483528] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (35752) chip[EM]: <<< [E:27147r S:25879 M:231227190] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (35762) NimBLE: GATT procedure initiated: indicate;
I (35762) NimBLE: att_handle=14
I (35862) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (35862) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (35882) chip[EM]: >>> [E:27148r S:25879 M:209483529] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:0a (IM:TimedRequest)
I (35892) chip[EM]: <<< [E:27148r S:25879 M:231227191] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (35902) NimBLE: GATT procedure initiated: indicate;
I (35902) NimBLE: att_handle=14
I (36012) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (36062) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (36062) chip[EM]: >>> [E:27148r S:25879 M:209483530] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (38562) NimBLE: GATT procedure initiated: indicate;
I (38562) NimBLE: att_handle=14
I (38692) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (39442) chip[DL]: WIFI_EVENT_SCAN_DONE
I (39452) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (39462) chip[EM]: <<< [E:27148r S:25879 M:231227192] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (39472) NimBLE: GATT procedure initiated: indicate;
I (39472) NimBLE: att_handle=14
I (39572) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (39572) NimBLE: GATT procedure initiated: indicate;
I (39582) NimBLE: att_handle=14
I (39672) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (39672) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (39682) chip[EM]: >>> [E:27149r S:25879 M:209483531] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:0a (IM:TimedRequest)
I (39692) chip[EM]: <<< [E:27149r S:25879 M:231227193] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (39702) NimBLE: GATT procedure initiated: indicate;
I (39712) NimBLE: att_handle=14
I (39812) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (39812) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (39832) chip[EM]: >>> [E:27149r S:25879 M:209483532] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (39842) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (39852) chip[EM]: <<< [E:27149r S:25879 M:231227194] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (39872) NimBLE: GATT procedure initiated: indicate;
I (39872) NimBLE: att_handle=14
I (39962) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (39962) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (39972) chip[EM]: >>> [E:27150r S:25879 M:209483533] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:02 (IM:ReadRequest)
I (39992) chip[EM]: <<< [E:27150r S:25879 M:231227195] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:05 (IM:ReportData)
I (40002) NimBLE: GATT procedure initiated: indicate;
I (40002) NimBLE: att_handle=14
I (40102) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (40112) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (40122) chip[EM]: >>> [E:27151r S:25879 M:209483534] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:0a (IM:TimedRequest)
I (40132) chip[EM]: <<< [E:27151r S:25879 M:231227196] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (40142) NimBLE: GATT procedure initiated: indicate;
I (40142) NimBLE: att_handle=14
I (40252) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (40252) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (40272) chip[EM]: >>> [E:27151r S:25879 M:209483535] (S) Msg RX from 1:FFFFFFFB00000000 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (40282) chip[NP]: ESP NetworkCommissioningDelegate: SSID: NETGEAR
W (40282) wifi:Haven't to connect to a suitable AP now!
I (40292) chip[DL]: WiFi station mode change: Enabled -> Disabled
I (40352) chip[DL]: WiFi station mode change: Disabled -> Enabled
W (40352) wifi:Haven't to connect to a suitable AP now!
I (40352) chip[DL]: Attempting to connect WiFi station interface
I (40362) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (40372) chip[DL]: Done driving station state, nothing else to do...
W (40372) wifi:Haven't to connect to a suitable AP now!
I (40382) chip[DL]: Attempting to connect WiFi station interface
E (40392) wifi:sta is connecting, return error
E (40392) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (40402) wifi:Haven't to connect to a suitable AP now!
I (40402) chip[DL]: Attempting to connect WiFi station interface
E (40412) wifi:sta is connecting, return error
E (40412) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (40422) wifi:Haven't to connect to a suitable AP now!
E (40422) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (40912) wifi:new:<3,0>, old:<1,0>, ap:<255,255>, sta:<3,0>, prof:1
I (40912) wifi:(connect)dot11_authmode:0x3, pairwise_cipher:0x3, group_cipher:0x3
I (41262) wifi:state: init -> auth (b0)
I (41262) wifi:state: auth -> assoc (0)
I (41272) wifi:state: assoc -> run (10)
I (41272) wifi:(trc)phytype:CBW20-SGI, snr:36, maxRate:144, highestRateIdx:0
I (41272) wifi:(trc)rate(S-MCS7, schedIdx:0), ampdu(rate:S-MCS7, schedIdx(0, stop:8)), snr:36, ampduState:wait operational
I (41282) wifi:ifidx:0, rssi:-59, nf:-95, phytype(0x3, CBW20-SGI), phymode(0x3, 11bgn), max_rate:1440, he:0
I (41292) wifi:max ampdu length exponent:2(32767 bytes), mmss:7(16 us)
I (41322) wifi:(extcap)mbssid:0, enhanced_mbssid_advertise:0, complete_nontxbssid_profiles:0, twt_responder: 0
I (41322) wifi:connected with NETGEAR, aid = 3, channel 3, BW20, bssid = 70:5d:cc:e7:65:26
I (41332) wifi:cipher(pairwise:0x3, group:0x3), pmf:0, security:WPA2-PSK, phy:11bgn, rssi:-59
I (41352) wifi:pm start, type: 1, itwt_start:0
I (41352) wifi:pm start, type:1, aid:0x3, trans-BSSID:70:5d:cc:e7:65:26, BSSID[5]:0x26, mbssid(max-indicator:0, index:0), he:0
I (41362) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us
I (41362) wifi:set rx beacon pti, rx_bcn_pti: 10, bcn_timeout: 25000, mt_pti: 10, mt_time: 10000
I (41372) wifi:[ADDBA]TX addba request, tid:0, dialogtoken:1, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0)
I (41382) wifi:[ADDBA]TX addba request, tid:7, dialogtoken:2, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x20)
I (41402) wifi:[ADDBA]TX addba request, tid:5, dialogtoken:3, bufsize:64, A-MSDU:0(not supported), policy:1(IMR), ssn:0(0x0)
I (41412) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (41412) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (41422) wifi:AP's beacon interval = 102400 us, DTIM period = 3
I (41432) wifi:[ADDBA]RX addba response, status:0, tid:0/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (41432) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (41452) chip[DL]: WiFi station interface connected
I (41452) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (41462) chip[EM]: <<< [E:27151r S:25879 M:231227197] (S) Msg TX to 1:FFFFFFFB00000000 [F989] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (41472) NimBLE: GATT procedure initiated: indicate;
I (41482) NimBLE: att_handle=14
I (41482) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (41492) chip[DL]: Done driving station state, nothing else to do...
I (41442) wifi:[ADDBA]RX addba response, status:0, tid:7/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (41512) wifi:[ADDBA]RX addba response, status:0, tid:5/tb:1(0xa1), bufsize:16, batimeout:0, txa_wnd:16
I (41522) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (41532) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (41542) chip[DIS]: mDNS service published: _matter._tcp
I (41542) chip[SVR]: Operational advertising enabled
I (41552) chip[DL]: Updating advertising data
I (41622) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
W (41972) wifi:<ba-add>idx:0, ifx:0, tid:0, TAHI:0x1002665, TALO:0xe7cc5d70, (ssn:2, win:64, cur_ssn:2), CONF:0xc0000005
I (42652) chip[DL]: IP_EVENT_GOT_IP6
I (42652) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:424c:caff:fe43:1518
I (42662) app_main: Interface IP Address changed
I (42662) chip[DIS]: Updating services using commissioning mode 0
I (42672) chip[DIS]: CHIP minimal mDNS started advertising.
I (42682) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (42682) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (42692) chip[DIS]: mDNS service published: _matter._tcp
I (42702) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (42712) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (42732) chip[DIS]: mDNS service published: _matterc._udp
I (42732) chip[SVR]: Server initialization complete
I (42732) chip[DIS]: Updating services using commissioning mode 0
I (42742) chip[DIS]: CHIP minimal mDNS started advertising.
I (42762) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (42762) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (42772) chip[DIS]: mDNS service published: _matter._tcp
I (42782) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (42792) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (42802) chip[DIS]: mDNS service published: _matterc._udp
I (42802) chip[SWU]: Stopping the watchdog timer
I (42812) chip[SWU]: Starting the periodic query timer, timeout: 86400 seconds
I (42832) chip[IM]: No subscriptions to resume
I (42832) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000002 is 0 **********
I (42842) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000002 is 1 **********
I (42852) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000003 is 0 **********
I (42862) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x00000003 is null **********
I (42912) esp_netif_handlers: sta ip: 192.168.19.35, mask: 255.255.255.0, gw: 192.168.19.1
I (42912) chip[DL]: IP_EVENT_STA_GOT_IP
I (42922) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.19.35/255.255.255.0 gateway 192.168.19.1
I (42932) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (42932) app_main: Interface IP Address changed
I (42942) chip[DIS]: Updating services using commissioning mode 0
I (42942) chip[DIS]: CHIP minimal mDNS started advertising.
I (42962) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (42962) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (42972) chip[DIS]: mDNS service published: _matter._tcp
I (42972) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (42982) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (43002) chip[DIS]: mDNS service published: _matterc._udp
I (44152) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (44942) chip[EM]: >>> [E:60841r S:0 M:26724749] (U) Msg RX from 0:87A3A07E99500499 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (44952) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x40822160
I (44952) chip[EM]: <<< [E:60841r S:0 M:72589259 (Ack:26724749)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::2EBA:BAFF:FE8E:7249%st1]:60366] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (44972) chip[SC]: Received Sigma1 msg
I (44982) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0xE58D79EBA566B125
I (45032) chip[EM]: <<< [E:60841r S:0 M:72589260 (Ack:26724749)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::2EBA:BAFF:FE8E:7249%st1]:60366] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (45042) chip[SC]: Sent Sigma2 msg
I (45422) chip[EM]: >>> [E:60841r S:0 M:26724750 (Ack:72589260)] (U) Msg RX from 0:87A3A07E99500499 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (45432) chip[EM]: <<< [E:60841r S:0 M:72589261 (Ack:26724750)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::2EBA:BAFF:FE8E:7249%st1]:60366] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (45452) chip[SC]: Received Sigma3 msg
E (45462) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time
I (45562) chip[EM]: <<< [E:60841r S:0 M:72589262 (Ack:26724750)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::2EBA:BAFF:FE8E:7249%st1]:60366] --- Type 0000:40 (SecureChannel:StatusReport)
I (45572) chip[SC]: SecureSession[0x40820880, LSID:25880]: State change 'kEstablishing' --> 'kActive'
I (45582) chip[IN]: CASE Session established to peer: <736A79B51D4DDCE7, 1>
I (45972) chip[EM]: Retransmitting MessageCounter:72589262 on exchange 60841r Send Cnt 1
I (46372) chip[EM]: Retransmitting MessageCounter:72589262 on exchange 60841r Send Cnt 2
I (46382) ROUTE_HOOK: Received RIO
I (46382) ROUTE_HOOK: prefix FDC6:DBBD:A5A4:1:: lifetime 1800
I (46392) chip[EM]: >>> [E:60842r S:25880 M:17281083] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (46402) chip[EM]: <<< [E:60842r S:25880 M:231850053 (Ack:17281083)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:[FE80::2EBA:BAFF:FE8E:7249%st1]:60366] --- Type 0001:05 (IM:ReportData)
I (46652) NimBLE: GATT procedure initiated: indicate;
I (46652) NimBLE: att_handle=14
I (46682) chip[EM]: >>> [E:60841r S:0 M:26724753 (Ack:72589262)] (U) Msg RX from 0:87A3A07E99500499 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (46792) chip[EM]: Retransmitting MessageCounter:231850053 on exchange 60842r Send Cnt 1
I (46832) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14
I (46852) chip[EM]: >>> [E:60842r S:25880 M:17281084 (Ack:231850053)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (46862) chip[EM]: >>> [E:60843r S:25880 M:17281085] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:03 (IM:SubscribeRequest)
I (46872) chip[DMG]: Final negotiated min/max parameters: Min = 1s, Max = 60s
I (46882) chip[EM]: >>> [E:60842r S:25880 M:17281083] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (46882) chip[EM]: <<< [E:60842r S:25880 M:231850054 (Ack:17281083)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (46912) chip[EM]: <<< [E:60843r S:25880 M:231850055 (Ack:17281085)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (46952) chip[EM]: >>> [E:60842r S:25880 M:17281086 (Ack:231850053)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (46992) chip[EM]: >>> [E:60843r S:25880 M:17281087 (Ack:231850055)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:01 (IM:StatusResponse)
I (47002) chip[IM]: Received status response, status is 0x00
I (47002) chip[EM]: <<< [E:60843r S:25880 M:231850056 (Ack:17281087)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:04 (IM:SubscribeResponse)
I (47012) chip[DMG]: Registered a ReadHandler that will schedule a report between system Timestamp: 0x000000000000BB95 and system Timestamp 0x000000000001A20D.
I (47152) chip[EM]: >>> [E:60843r S:25880 M:17281088 (Ack:231850056)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (47162) chip[EM]: >>> [E:60844r S:25880 M:17281089] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (47182) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (47182) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (47192) chip[FP]: Metadata for Fabric 0x1 persisted to storage.
I (47252) chip[TS]: Committing Last Known Good Time to storage: 2024-02-01T00:50:01
I (47252) chip[ZCL]: OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x98B08F9B983BF989, FabricId 04056106F39DB4F6, NodeId E58D79EBA566B125, VendorId 0x110A
I (47282) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (47282) chip[FS]: Fail-safe cleanly disarmed
I (47292) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (47302) chip[EM]: <<< [E:60844r S:25880 M:231850057 (Ack:17281089)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:09 (IM:InvokeCommandResponse)
I (47312) app_main: Fabric is committed
I (47322) chip[SWU]: Device commissioned, schedule a default provider query
I (47332) chip[SVR]: Commissioning completed successfully
I (47332) chip[DIS]: Updating services using commissioning mode 0
I (47342) chip[DIS]: CHIP minimal mDNS started advertising.
I (47352) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (47352) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (47372) chip[DIS]: mDNS service published: _matter._tcp
I (47372) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (47382) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (47402) chip[DIS]: mDNS service published: _matterc._udp
I (47402) chip[SC]: SecureSession[0x408207b0, LSID:25879]: State change 'kActive' --> 'kPendingEviction'
I (47412) chip[BLE]: Releasing end point's BLE connection back to application.
I (47422) app_main: Commissioning complete
I (47422) esp_matter_core: Commissioning Complete
I (47422) app_main: Commissioning window closed
I (47592) chip[EM]: >>> [E:60844r S:25880 M:17281089] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (47602) chip[EM]: <<< [E:60844r S:25880 M:231850058 (Ack:17281089)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (47652) chip[DL]: IP_EVENT_GOT_IP6
I (47652) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fdce:a223:52a8:aec3:424c:caff:fe43:1518
I (47662) ROUTE_HOOK: Hook already installed on netif, skip...
I (47662) app_main: Interface IP Address changed
I (47672) chip[DIS]: Updating services using commissioning mode 0
I (47672) chip[DIS]: CHIP minimal mDNS started advertising.
I (47692) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (47702) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (47712) chip[DIS]: mDNS service published: _matter._tcp
I (47712) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (47722) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A4CDE0D6450FF844.
I (47742) chip[DIS]: mDNS service published: _matterc._udp
I (47742) chip[EM]: Retransmitting MessageCounter:231850057 on exchange 60844r Send Cnt 1
I (47802) chip[EM]: >>> [E:60844r S:25880 M:17281090 (Ack:231850057)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (47822) chip[EM]: >>> [E:60844r S:25880 M:17281091 (Ack:231850057)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (47832) chip[EM]: >>> [E:60845r S:25880 M:17281092] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (47842) chip[EM]: <<< [E:60845r S:25880 M:231850059 (Ack:17281092)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (47892) chip[EM]: >>> [E:60845r S:25880 M:17281093 (Ack:231850059)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (48592) chip[EM]: >>> [E:60846r S:25880 M:17281094] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (48592) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFC is 0 **********
I (48612) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFD is 2 **********
I (48622) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x00000005 is **********
E (48632) chip[DMG]: Error retrieving data from clusterId: 0x0000_0028, err = 15
I (48642) chip[EM]: <<< [E:60846r S:25880 M:231850060 (Ack:17281094)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (49022) chip[EM]: >>> [E:60846r S:25880 M:17281094] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (49022) chip[EM]: <<< [E:60846r S:25880 M:231850061 (Ack:17281094)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (49072) chip[EM]: Retransmitting MessageCounter:231850060 on exchange 60846r Send Cnt 1
I (49272) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (49272) chip[BLE]: Releasing end point's BLE connection back to application.
E (49282) chip[BLE]: HandleChipConnectionReceived failed, err = d
E (49282) chip[BLE]: failed handle new chip BLE connection, status = d
I (49462) chip[EM]: Retransmitting MessageCounter:231850060 on exchange 60846r Send Cnt 2
I (49512) chip[EM]: >>> [E:60846r S:25880 M:17281094] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (49512) chip[EM]: <<< [E:60846r S:25880 M:231850062 (Ack:17281094)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (50092) chip[EM]: Retransmitting MessageCounter:231850060 on exchange 60846r Send Cnt 3
I (50942) chip[EM]: >>> [E:60846r S:25880 M:17281095 (Ack:231850060)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (50952) chip[EM]: >>> [E:60847r S:25880 M:17281096] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (50972) chip[EM]: >>> [E:60846r S:25880 M:17281097 (Ack:231850060)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (50982) chip[EM]: <<< [E:60847r S:25880 M:231850063 (Ack:17281096)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (51292) chip[EM]: >>> [E:60847r S:25880 M:17281096] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (51292) chip[EM]: <<< [E:60847r S:25880 M:231850064 (Ack:17281096)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (51392) chip[EM]: Retransmitting MessageCounter:231850063 on exchange 60847r Send Cnt 1
I (51762) chip[EM]: >>> [E:60847r S:25880 M:17281096] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (51772) chip[EM]: <<< [E:60847r S:25880 M:231850065 (Ack:17281096)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (51792) chip[EM]: Retransmitting MessageCounter:231850063 on exchange 60847r Send Cnt 2
I (52212) chip[EM]: >>> [E:60847r S:25880 M:17281096] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (52212) chip[EM]: <<< [E:60847r S:25880 M:231850066 (Ack:17281096)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (52332) chip[EM]: Retransmitting MessageCounter:231850063 on exchange 60847r Send Cnt 3
I (53002) chip[EM]: >>> [E:60848r S:25880 M:17281099] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53002) chip[EM]: <<< [E:60848r S:25880 M:231850067 (Ack:17281099)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (53192) chip[EM]: >>> [E:60848r S:25880 M:17281099] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53202) chip[EM]: <<< [E:60848r S:25880 M:231850068 (Ack:17281099)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53252) chip[EM]: Retransmitting MessageCounter:231850063 on exchange 60847r Send Cnt 4
I (53372) chip[EM]: Retransmitting MessageCounter:231850067 on exchange 60848r Send Cnt 1
I (53402) chip[EM]: >>> [E:60847r S:25880 M:17281100 (Ack:231850063)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53412) chip[EM]: >>> [E:60847r S:25880 M:17281101 (Ack:231850063)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53422) chip[EM]: >>> [E:60848r S:25880 M:17281102 (Ack:231850067)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53442) chip[EM]: >>> [E:60849r S:25880 M:17281103] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53452) chip[EM]: >>> [E:60847r S:25880 M:17281104 (Ack:231850063)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53462) chip[EM]: >>> [E:60848r S:25880 M:17281105 (Ack:231850067)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53482) chip[EM]: <<< [E:60849r S:25880 M:231850069 (Ack:17281103)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (53522) chip[EM]: >>> [E:60849r S:25880 M:17281106 (Ack:231850069)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53532) chip[EM]: >>> [E:60850r S:25880 M:17281107] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53542) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFD is 2 **********
I (53552) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x0000FFFD is 1 **********
I (53562) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000031's Attribute 0x0000FFFD is 1 **********
I (53572) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000033's Attribute 0x0000FFFD is 1 **********
I (53582) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000003C's Attribute 0x0000FFFD is 1 **********
I (53592) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000003E's Attribute 0x0000FFFD is 1 **********
I (53612) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000036's Attribute 0x0000FFFD is 1 **********
I (53622) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x0000FFFD is 1 **********
I (53632) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x0000FFFD is 4 **********
I (53652) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFD is 4 **********
I (53652) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x0000FFFD is 5 **********
I (53672) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x0000FFFD is 5 **********
I (53682) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x0000FFFD is 5 **********
I (53702) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x0000FFFD is 6 **********
I (53712) chip[EM]: <<< [E:60850r S:25880 M:231850070 (Ack:17281107)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (53922) chip[EM]: >>> [E:60850r S:25880 M:17281107] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53922) chip[EM]: <<< [E:60850r S:25880 M:231850071 (Ack:17281107)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53942) chip[EM]: >>> [E:60850r S:25880 M:17281108 (Ack:231850070)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (53962) chip[EM]: >>> [E:60851r S:25880 M:17281109] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (53972) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000001F's Attribute 0x0000FFFC is 0 **********
I (53982) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x0000FFFC is 0 **********
I (53992) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x0000FFFC is 0 **********
I (54012) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000033's Attribute 0x0000FFFC is 0 **********
I (54022) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000003C's Attribute 0x0000FFFC is 0 **********
I (54032) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000003E's Attribute 0x0000FFFC is 0 **********
I (54042) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000036's Attribute 0x0000FFFC is 0 **********
I (54052) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000002A's Attribute 0x0000FFFC is 0 **********
I (54062) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x0000FFFC is 0 **********
I (54072) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is 1 **********
I (54092) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x0000FFFC is 0 **********
I (54102) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x0000FFFC is 1 **********
I (54112) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x0000FFFC is 3 **********
I (54122) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x0000FFFC is 24 **********
I (54142) chip[EM]: <<< [E:60851r S:25880 M:231850072 (Ack:17281109)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (54222) chip[EM]: >>> [E:60851r S:25880 M:17281110 (Ack:231850072)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (54232) chip[EM]: >>> [E:60852r S:25880 M:17281111] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (54252) chip[EM]: <<< [E:60852r S:25880 M:231850073 (Ack:17281111)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (54632) chip[EM]: >>> [E:60852r S:25880 M:17281111] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (54632) chip[EM]: <<< [E:60852r S:25880 M:231850074 (Ack:17281111)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (54672) chip[EM]: Retransmitting MessageCounter:231850073 on exchange 60852r Send Cnt 1
I (55002) chip[EM]: Retransmitting MessageCounter:231850073 on exchange 60852r Send Cnt 2
I (55152) chip[EM]: >>> [E:60852r S:25880 M:17281111] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (55152) chip[EM]: <<< [E:60852r S:25880 M:231850075 (Ack:17281111)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (55572) chip[EM]: >>> [E:60852r S:25880 M:17281111] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (55572) chip[EM]: <<< [E:60852r S:25880 M:231850076 (Ack:17281111)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (55622) chip[EM]: Retransmitting MessageCounter:231850073 on exchange 60852r Send Cnt 3
I (56492) chip[EM]: >>> [E:60852r S:25880 M:17281112 (Ack:231850073)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56502) chip[EM]: >>> [E:60853r S:25880 M:17281113] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (56512) chip[EM]: >>> [E:60852r S:25880 M:17281114 (Ack:231850073)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56532) chip[EM]: <<< [E:60853r S:25880 M:231850077 (Ack:17281113)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (56782) chip[EM]: >>> [E:60853r S:25880 M:17281113] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (56782) chip[EM]: <<< [E:60853r S:25880 M:231850078 (Ack:17281113)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56802) chip[EM]: >>> [E:60852r S:25880 M:17281115 (Ack:231850073)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56882) chip[EM]: >>> [E:60853r S:25880 M:17281116 (Ack:231850077)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56892) chip[EM]: >>> [E:60854r S:25880 M:17281117] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (56912) chip[EM]: <<< [E:60854r S:25880 M:231850079 (Ack:17281117)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (56982) chip[EM]: >>> [E:60854r S:25880 M:17281118 (Ack:231850079)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (56992) chip[EM]: >>> [E:60855r S:25880 M:17281119] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (57012) chip[EM]: <<< [E:60855r S:25880 M:231850080 (Ack:17281119)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (57382) chip[EM]: Retransmitting MessageCounter:231850080 on exchange 60855r Send Cnt 1
I (57492) chip[EM]: >>> [E:60855r S:25880 M:17281119] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (57502) chip[EM]: <<< [E:60855r S:25880 M:231850081 (Ack:17281119)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (57522) chip[EM]: >>> [E:60855r S:25880 M:17281120 (Ack:231850080)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (57532) chip[EM]: >>> [E:60855r S:25880 M:17281121 (Ack:231850080)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (57602) chip[EM]: >>> [E:60856r S:25880 M:17281122] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (57612) esp_matter_command: Received command 0x00000009 for endpoint 0x0000's cluster 0x0000003E
I (57612) chip[ZCL]: OpCreds: Received an UpdateFabricLabel command
I (57632) chip[FP]: Metadata for Fabric 0x1 persisted to storage.
I (57632) chip[EM]: <<< [E:60856r S:25880 M:231850082 (Ack:17281122)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:09 (IM:InvokeCommandResponse)
I (58002) chip[EM]: >>> [E:60856r S:25880 M:17281122] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (58022) chip[EM]: <<< [E:60856r S:25880 M:231850083 (Ack:17281122)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (58032) chip[EM]: >>> [E:60856r S:25880 M:17281123 (Ack:231850082)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (61492) chip[EM]: >>> [E:60857r S:25880 M:17281124] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:06 (IM:WriteRequest)
I (61502) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000028's Attribute 0x00000005 is Matter Device **********
I (61522) chip[EM]: <<< [E:60857r S:25880 M:231850084 (Ack:17281124)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:07 (IM:WriteResponse)
I (61612) chip[EM]: >>> [E:60857r S:25880 M:17281125 (Ack:231850084)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (61912) chip[EM]: >>> [E:60858r S:25880 M:17281126] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:02 (IM:ReadRequest)
I (61922) chip[EM]: <<< [E:60858r S:25880 M:231850085 (Ack:17281126)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (62012) chip[EM]: >>> [E:60858r S:25880 M:17281127 (Ack:231850085)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (62522) chip[EM]: >>> [E:60859r S:25880 M:17281128] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:03 (IM:SubscribeRequest)
I (62532) chip[IM]: Deleting previous subscription from NodeId: 736A79B51D4DDCE7, FabricIndex: 1
I (62542) chip[DMG]: Final negotiated min/max parameters: Min = 1s, Max = 30s
I (62552) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x00000007 is 250 **********
I (62562) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x00000004 is 24701 **********
I (62572) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x00000003 is 24939 **********
I (62582) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00000000 is 64 **********
I (62602) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x00000000 is 0 **********
I (62612) chip[EM]: <<< [E:60859r S:25880 M:231850086 (Ack:17281128)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:05 (IM:ReportData)
I (62732) chip[EM]: >>> [E:60859r S:25880 M:17281128] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:03 (IM:SubscribeRequest)
I (62742) chip[EM]: <<< [E:60859r S:25880 M:231850087 (Ack:17281128)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (62752) chip[EM]: >>> [E:60859r S:25880 M:17281129 (Ack:231850086)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:01 (IM:StatusResponse)
I (62772) chip[IM]: Received status response, status is 0x00
I (62782) chip[EM]: <<< [E:60859r S:25880 M:231850088 (Ack:17281129)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:04 (IM:SubscribeResponse)
I (62792) chip[DMG]: Registered a ReadHandler that will schedule a report between system Timestamp: 0x000000000000F936 and system Timestamp 0x0000000000016A7E.
I (63132) chip[EM]: Retransmitting MessageCounter:231850088 on exchange 60859r Send Cnt 1
I (63242) chip[EM]: >>> [E:60859r S:25880 M:17281131 (Ack:231850088)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (63262) chip[EM]: >>> [E:60859r S:25880 M:17281132 (Ack:231850088)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (63272) chip[EM]: >>> [E:60860r S:25880 M:17281130] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:0a (IM:TimedRequest)
I (63282) chip[EM]: <<< [E:60860r S:25880 M:231850089 (Ack:17281130)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:01 (IM:StatusResponse)
I (63362) chip[EM]: >>> [E:60860r S:25880 M:17281133 (Ack:231850089)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0001:08 (IM:InvokeCommandRequest)
I (63372) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003C
I (63372) chip[ZCL]: Received command to open commissioning window
I (63382) chip[DIS]: Updating services using commissioning mode 2
I (63382) chip[DIS]: CHIP minimal mDNS started advertising.
I (63412) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (63412) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (63422) chip[DIS]: mDNS service published: _matter._tcp
I (63422) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3054/11 cm=2
I (63442) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 87BA3EC63CFBA3CE.
I (63452) chip[DIS]: mDNS service published: _matterc._udp
I (63462) chip[ZCL]: Commissioning window is now open
I (63462) chip[EM]: <<< [E:60860r S:25880 M:231850090 (Ack:17281133)] (S) Msg TX to 1:736A79B51D4DDCE7 [F989] [UDP:192.168.19.2%st1:36995] --- Type 0001:09 (IM:InvokeCommandResponse)
I (63482) app_main: Commissioning window opened
I (63562) chip[EM]: >>> [E:60860r S:25880 M:17281134 (Ack:231850090)] (S) Msg RX from 1:736A79B51D4DDCE7 [F989] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (64282) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (64292) chip[DL]: CHIPoBLE unsubscribe received
E (64292) chip[BLE]: no endpoint for unsub recvd
I (65502) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (65502) esp_matter_core: BLE Disconnected
I (65502) NimBLE: GAP procedure initiated: stop advertising.
I (65522) esp_matter_core: BLE deinit successful and memory reclaimed
I (65522) app_main: BLE deinitialized and memory reclaimed
I (68052) chip[EM]: >>> [E:26747r S:0 M:85733192] (U) Msg RX from 0:52EB5764F9764785 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (68062) chip[EM]: <<< [E:26747r S:0 M:72589263 (Ack:85733192)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68072) chip[EM]: <<< [E:26747r S:0 M:72589264 (Ack:85733192)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (68092) chip[SVR]: Commissioning session establishment step started
I (68422) chip[EM]: >>> [E:26747r S:0 M:85733193 (Ack:72589264)] (U) Msg RX from 0:52EB5764F9764785 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (68432) chip[EM]: <<< [E:26747r S:0 M:72589265 (Ack:85733193)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68502) chip[EM]: <<< [E:26747r S:0 M:72589266 (Ack:85733193)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (68522) chip[EM]: >>> [E:26747r S:0 M:85733193 (Ack:72589264)] (U) Msg RX from 0:52EB5764F9764785 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (68532) chip[EM]: <<< [E:26747r S:0 M:72589267 (Ack:85733193)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68552) chip[EM]: >>> [E:26747r S:0 M:85733194 (Ack:72589266)] (U) Msg RX from 0:52EB5764F9764785 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (68572) chip[EM]: <<< [E:26747r S:0 M:72589268 (Ack:85733194)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68582) chip[EM]: <<< [E:26747r S:0 M:72589269 (Ack:85733194)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:40 (SecureChannel:StatusReport)
I (68602) chip[SC]: SecureSession[0x408207b0, LSID:25882]: State change 'kEstablishing' --> 'kActive'
I (68602) chip[SVR]: Commissioning completed session establishment step
I (68612) chip[DIS]: Updating services using commissioning mode 0
I (68632) chip[DIS]: CHIP minimal mDNS started advertising.
I (68642) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (68642) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (68662) chip[DIS]: mDNS service published: _matter._tcp
I (68662) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (68672) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 87BA3EC63CFBA3CE.
I (68692) chip[DIS]: mDNS service published: _matterc._udp
I (68692) chip[SVR]: Device completed Rendezvous process
I (68702) app_main: Commissioning session started
I (68702) app_main: Commissioning window closed
I (68722) chip[EM]: >>> [E:26747r S:0 M:85733195 (Ack:72589269)] (U) Msg RX from 0:52EB5764F9764785 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68742) chip[EM]: >>> [E:26748r S:25882 M:243256201] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (68752) chip[EM]: <<< [E:26748r S:25882 M:232011839 (Ack:243256201)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (68802) chip[EM]: >>> [E:26748r S:25882 M:243256202 (Ack:232011839)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68812) chip[EM]: >>> [E:26749r S:25882 M:243256203] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (68832) chip[EM]: <<< [E:26749r S:25882 M:232011840 (Ack:243256203)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (68862) chip[EM]: >>> [E:26749r S:25882 M:243256204 (Ack:232011840)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68882) chip[EM]: >>> [E:26750r S:25882 M:243256205] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (68902) chip[EM]: <<< [E:26750r S:25882 M:232011841 (Ack:243256205)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (68932) chip[EM]: >>> [E:26750r S:25882 M:243256206 (Ack:232011841)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (68942) chip[EM]: >>> [E:26751r S:25882 M:243256207] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (68962) chip[EM]: <<< [E:26751r S:25882 M:232011842 (Ack:243256207)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69322) chip[EM]: >>> [E:26751r S:25882 M:243256208 (Ack:232011842)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69332) chip[EM]: >>> [E:26752r S:25882 M:243256209] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (69342) chip[EM]: <<< [E:26752r S:25882 M:232011843 (Ack:243256209)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69372) chip[EM]: >>> [E:26752r S:25882 M:243256210 (Ack:232011843)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69382) chip[EM]: >>> [E:26753r S:25882 M:243256211] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (69402) chip[EM]: <<< [E:26753r S:25882 M:232011844 (Ack:243256211)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69662) chip[EM]: >>> [E:26753r S:25882 M:243256212 (Ack:232011844)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69672) chip[EM]: >>> [E:26754r S:25882 M:243256213] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (69682) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (69702) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (69702) chip[EM]: <<< [E:26754r S:25882 M:232011845 (Ack:243256213)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (69732) chip[EM]: >>> [E:26754r S:25882 M:243256214 (Ack:232011845)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69742) chip[EM]: >>> [E:26755r S:25882 M:243256215] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (69762) chip[EM]: <<< [E:26755r S:25882 M:232011846 (Ack:243256215)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69792) chip[EM]: >>> [E:26755r S:25882 M:243256216 (Ack:232011846)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69802) chip[EM]: >>> [E:26756r S:25882 M:243256217] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (69822) chip[EM]: <<< [E:26756r S:25882 M:232011847 (Ack:243256217)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69912) chip[EM]: >>> [E:26756r S:25882 M:243256218 (Ack:232011847)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (69922) chip[EM]: >>> [E:26757r S:25882 M:243256219] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (69932) chip[EM]: <<< [E:26757r S:25882 M:232011848 (Ack:243256219)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (69972) chip[EM]: >>> [E:26757r S:25882 M:243256220 (Ack:232011848)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (70512) chip[EM]: >>> [E:26758r S:25882 M:243256221] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (70522) chip[EM]: <<< [E:26758r S:25882 M:232011849 (Ack:243256221)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:01 (IM:StatusResponse)
I (70542) chip[EM]: >>> [E:26758r S:25882 M:243256222 (Ack:232011849)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (70562) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (70562) chip[FS]: GeneralCommissioning: Received ArmFailSafe (120s)
I (70572) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (70582) chip[EM]: <<< [E:26758r S:25882 M:232011850 (Ack:243256222)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (71002) chip[EM]: Retransmitting MessageCounter:232011850 on exchange 26758r Send Cnt 1
I (71322) chip[EM]: >>> [E:26759r S:25882 M:243256224] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (71332) chip[EM]: >>> [E:26758r S:25882 M:243256225 (Ack:232011850)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71352) chip[EM]: <<< [E:26759r S:25882 M:232011851 (Ack:243256224)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (71422) chip[EM]: >>> [E:26759r S:25882 M:243256224] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (71422) chip[EM]: <<< [E:26759r S:25882 M:232011852 (Ack:243256224)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71452) chip[EM]: >>> [E:26759r S:25882 M:243256226 (Ack:232011851)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71462) chip[EM]: >>> [E:26760r S:25882 M:243256227] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (71472) chip[EM]: <<< [E:26760r S:25882 M:232011853 (Ack:243256227)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:01 (IM:StatusResponse)
I (71512) chip[EM]: >>> [E:26760r S:25882 M:243256228 (Ack:232011853)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (71522) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (71522) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (71532) chip[DL]: NVS set: chip-config/country-code = "KR"
I (71532) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (71552) chip[EM]: <<< [E:26760r S:25882 M:232011854 (Ack:243256228)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (71612) chip[EM]: >>> [E:26760r S:25882 M:243256229 (Ack:232011854)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71622) chip[EM]: >>> [E:26761r S:25882 M:243256230] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (71632) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (71642) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (71652) chip[EM]: <<< [E:26761r S:25882 M:232011855 (Ack:243256230)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (71682) chip[EM]: >>> [E:26761r S:25882 M:243256231 (Ack:232011855)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71692) chip[EM]: >>> [E:26762r S:25882 M:243256232] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (71712) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (71712) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (71722) chip[EM]: <<< [E:26762r S:25882 M:232011856 (Ack:243256232)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71762) chip[ZCL]: OpCreds: AttestationRequest successful.
I (71772) chip[EM]: <<< [E:26762r S:25882 M:232011857 (Ack:243256232)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (71792) chip[EM]: >>> [E:26762r S:25882 M:243256233 (Ack:232011857)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71812) chip[EM]: >>> [E:26763r S:25882 M:243256234] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (71822) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (71832) chip[ZCL]: OpCreds: Received a CSRRequest command
I (71832) chip[EM]: <<< [E:26763r S:25882 M:232011858 (Ack:243256234)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (71882) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (71902) chip[ZCL]: OpCreds: CSRRequest successful.
I (71902) chip[EM]: <<< [E:26763r S:25882 M:232011859 (Ack:243256234)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (71982) chip[EM]: >>> [E:26763r S:25882 M:243256235 (Ack:232011859)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (72662) chip[EM]: >>> [E:26764r S:25882 M:243256236] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (72682) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (72682) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (72692) chip[EM]: <<< [E:26764r S:25882 M:232011860 (Ack:243256236)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (72742) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (72752) chip[EM]: <<< [E:26764r S:25882 M:232011861 (Ack:243256236)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (72772) chip[EM]: >>> [E:26764r S:25882 M:243256237 (Ack:232011861)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (72792) chip[EM]: >>> [E:26765r S:25882 M:243256238] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (72802) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (72802) chip[ZCL]: OpCreds: Received an AddNOC command
I (72812) chip[EM]: <<< [E:26765r S:25882 M:232011862 (Ack:243256238)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (72832) chip[FP]: Validating NOC chain
I (72872) chip[FP]: NOC chain validation successful
I (72872) chip[FP]: Added new fabric at index: 0x2
I (72872) chip[FP]: Assigned compressed fabric ID: 0x7E6F1438C5793472, node ID: 0x8C30B66DB23A848C
I (72882) chip[TS]: Last Known Good Time: 2024-02-01T00:50:01
I (72892) chip[TS]: New proposed Last Known Good Time: 2024-01-25T00:00:00
I (72892) chip[TS]: Retaining current Last Known Good Time
I (72922) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0xFFFFFFFD00010001
I (72922) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (72942) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (72952) chip[DIS]: mDNS service published: _matter._tcp
I (72952) chip[DIS]: Advertise operational node 7E6F1438C5793472-8C30B66DB23A848C
I (72972) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7E6F1438C5793472-8C30B66DB23A848C.
I (72992) chip[DIS]: mDNS service published: _matter._tcp
I (72992) chip[ZCL]: OpCreds: successfully created fabric index 0x2 via AddNOC
I (73002) chip[EM]: <<< [E:26765r S:25882 M:232011863 (Ack:243256238)] (S) Msg TX to 2:FFFFFFFB00000000 [3472] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (73012) app_main: Fabric is updated
I (73042) chip[EM]: >>> [E:26765r S:25882 M:243256239 (Ack:232011863)] (S) Msg RX from 2:FFFFFFFB00000000 [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73272) chip[EM]: >>> [E:26766r S:0 M:85733196] (U) Msg RX from 0:11DC8632BE619403 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (73282) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x40822160
I (73282) chip[EM]: <<< [E:26766r S:0 M:72589270 (Ack:85733196)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73302) chip[SC]: Received Sigma1 msg
I (73312) chip[SC]: CASE matched destination ID: fabricIndex 2, NodeID 0x8C30B66DB23A848C
I (73352) chip[EM]: <<< [E:26766r S:0 M:72589271 (Ack:85733196)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (73362) chip[SC]: Sent Sigma2 msg
I (73412) chip[EM]: >>> [E:26766r S:0 M:85733197 (Ack:72589271)] (U) Msg RX from 0:11DC8632BE619403 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (73432) chip[EM]: <<< [E:26766r S:0 M:72589272 (Ack:85733197)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73442) chip[SC]: Received Sigma3 msg
E (73452) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time
I (73522) chip[EM]: <<< [E:26766r S:0 M:72589273 (Ack:85733197)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0000:40 (SecureChannel:StatusReport)
I (73532) chip[SC]: SecureSession[0x40820950, LSID:25881]: State change 'kEstablishing' --> 'kActive'
I (73552) chip[IN]: CASE Session established to peer: <FD38A73EBE1C8EAE, 2>
I (73552) chip[EM]: >>> [E:26766r S:0 M:85733198 (Ack:72589273)] (U) Msg RX from 0:11DC8632BE619403 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73562) chip[EM]: >>> [E:26767r S:25881 M:226473195] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:02 (IM:ReadRequest)
I (73582) chip[EM]: <<< [E:26767r S:25881 M:123561273 (Ack:226473195)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (73612) chip[EM]: >>> [E:26767r S:25881 M:226473196 (Ack:123561273)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73642) chip[EM]: >>> [E:26768r S:25881 M:226473197] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:02 (IM:ReadRequest)
I (73652) chip[EM]: <<< [E:26768r S:25881 M:123561274 (Ack:226473197)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:05 (IM:ReportData)
I (73682) chip[EM]: >>> [E:26768r S:25881 M:226473198 (Ack:123561274)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73702) chip[EM]: >>> [E:26769r S:25881 M:226473199] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0001:08 (IM:InvokeCommandRequest)
I (73712) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (73722) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (73732) chip[FP]: Metadata for Fabric 0x2 persisted to storage.
I (73732) chip[TS]: Committing Last Known Good Time to storage: 2024-02-01T00:50:01
I (73752) chip[ZCL]: OpCreds: Fabric index 0x2 was committed to storage. Compressed Fabric Id 0x7E6F1438C5793472, FabricId 2D414A912B1524F6, NodeId 8C30B66DB23A848C, VendorId 0x6006
I (73762) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (73772) chip[FS]: Fail-safe cleanly disarmed
I (73772) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (73782) chip[EM]: <<< [E:26769r S:25881 M:123561275 (Ack:226473199)] (S) Msg TX to 2:FD38A73EBE1C8EAE [3472] [UDP:[FE80::506A:62FF:FE84:9A0E%st1]:36068] --- Type 0001:09 (IM:InvokeCommandResponse)
I (73802) app_main: Fabric is committed
I (73812) chip[SVR]: Commissioning completed successfully
I (73812) chip[DIS]: Updating services using commissioning mode 0
I (73822) chip[DIS]: CHIP minimal mDNS started advertising.
I (73842) chip[DIS]: Advertise operational node 98B08F9B983BF989-E58D79EBA566B125
I (73842) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 98B08F9B983BF989-E58D79EBA566B125.
I (73852) chip[DIS]: mDNS service published: _matter._tcp
I (73862) chip[DIS]: Advertise operational node 7E6F1438C5793472-8C30B66DB23A848C
I (73872) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7E6F1438C5793472-8C30B66DB23A848C.
I (73882) chip[DIS]: mDNS service published: _matter._tcp
I (73882) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=0
I (73902) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 87BA3EC63CFBA3CE.
I (73922) chip[DIS]: mDNS service published: _matterc._udp
I (73922) chip[SC]: SecureSession[0x408207b0, LSID:25882]: State change 'kActive' --> 'kPendingEviction'
I (73932) chip[SWU]: Device commissioned, schedule a default provider query
I (73932) app_main: Commissioning complete
I (73942) esp_matter_core: Commissioning Complete
I (73942) chip[EM]: >>> [E:26769r S:25881 M:226473200 (Ack:123561275)] (S) Msg RX from 2:FD38A73EBE1C8EAE [3472] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (73952) app_main: Commissioning window closed
반응형
'MATTER' 카테고리의 다른 글
| [Android CHIPTool][Ubuntu] [1] script 사용해 빌드/배포/테스트 (0) | 2024.02.26 |
|---|---|
| [esp-matter ][CHIP-Tool] [1] build/ run / 사용법 (0) | 2024.01.03 |
