[1708101552.927644][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1708101552.927859][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_tool_kvs-hn7qo8) [1708101552.928059][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_tool_kvs) [1708101552.928880][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1708101552.928913][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1708101552.935069][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1708101552.935363][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-3xgMuo) [1708101552.935557][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101552.935675][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1708101552.935805][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_config.ini-3L2Eli) [1708101552.935943][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101552.936077][12733:12733] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1708101552.936205][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-YuAoGl) [1708101552.936339][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101552.936654][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-jdSHaR) [1708101552.937173][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101552.937226][12733:12733] CHIP:DL: NVS set: chip-factory/unique-id = "74271FF5580E5BB1" [1708101552.937362][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-BEDJzQ) [1708101552.938204][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101552.938259][12733:12733] CHIP:DL: NVS set: chip-factory/vendor-id = 65521 (0xFFF1) [1708101552.938410][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_factory.ini-ONwA1o) [1708101552.938984][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_factory.ini) [1708101552.939034][12733:12733] CHIP:DL: NVS set: chip-factory/product-id = 32769 (0x8001) [1708101552.939162][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-jr7n2X) [1708101552.939509][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101552.939556][12733:12733] CHIP:DL: NVS set: chip-counters/reboot-count = 1 (0x1) [1708101552.939773][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-n1QSSJ) [1708101552.940584][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101552.940637][12733:12733] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1708101552.940775][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-lmgmOD) [1708101552.941373][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101552.941422][12733:12733] CHIP:DL: NVS set: chip-counters/boot-reason = 0 (0x0) [1708101552.941547][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_config.ini-wE5Zgz) [1708101552.941885][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101552.941929][12733:12733] CHIP:DL: NVS set: chip-config/regulatory-location = 0 (0x0) [1708101552.942139][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_config.ini-w0P5ce) [1708101552.942959][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_config.ini) [1708101552.943006][12733:12733] CHIP:DL: NVS set: chip-config/location-capability = 2 (0x2) [1708101552.943681][12733:12733] CHIP:DL: Got Ethernet interface: eth0 [1708101552.944271][12733:12733] CHIP:DL: Found the primary Ethernet interface:eth0 [1708101552.944757][12733:12733] CHIP:DL: Got WiFi interface: wlan0 [1708101552.944829][12733:12733] CHIP:DL: Failed to reset WiFi statistic counts [1708101552.944886][12733:12733] CHIP:IN: UDP::Init bind&listen port=0 [1708101552.945004][12733:12733] CHIP:IN: UDP::Init bound to port=34330 [1708101552.945029][12733:12733] CHIP:IN: BLEBase::Init - setting/overriding transport [1708101552.945049][12733:12733] CHIP:IN: TransportMgr initialized [1708101552.945100][12733:12733] CHIP:FP: Initializing FabricTable from persistent storage [1708101552.945143][12733:12733] CHIP:TS: Last Known Good Time: [unknown] [1708101552.945172][12733:12733] CHIP:TS: Setting Last Known Good Time to firmware build time 2023-10-14T01:16:48 [1708101552.948934][12733:12733] CHIP:ZCL: Using ZAP configuration... [1708101552.953494][12733:12733] CHIP:DL: Avahi client registered [1708101552.953632][12733:12733] CHIP:CTL: System State Initialized... [1708101552.953822][12733:12733] CHIP:CTL: Setting attestation nonce to random value [1708101552.953868][12733:12733] CHIP:CTL: Setting CSR nonce to random value [1708101552.953951][12733:12733] CHIP:IN: UDP::Init bind&listen port=5550 [1708101552.954172][12733:12733] CHIP:IN: UDP::Init bound to port=5550 [1708101552.954202][12733:12733] CHIP:IN: TransportMgr initialized [1708101552.955453][12733:12735] CHIP:DL: CHIP task running [1708101552.955668][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1708101552.956276][12733:12735] CHIP:CTL: Setting attestation nonce to random value [1708101552.956504][12733:12735] CHIP:CTL: Setting CSR nonce to random value [1708101552.956681][12733:12735] CHIP:CTL: Couldn't get ExampleOpCredsCAKey from storage: src/controller/ExamplePersistentStorage.cpp:106: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101552.958036][12733:12735] CHIP:CTL: Couldn't get ExampleOpCredsICAKey from storage: src/controller/ExamplePersistentStorage.cpp:106: CHIP Error 0x000000A0: Value not found in the persisted storage [1708101552.959419][12733:12735] CHIP:CTL: Generating RCAC [1708101552.961680][12733:12735] CHIP:CTL: Generating ICAC [1708101552.962999][12733:12735] CHIP:CTL: Generating NOC [1708101552.964087][12733:12735] CHIP:FP: Validating NOC chain [1708101552.967696][12733:12735] CHIP:FP: NOC chain validation successful [1708101552.968021][12733:12735] CHIP:FP: Added new fabric at index: 0x1 [1708101552.968058][12733:12735] CHIP:FP: Assigned compressed fabric ID: 0x40158D25F58F891F, node ID: 0x000000000001B669 [1708101552.968101][12733:12735] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1708101552.968134][12733:12735] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1708101552.968161][12733:12735] CHIP:TS: Retaining current Last Known Good Time [1708101552.970078][12733:12735] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1708101552.972479][12733:12735] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1708101552.976887][12733:12735] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 40158D25F58F891F) [1708101552.976939][12733:12735] CHIP:IN: UDP::Init bind&listen port=5550 [1708101552.977057][12733:12735] CHIP:IN: UDP::Init bound to port=5550 [1708101552.977096][12733:12735] CHIP:IN: TransportMgr initialized [1708101553.042783][12733:12735] CHIP:CTL: Setting wifi credentials from parameters [1708101553.042841][12733:12735] CHIP:CTL: Setting attestation nonce to random value [1708101553.042900][12733:12735] CHIP:CTL: Setting CSR nonce to random value [1708101553.042953][12733:12735] CHIP:CTL: Checking ICD registration parameters [1708101553.043014][12733:12735] CHIP:CTL: Commission called for node ID 0x0000000000000050 [1708101553.056345][12733:12735] CHIP:DL: BlueZ integration init success [1708101553.062834][12733:12734] CHIP:BLE: BLE removing known devices. [1708101553.064660][12733:12734] CHIP:BLE: BLE initiating scan. [1708101553.067617][12733:12735] CHIP:BLE: ChipDeviceScanner has started scanning! [1708101553.122101][12733:12734] CHIP:BLE: Device 76:A1:5E:7A:A7:9F does not look like a CHIP device. [1708101553.124946][12733:12734] CHIP:BLE: Device 76:D4:75:BC:7D:21 does not look like a CHIP device. [1708101553.208451][12733:12734] CHIP:BLE: Device 5E:3C:2C:96:4E:C5 does not look like a CHIP device. [1708101553.214408][12733:12734] CHIP:BLE: Device 5A:F4:26:E4:34:49 does not look like a CHIP device. [1708101553.215891][12733:12734] CHIP:BLE: Device 73:F3:15:D1:B3:16 does not look like a CHIP device. [1708101553.237266][12733:12734] CHIP:BLE: Device 45:BE:F4:A5:7D:C0 does not look like a CHIP device. [1708101553.251666][12733:12734] CHIP:BLE: Device 5B:45:F1:03:1A:B1 does not look like a CHIP device. [1708101553.253808][12733:12734] CHIP:BLE: Device 78:7E:48:26:78:48 does not look like a CHIP device. [1708101553.256998][12733:12734] CHIP:BLE: Device 72:81:99:02:44:60 does not look like a CHIP device. [1708101553.325327][12733:12734] CHIP:BLE: Device 69:EE:4F:30:E3:57 does not look like a CHIP device. [1708101553.388613][12733:12734] CHIP:BLE: New device scanned: DC:A6:32:98:8C:EC [1708101553.388683][12733:12734] CHIP:BLE: Device discriminator match. Attempting to connect. [1708101553.393167][12733:12734] CHIP:BLE: ChipDeviceScanner has stopped scanning! [1708101556.072101][12733:12734] CHIP:DL: ConnectDevice complete [1708101556.072201][12733:12734] CHIP:BLE: New device connected: DC:A6:32:98:8C:EC [1708101557.488792][12733:12734] CHIP:DL: Char /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0012 on service /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 [1708101557.488851][12733:12734] CHIP:DL: Char /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0012 on service /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 [1708101557.488886][12733:12734] CHIP:DL: Char /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 on service /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 [1708101557.488918][12733:12734] CHIP:DL: Char /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 on service /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 [1708101557.488953][12733:12734] CHIP:DL: Char /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 on service /org/bluez/hci0/dev_DC_A6_32_98_8C_EC/service0021 [1708101557.489014][12733:12734] CHIP:DL: New BLE connection: conn 0xffffa802f010, device DC:A6:32:98:8C:EC, path /org/bluez/hci0/dev_DC_A6_32_98_8C_EC [1708101557.489156][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1708101557.489197][12733:12735] CHIP:DIS: Closing all BLE connections [1708101557.489264][12733:12735] CHIP:IN: BleConnectionComplete: endPoint 0xaaaae2486358 [1708101557.489784][12733:12735] CHIP:IN: SecureSession[0xffffb0037cf0]: Allocated Type:1 LSID:63037 [1708101557.489833][12733:12735] CHIP:SC: Assigned local session key ID 63037 [1708101557.489970][12733:12735] CHIP:EM: <<< [E:47753i S:0 M:5231169] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1708101557.490021][12733:12735] CHIP:IN: Message appended to BLE send queue [1708101557.490045][12733:12735] CHIP:SC: Sent PBKDF param request [1708101557.777643][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101557.875618][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1708101557.875704][12733:12735] CHIP:BLE: subscribe complete, ep = 0xaaaae2486358 [1708101557.876069][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101557.876313][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101557.876378][12733:12735] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1708101557.876430][12733:12735] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1708101557.876478][12733:12735] CHIP:BLE: local and remote recv window size = 6 [1708101557.877193][12733:12735] CHIP:IN: BLE EndPoint 0xaaaae2486358 Connection Complete [1708101557.972661][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101557.975714][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101557.975956][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101557.976221][12733:12735] CHIP:EM: >>> [E:47753i S:0 M:81241172] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1708101557.976299][12733:12735] CHIP:EM: Found matching exchange: 47753i, Delegate: 0xffffb0036d60 [1708101557.976393][12733:12735] CHIP:SC: Received PBKDF param response [1708101557.976487][12733:12735] CHIP:SC: Peer assigned session ID 62109 [1708101557.976560][12733:12735] CHIP:SC: Found MRP parameters in the message [1708101557.989034][12733:12735] CHIP:EM: <<< [E:47753i S:0 M:5231170] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1708101557.989463][12733:12735] CHIP:SC: Sent spake2p msg1 [1708101558.070233][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.072955][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.073185][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.073377][12733:12735] CHIP:EM: >>> [E:47753i S:0 M:81241173] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1708101558.073442][12733:12735] CHIP:EM: Found matching exchange: 47753i, Delegate: 0xffffb0036d60 [1708101558.073523][12733:12735] CHIP:SC: Received spake2p msg2 [1708101558.076535][12733:12735] CHIP:EM: <<< [E:47753i S:0 M:5231171] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1708101558.077275][12733:12735] CHIP:SC: Sent spake2p msg3 [1708101558.167585][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.168900][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.169121][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.169312][12733:12735] CHIP:EM: >>> [E:47753i S:0 M:81241174] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1708101558.169379][12733:12735] CHIP:EM: Found matching exchange: 47753i, Delegate: 0xffffb0036d60 [1708101558.169714][12733:12735] CHIP:SC: SecureSession[0xffffb0037cf0, LSID:63037]: State change 'kEstablishing' --> 'kActive' [1708101558.169774][12733:12735] CHIP:IN: SecureSession[0xffffb0037cf0]: Activated - Type:1 LSID:63037 [1708101558.169819][12733:12735] CHIP:IN: New secure session activated for device , LSID:63037 PSID:62109! [1708101558.169871][12733:12735] CHIP:CTL: Remote device completed SPAKE2+ handshake [1708101558.169914][12733:12735] CHIP:TOO: Pairing Success [1708101558.169952][12733:12735] CHIP:TOO: PASE establishment successful [1708101558.169997][12733:12735] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1708101558.170046][12733:12735] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1708101558.170088][12733:12735] CHIP:CTL: Sending read request for commissioning information [1708101558.170203][12733:12735] CHIP:DMG: SendReadRequest ReadClient[0xffffb00307b0]: Sending Read Request [1708101558.170490][12733:12735] CHIP:EM: <<< [E:47754i S:63037 M:156344650] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1708101558.171156][12733:12735] CHIP:DMG: MoveToState ReadClient[0xffffb00307b0]: Moving to [AwaitingIn] [1708101558.264936][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.362223][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.362468][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.462945][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.463184][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.560495][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.560735][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.655947][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.656238][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.656552][12733:12735] CHIP:EM: >>> [E:47754i S:63037 M:138661794] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1708101558.656632][12733:12735] CHIP:EM: Found matching exchange: 47754i, Delegate: 0xffffb00307c0 [1708101558.657027][12733:12735] CHIP:DMG: ReportDataMessage = [1708101558.657085][12733:12735] CHIP:DMG: { [1708101558.657135][12733:12735] CHIP:DMG: AttributeReportIBs = [1708101558.657209][12733:12735] CHIP:DMG: [ [1708101558.657265][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.657343][12733:12735] CHIP:DMG: { [1708101558.657404][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.657482][12733:12735] CHIP:DMG: { [1708101558.657553][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.657625][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.657700][12733:12735] CHIP:DMG: { [1708101558.657776][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.657857][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.657938][12733:12735] CHIP:DMG: Attribute = 0x0000_0000, [1708101558.658015][12733:12735] CHIP:DMG: } [1708101558.658094][12733:12735] CHIP:DMG: [1708101558.658173][12733:12735] CHIP:DMG: Data = 761416758229166, [1708101558.658240][12733:12735] CHIP:DMG: }, [1708101558.658314][12733:12735] CHIP:DMG: [1708101558.658374][12733:12735] CHIP:DMG: }, [1708101558.658459][12733:12735] CHIP:DMG: [1708101558.658516][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.658591][12733:12735] CHIP:DMG: { [1708101558.658652][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.658719][12733:12735] CHIP:DMG: { [1708101558.658787][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.658859][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.658933][12733:12735] CHIP:DMG: { [1708101558.659009][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.659089][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.659176][12733:12735] CHIP:DMG: Attribute = 0x0000_0001, [1708101558.659251][12733:12735] CHIP:DMG: } [1708101558.659326][12733:12735] CHIP:DMG: [1708101558.659402][12733:12735] CHIP:DMG: Data = 3, [1708101558.659472][12733:12735] CHIP:DMG: }, [1708101558.659545][12733:12735] CHIP:DMG: [1708101558.659605][12733:12735] CHIP:DMG: }, [1708101558.659689][12733:12735] CHIP:DMG: [1708101558.659746][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.659822][12733:12735] CHIP:DMG: { [1708101558.659882][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.659948][12733:12735] CHIP:DMG: { [1708101558.660074][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.660150][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.660224][12733:12735] CHIP:DMG: { [1708101558.660298][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.660380][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.660460][12733:12735] CHIP:DMG: Attribute = 0x0000_0002, [1708101558.660535][12733:12735] CHIP:DMG: } [1708101558.660614][12733:12735] CHIP:DMG: [1708101558.660691][12733:12735] CHIP:DMG: Data = 8, [1708101558.660761][12733:12735] CHIP:DMG: }, [1708101558.660835][12733:12735] CHIP:DMG: [1708101558.660894][12733:12735] CHIP:DMG: }, [1708101558.660979][12733:12735] CHIP:DMG: [1708101558.661036][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.661111][12733:12735] CHIP:DMG: { [1708101558.661171][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.661238][12733:12735] CHIP:DMG: { [1708101558.661307][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.661378][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.661452][12733:12735] CHIP:DMG: { [1708101558.661527][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.661607][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.661682][12733:12735] CHIP:DMG: Attribute = 0x0000_0003, [1708101558.661758][12733:12735] CHIP:DMG: } [1708101558.661835][12733:12735] CHIP:DMG: [1708101558.661907][12733:12735] CHIP:DMG: Data = NULL [1708101558.661978][12733:12735] CHIP:DMG: }, [1708101558.662050][12733:12735] CHIP:DMG: [1708101558.662110][12733:12735] CHIP:DMG: }, [1708101558.662194][12733:12735] CHIP:DMG: [1708101558.662250][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.662325][12733:12735] CHIP:DMG: { [1708101558.662386][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.662452][12733:12735] CHIP:DMG: { [1708101558.662520][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.662592][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.662666][12733:12735] CHIP:DMG: { [1708101558.662740][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.662820][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.662901][12733:12735] CHIP:DMG: Attribute = 0x0000_0004, [1708101558.662975][12733:12735] CHIP:DMG: } [1708101558.663049][12733:12735] CHIP:DMG: [1708101558.663116][12733:12735] CHIP:DMG: Data = NULL [1708101558.663186][12733:12735] CHIP:DMG: }, [1708101558.663261][12733:12735] CHIP:DMG: [1708101558.663321][12733:12735] CHIP:DMG: }, [1708101558.663408][12733:12735] CHIP:DMG: [1708101558.663465][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.663546][12733:12735] CHIP:DMG: { [1708101558.663607][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.663676][12733:12735] CHIP:DMG: { [1708101558.663748][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.663820][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.663894][12733:12735] CHIP:DMG: { [1708101558.663968][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.664069][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.664150][12733:12735] CHIP:DMG: Attribute = 0x0000_0005, [1708101558.664227][12733:12735] CHIP:DMG: } [1708101558.664305][12733:12735] CHIP:DMG: [1708101558.664378][12733:12735] CHIP:DMG: Data = [ [1708101558.664452][12733:12735] CHIP:DMG: [1708101558.664521][12733:12735] CHIP:DMG: { [1708101558.664560][12733:12735] CHIP:DMG: 0x0 = 0, [1708101558.664609][12733:12735] CHIP:DMG: 0x1 = 0, [1708101558.664649][12733:12735] CHIP:DMG: }, [1708101558.664685][12733:12735] CHIP:DMG: ], [1708101558.664718][12733:12735] CHIP:DMG: }, [1708101558.664754][12733:12735] CHIP:DMG: [1708101558.664782][12733:12735] CHIP:DMG: }, [1708101558.664823][12733:12735] CHIP:DMG: [1708101558.664850][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.664885][12733:12735] CHIP:DMG: { [1708101558.664913][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.664944][12733:12735] CHIP:DMG: { [1708101558.664977][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.665009][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.665041][12733:12735] CHIP:DMG: { [1708101558.665075][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.665112][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.665149][12733:12735] CHIP:DMG: Attribute = 0x0000_0006, [1708101558.665184][12733:12735] CHIP:DMG: } [1708101558.665218][12733:12735] CHIP:DMG: [1708101558.665252][12733:12735] CHIP:DMG: Data = [ [1708101558.665286][12733:12735] CHIP:DMG: [1708101558.665321][12733:12735] CHIP:DMG: ], [1708101558.665354][12733:12735] CHIP:DMG: }, [1708101558.665388][12733:12735] CHIP:DMG: [1708101558.665416][12733:12735] CHIP:DMG: }, [1708101558.665455][12733:12735] CHIP:DMG: [1708101558.665481][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.665519][12733:12735] CHIP:DMG: { [1708101558.665547][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.665578][12733:12735] CHIP:DMG: { [1708101558.665610][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.665644][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.665676][12733:12735] CHIP:DMG: { [1708101558.665711][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.665748][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.665784][12733:12735] CHIP:DMG: Attribute = 0x0000_0007, [1708101558.665820][12733:12735] CHIP:DMG: } [1708101558.665856][12733:12735] CHIP:DMG: [1708101558.665892][12733:12735] CHIP:DMG: Data = NULL [1708101558.665924][12733:12735] CHIP:DMG: }, [1708101558.665957][12733:12735] CHIP:DMG: [1708101558.665985][12733:12735] CHIP:DMG: }, [1708101558.666024][12733:12735] CHIP:DMG: [1708101558.666050][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.666086][12733:12735] CHIP:DMG: { [1708101558.666114][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.666145][12733:12735] CHIP:DMG: { [1708101558.666178][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.666212][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.666246][12733:12735] CHIP:DMG: { [1708101558.666281][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.666318][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.666355][12733:12735] CHIP:DMG: Attribute = 0x0000_0008, [1708101558.666389][12733:12735] CHIP:DMG: } [1708101558.666425][12733:12735] CHIP:DMG: [1708101558.666460][12733:12735] CHIP:DMG: Data = 0, [1708101558.666492][12733:12735] CHIP:DMG: }, [1708101558.666526][12733:12735] CHIP:DMG: [1708101558.666554][12733:12735] CHIP:DMG: }, [1708101558.666593][12733:12735] CHIP:DMG: [1708101558.666619][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.666654][12733:12735] CHIP:DMG: { [1708101558.666682][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.666714][12733:12735] CHIP:DMG: { [1708101558.666747][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.666781][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.666815][12733:12735] CHIP:DMG: { [1708101558.666849][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.666886][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.666922][12733:12735] CHIP:DMG: Attribute = 0x0000_000A, [1708101558.666957][12733:12735] CHIP:DMG: } [1708101558.666993][12733:12735] CHIP:DMG: [1708101558.667028][12733:12735] CHIP:DMG: Data = 2, [1708101558.667061][12733:12735] CHIP:DMG: }, [1708101558.667095][12733:12735] CHIP:DMG: [1708101558.667122][12733:12735] CHIP:DMG: }, [1708101558.667161][12733:12735] CHIP:DMG: [1708101558.667187][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.667222][12733:12735] CHIP:DMG: { [1708101558.667250][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.667280][12733:12735] CHIP:DMG: { [1708101558.667312][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.667345][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.667377][12733:12735] CHIP:DMG: { [1708101558.667412][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.667449][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.667485][12733:12735] CHIP:DMG: Attribute = 0x0000_000B, [1708101558.667520][12733:12735] CHIP:DMG: } [1708101558.667554][12733:12735] CHIP:DMG: [1708101558.667589][12733:12735] CHIP:DMG: Data = 2, [1708101558.667621][12733:12735] CHIP:DMG: }, [1708101558.667656][12733:12735] CHIP:DMG: [1708101558.667683][12733:12735] CHIP:DMG: }, [1708101558.667722][12733:12735] CHIP:DMG: [1708101558.667748][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.667782][12733:12735] CHIP:DMG: { [1708101558.667810][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.667841][12733:12735] CHIP:DMG: { [1708101558.667872][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.667906][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.667940][12733:12735] CHIP:DMG: { [1708101558.667975][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.668024][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.668061][12733:12735] CHIP:DMG: Attribute = 0x0000_000C, [1708101558.668097][12733:12735] CHIP:DMG: } [1708101558.668132][12733:12735] CHIP:DMG: [1708101558.668168][12733:12735] CHIP:DMG: Data = true, [1708101558.668201][12733:12735] CHIP:DMG: }, [1708101558.668239][12733:12735] CHIP:DMG: [1708101558.668266][12733:12735] CHIP:DMG: }, [1708101558.668306][12733:12735] CHIP:DMG: [1708101558.668332][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.668366][12733:12735] CHIP:DMG: { [1708101558.668394][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.668425][12733:12735] CHIP:DMG: { [1708101558.668457][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.668490][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.668524][12733:12735] CHIP:DMG: { [1708101558.668559][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.668596][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.668633][12733:12735] CHIP:DMG: Attribute = 0x0000_FFFC, [1708101558.668668][12733:12735] CHIP:DMG: } [1708101558.668704][12733:12735] CHIP:DMG: [1708101558.668740][12733:12735] CHIP:DMG: Data = 11, [1708101558.668772][12733:12735] CHIP:DMG: }, [1708101558.668809][12733:12735] CHIP:DMG: [1708101558.668836][12733:12735] CHIP:DMG: }, [1708101558.668876][12733:12735] CHIP:DMG: [1708101558.668902][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.668936][12733:12735] CHIP:DMG: { [1708101558.668964][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.668996][12733:12735] CHIP:DMG: { [1708101558.669029][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.669063][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.669097][12733:12735] CHIP:DMG: { [1708101558.669131][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.669166][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.669203][12733:12735] CHIP:DMG: Attribute = 0x0000_FFFD, [1708101558.669238][12733:12735] CHIP:DMG: } [1708101558.669274][12733:12735] CHIP:DMG: [1708101558.669309][12733:12735] CHIP:DMG: Data = 2, [1708101558.669341][12733:12735] CHIP:DMG: }, [1708101558.669377][12733:12735] CHIP:DMG: [1708101558.669404][12733:12735] CHIP:DMG: }, [1708101558.669443][12733:12735] CHIP:DMG: [1708101558.669469][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.669504][12733:12735] CHIP:DMG: { [1708101558.669532][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.669563][12733:12735] CHIP:DMG: { [1708101558.669595][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.669626][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.669660][12733:12735] CHIP:DMG: { [1708101558.669695][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.669729][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.669765][12733:12735] CHIP:DMG: Attribute = 0x0000_FFF8, [1708101558.669800][12733:12735] CHIP:DMG: } [1708101558.669835][12733:12735] CHIP:DMG: [1708101558.669868][12733:12735] CHIP:DMG: Data = [ [1708101558.669904][12733:12735] CHIP:DMG: 3, [1708101558.669943][12733:12735] CHIP:DMG: ], [1708101558.669975][12733:12735] CHIP:DMG: }, [1708101558.670010][12733:12735] CHIP:DMG: [1708101558.670037][12733:12735] CHIP:DMG: }, [1708101558.670079][12733:12735] CHIP:DMG: [1708101558.670105][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.670142][12733:12735] CHIP:DMG: { [1708101558.670171][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.670202][12733:12735] CHIP:DMG: { [1708101558.670233][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.670266][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.670300][12733:12735] CHIP:DMG: { [1708101558.670333][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.670371][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.670407][12733:12735] CHIP:DMG: Attribute = 0x0000_FFF9, [1708101558.670442][12733:12735] CHIP:DMG: } [1708101558.670478][12733:12735] CHIP:DMG: [1708101558.670509][12733:12735] CHIP:DMG: Data = [ [1708101558.670550][12733:12735] CHIP:DMG: 0, 1, 2, 4, 5, [1708101558.670589][12733:12735] CHIP:DMG: ], [1708101558.670621][12733:12735] CHIP:DMG: }, [1708101558.670661][12733:12735] CHIP:DMG: [1708101558.670688][12733:12735] CHIP:DMG: }, [1708101558.670737][12733:12735] CHIP:DMG: [1708101558.670764][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.670806][12733:12735] CHIP:DMG: { [1708101558.670834][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.670866][12733:12735] CHIP:DMG: { [1708101558.670900][12733:12735] CHIP:DMG: DataVersion = 0x42180fe6, [1708101558.670932][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.670967][12733:12735] CHIP:DMG: { [1708101558.671001][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.671037][12733:12735] CHIP:DMG: Cluster = 0x38, [1708101558.671074][12733:12735] CHIP:DMG: Attribute = 0x0000_FFFB, [1708101558.671109][12733:12735] CHIP:DMG: } [1708101558.671145][12733:12735] CHIP:DMG: [1708101558.671178][12733:12735] CHIP:DMG: Data = [ [1708101558.671235][12733:12735] CHIP:DMG: 0, 1, 2, 3, 4, 5, 6, 7, 8, 10, 11, 12, 65528, 65529, 65531, 65532, 65533, [1708101558.671276][12733:12735] CHIP:DMG: ], [1708101558.671308][12733:12735] CHIP:DMG: }, [1708101558.671351][12733:12735] CHIP:DMG: [1708101558.671378][12733:12735] CHIP:DMG: }, [1708101558.671424][12733:12735] CHIP:DMG: [1708101558.671451][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.671487][12733:12735] CHIP:DMG: { [1708101558.671515][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.671547][12733:12735] CHIP:DMG: { [1708101558.671578][12733:12735] CHIP:DMG: DataVersion = 0x976ac39f, [1708101558.671611][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.671645][12733:12735] CHIP:DMG: { [1708101558.671682][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.671719][12733:12735] CHIP:DMG: Cluster = 0x31, [1708101558.671755][12733:12735] CHIP:DMG: Attribute = 0x0000_0003, [1708101558.671789][12733:12735] CHIP:DMG: } [1708101558.671824][12733:12735] CHIP:DMG: [1708101558.671859][12733:12735] CHIP:DMG: Data = 20, [1708101558.671891][12733:12735] CHIP:DMG: }, [1708101558.671927][12733:12735] CHIP:DMG: [1708101558.671954][12733:12735] CHIP:DMG: }, [1708101558.672003][12733:12735] CHIP:DMG: [1708101558.672032][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.672066][12733:12735] CHIP:DMG: { [1708101558.672094][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.672126][12733:12735] CHIP:DMG: { [1708101558.672157][12733:12735] CHIP:DMG: DataVersion = 0xcb8cb6f8, [1708101558.672191][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.672225][12733:12735] CHIP:DMG: { [1708101558.672259][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.672295][12733:12735] CHIP:DMG: Cluster = 0x28, [1708101558.672331][12733:12735] CHIP:DMG: Attribute = 0x0000_0004, [1708101558.672364][12733:12735] CHIP:DMG: } [1708101558.672399][12733:12735] CHIP:DMG: [1708101558.672434][12733:12735] CHIP:DMG: Data = 32769, [1708101558.672465][12733:12735] CHIP:DMG: }, [1708101558.672498][12733:12735] CHIP:DMG: [1708101558.672526][12733:12735] CHIP:DMG: }, [1708101558.672564][12733:12735] CHIP:DMG: [1708101558.672590][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.672624][12733:12735] CHIP:DMG: { [1708101558.672652][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.672685][12733:12735] CHIP:DMG: { [1708101558.672716][12733:12735] CHIP:DMG: DataVersion = 0xcb8cb6f8, [1708101558.672749][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.672783][12733:12735] CHIP:DMG: { [1708101558.672817][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.672854][12733:12735] CHIP:DMG: Cluster = 0x28, [1708101558.672890][12733:12735] CHIP:DMG: Attribute = 0x0000_0002, [1708101558.672925][12733:12735] CHIP:DMG: } [1708101558.672960][12733:12735] CHIP:DMG: [1708101558.672995][12733:12735] CHIP:DMG: Data = 65521, [1708101558.673026][12733:12735] CHIP:DMG: }, [1708101558.673060][12733:12735] CHIP:DMG: [1708101558.673088][12733:12735] CHIP:DMG: }, [1708101558.673126][12733:12735] CHIP:DMG: [1708101558.673152][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.673187][12733:12735] CHIP:DMG: { [1708101558.673215][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.673247][12733:12735] CHIP:DMG: { [1708101558.673280][12733:12735] CHIP:DMG: DataVersion = 0x5bde7300, [1708101558.673312][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.673346][12733:12735] CHIP:DMG: { [1708101558.673381][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.673417][12733:12735] CHIP:DMG: Cluster = 0x30, [1708101558.673453][12733:12735] CHIP:DMG: Attribute = 0x0000_0003, [1708101558.673486][12733:12735] CHIP:DMG: } [1708101558.673518][12733:12735] CHIP:DMG: [1708101558.673552][12733:12735] CHIP:DMG: Data = 2, [1708101558.673585][12733:12735] CHIP:DMG: }, [1708101558.673619][12733:12735] CHIP:DMG: [1708101558.673647][12733:12735] CHIP:DMG: }, [1708101558.673686][12733:12735] CHIP:DMG: [1708101558.673712][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.673747][12733:12735] CHIP:DMG: { [1708101558.673774][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.673806][12733:12735] CHIP:DMG: { [1708101558.673837][12733:12735] CHIP:DMG: DataVersion = 0x5bde7300, [1708101558.673870][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.673904][12733:12735] CHIP:DMG: { [1708101558.673938][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.673974][12733:12735] CHIP:DMG: Cluster = 0x30, [1708101558.674009][12733:12735] CHIP:DMG: Attribute = 0x0000_0002, [1708101558.674043][12733:12735] CHIP:DMG: } [1708101558.674078][12733:12735] CHIP:DMG: [1708101558.674111][12733:12735] CHIP:DMG: Data = 0, [1708101558.674143][12733:12735] CHIP:DMG: }, [1708101558.674179][12733:12735] CHIP:DMG: [1708101558.674206][12733:12735] CHIP:DMG: }, [1708101558.674246][12733:12735] CHIP:DMG: [1708101558.674271][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.674310][12733:12735] CHIP:DMG: { [1708101558.674338][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.674369][12733:12735] CHIP:DMG: { [1708101558.674400][12733:12735] CHIP:DMG: DataVersion = 0x5bde7300, [1708101558.674433][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.674467][12733:12735] CHIP:DMG: { [1708101558.674503][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.674539][12733:12735] CHIP:DMG: Cluster = 0x30, [1708101558.674573][12733:12735] CHIP:DMG: Attribute = 0x0000_0001, [1708101558.674607][12733:12735] CHIP:DMG: } [1708101558.674643][12733:12735] CHIP:DMG: [1708101558.674676][12733:12735] CHIP:DMG: Data = [1708101558.674710][12733:12735] CHIP:DMG: { [1708101558.674746][12733:12735] CHIP:DMG: 0x0 = 60, [1708101558.674783][12733:12735] CHIP:DMG: 0x1 = 900, [1708101558.674819][12733:12735] CHIP:DMG: }, [1708101558.674850][12733:12735] CHIP:DMG: }, [1708101558.674882][12733:12735] CHIP:DMG: [1708101558.674906][12733:12735] CHIP:DMG: }, [1708101558.674940][12733:12735] CHIP:DMG: [1708101558.674963][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.674993][12733:12735] CHIP:DMG: { [1708101558.675017][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.675044][12733:12735] CHIP:DMG: { [1708101558.675072][12733:12735] CHIP:DMG: DataVersion = 0x5bde7300, [1708101558.675100][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.675129][12733:12735] CHIP:DMG: { [1708101558.675158][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.675190][12733:12735] CHIP:DMG: Cluster = 0x30, [1708101558.675221][12733:12735] CHIP:DMG: Attribute = 0x0000_0000, [1708101558.675251][12733:12735] CHIP:DMG: } [1708101558.675282][12733:12735] CHIP:DMG: [1708101558.675311][12733:12735] CHIP:DMG: Data = 0, [1708101558.675338][12733:12735] CHIP:DMG: }, [1708101558.675368][12733:12735] CHIP:DMG: [1708101558.675392][12733:12735] CHIP:DMG: }, [1708101558.675428][12733:12735] CHIP:DMG: [1708101558.675451][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.675480][12733:12735] CHIP:DMG: { [1708101558.675504][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.675532][12733:12735] CHIP:DMG: { [1708101558.675560][12733:12735] CHIP:DMG: DataVersion = 0x976ac39f, [1708101558.675589][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.675618][12733:12735] CHIP:DMG: { [1708101558.675648][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.675680][12733:12735] CHIP:DMG: Cluster = 0x31, [1708101558.675711][12733:12735] CHIP:DMG: Attribute = 0x0000_FFFC, [1708101558.675742][12733:12735] CHIP:DMG: } [1708101558.675773][12733:12735] CHIP:DMG: [1708101558.675804][12733:12735] CHIP:DMG: Data = 9, [1708101558.675832][12733:12735] CHIP:DMG: }, [1708101558.675861][12733:12735] CHIP:DMG: [1708101558.675884][12733:12735] CHIP:DMG: }, [1708101558.675912][12733:12735] CHIP:DMG: [1708101558.675935][12733:12735] CHIP:DMG: ], [1708101558.676075][12733:12735] CHIP:DMG: [1708101558.676102][12733:12735] CHIP:DMG: SuppressResponse = true, [1708101558.676125][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101558.676147][12733:12735] CHIP:DMG: } [1708101558.678384][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1708101558.678415][12733:12735] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1708101558.678445][12733:12735] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo2' [1708101558.678481][12733:12735] CHIP:DMG: SendReadRequest ReadClient[0xffffb00307b0]: Sending Read Request [1708101558.678695][12733:12735] CHIP:EM: <<< [E:47755i S:63037 M:156344651] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1708101558.679102][12733:12735] CHIP:DMG: MoveToState ReadClient[0xffffb00307b0]: Moving to [AwaitingIn] [1708101558.752669][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.755589][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.755821][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.756158][12733:12735] CHIP:EM: >>> [E:47755i S:63037 M:138661795] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1708101558.756237][12733:12735] CHIP:EM: Found matching exchange: 47755i, Delegate: 0xffffb00307c0 [1708101558.756401][12733:12735] CHIP:DMG: ReportDataMessage = [1708101558.756462][12733:12735] CHIP:DMG: { [1708101558.756508][12733:12735] CHIP:DMG: AttributeReportIBs = [1708101558.756575][12733:12735] CHIP:DMG: [ [1708101558.756631][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.756707][12733:12735] CHIP:DMG: { [1708101558.756767][12733:12735] CHIP:DMG: AttributeStatusIB = [1708101558.756839][12733:12735] CHIP:DMG: { [1708101558.756903][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.756978][12733:12735] CHIP:DMG: { [1708101558.757056][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.757135][12733:12735] CHIP:DMG: Cluster = 0x46, [1708101558.757215][12733:12735] CHIP:DMG: Attribute = 0x0000_0007, [1708101558.757290][12733:12735] CHIP:DMG: } [1708101558.757373][12733:12735] CHIP:DMG: [1708101558.757446][12733:12735] CHIP:DMG: StatusIB = [1708101558.757524][12733:12735] CHIP:DMG: { [1708101558.757598][12733:12735] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1708101558.757674][12733:12735] CHIP:DMG: }, [1708101558.757748][12733:12735] CHIP:DMG: [1708101558.757814][12733:12735] CHIP:DMG: }, [1708101558.757893][12733:12735] CHIP:DMG: [1708101558.757953][12733:12735] CHIP:DMG: }, [1708101558.758037][12733:12735] CHIP:DMG: [1708101558.758094][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.758168][12733:12735] CHIP:DMG: { [1708101558.758229][12733:12735] CHIP:DMG: AttributeStatusIB = [1708101558.758300][12733:12735] CHIP:DMG: { [1708101558.758364][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.758438][12733:12735] CHIP:DMG: { [1708101558.758512][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.758590][12733:12735] CHIP:DMG: Cluster = 0x46, [1708101558.758669][12733:12735] CHIP:DMG: Attribute = 0x0000_0006, [1708101558.758744][12733:12735] CHIP:DMG: } [1708101558.758825][12733:12735] CHIP:DMG: [1708101558.758896][12733:12735] CHIP:DMG: StatusIB = [1708101558.758970][12733:12735] CHIP:DMG: { [1708101558.759044][12733:12735] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1708101558.759118][12733:12735] CHIP:DMG: }, [1708101558.759189][12733:12735] CHIP:DMG: [1708101558.759252][12733:12735] CHIP:DMG: }, [1708101558.759325][12733:12735] CHIP:DMG: [1708101558.759385][12733:12735] CHIP:DMG: }, [1708101558.759470][12733:12735] CHIP:DMG: [1708101558.759527][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.759602][12733:12735] CHIP:DMG: { [1708101558.759663][12733:12735] CHIP:DMG: AttributeStatusIB = [1708101558.759730][12733:12735] CHIP:DMG: { [1708101558.759794][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.759866][12733:12735] CHIP:DMG: { [1708101558.759939][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.760044][12733:12735] CHIP:DMG: Cluster = 0x46, [1708101558.760129][12733:12735] CHIP:DMG: Attribute = 0x0000_FFFC, [1708101558.760200][12733:12735] CHIP:DMG: } [1708101558.760283][12733:12735] CHIP:DMG: [1708101558.760354][12733:12735] CHIP:DMG: StatusIB = [1708101558.760427][12733:12735] CHIP:DMG: { [1708101558.760505][12733:12735] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1708101558.760581][12733:12735] CHIP:DMG: }, [1708101558.760655][12733:12735] CHIP:DMG: [1708101558.760718][12733:12735] CHIP:DMG: }, [1708101558.760791][12733:12735] CHIP:DMG: [1708101558.760851][12733:12735] CHIP:DMG: }, [1708101558.760940][12733:12735] CHIP:DMG: [1708101558.760997][12733:12735] CHIP:DMG: AttributeReportIB = [1708101558.761071][12733:12735] CHIP:DMG: { [1708101558.761132][12733:12735] CHIP:DMG: AttributeDataIB = [1708101558.761199][12733:12735] CHIP:DMG: { [1708101558.761267][12733:12735] CHIP:DMG: DataVersion = 0x5bde7300, [1708101558.761335][12733:12735] CHIP:DMG: AttributePathIB = [1708101558.761407][12733:12735] CHIP:DMG: { [1708101558.761489][12733:12735] CHIP:DMG: Endpoint = 0x0, [1708101558.761567][12733:12735] CHIP:DMG: Cluster = 0x30, [1708101558.761647][12733:12735] CHIP:DMG: Attribute = 0x0000_0004, [1708101558.761715][12733:12735] CHIP:DMG: } [1708101558.761756][12733:12735] CHIP:DMG: [1708101558.761798][12733:12735] CHIP:DMG: Data = true, [1708101558.761835][12733:12735] CHIP:DMG: }, [1708101558.761876][12733:12735] CHIP:DMG: [1708101558.761908][12733:12735] CHIP:DMG: }, [1708101558.761946][12733:12735] CHIP:DMG: [1708101558.761976][12733:12735] CHIP:DMG: ], [1708101558.762030][12733:12735] CHIP:DMG: [1708101558.762062][12733:12735] CHIP:DMG: SuppressResponse = true, [1708101558.762094][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101558.762123][12733:12735] CHIP:DMG: } [1708101558.762743][12733:12735] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid = 0 [1708101558.762879][12733:12735] CHIP:SVR: OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8001 [1708101558.762926][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo2' [1708101558.762965][12733:12735] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1708101558.763002][12733:12735] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1708101558.763034][12733:12735] CHIP:CTL: Arming failsafe (60 seconds) [1708101558.763133][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101558.763174][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101558.763318][12733:12735] CHIP:EM: <<< [E:47756i S:63037 M:156344652] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101558.763804][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101558.850155][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.852217][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.852455][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.852746][12733:12735] CHIP:EM: >>> [E:47756i S:63037 M:138661796] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101558.852825][12733:12735] CHIP:EM: Found matching exchange: 47756i, Delegate: 0xffffb0013a98 [1708101558.852905][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101558.853006][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101558.853063][12733:12735] CHIP:DMG: { [1708101558.853116][12733:12735] CHIP:DMG: suppressResponse = false, [1708101558.853173][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101558.853246][12733:12735] CHIP:DMG: [ [1708101558.853302][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101558.853380][12733:12735] CHIP:DMG: { [1708101558.853441][12733:12735] CHIP:DMG: CommandDataIB = [1708101558.853508][12733:12735] CHIP:DMG: { [1708101558.853572][12733:12735] CHIP:DMG: CommandPathIB = [1708101558.853659][12733:12735] CHIP:DMG: { [1708101558.853735][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101558.853814][12733:12735] CHIP:DMG: ClusterId = 0x30, [1708101558.853892][12733:12735] CHIP:DMG: CommandId = 0x1, [1708101558.853968][12733:12735] CHIP:DMG: }, [1708101558.854046][12733:12735] CHIP:DMG: [1708101558.854112][12733:12735] CHIP:DMG: CommandFields = [1708101558.854186][12733:12735] CHIP:DMG: { [1708101558.854264][12733:12735] CHIP:DMG: 0x0 = 0, [1708101558.854347][12733:12735] CHIP:DMG: 0x1 = "" (0 chars), [1708101558.854426][12733:12735] CHIP:DMG: }, [1708101558.854497][12733:12735] CHIP:DMG: }, [1708101558.854574][12733:12735] CHIP:DMG: [1708101558.854635][12733:12735] CHIP:DMG: }, [1708101558.854707][12733:12735] CHIP:DMG: [1708101558.854763][12733:12735] CHIP:DMG: ], [1708101558.854835][12733:12735] CHIP:DMG: [1708101558.854889][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101558.854943][12733:12735] CHIP:DMG: }, [1708101558.855095][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1708101558.855226][12733:12735] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1708101558.855295][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1708101558.855346][12733:12735] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1708101558.855407][12733:12735] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1708101558.855456][12733:12735] CHIP:CTL: Setting Regulatory Config [1708101558.855506][12733:12735] CHIP:CTL: No regulatory config supplied by controller, leaving as device default (0) [1708101558.855695][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101558.855769][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101558.855968][12733:12735] CHIP:EM: <<< [E:47757i S:63037 M:156344653] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101558.856788][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101558.856949][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101558.947674][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101558.949724][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101558.949979][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101558.950274][12733:12735] CHIP:EM: >>> [E:47757i S:63037 M:138661797] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101558.950355][12733:12735] CHIP:EM: Found matching exchange: 47757i, Delegate: 0xffffb003dac8 [1708101558.950438][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101558.950540][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101558.950598][12733:12735] CHIP:DMG: { [1708101558.950653][12733:12735] CHIP:DMG: suppressResponse = false, [1708101558.950707][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101558.950787][12733:12735] CHIP:DMG: [ [1708101558.950844][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101558.950924][12733:12735] CHIP:DMG: { [1708101558.950986][12733:12735] CHIP:DMG: CommandDataIB = [1708101558.951060][12733:12735] CHIP:DMG: { [1708101558.951125][12733:12735] CHIP:DMG: CommandPathIB = [1708101558.951200][12733:12735] CHIP:DMG: { [1708101558.951276][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101558.951358][12733:12735] CHIP:DMG: ClusterId = 0x30, [1708101558.951437][12733:12735] CHIP:DMG: CommandId = 0x3, [1708101558.951509][12733:12735] CHIP:DMG: }, [1708101558.951587][12733:12735] CHIP:DMG: [1708101558.951657][12733:12735] CHIP:DMG: CommandFields = [1708101558.951734][12733:12735] CHIP:DMG: { [1708101558.951813][12733:12735] CHIP:DMG: 0x0 = 0, [1708101558.951896][12733:12735] CHIP:DMG: 0x1 = "" (0 chars), [1708101558.952029][12733:12735] CHIP:DMG: }, [1708101558.952105][12733:12735] CHIP:DMG: }, [1708101558.952186][12733:12735] CHIP:DMG: [1708101558.952246][12733:12735] CHIP:DMG: }, [1708101558.952319][12733:12735] CHIP:DMG: [1708101558.952375][12733:12735] CHIP:DMG: ], [1708101558.952447][12733:12735] CHIP:DMG: [1708101558.952502][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101558.952557][12733:12735] CHIP:DMG: }, [1708101558.952713][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1708101558.952801][12733:12735] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1708101558.952872][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1708101558.952926][12733:12735] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'ConfigureUTCTime' [1708101558.952988][12733:12735] CHIP:CTL: Performing next commissioning step 'ConfigureUTCTime' [1708101558.953116][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101558.953262][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101558.953469][12733:12735] CHIP:EM: <<< [E:47758i S:63037 M:156344654] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101558.954267][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101558.954428][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101559.045171][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101559.047384][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.047634][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.047906][12733:12735] CHIP:EM: >>> [E:47758i S:63037 M:138661798] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.047974][12733:12735] CHIP:EM: Found matching exchange: 47758i, Delegate: 0xffffb0013a98 [1708101559.048089][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101559.048180][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101559.048230][12733:12735] CHIP:DMG: { [1708101559.048289][12733:12735] CHIP:DMG: suppressResponse = false, [1708101559.048341][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101559.048404][12733:12735] CHIP:DMG: [ [1708101559.048454][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101559.048521][12733:12735] CHIP:DMG: { [1708101559.048573][12733:12735] CHIP:DMG: CommandStatusIB = [1708101559.048633][12733:12735] CHIP:DMG: { [1708101559.048689][12733:12735] CHIP:DMG: CommandPathIB = [1708101559.048754][12733:12735] CHIP:DMG: { [1708101559.048819][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101559.048888][12733:12735] CHIP:DMG: ClusterId = 0x38, [1708101559.048953][12733:12735] CHIP:DMG: CommandId = 0x0, [1708101559.049018][12733:12735] CHIP:DMG: }, [1708101559.049089][12733:12735] CHIP:DMG: [1708101559.049145][12733:12735] CHIP:DMG: StatusIB = [1708101559.049210][12733:12735] CHIP:DMG: { [1708101559.049283][12733:12735] CHIP:DMG: status = 0x01 (FAILURE), [1708101559.049347][12733:12735] CHIP:DMG: cluster-status = 0x2, [1708101559.049411][12733:12735] CHIP:DMG: }, [1708101559.049477][12733:12735] CHIP:DMG: [1708101559.049534][12733:12735] CHIP:DMG: }, [1708101559.049598][12733:12735] CHIP:DMG: [1708101559.049649][12733:12735] CHIP:DMG: }, [1708101559.049711][12733:12735] CHIP:DMG: [1708101559.049760][12733:12735] CHIP:DMG: ], [1708101559.049822][12733:12735] CHIP:DMG: [1708101559.049869][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101559.049916][12733:12735] CHIP:DMG: }, [1708101559.050049][12733:12735] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0038 Command=0x0000_0000 Status=0x1 [1708101559.050120][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ConfigureUTCTime' [1708101559.050171][12733:12735] CHIP:CTL: Commissioning stage next step: 'ConfigureUTCTime' -> 'SendPAICertificateRequest' [1708101559.050225][12733:12735] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1708101559.050267][12733:12735] CHIP:CTL: Sending request for PAI certificate [1708101559.050310][12733:12735] CHIP:CTL: Sending Certificate Chain request to 0xffffb0036d10 device [1708101559.050421][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101559.050480][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101559.050654][12733:12735] CHIP:EM: <<< [E:47759i S:63037 M:156344655] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.051422][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101559.051571][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101559.142647][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101559.190929][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.191146][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.291742][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.291981][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.387246][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.387511][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.387811][12733:12735] CHIP:EM: >>> [E:47759i S:63037 M:138661799] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.387891][12733:12735] CHIP:EM: Found matching exchange: 47759i, Delegate: 0xffffb003dac8 [1708101559.387978][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101559.388131][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101559.388189][12733:12735] CHIP:DMG: { [1708101559.388243][12733:12735] CHIP:DMG: suppressResponse = false, [1708101559.388302][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101559.388377][12733:12735] CHIP:DMG: [ [1708101559.388434][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101559.388512][12733:12735] CHIP:DMG: { [1708101559.388573][12733:12735] CHIP:DMG: CommandDataIB = [1708101559.388641][12733:12735] CHIP:DMG: { [1708101559.388706][12733:12735] CHIP:DMG: CommandPathIB = [1708101559.388792][12733:12735] CHIP:DMG: { [1708101559.388869][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101559.388950][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101559.389028][12733:12735] CHIP:DMG: CommandId = 0x3, [1708101559.389102][12733:12735] CHIP:DMG: }, [1708101559.389181][12733:12735] CHIP:DMG: [1708101559.389247][12733:12735] CHIP:DMG: CommandFields = [1708101559.389323][12733:12735] CHIP:DMG: { [1708101559.389398][12733:12735] CHIP:DMG: 0x0 = [ [1708101559.389740][12733:12735] 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 [1708101559.389914][12733:12735] CHIP:DMG: ] (463 bytes) [1708101559.389993][12733:12735] CHIP:DMG: }, [1708101559.390064][12733:12735] CHIP:DMG: }, [1708101559.390140][12733:12735] CHIP:DMG: [1708101559.390200][12733:12735] CHIP:DMG: }, [1708101559.390271][12733:12735] CHIP:DMG: [1708101559.390327][12733:12735] CHIP:DMG: ], [1708101559.390398][12733:12735] CHIP:DMG: [1708101559.390453][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101559.390508][12733:12735] CHIP:DMG: }, [1708101559.390663][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1708101559.390750][12733:12735] CHIP:CTL: Received certificate chain from the device [1708101559.390818][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1708101559.390876][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1708101559.390938][12733:12735] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1708101559.390987][12733:12735] CHIP:CTL: Sending request for DAC certificate [1708101559.391034][12733:12735] CHIP:CTL: Sending Certificate Chain request to 0xffffb0036d10 device [1708101559.391158][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101559.391225][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101559.391428][12733:12735] CHIP:EM: <<< [E:47760i S:63037 M:156344656] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.392240][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101559.392394][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101559.483974][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101559.532221][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.532465][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.633024][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.633272][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.728631][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.728870][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.729161][12733:12735] CHIP:EM: >>> [E:47760i S:63037 M:138661800] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101559.729242][12733:12735] CHIP:EM: Found matching exchange: 47760i, Delegate: 0xffffb0013a98 [1708101559.729329][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101559.729431][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101559.729487][12733:12735] CHIP:DMG: { [1708101559.729542][12733:12735] CHIP:DMG: suppressResponse = false, [1708101559.729599][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101559.729673][12733:12735] CHIP:DMG: [ [1708101559.729730][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101559.729808][12733:12735] CHIP:DMG: { [1708101559.729869][12733:12735] CHIP:DMG: CommandDataIB = [1708101559.729940][12733:12735] CHIP:DMG: { [1708101559.730004][12733:12735] CHIP:DMG: CommandPathIB = [1708101559.730079][12733:12735] CHIP:DMG: { [1708101559.730156][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101559.730236][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101559.730314][12733:12735] CHIP:DMG: CommandId = 0x3, [1708101559.730389][12733:12735] CHIP:DMG: }, [1708101559.730464][12733:12735] CHIP:DMG: [1708101559.730530][12733:12735] CHIP:DMG: CommandFields = [1708101559.730605][12733:12735] CHIP:DMG: { [1708101559.730682][12733:12735] CHIP:DMG: 0x0 = [ [1708101559.731032][12733:12735] 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 [1708101559.731206][12733:12735] CHIP:DMG: ] (491 bytes) [1708101559.731286][12733:12735] CHIP:DMG: }, [1708101559.731356][12733:12735] CHIP:DMG: }, [1708101559.731432][12733:12735] CHIP:DMG: [1708101559.731492][12733:12735] CHIP:DMG: }, [1708101559.731564][12733:12735] CHIP:DMG: [1708101559.731620][12733:12735] CHIP:DMG: ], [1708101559.731693][12733:12735] CHIP:DMG: [1708101559.731748][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101559.731802][12733:12735] CHIP:DMG: }, [1708101559.731956][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1708101559.732089][12733:12735] CHIP:CTL: Received certificate chain from the device [1708101559.732159][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1708101559.732222][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1708101559.732286][12733:12735] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1708101559.732337][12733:12735] CHIP:CTL: Sending Attestation Request to the device. [1708101559.732386][12733:12735] CHIP:CTL: Sending Attestation request to 0xffffb0036d10 device [1708101559.732514][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101559.732583][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101559.732789][12733:12735] CHIP:EM: <<< [E:47761i S:63037 M:156344657] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101559.733585][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101559.733660][12733:12735] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1708101559.733792][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101559.825216][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101559.873270][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.873479][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101559.974360][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101559.974626][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.169267][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101560.169523][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.169840][12733:12735] CHIP:EM: >>> [E:47761i S:63037 M:138661801] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.169921][12733:12735] CHIP:EM: Found matching exchange: 47761i, Delegate: 0xffffb003dac8 [1708101560.170009][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101560.170113][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101560.170171][12733:12735] CHIP:DMG: { [1708101560.170225][12733:12735] CHIP:DMG: suppressResponse = false, [1708101560.170283][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101560.170358][12733:12735] CHIP:DMG: [ [1708101560.170414][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101560.170492][12733:12735] CHIP:DMG: { [1708101560.170553][12733:12735] CHIP:DMG: CommandDataIB = [1708101560.170630][12733:12735] CHIP:DMG: { [1708101560.170695][12733:12735] CHIP:DMG: CommandPathIB = [1708101560.170769][12733:12735] CHIP:DMG: { [1708101560.170846][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101560.170927][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101560.171005][12733:12735] CHIP:DMG: CommandId = 0x1, [1708101560.171080][12733:12735] CHIP:DMG: }, [1708101560.171158][12733:12735] CHIP:DMG: [1708101560.171231][12733:12735] CHIP:DMG: CommandFields = [1708101560.171306][12733:12735] CHIP:DMG: { [1708101560.171382][12733:12735] CHIP:DMG: 0x0 = [ [1708101560.171725][12733:12735] 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 [1708101560.171900][12733:12735] CHIP:DMG: ] (583 bytes) [1708101560.171980][12733:12735] CHIP:DMG: 0x1 = [ [1708101560.172149][12733:12735] CHIP:DMG: 0xd3, 0x93, 0x8a, 0x06, 0x88, 0x18, 0x17, 0x29, 0x75, 0xb6, 0x31, 0xa7, 0xd2, 0xdf, 0xf5, 0xf0, 0x7c, 0xe7, 0x99, 0xdc, 0xb1, 0x42, 0xb4, 0xe2, 0x00, 0x02, 0xa1, 0xae, 0x86, 0xf3, 0x87, 0x67, 0xec, 0x0f, 0x89, 0xeb, 0x46, 0xb2, 0x94, 0xdb, 0x00, 0xbd, 0xc3, 0x88, 0x83, 0x22, 0x20, 0xf7, 0x47, 0x8e, 0x4c, 0xaa, 0x8e, 0xd9, 0xa7, 0x15, 0xc3, 0x36, 0x11, 0x10, 0xfc, 0xc9, 0x6e, 0x68, [1708101560.172252][12733:12735] CHIP:DMG: ] (64 bytes) [1708101560.172331][12733:12735] CHIP:DMG: }, [1708101560.172400][12733:12735] CHIP:DMG: }, [1708101560.172480][12733:12735] CHIP:DMG: [1708101560.172539][12733:12735] CHIP:DMG: }, [1708101560.172613][12733:12735] CHIP:DMG: [1708101560.172669][12733:12735] CHIP:DMG: ], [1708101560.172749][12733:12735] CHIP:DMG: [1708101560.172804][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101560.172859][12733:12735] CHIP:DMG: }, [1708101560.173024][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1708101560.173116][12733:12735] CHIP:CTL: Received Attestation Information from the device [1708101560.173183][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1708101560.173238][12733:12735] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583 [1708101560.173345][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1708101560.173413][12733:12735] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1708101560.173464][12733:12735] CHIP:CTL: Verifying attestation [1708101560.204359][12733:12735] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1708101560.204430][12733:12735] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1708101560.204454][12733:12735] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1708101560.204486][12733:12735] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1708101560.204510][12733:12735] CHIP:CTL: Sending CSR request to 0xffffb0036d10 device [1708101560.204594][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101560.204629][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101560.204795][12733:12735] CHIP:EM: <<< [E:47762i S:63037 M:156344658] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.205216][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101560.205259][12733:12735] CHIP:CTL: Sent CSR request, waiting for the CSR [1708101560.205341][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101560.263752][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.312129][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101560.312380][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.411914][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101560.412259][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.412540][12733:12735] CHIP:EM: >>> [E:47762i S:63037 M:138661802] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.412620][12733:12735] CHIP:EM: Found matching exchange: 47762i, Delegate: 0xffffb0013a98 [1708101560.412708][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101560.412813][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101560.412990][12733:12735] CHIP:DMG: { [1708101560.413049][12733:12735] CHIP:DMG: suppressResponse = false, [1708101560.413109][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101560.413232][12733:12735] CHIP:DMG: [ [1708101560.413299][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101560.413430][12733:12735] CHIP:DMG: { [1708101560.413572][12733:12735] CHIP:DMG: CommandDataIB = [1708101560.413705][12733:12735] CHIP:DMG: { [1708101560.413776][12733:12735] CHIP:DMG: CommandPathIB = [1708101560.413898][12733:12735] CHIP:DMG: { [1708101560.414023][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101560.414115][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101560.414242][12733:12735] CHIP:DMG: CommandId = 0x5, [1708101560.414369][12733:12735] CHIP:DMG: }, [1708101560.414453][12733:12735] CHIP:DMG: [1708101560.414576][12733:12735] CHIP:DMG: CommandFields = [1708101560.414691][12733:12735] CHIP:DMG: { [1708101560.414817][12733:12735] CHIP:DMG: 0x0 = [ [1708101560.415188][12733:12735] 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, 0x19, 0x90, 0xaa, 0x23, 0xa6, 0x93, 0x76, 0x55, 0xc1, 0x7a, 0x48, 0x5e, 0x70, 0xe8, 0x20, 0x38, 0x1c, 0x26, 0xa1, 0xaa, 0x39, 0x1d, 0x62, 0x74, 0x50, 0x91, 0x03, 0x3f, 0xff, 0x5e, 0x22, 0x27, 0x60, 0x8d, 0x93, 0x78, 0x1a, 0x5a, 0x5d, 0x2b, 0x05, 0x1d, 0x47, 0x0d, 0xf2, 0x46, 0x50, 0xb8, 0xaf, 0xa7, 0x8b, 0xe2, 0x11, 0x45, 0xed, 0x60, 0x77, 0x8a, 0x8f, 0xaf, 0xb7, 0xe5, 0x78, 0xa2, 0xa0, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x49, 0x00, 0x30, 0x46, 0x02, 0x21, 0x00, 0xab, 0xbc, 0xd2, 0x90, 0x48, 0x79, 0x36, 0x0d, 0x13, 0x75, 0x8c, 0x5e, 0x60, 0x7a, 0x96, 0x7f, 0xff, 0x1b, 0x30, 0x60, 0xcc, 0xec, 0xf8, 0x2d, 0x1b, 0xf2, 0xbf, 0x54, 0x63, 0x8f, 0x47, 0xfc, 0x02, 0x21, 0x00, 0xb0, 0xe7, 0xfe, 0x5b, 0x84, 0x4f, 0x7e, 0x14, 0x3b, 0xae, 0x0f, 0x2b, 0x6a, 0x0e, 0x46, 0x7d, 0xf4, 0xf8, 0x5d, 0x19, 0xd8, 0x89, 0x6a, 0x83, 0x70, 0x5e, 0x9a, 0xc2, 0x55, 0x3a, 0x90, 0x4b, 0x30, 0x02, 0x20, 0x76, 0x21, 0x5a, 0x48, 0xf6, 0xeb, 0xe3, 0xcc, 0xae, 0x5c, 0x28, 0x99, 0x5a, 0x13, 0xdd, 0x50, 0x51, 0x31, 0x56, 0xe3, 0x54, 0x00, 0x3f, 0x4f, 0x0d, 0x2a, 0x81, 0xcf, 0x74, 0x26, 0x30, 0x05, 0x18, [1708101560.415435][12733:12735] CHIP:DMG: ] (244 bytes) [1708101560.415521][12733:12735] CHIP:DMG: 0x1 = [ [1708101560.415651][12733:12735] CHIP:DMG: 0x8a, 0x1f, 0xd1, 0x67, 0xbe, 0xec, 0xc8, 0xb7, 0xce, 0xa8, 0xd4, 0x64, 0x97, 0xdc, 0x31, 0x2a, 0x7a, 0x93, 0x2e, 0xe1, 0xdd, 0xf9, 0x67, 0x75, 0x6f, 0x63, 0x3b, 0xb5, 0x45, 0x4d, 0x37, 0x6f, 0xd7, 0xfc, 0xf6, 0x9b, 0x79, 0x22, 0x82, 0x66, 0xc9, 0x9c, 0xcb, 0x09, 0xef, 0xb9, 0xdd, 0x2a, 0xbf, 0x5e, 0x31, 0x6c, 0x7d, 0x5a, 0x9f, 0x93, 0x98, 0x0c, 0xfc, 0x24, 0x42, 0x3c, 0xa0, 0xc1, [1708101560.415751][12733:12735] CHIP:DMG: ] (64 bytes) [1708101560.415831][12733:12735] CHIP:DMG: }, [1708101560.415901][12733:12735] CHIP:DMG: }, [1708101560.416134][12733:12735] CHIP:DMG: [1708101560.416251][12733:12735] CHIP:DMG: }, [1708101560.416374][12733:12735] CHIP:DMG: [1708101560.416435][12733:12735] CHIP:DMG: ], [1708101560.416554][12733:12735] CHIP:DMG: [1708101560.416645][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101560.416700][12733:12735] CHIP:DMG: }, [1708101560.416916][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1708101560.417010][12733:12735] CHIP:CTL: Received certificate signing request from the device [1708101560.417140][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1708101560.417239][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1708101560.417304][12733:12735] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1708101560.423618][12733:12735] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1708101560.423683][12733:12735] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1708101560.423715][12733:12735] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1708101560.423743][12733:12735] CHIP:CTL: Getting certificate chain for the device from the issuer [1708101560.426763][12733:12735] CHIP:CTL: Verifying Certificate Signing Request [1708101560.429070][12733:12735] CHIP:CTL: Generating NOC [1708101560.429466][12733:12735] CHIP:CTL: Providing certificate chain to the commissioner [1708101560.429508][12733:12735] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success [1708101560.429545][12733:12735] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1708101560.429797][12733:12735] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1708101560.429831][12733:12735] CHIP:CTL: Sending root certificate to the device [1708101560.429947][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101560.429984][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101560.430181][12733:12735] CHIP:EM: <<< [E:47763i S:63037 M:156344659] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.430746][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101560.430794][12733:12735] CHIP:CTL: Sent root certificate to the device [1708101560.431323][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101560.506550][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.604780][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.606660][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101560.606890][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.607185][12733:12735] CHIP:EM: >>> [E:47763i S:63037 M:138661803] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.607266][12733:12735] CHIP:EM: Found matching exchange: 47763i, Delegate: 0xffffb003dac8 [1708101560.607349][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101560.607450][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101560.607506][12733:12735] CHIP:DMG: { [1708101560.607561][12733:12735] CHIP:DMG: suppressResponse = false, [1708101560.607620][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101560.607694][12733:12735] CHIP:DMG: [ [1708101560.607751][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101560.607828][12733:12735] CHIP:DMG: { [1708101560.607889][12733:12735] CHIP:DMG: CommandStatusIB = [1708101560.607961][12733:12735] CHIP:DMG: { [1708101560.608069][12733:12735] CHIP:DMG: CommandPathIB = [1708101560.608152][12733:12735] CHIP:DMG: { [1708101560.608230][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101560.608309][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101560.608390][12733:12735] CHIP:DMG: CommandId = 0xb, [1708101560.608464][12733:12735] CHIP:DMG: }, [1708101560.608551][12733:12735] CHIP:DMG: [1708101560.608619][12733:12735] CHIP:DMG: StatusIB = [1708101560.608694][12733:12735] CHIP:DMG: { [1708101560.608770][12733:12735] CHIP:DMG: status = 0x00 (SUCCESS), [1708101560.608847][12733:12735] CHIP:DMG: }, [1708101560.608921][12733:12735] CHIP:DMG: [1708101560.608988][12733:12735] CHIP:DMG: }, [1708101560.609065][12733:12735] CHIP:DMG: [1708101560.609125][12733:12735] CHIP:DMG: }, [1708101560.609196][12733:12735] CHIP:DMG: [1708101560.609252][12733:12735] CHIP:DMG: ], [1708101560.609323][12733:12735] CHIP:DMG: [1708101560.609377][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101560.609436][12733:12735] CHIP:DMG: }, [1708101560.609583][12733:12735] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1708101560.609650][12733:12735] CHIP:CTL: Device confirmed that it has received the root certificate [1708101560.609714][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1708101560.609767][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1708101560.609829][12733:12735] CHIP:CTL: Performing next commissioning step 'SendNOC' [1708101560.609966][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101560.610050][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101560.610312][12733:12735] CHIP:EM: <<< [E:47764i S:63037 M:156344660] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.611065][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101560.611143][12733:12735] CHIP:CTL: Sent operational certificate to the device [1708101560.611286][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101560.751143][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.848595][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.945808][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101560.947848][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101560.948136][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101560.948429][12733:12735] CHIP:EM: >>> [E:47764i S:63037 M:138661804] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101560.948508][12733:12735] CHIP:EM: Found matching exchange: 47764i, Delegate: 0xffffb0013a98 [1708101560.948587][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101560.948688][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101560.948744][12733:12735] CHIP:DMG: { [1708101560.948799][12733:12735] CHIP:DMG: suppressResponse = false, [1708101560.948857][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101560.948929][12733:12735] CHIP:DMG: [ [1708101560.948986][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101560.949062][12733:12735] CHIP:DMG: { [1708101560.949123][12733:12735] CHIP:DMG: CommandDataIB = [1708101560.949201][12733:12735] CHIP:DMG: { [1708101560.949267][12733:12735] CHIP:DMG: CommandPathIB = [1708101560.949342][12733:12735] CHIP:DMG: { [1708101560.949418][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101560.949498][12733:12735] CHIP:DMG: ClusterId = 0x3e, [1708101560.949572][12733:12735] CHIP:DMG: CommandId = 0x8, [1708101560.949647][12733:12735] CHIP:DMG: }, [1708101560.949725][12733:12735] CHIP:DMG: [1708101560.949791][12733:12735] CHIP:DMG: CommandFields = [1708101560.949864][12733:12735] CHIP:DMG: { [1708101560.949942][12733:12735] CHIP:DMG: 0x0 = 0, [1708101560.950018][12733:12735] CHIP:DMG: 0x1 = 1, [1708101560.950096][12733:12735] CHIP:DMG: }, [1708101560.950167][12733:12735] CHIP:DMG: }, [1708101560.950242][12733:12735] CHIP:DMG: [1708101560.950301][12733:12735] CHIP:DMG: }, [1708101560.950373][12733:12735] CHIP:DMG: [1708101560.950428][12733:12735] CHIP:DMG: ], [1708101560.950500][12733:12735] CHIP:DMG: [1708101560.950555][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101560.950610][12733:12735] CHIP:DMG: }, [1708101560.950764][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1708101560.950901][12733:12735] CHIP:CTL: Device returned status 0 on receiving the NOC [1708101560.950959][12733:12735] CHIP:CTL: Operational credentials provisioned on device 0xffffb0036d10 [1708101560.951009][12733:12735] CHIP:TOO: Secure Pairing Success [1708101560.951055][12733:12735] CHIP:TOO: CASE establishment successful [1708101560.951111][12733:12735] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1708101560.951166][12733:12735] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1708101560.951217][12733:12735] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'WiFiNetworkSetup' [1708101560.951275][12733:12735] CHIP:CTL: Performing next commissioning step 'WiFiNetworkSetup' [1708101560.951407][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101560.951483][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101560.951680][12733:12735] CHIP:EM: <<< [E:47765i S:63037 M:156344661] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101560.952485][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101560.952651][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101561.044068][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101561.045994][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101561.046227][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101561.046518][12733:12735] CHIP:EM: >>> [E:47765i S:63037 M:138661805] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.046596][12733:12735] CHIP:EM: Found matching exchange: 47765i, Delegate: 0xffffb003dac8 [1708101561.046677][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101561.046776][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101561.046832][12733:12735] CHIP:DMG: { [1708101561.046882][12733:12735] CHIP:DMG: suppressResponse = false, [1708101561.046940][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101561.047009][12733:12735] CHIP:DMG: [ [1708101561.047065][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101561.047142][12733:12735] CHIP:DMG: { [1708101561.047203][12733:12735] CHIP:DMG: CommandDataIB = [1708101561.047271][12733:12735] CHIP:DMG: { [1708101561.047335][12733:12735] CHIP:DMG: CommandPathIB = [1708101561.047422][12733:12735] CHIP:DMG: { [1708101561.047498][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101561.047578][12733:12735] CHIP:DMG: ClusterId = 0x31, [1708101561.047658][12733:12735] CHIP:DMG: CommandId = 0x5, [1708101561.047736][12733:12735] CHIP:DMG: }, [1708101561.047812][12733:12735] CHIP:DMG: [1708101561.047879][12733:12735] CHIP:DMG: CommandFields = [1708101561.047953][12733:12735] CHIP:DMG: { [1708101561.048081][12733:12735] CHIP:DMG: 0x0 = 0, [1708101561.048160][12733:12735] CHIP:DMG: 0x2 = 0, [1708101561.048238][12733:12735] CHIP:DMG: }, [1708101561.048305][12733:12735] CHIP:DMG: }, [1708101561.048380][12733:12735] CHIP:DMG: [1708101561.048440][12733:12735] CHIP:DMG: }, [1708101561.048512][12733:12735] CHIP:DMG: [1708101561.048568][12733:12735] CHIP:DMG: ], [1708101561.048641][12733:12735] CHIP:DMG: [1708101561.048696][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101561.048747][12733:12735] CHIP:DMG: }, [1708101561.048901][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1708101561.048992][12733:12735] CHIP:CTL: Received NetworkConfig response, networkingStatus=0 [1708101561.049061][12733:12735] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkSetup' [1708101561.049114][12733:12735] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkSetup' -> 'FailsafeBeforeWiFiEnable' [1708101561.049174][12733:12735] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeWiFiEnable' [1708101561.049249][12733:12735] CHIP:CTL: Arming failsafe (69 seconds) [1708101561.049378][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101561.049449][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101561.049644][12733:12735] CHIP:EM: <<< [E:47766i S:63037 M:156344662] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101561.050422][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101561.050583][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101561.141572][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101561.143340][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101561.143540][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101561.143780][12733:12735] CHIP:EM: >>> [E:47766i S:63037 M:138661806] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.143841][12733:12735] CHIP:EM: Found matching exchange: 47766i, Delegate: 0xffffb0013a98 [1708101561.143901][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101561.143978][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101561.144049][12733:12735] CHIP:DMG: { [1708101561.144089][12733:12735] CHIP:DMG: suppressResponse = false, [1708101561.144132][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101561.144187][12733:12735] CHIP:DMG: [ [1708101561.144229][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101561.144288][12733:12735] CHIP:DMG: { [1708101561.144333][12733:12735] CHIP:DMG: CommandDataIB = [1708101561.144391][12733:12735] CHIP:DMG: { [1708101561.144441][12733:12735] CHIP:DMG: CommandPathIB = [1708101561.144497][12733:12735] CHIP:DMG: { [1708101561.144557][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101561.144618][12733:12735] CHIP:DMG: ClusterId = 0x30, [1708101561.144677][12733:12735] CHIP:DMG: CommandId = 0x1, [1708101561.144730][12733:12735] CHIP:DMG: }, [1708101561.144788][12733:12735] CHIP:DMG: [1708101561.144840][12733:12735] CHIP:DMG: CommandFields = [1708101561.144895][12733:12735] CHIP:DMG: { [1708101561.144953][12733:12735] CHIP:DMG: 0x0 = 0, [1708101561.145015][12733:12735] CHIP:DMG: 0x1 = "" (0 chars), [1708101561.145071][12733:12735] CHIP:DMG: }, [1708101561.145122][12733:12735] CHIP:DMG: }, [1708101561.145180][12733:12735] CHIP:DMG: [1708101561.145225][12733:12735] CHIP:DMG: }, [1708101561.145282][12733:12735] CHIP:DMG: [1708101561.145324][12733:12735] CHIP:DMG: ], [1708101561.145378][12733:12735] CHIP:DMG: [1708101561.145418][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101561.145459][12733:12735] CHIP:DMG: }, [1708101561.145576][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1708101561.145643][12733:12735] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1708101561.145697][12733:12735] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeWiFiEnable' [1708101561.145736][12733:12735] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeWiFiEnable' -> 'WiFiNetworkEnable' [1708101561.145776][12733:12735] CHIP:CTL: Setting wifi connection time min = 20 [1708101561.145819][12733:12735] CHIP:CTL: Performing next commissioning step 'WiFiNetworkEnable' [1708101561.145864][12733:12735] CHIP:CTL: SendCommand kWiFiNetworkEnable, supportsConcurrentConnection=1 [1708101561.145969][12733:12735] CHIP:DMG: ICR moving to [AddingComm] [1708101561.146025][12733:12735] CHIP:DMG: ICR moving to [AddedComma] [1708101561.146184][12733:12735] CHIP:EM: <<< [E:47767i S:63037 M:156344663] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1708101561.146791][12733:12735] CHIP:DMG: ICR moving to [AwaitingRe] [1708101561.146915][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101561.238790][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1708101561.240887][12733:12734] CHIP:DL: Indication received, conn = 0xffffa802f010 [1708101561.241101][12733:12735] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1708101561.241361][12733:12735] CHIP:EM: >>> [E:47767i S:63037 M:138661807] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1708101561.241429][12733:12735] CHIP:EM: Found matching exchange: 47767i, Delegate: 0xffffb003dac8 [1708101561.241500][12733:12735] CHIP:DMG: ICR moving to [ResponseRe] [1708101561.241586][12733:12735] CHIP:DMG: InvokeResponseMessage = [1708101561.241636][12733:12735] CHIP:DMG: { [1708101561.241681][12733:12735] CHIP:DMG: suppressResponse = false, [1708101561.241730][12733:12735] CHIP:DMG: InvokeResponseIBs = [1708101561.241793][12733:12735] CHIP:DMG: [ [1708101561.241841][12733:12735] CHIP:DMG: InvokeResponseIB = [1708101561.241906][12733:12735] CHIP:DMG: { [1708101561.241958][12733:12735] CHIP:DMG: CommandDataIB = [1708101561.242026][12733:12735] CHIP:DMG: { [1708101561.242081][12733:12735] CHIP:DMG: CommandPathIB = [1708101561.242142][12733:12735] CHIP:DMG: { [1708101561.242203][12733:12735] CHIP:DMG: EndpointId = 0x0, [1708101561.242271][12733:12735] CHIP:DMG: ClusterId = 0x31, [1708101561.242338][12733:12735] CHIP:DMG: CommandId = 0x7, [1708101561.242403][12733:12735] CHIP:DMG: }, [1708101561.242470][12733:12735] CHIP:DMG: [1708101561.242527][12733:12735] CHIP:DMG: CommandFields = [1708101561.242590][12733:12735] CHIP:DMG: { [1708101561.242658][12733:12735] CHIP:DMG: 0x0 = 12, [1708101561.242727][12733:12735] CHIP:DMG: 0x2 = 0, [1708101561.242795][12733:12735] CHIP:DMG: }, [1708101561.242855][12733:12735] CHIP:DMG: }, [1708101561.242918][12733:12735] CHIP:DMG: [1708101561.242969][12733:12735] CHIP:DMG: }, [1708101561.243031][12733:12735] CHIP:DMG: [1708101561.243078][12733:12735] CHIP:DMG: ], [1708101561.243139][12733:12735] CHIP:DMG: [1708101561.243186][12733:12735] CHIP:DMG: InteractionModelRevision = 11 [1708101561.243233][12733:12735] CHIP:DMG: }, [1708101561.243369][12733:12735] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1708101561.243494][12733:12735] CHIP:CTL: Received ConnectNetwork response, networkingStatus=12 [1708101561.243573][12733:12735] CHIP:CTL: Error on commissioning step 'WiFiNetworkEnable': 'src/controller/CHIPDeviceController.cpp:2464: CHIP Error 0x000000AC: Internal error' [1708101561.243620][12733:12735] CHIP:CTL: Failed to perform commissioning step 27 [1708101561.243674][12733:12735] CHIP:CTL: Going from commissioning step 'WiFiNetworkEnable' with lastErr = 'src/controller/CHIPDeviceController.cpp:2464: CHIP Error 0x000000AC: Internal error' -> 'Cleanup' [1708101561.243736][12733:12735] CHIP:CTL: Performing next commissioning step 'Cleanup' with completion status = 'src/controller/CHIPDeviceController.cpp:2464: CHIP Error 0x000000AC: Internal error' [1708101561.243786][12733:12735] CHIP:CTL: Successfully finished commissioning step 'Cleanup' [1708101561.243846][12733:12735] CHIP:TOO: Device commissioning Failure: src/controller/CHIPDeviceController.cpp:2464: CHIP Error 0x000000AC: Internal error [1708101561.243983][12733:12735] CHIP:DMG: ICR moving to [AwaitingDe] [1708101561.244458][12733:12733] CHIP:CTL: Shutting down the commissioner [1708101561.244647][12733:12733] CHIP:DIS: Closing all BLE connections [1708101561.244701][12733:12733] CHIP:IN: Clearing BLE pending packets. [1708101561.245257][12733:12733] CHIP:BLE: Auto-closing end point's BLE connection. [1708101561.245307][12733:12733] CHIP:DL: Closing BLE GATT connection (con 0xffffa802f010) [1708101561.245466][12733:12734] CHIP:DL: BluezDisconnect peer=DC:A6:32:98:8C:EC [1708101563.775269][12733:12733] CHIP:IN: SecureSession[0xffffb0037cf0]: MarkForEviction Type:1 LSID:63037 [1708101563.775363][12733:12733] CHIP:SC: SecureSession[0xffffb0037cf0, LSID:63037]: State change 'kActive' --> 'kPendingEviction' [1708101563.775499][12733:12733] CHIP:IN: SecureSession[0xffffb0037cf0]: Released - Type:1 LSID:63037 [1708101563.775581][12733:12733] CHIP:CTL: Shutting down the controller [1708101563.775645][12733:12733] CHIP:IN: Expiring all sessions for fabric 0x1!! [1708101563.775696][12733:12733] CHIP:FP: Forgetting fabric 0x1 [1708101563.775768][12733:12733] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1708101563.776170][12733:12733] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1708101563.776269][12733:12733] CHIP:TS: Reverted Last Known Good Time to previous value [1708101563.776171][12733:12734] CHIP:DL: Bluez disconnected [1708101563.776441][12733:12734] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1708101563.776373][12733:12733] CHIP:CTL: Shutting down the commissioner [1708101563.776751][12733:12733] CHIP:CTL: Shutting down the controller [1708101563.776831][12733:12733] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1708101563.777170][12733:12733] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1708101563.777359][12733:12733] CHIP:FP: Shutting down FabricTable [1708101563.777438][12733:12733] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1708101563.777672][12733:12733] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1708101563.777764][12733:12733] CHIP:TS: Reverted Last Known Good Time to previous value [1708101563.778290][12733:12733] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-ONFMKd) [1708101563.780905][12733:12733] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1708101563.781028][12733:12733] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1708101563.781067][12733:12733] CHIP:DL: Inet Layer shutdown [1708101563.781097][12733:12733] CHIP:DL: BLE shutdown [1708101563.784823][12733:12733] CHIP:DL: System Layer shutdown [1708101563.785570][12733:12733] CHIP:TOO: Run command failure: src/controller/CHIPDeviceController.cpp:2464: CHIP Error 0x000000AC: Internal error