ubuntu@ubuntu:~/master_cntrl/connectedhomeip/out/chip-tool$ ./chip-tool pairing ble-wifi 1 GRLPrivate grlprivatewifi092010 20202021 3840 [1707930958.434242][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1707930958.434692][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_tool_kvs-xIoZ23) [1707930958.435076][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_tool_kvs) [1707930958.436817][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1707930958.436913][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1707930958.451617][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1707930958.452178][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-b3NsQq) [1707930958.452659][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1707930958.452945][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1707930958.453229][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_config.ini-J3Xlfv) [1707930958.453551][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1707930958.453766][1814:1814] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1707930958.454027][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-wgMJvA) [1707930958.454337][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1707930958.454965][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-8yNC1h) [1707930958.456013][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1707930958.456131][1814:1814] CHIP:DL: NVS set: chip-factory/unique-id = "74C30705DBB92D5C" [1707930958.456426][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-zEXRFg) [1707930958.457874][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1707930958.457993][1814:1814] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1707930958.458288][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-fqcq4q) [1707930958.462105][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1707930958.462221][1814:1814] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1707930958.462502][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-GTNTSH) [1707930958.463345][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1707930958.463456][1814:1814] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1707930958.463726][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-KCKjW3) [1707930958.464751][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1707930958.464869][1814:1814] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1707930958.465208][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-pnausA) [1707930958.466178][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1707930958.466289][1814:1814] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1707930958.466571][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_config.ini-Sd9N9U) [1707930958.467431][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1707930958.467549][1814:1814] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1707930958.467843][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_config.ini-tpbuUP) [1707930958.468890][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1707930958.469012][1814:1814] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1707930958.470554][1814:1814] CHIP:DL: Got Ethernet interface: eth0 [1707930958.471767][1814:1814] CHIP:DL: Found the primary Ethernet interface:eth0 [1707930958.473088][1814:1814] CHIP:DL: Got WiFi interface: wlan0 [1707930958.473256][1814:1814] CHIP:DL: Failed to reset WiFi statistic counts [1707930958.473364][1814:1814] CHIP:IN: UDP::Init bind&listen port=0 [1707930958.473628][1814:1814] CHIP:IN: UDP::Init bound to port=36318 [1707930958.473685][1814:1814] CHIP:IN: BLEBase::Init - setting/overriding transport [1707930958.473734][1814:1814] CHIP:IN: TransportMgr initialized [1707930958.473848][1814:1814] CHIP:FP: Initializing FabricTable from persistent storage [1707930958.473940][1814:1814] CHIP:TS: Last Known Good Time: [unknown] [1707930958.474005][1814:1814] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48 [1707930958.477852][1814:1814] CHIP:ZCL: Using ZAP configuration... [1707930958.487676][1814:1814] CHIP:DL: Avahi client registered [1707930958.487923][1814:1814] CHIP:CTL: System State Initialized... [1707930958.488291][1814:1814] CHIP:CTL: Setting attestation nonce to random value [1707930958.488390][1814:1814] CHIP:CTL: Setting CSR nonce to random value [1707930958.488567][1814:1814] CHIP:IN: UDP::Init bind&listen port=5550 [1707930958.488852][1814:1814] CHIP:IN: UDP::Init bound to port=5550 [1707930958.488911][1814:1814] CHIP:IN: TransportMgr initialized [1707930958.491654][1814:1816] CHIP:DL: CHIP task running [1707930958.492070][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 32785 [1707930958.493123][1814:1816] CHIP:CTL: Setting attestation nonce to random value [1707930958.493527][1814:1816] CHIP:CTL: Setting CSR nonce to random value [1707930958.493837][1814:1816] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: src/controller/ExamplePersistentStorage.cpp:106: CHIP Error 0x000000A0: Value not found in the persisted storage [1707930958.496079][1814:1816] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: src/controller/ExamplePersistentStorage.cpp:106: CHIP Error 0x000000A0: Value not found in the persisted storage [1707930958.499065][1814:1816] CHIP:CTL: Generating RCAC [1707930958.502718][1814:1816] CHIP:CTL: Generating ICAC [1707930958.504837][1814:1816] CHIP:CTL: Generating NOC [1707930958.506575][1814:1816] CHIP:FP: Validating NOC chain [1707930958.512685][1814:1816] CHIP:FP: NOC chain validation successful [1707930958.513125][1814:1816] CHIP:FP: Added new fabric at index: 0x1 [1707930958.513187][1814:1816] CHIP:FP: Assigned compressed fabric ID: 0xEE067FCD7D10156A, node ID: 0x000000000001B669 [1707930958.513253][1814:1816] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1707930958.513307][1814:1816] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1707930958.513356][1814:1816] CHIP:TS: Retaining current Last Known Good Time [1707930958.516588][1814:1816] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1707930958.521278][1814:1816] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1707930958.526430][1814:1816] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: EE067FCD7D10156A) [1707930958.526541][1814:1816] CHIP:IN: UDP::Init bind&listen port=5550 [1707930958.526792][1814:1816] CHIP:IN: UDP::Init bound to port=5550 [1707930958.526850][1814:1816] CHIP:IN: TransportMgr initialized [1707930958.598644][1814:1816] CHIP:CTL: Setting wifi credentials from parameters [1707930958.598775][1814:1816] CHIP:CTL: Setting attestation nonce to random value [1707930958.598867][1814:1816] CHIP:CTL: Setting CSR nonce to random value [1707930958.598952][1814:1816] CHIP:CTL: Checking ICD registration parameters [1707930958.599049][1814:1816] CHIP:CTL: Commission called for node ID 0x0000000000000001 [1707930958.599121][1814:1816] CHIP:DL: Long dispatch time: 107 ms, for event type 2 [1707930958.625877][1814:1816] CHIP:DL: BlueZ integration init success [1707930958.639124][1814:1815] CHIP:BLE: BLE removing known devices. [1707930958.642715][1814:1815] CHIP:BLE: BLE initiating scan. [1707930958.647740][1814:1816] CHIP:BLE: ChipDeviceScanner has started scanning! [1707930958.656430][1814:1815] CHIP:BLE: Device 38:0B:9D:91:A4:52 does not look like a CHIP device. [1707930958.660962][1814:1815] CHIP:BLE: Device 0D:88:1B:DF:76:B4 does not look like a CHIP device. [1707930958.664087][1814:1815] CHIP:BLE: Device 11:BA:CF:22:77:5A does not look like a CHIP device. [1707930958.666961][1814:1815] CHIP:BLE: Device 01:93:B3:E0:B6:1F does not look like a CHIP device. [1707930958.707140][1814:1815] CHIP:BLE: Device 09:1B:29:43:35:C4 does not look like a CHIP device. [1707930958.733486][1814:1815] CHIP:BLE: Device 17:B3:72:9E:C8:73 does not look like a CHIP device. [1707930958.779233][1814:1815] CHIP:BLE: Device 3C:B0:89:19:A0:2C does not look like a CHIP device. [1707930958.842123][1814:1815] CHIP:BLE: Device AE:50:DE:4C:31:D6 does not look like a CHIP device. [1707930958.845004][1814:1815] CHIP:BLE: New device scanned: E4:5F:01:43:42:16 [1707930958.845080][1814:1815] CHIP:BLE: Device discriminator match. Attempting to connect. [1707930958.850239][1814:1815] CHIP:BLE: ChipDeviceScanner has stopped scanning! [1707930964.022669][1814:1815] CHIP:DL: ConnectDevice complete [1707930965.562506][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0008 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562614][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0008 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562695][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0008 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562759][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0008 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562824][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562887][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.562953][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0012 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.563010][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0012 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.563080][1814:1815] CHIP:DL: Char /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 on service /org/bluez/hci0/dev_E4_5F_01_43_42_16/service0027 [1707930965.563190][1814:1815] CHIP:DL: New BLE connection: conn 0xffff80026720, device E4:5F:01:43:42:16, path /org/bluez/hci0/dev_E4_5F_01_43_42_16 [1707930965.563446][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1707930965.563528][1814:1816] CHIP:DIS: Closing all BLE connections [1707930965.563649][1814:1816] CHIP:IN: BleConnectionComplete: endPoint 0xaaaac45d6330 [1707930965.564720][1814:1816] CHIP:IN: SecureSession[0xffff8c038c60]: Allocated Type:1 LSID:7071 [1707930965.564815][1814:1816] CHIP:SC: Assigned local session key ID 7071 [1707930965.565077][1814:1816] CHIP:EM: <<< [E:41785i S:0 M:210906348] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1707930965.565285][1814:1816] CHIP:IN: Message appended to BLE send queue [1707930965.565353][1814:1816] CHIP:SC: Sent PBKDF param request [1707930965.923051][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930966.118653][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1707930966.118752][1814:1816] CHIP:BLE: subscribe complete, ep = 0xaaaac45d6330 [1707930966.119507][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930966.119724][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930966.119808][1814:1816] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1707930966.119860][1814:1816] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1707930966.119908][1814:1816] CHIP:BLE: local and remote recv window size = 6 [1707930966.120711][1814:1816] CHIP:IN: BLE EndPoint 0xaaaac45d6330 Connection Complete [1707930966.217259][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930966.315154][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930966.315412][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930966.315726][1814:1816] CHIP:EM: >>> [E:41785i S:0 M:170926395] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1707930966.315808][1814:1816] CHIP:EM: Found matching exchange: 41785i, Delegate: 0xffff8c036db0 [1707930966.315927][1814:1816] CHIP:SC: Received PBKDF param response [1707930966.316026][1814:1816] CHIP:SC: Peer assigned session ID 1291 [1707930966.316098][1814:1816] CHIP:SC: Found MRP parameters in the message [1707930966.332563][1814:1816] CHIP:EM: <<< [E:41785i S:0 M:210906349] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1707930966.333356][1814:1816] CHIP:SC: Sent spake2p msg1 [1707930966.557131][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930966.559966][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930966.560249][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930966.560596][1814:1816] CHIP:EM: >>> [E:41785i S:0 M:170926396] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1707930966.560678][1814:1816] CHIP:EM: Found matching exchange: 41785i, Delegate: 0xffff8c036db0 [1707930966.560800][1814:1816] CHIP:SC: Received spake2p msg2 [1707930966.564180][1814:1816] CHIP:EM: <<< [E:41785i S:0 M:210906350] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1707930966.564995][1814:1816] CHIP:SC: Sent spake2p msg3 [1707930966.703402][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930967.044233][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930967.044648][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930967.044926][1814:1816] CHIP:EM: >>> [E:41785i S:0 M:170926397] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1707930967.045003][1814:1816] CHIP:EM: Found matching exchange: 41785i, Delegate: 0xffff8c036db0 [1707930967.045469][1814:1816] CHIP:SC: SecureSession[0xffff8c038c60, LSID:7071]: State change 'kEstablishing' --> 'kActive' [1707930967.045541][1814:1816] CHIP:IN: SecureSession[0xffff8c038c60]: Activated - Type:1 LSID:7071 [1707930967.045591][1814:1816] CHIP:IN: New secure session activated for device , LSID:7071 PSID:1291! [1707930967.045654][1814:1816] CHIP:CTL: Remote device completed SPAKE2+ handshake [1707930967.045707][1814:1816] CHIP:TOO: Pairing Success [1707930967.045752][1814:1816] CHIP:TOO: PASE establishment successful [1707930967.045805][1814:1816] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1707930967.045863][1814:1816] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1707930967.045938][1814:1816] CHIP:CTL: Sending read request for commissioning information [1707930967.046107][1814:1816] CHIP:DMG: SendReadRequest ReadClient[0xffff8c030800]: Sending Read Request [1707930967.046568][1814:1816] CHIP:EM: <<< [E:41786i S:7071 M:176619639] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1707930967.047334][1814:1816] CHIP:DMG: MoveToState ReadClient[0xffff8c030800]: Moving to [AwaitingIn] [1707930967.386025][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930967.434092][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930967.434353][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930967.632428][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930967.632758][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930968.019028][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930968.019298][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930968.556103][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930968.556384][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930968.556947][1814:1816] CHIP:EM: >>> [E:41786i S:7071 M:80036317] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1707930968.557062][1814:1816] CHIP:EM: Found matching exchange: 41786i, Delegate: 0xffff8c030810 [1707930968.557518][1814:1816] CHIP:DMG: ReportDataMessage = [1707930968.557581][1814:1816] CHIP:DMG: { [1707930968.557632][1814:1816] CHIP:DMG: AttributeReportIBs = [1707930968.557704][1814:1816] CHIP:DMG: [ [1707930968.557761][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.557836][1814:1816] CHIP:DMG: { [1707930968.557936][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.558008][1814:1816] CHIP:DMG: { [1707930968.558102][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.558176][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.558275][1814:1816] CHIP:DMG: { [1707930968.558352][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.558454][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.558536][1814:1816] CHIP:DMG: Attribute = 0x0000_0000, [1707930968.558634][1814:1816] CHIP:DMG: } [1707930968.558713][1814:1816] CHIP:DMG: [1707930968.558814][1814:1816] CHIP:DMG: Data = 761246167298516, [1707930968.558887][1814:1816] CHIP:DMG: }, [1707930968.558984][1814:1816] CHIP:DMG: [1707930968.559046][1814:1816] CHIP:DMG: }, [1707930968.559132][1814:1816] CHIP:DMG: [1707930968.559189][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.559263][1814:1816] CHIP:DMG: { [1707930968.559323][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.559389][1814:1816] CHIP:DMG: { [1707930968.559457][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.559529][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.559602][1814:1816] CHIP:DMG: { [1707930968.559677][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.559758][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.559838][1814:1816] CHIP:DMG: Attribute = 0x0000_0001, [1707930968.559914][1814:1816] CHIP:DMG: } [1707930968.559990][1814:1816] CHIP:DMG: [1707930968.560066][1814:1816] CHIP:DMG: Data = 3, [1707930968.560136][1814:1816] CHIP:DMG: }, [1707930968.560209][1814:1816] CHIP:DMG: [1707930968.560269][1814:1816] CHIP:DMG: }, [1707930968.560352][1814:1816] CHIP:DMG: [1707930968.560409][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.560522][1814:1816] CHIP:DMG: { [1707930968.560585][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.560651][1814:1816] CHIP:DMG: { [1707930968.560719][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.560790][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.560864][1814:1816] CHIP:DMG: { [1707930968.560939][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.561018][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.561096][1814:1816] CHIP:DMG: Attribute = 0x0000_0002, [1707930968.561172][1814:1816] CHIP:DMG: } [1707930968.561249][1814:1816] CHIP:DMG: [1707930968.561326][1814:1816] CHIP:DMG: Data = 8, [1707930968.561396][1814:1816] CHIP:DMG: }, [1707930968.561468][1814:1816] CHIP:DMG: [1707930968.561527][1814:1816] CHIP:DMG: }, [1707930968.561611][1814:1816] CHIP:DMG: [1707930968.561667][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.561740][1814:1816] CHIP:DMG: { [1707930968.561801][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.561867][1814:1816] CHIP:DMG: { [1707930968.561934][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.562006][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.562078][1814:1816] CHIP:DMG: { [1707930968.562154][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.562234][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.562314][1814:1816] CHIP:DMG: Attribute = 0x0000_0003, [1707930968.562390][1814:1816] CHIP:DMG: } [1707930968.562467][1814:1816] CHIP:DMG: [1707930968.562542][1814:1816] CHIP:DMG: Data = NULL [1707930968.562613][1814:1816] CHIP:DMG: }, [1707930968.562687][1814:1816] CHIP:DMG: [1707930968.562747][1814:1816] CHIP:DMG: }, [1707930968.562830][1814:1816] CHIP:DMG: [1707930968.562886][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.562959][1814:1816] CHIP:DMG: { [1707930968.563020][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.563085][1814:1816] CHIP:DMG: { [1707930968.563152][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.563225][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.563298][1814:1816] CHIP:DMG: { [1707930968.563372][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.563452][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.563531][1814:1816] CHIP:DMG: Attribute = 0x0000_0004, [1707930968.563607][1814:1816] CHIP:DMG: } [1707930968.563685][1814:1816] CHIP:DMG: [1707930968.563760][1814:1816] CHIP:DMG: Data = NULL [1707930968.563829][1814:1816] CHIP:DMG: }, [1707930968.563903][1814:1816] CHIP:DMG: [1707930968.563962][1814:1816] CHIP:DMG: }, [1707930968.564049][1814:1816] CHIP:DMG: [1707930968.564105][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.564184][1814:1816] CHIP:DMG: { [1707930968.564243][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.564308][1814:1816] CHIP:DMG: { [1707930968.564376][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.564488][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.564565][1814:1816] CHIP:DMG: { [1707930968.564641][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.564721][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.564799][1814:1816] CHIP:DMG: Attribute = 0x0000_0005, [1707930968.564870][1814:1816] CHIP:DMG: } [1707930968.564940][1814:1816] CHIP:DMG: [1707930968.565011][1814:1816] CHIP:DMG: Data = [ [1707930968.565084][1814:1816] CHIP:DMG: [1707930968.565220][1814:1816] CHIP:DMG: { [1707930968.565304][1814:1816] CHIP:DMG: 0x0 = 0, [1707930968.565390][1814:1816] CHIP:DMG: 0x1 = 0, [1707930968.565474][1814:1816] CHIP:DMG: }, [1707930968.565551][1814:1816] CHIP:DMG: ], [1707930968.565621][1814:1816] CHIP:DMG: }, [1707930968.565698][1814:1816] CHIP:DMG: [1707930968.565757][1814:1816] CHIP:DMG: }, [1707930968.565846][1814:1816] CHIP:DMG: [1707930968.565902][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.565977][1814:1816] CHIP:DMG: { [1707930968.566037][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.566102][1814:1816] CHIP:DMG: { [1707930968.566169][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.566240][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.566313][1814:1816] CHIP:DMG: { [1707930968.566389][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.566467][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.566546][1814:1816] CHIP:DMG: Attribute = 0x0000_0006, [1707930968.566621][1814:1816] CHIP:DMG: } [1707930968.566697][1814:1816] CHIP:DMG: [1707930968.566769][1814:1816] CHIP:DMG: Data = [ [1707930968.566841][1814:1816] CHIP:DMG: [1707930968.566919][1814:1816] CHIP:DMG: ], [1707930968.566989][1814:1816] CHIP:DMG: }, [1707930968.567061][1814:1816] CHIP:DMG: [1707930968.567120][1814:1816] CHIP:DMG: }, [1707930968.567202][1814:1816] CHIP:DMG: [1707930968.567258][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.567330][1814:1816] CHIP:DMG: { [1707930968.567392][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.567459][1814:1816] CHIP:DMG: { [1707930968.567526][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.567597][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.567670][1814:1816] CHIP:DMG: { [1707930968.567744][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.567825][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.567904][1814:1816] CHIP:DMG: Attribute = 0x0000_0007, [1707930968.567980][1814:1816] CHIP:DMG: } [1707930968.568055][1814:1816] CHIP:DMG: [1707930968.568129][1814:1816] CHIP:DMG: Data = NULL [1707930968.568199][1814:1816] CHIP:DMG: }, [1707930968.568274][1814:1816] CHIP:DMG: [1707930968.568333][1814:1816] CHIP:DMG: }, [1707930968.568415][1814:1816] CHIP:DMG: [1707930968.568495][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.568572][1814:1816] CHIP:DMG: { [1707930968.568632][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.568697][1814:1816] CHIP:DMG: { [1707930968.568764][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.568836][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.568909][1814:1816] CHIP:DMG: { [1707930968.568983][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.569062][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.569142][1814:1816] CHIP:DMG: Attribute = 0x0000_0008, [1707930968.569217][1814:1816] CHIP:DMG: } [1707930968.569294][1814:1816] CHIP:DMG: [1707930968.569370][1814:1816] CHIP:DMG: Data = 0, [1707930968.569440][1814:1816] CHIP:DMG: }, [1707930968.569515][1814:1816] CHIP:DMG: [1707930968.569575][1814:1816] CHIP:DMG: }, [1707930968.569658][1814:1816] CHIP:DMG: [1707930968.569714][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.569787][1814:1816] CHIP:DMG: { [1707930968.569847][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.569912][1814:1816] CHIP:DMG: { [1707930968.569979][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.570051][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.570125][1814:1816] CHIP:DMG: { [1707930968.570199][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.570279][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.570358][1814:1816] CHIP:DMG: Attribute = 0x0000_000A, [1707930968.570433][1814:1816] CHIP:DMG: } [1707930968.570511][1814:1816] CHIP:DMG: [1707930968.570586][1814:1816] CHIP:DMG: Data = 2, [1707930968.570656][1814:1816] CHIP:DMG: }, [1707930968.570730][1814:1816] CHIP:DMG: [1707930968.570790][1814:1816] CHIP:DMG: }, [1707930968.570873][1814:1816] CHIP:DMG: [1707930968.570929][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.571001][1814:1816] CHIP:DMG: { [1707930968.571061][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.571126][1814:1816] CHIP:DMG: { [1707930968.571194][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.571264][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.571337][1814:1816] CHIP:DMG: { [1707930968.571411][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.571491][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.571570][1814:1816] CHIP:DMG: Attribute = 0x0000_000B, [1707930968.571646][1814:1816] CHIP:DMG: } [1707930968.571723][1814:1816] CHIP:DMG: [1707930968.571799][1814:1816] CHIP:DMG: Data = 2, [1707930968.571869][1814:1816] CHIP:DMG: }, [1707930968.571943][1814:1816] CHIP:DMG: [1707930968.572002][1814:1816] CHIP:DMG: }, [1707930968.572085][1814:1816] CHIP:DMG: [1707930968.572141][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.572214][1814:1816] CHIP:DMG: { [1707930968.572274][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.572340][1814:1816] CHIP:DMG: { [1707930968.572407][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.572506][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.572580][1814:1816] CHIP:DMG: { [1707930968.572656][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.572735][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.572814][1814:1816] CHIP:DMG: Attribute = 0x0000_000C, [1707930968.572890][1814:1816] CHIP:DMG: } [1707930968.572968][1814:1816] CHIP:DMG: [1707930968.573044][1814:1816] CHIP:DMG: Data = true, [1707930968.573114][1814:1816] CHIP:DMG: }, [1707930968.573189][1814:1816] CHIP:DMG: [1707930968.573248][1814:1816] CHIP:DMG: }, [1707930968.573330][1814:1816] CHIP:DMG: [1707930968.573387][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.573460][1814:1816] CHIP:DMG: { [1707930968.573520][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.573585][1814:1816] CHIP:DMG: { [1707930968.573653][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.573724][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.573797][1814:1816] CHIP:DMG: { [1707930968.573872][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.573952][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.574031][1814:1816] CHIP:DMG: Attribute = 0x0000_FFFC, [1707930968.574106][1814:1816] CHIP:DMG: } [1707930968.574182][1814:1816] CHIP:DMG: [1707930968.574258][1814:1816] CHIP:DMG: Data = 11, [1707930968.574328][1814:1816] CHIP:DMG: }, [1707930968.574403][1814:1816] CHIP:DMG: [1707930968.574462][1814:1816] CHIP:DMG: }, [1707930968.574545][1814:1816] CHIP:DMG: [1707930968.574601][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.574674][1814:1816] CHIP:DMG: { [1707930968.574735][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.574803][1814:1816] CHIP:DMG: { [1707930968.574873][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.574944][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.575016][1814:1816] CHIP:DMG: { [1707930968.575092][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.575171][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.575249][1814:1816] CHIP:DMG: Attribute = 0x0000_FFFD, [1707930968.575323][1814:1816] CHIP:DMG: } [1707930968.575398][1814:1816] CHIP:DMG: [1707930968.575472][1814:1816] CHIP:DMG: Data = 2, [1707930968.575541][1814:1816] CHIP:DMG: }, [1707930968.575616][1814:1816] CHIP:DMG: [1707930968.575675][1814:1816] CHIP:DMG: }, [1707930968.575760][1814:1816] CHIP:DMG: [1707930968.575816][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.575892][1814:1816] CHIP:DMG: { [1707930968.575952][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.576018][1814:1816] CHIP:DMG: { [1707930968.576085][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.576157][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.576229][1814:1816] CHIP:DMG: { [1707930968.576303][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.576382][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.576480][1814:1816] CHIP:DMG: Attribute = 0x0000_FFF8, [1707930968.576556][1814:1816] CHIP:DMG: } [1707930968.576632][1814:1816] CHIP:DMG: [1707930968.576704][1814:1816] CHIP:DMG: Data = [ [1707930968.576780][1814:1816] CHIP:DMG: 3, [1707930968.576863][1814:1816] CHIP:DMG: ], [1707930968.576932][1814:1816] CHIP:DMG: }, [1707930968.577006][1814:1816] CHIP:DMG: [1707930968.577065][1814:1816] CHIP:DMG: }, [1707930968.577155][1814:1816] CHIP:DMG: [1707930968.577211][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.577291][1814:1816] CHIP:DMG: { [1707930968.578092][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.578171][1814:1816] CHIP:DMG: { [1707930968.578269][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.578344][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.578416][1814:1816] CHIP:DMG: { [1707930968.578527][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.578609][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.578690][1814:1816] CHIP:DMG: Attribute = 0x0000_FFF9, [1707930968.578766][1814:1816] CHIP:DMG: } [1707930968.578844][1814:1816] CHIP:DMG: [1707930968.578917][1814:1816] CHIP:DMG: Data = [ [1707930968.579005][1814:1816] CHIP:DMG: 0, 1, 2, 4, 5, [1707930968.579088][1814:1816] CHIP:DMG: ], [1707930968.579156][1814:1816] CHIP:DMG: }, [1707930968.579237][1814:1816] CHIP:DMG: [1707930968.579296][1814:1816] CHIP:DMG: }, [1707930968.579402][1814:1816] CHIP:DMG: [1707930968.579459][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.579550][1814:1816] CHIP:DMG: { [1707930968.579611][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.579676][1814:1816] CHIP:DMG: { [1707930968.579744][1814:1816] CHIP:DMG: DataVersion = 0x4c7d3f77, [1707930968.579816][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.579888][1814:1816] CHIP:DMG: { [1707930968.579963][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.580043][1814:1816] CHIP:DMG: Cluster = 0x38, [1707930968.580122][1814:1816] CHIP:DMG: Attribute = 0x0000_FFFB, [1707930968.580197][1814:1816] CHIP:DMG: } [1707930968.580273][1814:1816] CHIP:DMG: [1707930968.580345][1814:1816] CHIP:DMG: Data = [ [1707930968.580487][1814:1816] CHIP:DMG: 0, 1, 2, 3, 4, 5, 6, 7, 8, 10, 11, 12, 65528, 65529, 65531, 65532, 65533, [1707930968.580577][1814:1816] CHIP:DMG: ], [1707930968.580647][1814:1816] CHIP:DMG: }, [1707930968.580740][1814:1816] CHIP:DMG: [1707930968.580801][1814:1816] CHIP:DMG: }, [1707930968.580901][1814:1816] CHIP:DMG: [1707930968.580957][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.581029][1814:1816] CHIP:DMG: { [1707930968.581089][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.581155][1814:1816] CHIP:DMG: { [1707930968.581222][1814:1816] CHIP:DMG: DataVersion = 0xa07a1b90, [1707930968.581293][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.581367][1814:1816] CHIP:DMG: { [1707930968.581442][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.581523][1814:1816] CHIP:DMG: Cluster = 0x31, [1707930968.581602][1814:1816] CHIP:DMG: Attribute = 0x0000_0003, [1707930968.581677][1814:1816] CHIP:DMG: } [1707930968.581754][1814:1816] CHIP:DMG: [1707930968.581830][1814:1816] CHIP:DMG: Data = 20, [1707930968.581900][1814:1816] CHIP:DMG: }, [1707930968.581974][1814:1816] CHIP:DMG: [1707930968.582034][1814:1816] CHIP:DMG: }, [1707930968.582117][1814:1816] CHIP:DMG: [1707930968.582173][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.582247][1814:1816] CHIP:DMG: { [1707930968.582307][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.582372][1814:1816] CHIP:DMG: { [1707930968.582440][1814:1816] CHIP:DMG: DataVersion = 0x384d33d5, [1707930968.582511][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.582584][1814:1816] CHIP:DMG: { [1707930968.582658][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.582738][1814:1816] CHIP:DMG: Cluster = 0x28, [1707930968.582817][1814:1816] CHIP:DMG: Attribute = 0x0000_0004, [1707930968.582891][1814:1816] CHIP:DMG: } [1707930968.582967][1814:1816] CHIP:DMG: [1707930968.583042][1814:1816] CHIP:DMG: Data = 32769, [1707930968.583112][1814:1816] CHIP:DMG: }, [1707930968.583186][1814:1816] CHIP:DMG: [1707930968.583245][1814:1816] CHIP:DMG: }, [1707930968.583328][1814:1816] CHIP:DMG: [1707930968.583385][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.583457][1814:1816] CHIP:DMG: { [1707930968.583517][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.583582][1814:1816] CHIP:DMG: { [1707930968.583649][1814:1816] CHIP:DMG: DataVersion = 0x384d33d5, [1707930968.583719][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.583791][1814:1816] CHIP:DMG: { [1707930968.583866][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.583946][1814:1816] CHIP:DMG: Cluster = 0x28, [1707930968.584025][1814:1816] CHIP:DMG: Attribute = 0x0000_0002, [1707930968.584099][1814:1816] CHIP:DMG: } [1707930968.584175][1814:1816] CHIP:DMG: [1707930968.584250][1814:1816] CHIP:DMG: Data = 65521, [1707930968.584320][1814:1816] CHIP:DMG: }, [1707930968.584395][1814:1816] CHIP:DMG: [1707930968.584475][1814:1816] CHIP:DMG: }, [1707930968.584561][1814:1816] CHIP:DMG: [1707930968.584617][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.584690][1814:1816] CHIP:DMG: { [1707930968.584749][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.584815][1814:1816] CHIP:DMG: { [1707930968.584881][1814:1816] CHIP:DMG: DataVersion = 0xe0c6e474, [1707930968.584953][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.585026][1814:1816] CHIP:DMG: { [1707930968.585099][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.585179][1814:1816] CHIP:DMG: Cluster = 0x30, [1707930968.585257][1814:1816] CHIP:DMG: Attribute = 0x0000_0003, [1707930968.585332][1814:1816] CHIP:DMG: } [1707930968.585408][1814:1816] CHIP:DMG: [1707930968.585483][1814:1816] CHIP:DMG: Data = 2, [1707930968.585553][1814:1816] CHIP:DMG: }, [1707930968.585625][1814:1816] CHIP:DMG: [1707930968.585684][1814:1816] CHIP:DMG: }, [1707930968.585766][1814:1816] CHIP:DMG: [1707930968.585822][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.585898][1814:1816] CHIP:DMG: { [1707930968.585958][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.586024][1814:1816] CHIP:DMG: { [1707930968.586090][1814:1816] CHIP:DMG: DataVersion = 0xe0c6e474, [1707930968.586161][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.586233][1814:1816] CHIP:DMG: { [1707930968.586307][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.586387][1814:1816] CHIP:DMG: Cluster = 0x30, [1707930968.586465][1814:1816] CHIP:DMG: Attribute = 0x0000_0002, [1707930968.586541][1814:1816] CHIP:DMG: } [1707930968.586618][1814:1816] CHIP:DMG: [1707930968.586693][1814:1816] CHIP:DMG: Data = 0, [1707930968.586763][1814:1816] CHIP:DMG: }, [1707930968.586835][1814:1816] CHIP:DMG: [1707930968.586894][1814:1816] CHIP:DMG: }, [1707930968.586978][1814:1816] CHIP:DMG: [1707930968.587034][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.587110][1814:1816] CHIP:DMG: { [1707930968.587169][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.587235][1814:1816] CHIP:DMG: { [1707930968.587301][1814:1816] CHIP:DMG: DataVersion = 0xe0c6e474, [1707930968.587373][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.587446][1814:1816] CHIP:DMG: { [1707930968.587519][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.587599][1814:1816] CHIP:DMG: Cluster = 0x30, [1707930968.587678][1814:1816] CHIP:DMG: Attribute = 0x0000_0001, [1707930968.587753][1814:1816] CHIP:DMG: } [1707930968.587829][1814:1816] CHIP:DMG: [1707930968.587900][1814:1816] CHIP:DMG: Data = [1707930968.587972][1814:1816] CHIP:DMG: { [1707930968.588048][1814:1816] CHIP:DMG: 0x0 = 60, [1707930968.588127][1814:1816] CHIP:DMG: 0x1 = 900, [1707930968.588206][1814:1816] CHIP:DMG: }, [1707930968.588274][1814:1816] CHIP:DMG: }, [1707930968.588351][1814:1816] CHIP:DMG: [1707930968.588410][1814:1816] CHIP:DMG: }, [1707930968.588518][1814:1816] CHIP:DMG: [1707930968.588576][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.588649][1814:1816] CHIP:DMG: { [1707930968.588709][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.588774][1814:1816] CHIP:DMG: { [1707930968.588841][1814:1816] CHIP:DMG: DataVersion = 0xe0c6e474, [1707930968.588911][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.588985][1814:1816] CHIP:DMG: { [1707930968.589058][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.589138][1814:1816] CHIP:DMG: Cluster = 0x30, [1707930968.589216][1814:1816] CHIP:DMG: Attribute = 0x0000_0000, [1707930968.589292][1814:1816] CHIP:DMG: } [1707930968.589367][1814:1816] CHIP:DMG: [1707930968.589443][1814:1816] CHIP:DMG: Data = 0, [1707930968.589513][1814:1816] CHIP:DMG: }, [1707930968.589585][1814:1816] CHIP:DMG: [1707930968.589644][1814:1816] CHIP:DMG: }, [1707930968.589727][1814:1816] CHIP:DMG: [1707930968.589782][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.589855][1814:1816] CHIP:DMG: { [1707930968.589915][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.589980][1814:1816] CHIP:DMG: { [1707930968.590047][1814:1816] CHIP:DMG: DataVersion = 0xa07a1b90, [1707930968.590118][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.590192][1814:1816] CHIP:DMG: { [1707930968.590267][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.590346][1814:1816] CHIP:DMG: Cluster = 0x31, [1707930968.590424][1814:1816] CHIP:DMG: Attribute = 0x0000_FFFC, [1707930968.590499][1814:1816] CHIP:DMG: } [1707930968.590575][1814:1816] CHIP:DMG: [1707930968.590651][1814:1816] CHIP:DMG: Data = 9, [1707930968.590720][1814:1816] CHIP:DMG: }, [1707930968.590792][1814:1816] CHIP:DMG: [1707930968.590851][1814:1816] CHIP:DMG: }, [1707930968.590921][1814:1816] CHIP:DMG: [1707930968.590976][1814:1816] CHIP:DMG: ], [1707930968.591307][1814:1816] CHIP:DMG: [1707930968.591364][1814:1816] CHIP:DMG: SuppressResponse = true, [1707930968.591422][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930968.591475][1814:1816] CHIP:DMG: } [1707930968.597174][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1707930968.597248][1814:1816] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1707930968.597315][1814:1816] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo2' [1707930968.597392][1814:1816] CHIP:DMG: SendReadRequest ReadClient[0xffff8c030800]: Sending Read Request [1707930968.597829][1814:1816] CHIP:EM: <<< [E:41787i S:7071 M:176619640] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1707930968.598650][1814:1816] CHIP:DMG: MoveToState ReadClient[0xffff8c030800]: Moving to [AwaitingIn] [1707930968.801115][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930968.849586][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930968.849847][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930968.850215][1814:1816] CHIP:EM: >>> [E:41787i S:7071 M:80036318] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1707930968.850349][1814:1816] CHIP:EM: Found matching exchange: 41787i, Delegate: 0xffff8c030810 [1707930968.850523][1814:1816] CHIP:DMG: ReportDataMessage = [1707930968.850605][1814:1816] CHIP:DMG: { [1707930968.850656][1814:1816] CHIP:DMG: AttributeReportIBs = [1707930968.850728][1814:1816] CHIP:DMG: [ [1707930968.850784][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.850858][1814:1816] CHIP:DMG: { [1707930968.850943][1814:1816] CHIP:DMG: AttributeStatusIB = [1707930968.851011][1814:1816] CHIP:DMG: { [1707930968.851098][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.851189][1814:1816] CHIP:DMG: { [1707930968.851293][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.851374][1814:1816] CHIP:DMG: Cluster = 0x46, [1707930968.851475][1814:1816] CHIP:DMG: Attribute = 0x0000_0007, [1707930968.851551][1814:1816] CHIP:DMG: } [1707930968.851657][1814:1816] CHIP:DMG: [1707930968.851730][1814:1816] CHIP:DMG: StatusIB = [1707930968.851828][1814:1816] CHIP:DMG: { [1707930968.851903][1814:1816] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1707930968.852002][1814:1816] CHIP:DMG: }, [1707930968.852079][1814:1816] CHIP:DMG: [1707930968.852165][1814:1816] CHIP:DMG: }, [1707930968.852241][1814:1816] CHIP:DMG: [1707930968.852301][1814:1816] CHIP:DMG: }, [1707930968.852387][1814:1816] CHIP:DMG: [1707930968.852483][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.852564][1814:1816] CHIP:DMG: { [1707930968.852625][1814:1816] CHIP:DMG: AttributeStatusIB = [1707930968.852693][1814:1816] CHIP:DMG: { [1707930968.852759][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.852832][1814:1816] CHIP:DMG: { [1707930968.852908][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.852988][1814:1816] CHIP:DMG: Cluster = 0x46, [1707930968.853068][1814:1816] CHIP:DMG: Attribute = 0x0000_0006, [1707930968.853143][1814:1816] CHIP:DMG: } [1707930968.853225][1814:1816] CHIP:DMG: [1707930968.853296][1814:1816] CHIP:DMG: StatusIB = [1707930968.853369][1814:1816] CHIP:DMG: { [1707930968.853443][1814:1816] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1707930968.853518][1814:1816] CHIP:DMG: }, [1707930968.853593][1814:1816] CHIP:DMG: [1707930968.853659][1814:1816] CHIP:DMG: }, [1707930968.853734][1814:1816] CHIP:DMG: [1707930968.853793][1814:1816] CHIP:DMG: }, [1707930968.853877][1814:1816] CHIP:DMG: [1707930968.853933][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.854006][1814:1816] CHIP:DMG: { [1707930968.854066][1814:1816] CHIP:DMG: AttributeStatusIB = [1707930968.854134][1814:1816] CHIP:DMG: { [1707930968.854200][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.854273][1814:1816] CHIP:DMG: { [1707930968.854347][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.854426][1814:1816] CHIP:DMG: Cluster = 0x46, [1707930968.854506][1814:1816] CHIP:DMG: Attribute = 0x0000_FFFC, [1707930968.854581][1814:1816] CHIP:DMG: } [1707930968.854662][1814:1816] CHIP:DMG: [1707930968.854734][1814:1816] CHIP:DMG: StatusIB = [1707930968.854807][1814:1816] CHIP:DMG: { [1707930968.854881][1814:1816] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1707930968.854956][1814:1816] CHIP:DMG: }, [1707930968.855030][1814:1816] CHIP:DMG: [1707930968.855096][1814:1816] CHIP:DMG: }, [1707930968.855171][1814:1816] CHIP:DMG: [1707930968.855230][1814:1816] CHIP:DMG: }, [1707930968.855314][1814:1816] CHIP:DMG: [1707930968.855370][1814:1816] CHIP:DMG: AttributeReportIB = [1707930968.855444][1814:1816] CHIP:DMG: { [1707930968.855505][1814:1816] CHIP:DMG: AttributeDataIB = [1707930968.855571][1814:1816] CHIP:DMG: { [1707930968.855638][1814:1816] CHIP:DMG: DataVersion = 0xe0c6e474, [1707930968.855709][1814:1816] CHIP:DMG: AttributePathIB = [1707930968.855782][1814:1816] CHIP:DMG: { [1707930968.855857][1814:1816] CHIP:DMG: Endpoint = 0x0, [1707930968.855936][1814:1816] CHIP:DMG: Cluster = 0x30, [1707930968.856016][1814:1816] CHIP:DMG: Attribute = 0x0000_0004, [1707930968.856091][1814:1816] CHIP:DMG: } [1707930968.856169][1814:1816] CHIP:DMG: [1707930968.856245][1814:1816] CHIP:DMG: Data = true, [1707930968.856315][1814:1816] CHIP:DMG: }, [1707930968.856390][1814:1816] CHIP:DMG: [1707930968.856472][1814:1816] CHIP:DMG: }, [1707930968.856547][1814:1816] CHIP:DMG: [1707930968.856602][1814:1816] CHIP:DMG: ], [1707930968.856706][1814:1816] CHIP:DMG: [1707930968.856762][1814:1816] CHIP:DMG: SuppressResponse = true, [1707930968.856819][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930968.856873][1814:1816] CHIP:DMG: } [1707930968.857999][1814:1816] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid = 0 [1707930968.858237][1814:1816] CHIP:SVR: OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8001 [1707930968.858316][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo2' [1707930968.858381][1814:1816] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1707930968.858443][1814:1816] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1707930968.858499][1814:1816] CHIP:CTL: Arming failsafe (60 seconds) [1707930968.858632][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930968.858700][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930968.858918][1814:1816] CHIP:EM: <<< [E:41788i S:7071 M:176619641] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930968.859695][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930968.994744][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930968.996628][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930968.996885][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930968.997291][1814:1816] CHIP:EM: >>> [E:41788i S:7071 M:80036319] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930968.997395][1814:1816] CHIP:EM: Found matching exchange: 41788i, Delegate: 0xffff8c038008 [1707930968.997481][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930968.997587][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930968.997642][1814:1816] CHIP:DMG: { [1707930968.997694][1814:1816] CHIP:DMG: suppressResponse = false, [1707930968.997775][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930968.997851][1814:1816] CHIP:DMG: [ [1707930968.997907][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930968.998007][1814:1816] CHIP:DMG: { [1707930968.998068][1814:1816] CHIP:DMG: CommandDataIB = [1707930968.998166][1814:1816] CHIP:DMG: { [1707930968.998231][1814:1816] CHIP:DMG: CommandPathIB = [1707930968.998327][1814:1816] CHIP:DMG: { [1707930968.998406][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930968.998515][1814:1816] CHIP:DMG: ClusterId = 0x30, [1707930968.998596][1814:1816] CHIP:DMG: CommandId = 0x1, [1707930968.998691][1814:1816] CHIP:DMG: }, [1707930968.998770][1814:1816] CHIP:DMG: [1707930968.998860][1814:1816] CHIP:DMG: CommandFields = [1707930968.998935][1814:1816] CHIP:DMG: { [1707930968.999040][1814:1816] CHIP:DMG: 0x0 = 0, [1707930968.999126][1814:1816] CHIP:DMG: 0x1 = "" (0 chars), [1707930968.999229][1814:1816] CHIP:DMG: }, [1707930968.999300][1814:1816] CHIP:DMG: }, [1707930968.999398][1814:1816] CHIP:DMG: [1707930968.999458][1814:1816] CHIP:DMG: }, [1707930968.999530][1814:1816] CHIP:DMG: [1707930968.999585][1814:1816] CHIP:DMG: ], [1707930968.999657][1814:1816] CHIP:DMG: [1707930968.999712][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930968.999766][1814:1816] CHIP:DMG: }, [1707930968.999921][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1707930969.000083][1814:1816] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1707930969.000158][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1707930969.000212][1814:1816] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1707930969.000274][1814:1816] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1707930969.000323][1814:1816] CHIP:CTL: Setting Regulatory Config [1707930969.000372][1814:1816] CHIP:CTL: No regulatory config supplied by controller, leaving as device default (0) [1707930969.000632][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930969.000715][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930969.000928][1814:1816] CHIP:EM: <<< [E:41789i S:7071 M:176619642] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930969.001676][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930969.001840][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930969.189726][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930969.191625][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.191876][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930969.192245][1814:1816] CHIP:EM: >>> [E:41789i S:7071 M:80036320] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930969.192355][1814:1816] CHIP:EM: Found matching exchange: 41789i, Delegate: 0xffff8c03cf08 [1707930969.192475][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930969.192585][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930969.192639][1814:1816] CHIP:DMG: { [1707930969.192713][1814:1816] CHIP:DMG: suppressResponse = false, [1707930969.192776][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930969.192851][1814:1816] CHIP:DMG: [ [1707930969.192928][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930969.193006][1814:1816] CHIP:DMG: { [1707930969.193091][1814:1816] CHIP:DMG: CommandDataIB = [1707930969.193161][1814:1816] CHIP:DMG: { [1707930969.193225][1814:1816] CHIP:DMG: CommandPathIB = [1707930969.193298][1814:1816] CHIP:DMG: { [1707930969.193375][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930969.193479][1814:1816] CHIP:DMG: ClusterId = 0x30, [1707930969.193560][1814:1816] CHIP:DMG: CommandId = 0x3, [1707930969.193657][1814:1816] CHIP:DMG: }, [1707930969.193736][1814:1816] CHIP:DMG: [1707930969.193826][1814:1816] CHIP:DMG: CommandFields = [1707930969.193901][1814:1816] CHIP:DMG: { [1707930969.194001][1814:1816] CHIP:DMG: 0x0 = 0, [1707930969.194086][1814:1816] CHIP:DMG: 0x1 = "" (0 chars), [1707930969.194188][1814:1816] CHIP:DMG: }, [1707930969.194259][1814:1816] CHIP:DMG: }, [1707930969.194359][1814:1816] CHIP:DMG: [1707930969.194420][1814:1816] CHIP:DMG: }, [1707930969.194516][1814:1816] CHIP:DMG: [1707930969.194572][1814:1816] CHIP:DMG: ], [1707930969.194667][1814:1816] CHIP:DMG: [1707930969.194723][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930969.194777][1814:1816] CHIP:DMG: }, [1707930969.194931][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1707930969.195022][1814:1816] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1707930969.195094][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1707930969.195147][1814:1816] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'ConfigureUTCTime' [1707930969.195209][1814:1816] CHIP:CTL: Performing next commissioning step 'ConfigureUTCTime' [1707930969.195348][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930969.195498][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930969.195711][1814:1816] CHIP:EM: <<< [E:41790i S:7071 M:176619643] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930969.196528][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930969.196711][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930969.336067][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930969.338075][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.338321][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930969.338650][1814:1816] CHIP:EM: >>> [E:41790i S:7071 M:80036321] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930969.338735][1814:1816] CHIP:EM: Found matching exchange: 41790i, Delegate: 0xffff8c038008 [1707930969.338821][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930969.338928][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930969.338983][1814:1816] CHIP:DMG: { [1707930969.339035][1814:1816] CHIP:DMG: suppressResponse = false, [1707930969.339093][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930969.339180][1814:1816] CHIP:DMG: [ [1707930969.339238][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930969.339316][1814:1816] CHIP:DMG: { [1707930969.339377][1814:1816] CHIP:DMG: CommandStatusIB = [1707930969.339444][1814:1816] CHIP:DMG: { [1707930969.339509][1814:1816] CHIP:DMG: CommandPathIB = [1707930969.339613][1814:1816] CHIP:DMG: { [1707930969.339690][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930969.339773][1814:1816] CHIP:DMG: ClusterId = 0x38, [1707930969.339851][1814:1816] CHIP:DMG: CommandId = 0x0, [1707930969.339924][1814:1816] CHIP:DMG: }, [1707930969.340010][1814:1816] CHIP:DMG: [1707930969.340075][1814:1816] CHIP:DMG: StatusIB = [1707930969.340149][1814:1816] CHIP:DMG: { [1707930969.340226][1814:1816] CHIP:DMG: status = 0x01 (FAILURE), [1707930969.340306][1814:1816] CHIP:DMG: cluster-status = 0x2, [1707930969.340381][1814:1816] CHIP:DMG: }, [1707930969.340496][1814:1816] CHIP:DMG: [1707930969.340566][1814:1816] CHIP:DMG: }, [1707930969.340650][1814:1816] CHIP:DMG: [1707930969.340710][1814:1816] CHIP:DMG: }, [1707930969.340783][1814:1816] CHIP:DMG: [1707930969.340838][1814:1816] CHIP:DMG: ], [1707930969.340910][1814:1816] CHIP:DMG: [1707930969.340964][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930969.341018][1814:1816] CHIP:DMG: }, [1707930969.341171][1814:1816] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0038 Command=0x0000_0000 Status=0x1 [1707930969.341257][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ConfigureUTCTime' [1707930969.341316][1814:1816] CHIP:CTL: Commissioning stage next step: 'ConfigureUTCTime' -> 'SendPAICertificateRequest' [1707930969.341379][1814:1816] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1707930969.341428][1814:1816] CHIP:CTL: Sending request for PAI certificate [1707930969.341476][1814:1816] CHIP:CTL: Sending Certificate Chain request to 0xffff8c036d60 device [1707930969.341609][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930969.341681][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930969.341886][1814:1816] CHIP:EM: <<< [E:41791i S:7071 M:176619644] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930969.342629][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930969.342794][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930969.433464][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930969.530522][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.530797][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930969.631091][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.631347][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930969.775358][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.775625][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930969.775951][1814:1816] CHIP:EM: >>> [E:41791i S:7071 M:80036322] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930969.776034][1814:1816] CHIP:EM: Found matching exchange: 41791i, Delegate: 0xffff8c03cf08 [1707930969.776124][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930969.776230][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930969.776284][1814:1816] CHIP:DMG: { [1707930969.776337][1814:1816] CHIP:DMG: suppressResponse = false, [1707930969.776394][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930969.776517][1814:1816] CHIP:DMG: [ [1707930969.776578][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930969.776655][1814:1816] CHIP:DMG: { [1707930969.776715][1814:1816] CHIP:DMG: CommandDataIB = [1707930969.776785][1814:1816] CHIP:DMG: { [1707930969.776848][1814:1816] CHIP:DMG: CommandPathIB = [1707930969.776924][1814:1816] CHIP:DMG: { [1707930969.777001][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930969.777084][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930969.777161][1814:1816] CHIP:DMG: CommandId = 0x3, [1707930969.777236][1814:1816] CHIP:DMG: }, [1707930969.777313][1814:1816] CHIP:DMG: [1707930969.777381][1814:1816] CHIP:DMG: CommandFields = [1707930969.777456][1814:1816] CHIP:DMG: { [1707930969.777533][1814:1816] CHIP:DMG: 0x0 = [ [1707930969.777863][1814:1816] 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 [1707930969.778040][1814:1816] CHIP:DMG: ] (463 bytes) [1707930969.778121][1814:1816] CHIP:DMG: }, [1707930969.778191][1814:1816] CHIP:DMG: }, [1707930969.778268][1814:1816] CHIP:DMG: [1707930969.778329][1814:1816] CHIP:DMG: }, [1707930969.778400][1814:1816] CHIP:DMG: [1707930969.778455][1814:1816] CHIP:DMG: ], [1707930969.778527][1814:1816] CHIP:DMG: [1707930969.778583][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930969.778637][1814:1816] CHIP:DMG: }, [1707930969.778793][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1707930969.778881][1814:1816] CHIP:CTL: Received certificate chain from the device [1707930969.778951][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1707930969.779012][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1707930969.779076][1814:1816] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1707930969.779126][1814:1816] CHIP:CTL: Sending request for DAC certificate [1707930969.779175][1814:1816] CHIP:CTL: Sending Certificate Chain request to 0xffff8c036d60 device [1707930969.779311][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930969.779381][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930969.779597][1814:1816] CHIP:EM: <<< [E:41792i S:7071 M:176619645] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930969.780400][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930969.780614][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930969.920872][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930969.969066][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930969.969324][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.119098][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.119370][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.262888][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.263150][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.263482][1814:1816] CHIP:EM: >>> [E:41792i S:7071 M:80036323] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930970.263566][1814:1816] CHIP:EM: Found matching exchange: 41792i, Delegate: 0xffff8c038008 [1707930970.263654][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930970.263760][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930970.263814][1814:1816] CHIP:DMG: { [1707930970.263865][1814:1816] CHIP:DMG: suppressResponse = false, [1707930970.263921][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930970.263995][1814:1816] CHIP:DMG: [ [1707930970.264052][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930970.264127][1814:1816] CHIP:DMG: { [1707930970.264188][1814:1816] CHIP:DMG: CommandDataIB = [1707930970.264262][1814:1816] CHIP:DMG: { [1707930970.264326][1814:1816] CHIP:DMG: CommandPathIB = [1707930970.264400][1814:1816] CHIP:DMG: { [1707930970.264566][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930970.264650][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930970.264730][1814:1816] CHIP:DMG: CommandId = 0x3, [1707930970.264803][1814:1816] CHIP:DMG: }, [1707930970.264881][1814:1816] CHIP:DMG: [1707930970.264950][1814:1816] CHIP:DMG: CommandFields = [1707930970.265025][1814:1816] CHIP:DMG: { [1707930970.265102][1814:1816] CHIP:DMG: 0x0 = [ [1707930970.265436][1814:1816] CHIP:DMG: 0x30, 0x82, 0x01, 0xe7, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x69, 0xcd, 0xf1, 0x0d, 0xe9, 0xe5, 0x4e, 0xd1, 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, 0x31, 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, 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, 0x46, 0x3a, 0xc6, 0x93, 0x42, 0x91, 0x0a, 0x0e, 0x55, 0x88, 0xfc, 0x6f, 0xf5, 0x6b, 0xb6, 0x3e, 0x62, 0xec, 0xce, 0xcb, 0x14, 0x8f, 0x7d, 0x4e, 0xb0, 0x3e, 0xe5, 0x52, 0x60, 0x14, 0x15, 0x76, 0x7d, 0x16, 0xa5, 0xc6, 0x63, 0xf7, 0x93, 0x [1707930970.265613][1814:1816] CHIP:DMG: ] (491 bytes) [1707930970.265694][1814:1816] CHIP:DMG: }, [1707930970.265764][1814:1816] CHIP:DMG: }, [1707930970.265840][1814:1816] CHIP:DMG: [1707930970.265900][1814:1816] CHIP:DMG: }, [1707930970.265972][1814:1816] CHIP:DMG: [1707930970.266027][1814:1816] CHIP:DMG: ], [1707930970.266099][1814:1816] CHIP:DMG: [1707930970.266154][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930970.266208][1814:1816] CHIP:DMG: }, [1707930970.266363][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1707930970.266454][1814:1816] CHIP:CTL: Received certificate chain from the device [1707930970.266524][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1707930970.266588][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1707930970.266653][1814:1816] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1707930970.266702][1814:1816] CHIP:CTL: Sending Attestation Request to the device. [1707930970.266753][1814:1816] CHIP:CTL: Sending Attestation request to 0xffff8c036d60 device [1707930970.266890][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930970.266961][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930970.267182][1814:1816] CHIP:EM: <<< [E:41793i S:7071 M:176619646] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930970.267941][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930970.268023][1814:1816] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1707930970.268160][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930970.456617][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930970.460271][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.460563][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.605646][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.605914][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.703594][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.703867][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930970.704217][1814:1816] CHIP:EM: >>> [E:41793i S:7071 M:80036324] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930970.704301][1814:1816] CHIP:EM: Found matching exchange: 41793i, Delegate: 0xffff8c03cf08 [1707930970.704390][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930970.704561][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930970.704622][1814:1816] CHIP:DMG: { [1707930970.704676][1814:1816] CHIP:DMG: suppressResponse = false, [1707930970.704733][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930970.704814][1814:1816] CHIP:DMG: [ [1707930970.704870][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930970.704947][1814:1816] CHIP:DMG: { [1707930970.705008][1814:1816] CHIP:DMG: CommandDataIB = [1707930970.705080][1814:1816] CHIP:DMG: { [1707930970.705143][1814:1816] CHIP:DMG: CommandPathIB = [1707930970.705217][1814:1816] CHIP:DMG: { [1707930970.705294][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930970.705375][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930970.705454][1814:1816] CHIP:DMG: CommandId = 0x1, [1707930970.705530][1814:1816] CHIP:DMG: }, [1707930970.705607][1814:1816] CHIP:DMG: [1707930970.705676][1814:1816] CHIP:DMG: CommandFields = [1707930970.705750][1814:1816] CHIP:DMG: { [1707930970.705825][1814:1816] CHIP:DMG: 0x0 = [ [1707930970.706169][1814:1816] 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 [1707930970.706337][1814:1816] CHIP:DMG: ] (583 bytes) [1707930970.706415][1814:1816] CHIP:DMG: 0x1 = [ [1707930970.706543][1814:1816] CHIP:DMG: 0xdf, 0x97, 0x5a, 0x3b, 0xcf, 0x98, 0x9d, 0x6a, 0x2a, 0x87, 0x18, 0xbf, 0x21, 0x0f, 0xac, 0x7a, 0x79, 0x82, 0x18, 0xa1, 0xce, 0x8f, 0x3e, 0x75, 0xb1, 0xd1, 0x68, 0xb1, 0x25, 0xfa, 0xc7, 0x66, 0xf3, 0x85, 0x77, 0xad, 0x40, 0x05, 0xdc, 0x9e, 0x93, 0xfe, 0xcf, 0xb2, 0x56, 0x31, 0x5a, 0xc9, 0xe0, 0x54, 0xf9, 0x70, 0x5f, 0x21, 0xd6, 0x61, 0x91, 0xa6, 0x02, 0x2f, 0xf3, 0x91, 0xce, 0x7a, [1707930970.706644][1814:1816] CHIP:DMG: ] (64 bytes) [1707930970.706722][1814:1816] CHIP:DMG: }, [1707930970.706792][1814:1816] CHIP:DMG: }, [1707930970.706867][1814:1816] CHIP:DMG: [1707930970.706927][1814:1816] CHIP:DMG: }, [1707930970.706999][1814:1816] CHIP:DMG: [1707930970.707054][1814:1816] CHIP:DMG: ], [1707930970.707127][1814:1816] CHIP:DMG: [1707930970.707181][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930970.707235][1814:1816] CHIP:DMG: }, [1707930970.707396][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1707930970.707487][1814:1816] CHIP:CTL: Received Attestation Information from the device [1707930970.707558][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1707930970.707613][1814:1816] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583 [1707930970.707727][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1707930970.707796][1814:1816] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1707930970.707847][1814:1816] CHIP:CTL: Verifying attestation [1707930970.772002][1814:1816] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1707930970.772147][1814:1816] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1707930970.772203][1814:1816] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1707930970.772272][1814:1816] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1707930970.772327][1814:1816] CHIP:CTL: Sending CSR request to 0xffff8c036d60 device [1707930970.772560][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930970.772640][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930970.772953][1814:1816] CHIP:EM: <<< [E:41794i S:7071 M:176619647] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930970.773826][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930970.773913][1814:1816] CHIP:CTL: Sent CSR request, waiting for the CSR [1707930970.774078][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930970.847065][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930970.895506][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930970.895820][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930971.043827][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930971.044087][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930971.044393][1814:1816] CHIP:EM: >>> [E:41794i S:7071 M:80036325] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930971.044543][1814:1816] CHIP:EM: Found matching exchange: 41794i, Delegate: 0xffff8c065848 [1707930971.044642][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930971.044751][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930971.044807][1814:1816] CHIP:DMG: { [1707930971.044859][1814:1816] CHIP:DMG: suppressResponse = false, [1707930971.044917][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930971.044993][1814:1816] CHIP:DMG: [ [1707930971.045050][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930971.045129][1814:1816] CHIP:DMG: { [1707930971.045190][1814:1816] CHIP:DMG: CommandDataIB = [1707930971.045258][1814:1816] CHIP:DMG: { [1707930971.045322][1814:1816] CHIP:DMG: CommandPathIB = [1707930971.045399][1814:1816] CHIP:DMG: { [1707930971.045475][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930971.045558][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930971.045637][1814:1816] CHIP:DMG: CommandId = 0x5, [1707930971.045712][1814:1816] CHIP:DMG: }, [1707930971.045790][1814:1816] CHIP:DMG: [1707930971.045859][1814:1816] CHIP:DMG: CommandFields = [1707930971.045935][1814:1816] CHIP:DMG: { [1707930971.046013][1814:1816] CHIP:DMG: 0x0 = [ [1707930971.046296][1814:1816] CHIP:DMG: 0x15, 0x30, 0x01, 0xcc, 0x30, 0x81, 0xc9, 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, 0xef, 0x95, 0xf8, 0x56, 0x91, 0x90, 0x4e, 0x2f, 0xb7, 0x68, 0xaa, 0x71, 0xbf, 0x4f, 0x70, 0xd9, 0x05, 0xa0, 0x44, 0x67, 0x31, 0x0e, 0x5d, 0x30, 0xe5, 0x8f, 0x99, 0x4e, 0xcd, 0xcf, 0x37, 0x5a, 0xc0, 0x70, 0x28, 0x1a, 0x90, 0x42, 0x02, 0xd4, 0x04, 0xac, 0xfd, 0x9b, 0x22, 0xe3, 0xa3, 0x6c, 0xf0, 0xad, 0xe2, 0x83, 0xd4, 0xd3, 0x21, 0xd2, 0x3f, 0xd4, 0x6b, 0x1a, 0x4b, 0x0d, 0x5a, 0x2f, 0xa0, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x49, 0x00, 0x30, 0x46, 0x02, 0x21, 0x00, 0xeb, 0x7e, 0xb3, 0x05, 0xad, 0x91, 0xed, 0xd1, 0xc7, 0x19, 0xef, 0xc3, 0x8d, 0x4a, 0x4e, 0x9c, 0xc1, 0xd5, 0xd7, 0x61, 0x09, 0xb2, 0xe1, 0x08, 0xf9, 0x4b, 0xa3, 0x5c, 0x11, 0x7a, 0x36, 0xcf, 0x02, 0x21, 0x00, 0xa0, 0x5e, 0x23, 0xe0, 0x84, 0x94, 0x47, 0xac, 0x95, 0x50, 0xb7, 0x0c, 0xfb, 0xd3, 0xe1, 0x1b, 0xfd, 0x1b, 0x8a, 0x62, 0x5a, 0xa7, 0x89, 0xcc, 0x6c, 0x6d, 0xd7, 0xa2, 0x85, 0xd0, 0x3f, 0xb4, 0x30, 0x02, 0x20, 0x83, 0x38, 0x70, 0x2c, 0x64, 0x80, 0xaa, 0xc0, 0x0a, 0xee, 0x4d, 0x5a, 0x61, 0x58, 0x17, 0xaf, 0x60, 0xb4, 0x96, 0x41, 0xae, 0x50, 0x25, 0x9e, 0x0c, 0xa4, 0x82, 0xcd, 0xad, 0x9c, 0x62, 0xa9, 0x18, [1707930971.046460][1814:1816] CHIP:DMG: ] (244 bytes) [1707930971.046540][1814:1816] CHIP:DMG: 0x1 = [ [1707930971.046671][1814:1816] CHIP:DMG: 0x10, 0x30, 0x5a, 0x52, 0x2d, 0x9b, 0xd5, 0x0f, 0x42, 0x10, 0xdb, 0x25, 0x67, 0x7c, 0x4b, 0x4e, 0x2a, 0x9b, 0x89, 0xab, 0xde, 0x92, 0xb5, 0x9a, 0xf2, 0xfd, 0x20, 0x0b, 0x48, 0xcc, 0x55, 0x37, 0x49, 0x47, 0xca, 0x5b, 0xf1, 0xde, 0xe3, 0x98, 0x80, 0x23, 0xc2, 0x38, 0x94, 0x4f, 0x2f, 0x9c, 0x26, 0x6b, 0x1c, 0xf6, 0x5c, 0xf6, 0x4e, 0x05, 0x0e, 0xb9, 0x0d, 0xfe, 0xbc, 0xeb, 0x75, 0x21, [1707930971.046773][1814:1816] CHIP:DMG: ] (64 bytes) [1707930971.046852][1814:1816] CHIP:DMG: }, [1707930971.046923][1814:1816] CHIP:DMG: }, [1707930971.046999][1814:1816] CHIP:DMG: [1707930971.047059][1814:1816] CHIP:DMG: }, [1707930971.047133][1814:1816] CHIP:DMG: [1707930971.047188][1814:1816] CHIP:DMG: ], [1707930971.047262][1814:1816] CHIP:DMG: [1707930971.047317][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930971.047372][1814:1816] CHIP:DMG: }, [1707930971.047531][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1707930971.047622][1814:1816] CHIP:CTL: Received certificate signing request from the device [1707930971.047693][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1707930971.047748][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1707930971.047812][1814:1816] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1707930971.056709][1814:1816] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1707930971.056821][1814:1816] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1707930971.056886][1814:1816] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1707930971.056944][1814:1816] CHIP:CTL: Getting certificate chain for the device from the issuer [1707930971.063499][1814:1816] CHIP:CTL: Verifying Certificate Signing Request [1707930971.068111][1814:1816] CHIP:CTL: Generating NOC [1707930971.068923][1814:1816] CHIP:CTL: Providing certificate chain to the commissioner [1707930971.069008][1814:1816] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success [1707930971.069084][1814:1816] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1707930971.069464][1814:1816] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1707930971.069534][1814:1816] CHIP:CTL: Sending root certificate to the device [1707930971.069693][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930971.069765][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930971.070033][1814:1816] CHIP:EM: <<< [E:41795i S:7071 M:176619648] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930971.070866][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930971.070955][1814:1816] CHIP:CTL: Sent root certificate to the device [1707930971.071954][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930971.139216][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930971.236433][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930971.238533][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930971.238794][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930971.239128][1814:1816] CHIP:EM: >>> [E:41795i S:7071 M:80036326] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930971.239210][1814:1816] CHIP:EM: Found matching exchange: 41795i, Delegate: 0xffff8c066598 [1707930971.239294][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930971.239398][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930971.239453][1814:1816] CHIP:DMG: { [1707930971.239506][1814:1816] CHIP:DMG: suppressResponse = false, [1707930971.239563][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930971.239636][1814:1816] CHIP:DMG: [ [1707930971.239693][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930971.239769][1814:1816] CHIP:DMG: { [1707930971.239830][1814:1816] CHIP:DMG: CommandStatusIB = [1707930971.239902][1814:1816] CHIP:DMG: { [1707930971.239966][1814:1816] CHIP:DMG: CommandPathIB = [1707930971.240040][1814:1816] CHIP:DMG: { [1707930971.240118][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930971.240199][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930971.240279][1814:1816] CHIP:DMG: CommandId = 0xb, [1707930971.240354][1814:1816] CHIP:DMG: }, [1707930971.240501][1814:1816] CHIP:DMG: [1707930971.240571][1814:1816] CHIP:DMG: StatusIB = [1707930971.240646][1814:1816] CHIP:DMG: { [1707930971.240722][1814:1816] CHIP:DMG: status = 0x00 (SUCCESS), [1707930971.240798][1814:1816] CHIP:DMG: }, [1707930971.240873][1814:1816] CHIP:DMG: [1707930971.240940][1814:1816] CHIP:DMG: }, [1707930971.241014][1814:1816] CHIP:DMG: [1707930971.241073][1814:1816] CHIP:DMG: }, [1707930971.241145][1814:1816] CHIP:DMG: [1707930971.241200][1814:1816] CHIP:DMG: ], [1707930971.241270][1814:1816] CHIP:DMG: [1707930971.241325][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930971.241380][1814:1816] CHIP:DMG: }, [1707930971.241527][1814:1816] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1707930971.241595][1814:1816] CHIP:CTL: Device confirmed that it has received the root certificate [1707930971.241662][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1707930971.241715][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1707930971.241777][1814:1816] CHIP:CTL: Performing next commissioning step 'SendNOC' [1707930971.241929][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930971.242015][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930971.242290][1814:1816] CHIP:EM: <<< [E:41796i S:7071 M:176619649] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930971.243071][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930971.243189][1814:1816] CHIP:CTL: Sent operational certificate to the device [1707930971.243332][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930971.431719][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930971.578103][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930971.918945][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930971.920429][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930971.920758][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930971.921104][1814:1816] CHIP:EM: >>> [E:41796i S:7071 M:80036327] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930971.921190][1814:1816] CHIP:EM: Found matching exchange: 41796i, Delegate: 0xffff8c065848 [1707930971.921275][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930971.921384][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930971.921439][1814:1816] CHIP:DMG: { [1707930971.921492][1814:1816] CHIP:DMG: suppressResponse = false, [1707930971.921549][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930971.921624][1814:1816] CHIP:DMG: [ [1707930971.921680][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930971.921758][1814:1816] CHIP:DMG: { [1707930971.921818][1814:1816] CHIP:DMG: CommandDataIB = [1707930971.921885][1814:1816] CHIP:DMG: { [1707930971.921949][1814:1816] CHIP:DMG: CommandPathIB = [1707930971.922038][1814:1816] CHIP:DMG: { [1707930971.922118][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930971.922203][1814:1816] CHIP:DMG: ClusterId = 0x3e, [1707930971.922282][1814:1816] CHIP:DMG: CommandId = 0x8, [1707930971.922357][1814:1816] CHIP:DMG: }, [1707930971.922435][1814:1816] CHIP:DMG: [1707930971.922504][1814:1816] CHIP:DMG: CommandFields = [1707930971.922579][1814:1816] CHIP:DMG: { [1707930971.922657][1814:1816] CHIP:DMG: 0x0 = 0, [1707930971.922743][1814:1816] CHIP:DMG: 0x1 = 1, [1707930971.922824][1814:1816] CHIP:DMG: }, [1707930971.922895][1814:1816] CHIP:DMG: }, [1707930971.922970][1814:1816] CHIP:DMG: [1707930971.923030][1814:1816] CHIP:DMG: }, [1707930971.923103][1814:1816] CHIP:DMG: [1707930971.923158][1814:1816] CHIP:DMG: ], [1707930971.923231][1814:1816] CHIP:DMG: [1707930971.923287][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930971.923341][1814:1816] CHIP:DMG: }, [1707930971.923496][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1707930971.923642][1814:1816] CHIP:CTL: Device returned status 0 on receiving the NOC [1707930971.923701][1814:1816] CHIP:CTL: Operational credentials provisioned on device 0xffff8c036d60 [1707930971.923753][1814:1816] CHIP:TOO: Secure Pairing Success [1707930971.923799][1814:1816] CHIP:TOO: CASE establishment successful [1707930971.923862][1814:1816] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1707930971.923920][1814:1816] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1707930971.923971][1814:1816] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'WiFiNetworkSetup' [1707930971.924035][1814:1816] CHIP:CTL: Performing next commissioning step 'WiFiNetworkSetup' [1707930971.924176][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930971.924255][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930971.924620][1814:1816] CHIP:EM: <<< [E:41797i S:7071 M:176619650] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930971.925390][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930971.925559][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930972.357854][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930972.359863][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930972.360406][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930972.360796][1814:1816] CHIP:EM: >>> [E:41797i S:7071 M:80036328] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930972.360904][1814:1816] CHIP:EM: Found matching exchange: 41797i, Delegate: 0xffff8c066598 [1707930972.360991][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930972.361098][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930972.361154][1814:1816] CHIP:DMG: { [1707930972.361207][1814:1816] CHIP:DMG: suppressResponse = false, [1707930972.361287][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930972.361364][1814:1816] CHIP:DMG: [ [1707930972.361444][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930972.361524][1814:1816] CHIP:DMG: { [1707930972.361585][1814:1816] CHIP:DMG: CommandDataIB = [1707930972.361678][1814:1816] CHIP:DMG: { [1707930972.361742][1814:1816] CHIP:DMG: CommandPathIB = [1707930972.361851][1814:1816] CHIP:DMG: { [1707930972.361929][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930972.362031][1814:1816] CHIP:DMG: ClusterId = 0x31, [1707930972.362112][1814:1816] CHIP:DMG: CommandId = 0x5, [1707930972.362209][1814:1816] CHIP:DMG: }, [1707930972.362290][1814:1816] CHIP:DMG: [1707930972.362377][1814:1816] CHIP:DMG: CommandFields = [1707930972.362453][1814:1816] CHIP:DMG: { [1707930972.362531][1814:1816] CHIP:DMG: 0x0 = 0, [1707930972.362611][1814:1816] CHIP:DMG: 0x2 = 0, [1707930972.362691][1814:1816] CHIP:DMG: }, [1707930972.362761][1814:1816] CHIP:DMG: }, [1707930972.362836][1814:1816] CHIP:DMG: [1707930972.362896][1814:1816] CHIP:DMG: }, [1707930972.362967][1814:1816] CHIP:DMG: [1707930972.363021][1814:1816] CHIP:DMG: ], [1707930972.363093][1814:1816] CHIP:DMG: [1707930972.363147][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930972.363201][1814:1816] CHIP:DMG: }, [1707930972.363360][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1707930972.363506][1814:1816] CHIP:CTL: Received NetworkConfig response, networkingStatus=0 [1707930972.363578][1814:1816] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkSetup' [1707930972.363633][1814:1816] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkSetup' -> 'FailsafeBeforeWiFiEnable' [1707930972.363708][1814:1816] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeWiFiEnable' [1707930972.363784][1814:1816] CHIP:CTL: Arming failsafe (66 seconds) [1707930972.363920][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930972.363989][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930972.364193][1814:1816] CHIP:EM: <<< [E:41798i S:7071 M:176619651] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930972.365054][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930972.365224][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930972.698746][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930972.846207][1814:1815] CHIP:DL: Indication received, conn = 0xffff80026720 [1707930972.846492][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1707930972.846832][1814:1816] CHIP:EM: >>> [E:41798i S:7071 M:80036329] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1707930972.846917][1814:1816] CHIP:EM: Found matching exchange: 41798i, Delegate: 0xffff8c065848 [1707930972.847000][1814:1816] CHIP:DMG: ICR moving to [ResponseRe] [1707930972.847109][1814:1816] CHIP:DMG: InvokeResponseMessage = [1707930972.847164][1814:1816] CHIP:DMG: { [1707930972.847217][1814:1816] CHIP:DMG: suppressResponse = false, [1707930972.847274][1814:1816] CHIP:DMG: InvokeResponseIBs = [1707930972.847349][1814:1816] CHIP:DMG: [ [1707930972.847405][1814:1816] CHIP:DMG: InvokeResponseIB = [1707930972.847482][1814:1816] CHIP:DMG: { [1707930972.847543][1814:1816] CHIP:DMG: CommandDataIB = [1707930972.847618][1814:1816] CHIP:DMG: { [1707930972.847688][1814:1816] CHIP:DMG: CommandPathIB = [1707930972.847759][1814:1816] CHIP:DMG: { [1707930972.847832][1814:1816] CHIP:DMG: EndpointId = 0x0, [1707930972.847913][1814:1816] CHIP:DMG: ClusterId = 0x30, [1707930972.847992][1814:1816] CHIP:DMG: CommandId = 0x1, [1707930972.848065][1814:1816] CHIP:DMG: }, [1707930972.848143][1814:1816] CHIP:DMG: [1707930972.848209][1814:1816] CHIP:DMG: CommandFields = [1707930972.848281][1814:1816] CHIP:DMG: { [1707930972.848358][1814:1816] CHIP:DMG: 0x0 = 0, [1707930972.848489][1814:1816] CHIP:DMG: 0x1 = "" (0 chars), [1707930972.848573][1814:1816] CHIP:DMG: }, [1707930972.848640][1814:1816] CHIP:DMG: }, [1707930972.848716][1814:1816] CHIP:DMG: [1707930972.848774][1814:1816] CHIP:DMG: }, [1707930972.848846][1814:1816] CHIP:DMG: [1707930972.848901][1814:1816] CHIP:DMG: ], [1707930972.848973][1814:1816] CHIP:DMG: [1707930972.849027][1814:1816] CHIP:DMG: InteractionModelRevision = 11 [1707930972.849081][1814:1816] CHIP:DMG: }, [1707930972.849237][1814:1816] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1707930972.849327][1814:1816] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1707930972.849397][1814:1816] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeWiFiEnable' [1707930972.849449][1814:1816] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeWiFiEnable' -> 'WiFiNetworkEnable' [1707930972.849501][1814:1816] CHIP:CTL: Setting wifi connection time min = 20 [1707930972.849557][1814:1816] CHIP:CTL: Performing next commissioning step 'WiFiNetworkEnable' [1707930972.849610][1814:1816] CHIP:CTL: SendCommand kWiFiNetworkEnable, supportsConcurrentConnection=1 [1707930972.849746][1814:1816] CHIP:DMG: ICR moving to [AddingComm] [1707930972.849819][1814:1816] CHIP:DMG: ICR moving to [AddedComma] [1707930972.850027][1814:1816] CHIP:EM: <<< [E:41799i S:7071 M:176619652] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1707930972.850786][1814:1816] CHIP:DMG: ICR moving to [AwaitingRe] [1707930972.850946][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707930973.186862][1814:1816] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1707930987.865862][1814:1816] CHIP:BLE: ack recv timeout, closing ep 0xaaaac45d6330 [1707930987.865969][1814:1816] CHIP:IN: Clearing BLE pending packets. [1707930987.869283][1814:1815] CHIP:DL: FAIL: UnsubscribeCharacteristic : GDBus.Error:org.freedesktop.DBus.Error.UnknownObject: Method "StopNotify" with signature "" on interface "org.bluez.GattCharacteristic1" doesn't exist [1707930992.871995][1814:1816] CHIP:BLE: unsubscribe timed out, ble ep 0xaaaac45d6330 [1707930992.872093][1814:1816] CHIP:BLE: Auto-closing end point's BLE connection. [1707930992.872142][1814:1816] CHIP:DL: Closing BLE GATT connection (con 0xffff80026720) [1707930992.872379][1814:1815] CHIP:DL: BluezDisconnect peer=E4:5F:01:43:42:16 [1707930995.077617][1814:1815] CHIP:DL: Bluez disconnected [1707930995.077714][1814:1815] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1707931007.862926][1814:1816] CHIP:DMG: Time out! failed to receive invoke command response from Exchange: 41799i [1707931007.863060][1814:1816] CHIP:CTL: Received failure response src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout [1707931007.863175][1814:1816] CHIP:CTL: Error on commissioning step 'WiFiNetworkEnable': 'src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout' [1707931007.863231][1814:1816] CHIP:CTL: Failed to perform commissioning step 27 [1707931007.863292][1814:1816] CHIP:CTL: Going from commissioning step 'WiFiNetworkEnable' with lastErr = 'src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout' -> 'Cleanup' [1707931007.863368][1814:1816] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = 'src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout' [1707931007.863428][1814:1816] CHIP:CTL: Successfully finished commissioning step 'Cleanup' [1707931007.863505][1814:1816] CHIP:TOO: Device commissioning Failure: src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout [1707931007.863640][1814:1816] CHIP:DMG: ICR moving to [AwaitingDe] [1707931007.864172][1814:1814] CHIP:CTL: Shutting down the commissioner [1707931007.864380][1814:1814] CHIP:DIS: Closing all BLE connections [1707931007.864490][1814:1814] CHIP:IN: SecureSession[0xffff8c038c60]: MarkForEviction Type:1 LSID:7071 [1707931007.864550][1814:1814] CHIP:SC: SecureSession[0xffff8c038c60, LSID:7071]: State change 'kActive' --> 'kPendingEviction' [1707931007.864661][1814:1814] CHIP:IN: SecureSession[0xffff8c038c60]: Released - Type:1 LSID:7071 [1707931007.864735][1814:1814] CHIP:CTL: Shutting down the controller [1707931007.864793][1814:1814] CHIP:IN: Expiring all sessions for fabric 0x1!! [1707931007.864860][1814:1814] CHIP:FP: Forgetting fabric 0x1 [1707931007.864936][1814:1814] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1707931007.865311][1814:1814] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1707931007.865374][1814:1814] CHIP:TS: Reverted Last Known Good Time to previous value [1707931007.865463][1814:1814] CHIP:CTL: Shutting down the commissioner [1707931007.865626][1814:1814] CHIP:CTL: Shutting down the controller [1707931007.865681][1814:1814] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1707931007.865996][1814:1814] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1707931007.866183][1814:1814] CHIP:FP: Shutting down FabricTable [1707931007.866258][1814:1814] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1707931007.866488][1814:1814] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1707931007.866548][1814:1814] CHIP:TS: Reverted Last Known Good Time to previous value [1707931007.867081][1814:1814] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-oRb6yb) [1707931007.868552][1814:1814] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1707931007.868699][1814:1814] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1707931007.868759][1814:1814] CHIP:DL: Inet Layer shutdown [1707931007.868808][1814:1814] CHIP:DL: BLE shutdown [1707931007.872420][1814:1814] CHIP:DL: System Layer shutdown [1707931007.873859][1814:1814] CHIP:TOO: Run command failure: src/app/CommandSender.cpp:329: CHIP Error 0x00000032: Timeout