[1706278697.228340][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_tool_kvs [1706278697.231004][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1706278697.231046][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Attempt to re-initialize with KVS config file: /tmp/chip_kvs [1706278697.247995][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1706278697.248500][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1706278697.248735][22262:22262] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1706278697.249422][22262:22262] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-Brb9sL) [1706278697.250041][22262:22262] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1706278697.250131][22262:22262] CHIP:DL: NVS set: chip-counters/reboot-count = 129 (0x81) [1706278697.251999][22262:22262] CHIP:DL: Got Ethernet interface: eth0 [1706278697.252723][22262:22262] CHIP:DL: Found the primary Ethernet interface:eth0 [1706278697.253349][22262:22262] CHIP:DL: Got WiFi interface: wlan0 [1706278697.253422][22262:22262] CHIP:DL: Failed to reset WiFi statistic counts [1706278697.253503][22262:22262] CHIP:IN: UDP::Init bind&listen port=0 [1706278697.253666][22262:22262] CHIP:IN: UDP::Init bound to port=46247 [1706278697.253706][22262:22262] CHIP:IN: UDP::Init bind&listen port=0 [1706278697.253795][22262:22262] CHIP:IN: UDP::Init bound to port=42132 [1706278697.253825][22262:22262] CHIP:IN: BLEBase::Init - setting/overriding transport [1706278697.253843][22262:22262] CHIP:IN: TransportMgr initialized [1706278697.253964][22262:22262] CHIP:FP: Initializing FabricTable from persistent storage [1706278697.254461][22262:22262] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1706278697.259243][22262:22262] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x7EEEC0CBDA802E76, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1706278697.262593][22262:22262] CHIP:ZCL: Using ZAP configuration... [1706278697.282227][22262:22262] CHIP:CTL: System State Initialized... [1706278697.282621][22262:22262] CHIP:CTL: Setting attestation nonce to random value [1706278697.282702][22262:22262] CHIP:CTL: Setting CSR nonce to random value [1706278697.282889][22262:22262] CHIP:IN: UDP::Init bind&listen port=5550 [1706278697.283021][22262:22262] CHIP:IN: UDP::Init bound to port=5550 [1706278697.283070][22262:22262] CHIP:IN: UDP::Init bind&listen port=5550 [1706278697.283165][22262:22262] CHIP:IN: UDP::Init bound to port=5550 [1706278697.283185][22262:22262] CHIP:IN: TransportMgr initialized [1706278697.283809][22262:22266] CHIP:DL: CHIP task running [1706278697.283964][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 32786 [1706278697.284693][22262:22266] CHIP:CTL: Setting attestation nonce to random value [1706278697.284971][22262:22266] CHIP:CTL: Setting CSR nonce to random value [1706278697.288526][22262:22266] CHIP:CTL: Generating NOC [1706278697.291700][22262:22266] CHIP:FP: Validating NOC chain [1706278697.296050][22262:22266] CHIP:FP: NOC chain validation successful [1706278697.296949][22262:22266] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1706278697.297000][22262:22266] CHIP:TS: Last Known Good Time: 2023-10-14T01:16:48 [1706278697.297021][22262:22266] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1706278697.297035][22262:22266] CHIP:TS: Retaining current Last Known Good Time [1706278697.299988][22262:22266] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1706278697.302669][22262:22266] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-14T01:16:48 [1706278697.305260][22262:22266] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 7EEEC0CBDA802E76) [1706278697.305339][22262:22266] CHIP:IN: UDP::Init bind&listen port=5550 [1706278697.305475][22262:22266] CHIP:IN: UDP::Init bound to port=5550 [1706278697.305504][22262:22266] CHIP:IN: UDP::Init bind&listen port=5550 [1706278697.305590][22262:22266] CHIP:IN: UDP::Init bound to port=5550 [1706278697.305609][22262:22266] CHIP:IN: TransportMgr initialized [1706278697.346583][22262:22266] CHIP:CTL: Setting wifi credentials from parameters [1706278697.346633][22262:22266] CHIP:CTL: Setting attestation nonce to random value [1706278697.346684][22262:22266] CHIP:CTL: Setting CSR nonce to random value [1706278697.346720][22262:22266] CHIP:CTL: Checking ICD registration parameters [1706278697.346807][22262:22266] CHIP:CTL: Commission called for node ID 0x00000000000007CE [1706278697.354600][22262:22265] CHIP:DL: TRACE: Bus acquired for name C-56f6 [1706278697.393460][22262:22266] CHIP:DL: BlueZ integration init success [1706278697.423234][22262:22265] CHIP:BLE: BLE removing known devices. [1706278699.762476][22262:22265] CHIP:BLE: BLE initiating scan. [1706278699.794910][22262:22266] CHIP:BLE: ChipDeviceScanner has started scanning! [1706278699.795018][22262:22266] CHIP:DL: Long dispatch time: 2448 ms, for event type 2 [1706278699.795460][22262:22265] CHIP:BLE: New device scanned: 0A:62:24:06:50:8D [1706278699.795540][22262:22265] CHIP:BLE: Device discriminator match. Attempting to connect. [1706278699.803761][22262:22265] CHIP:BLE: ChipDeviceScanner has stopped scanning! [1706278701.386057][22262:22265] CHIP:DL: ConnectDevice complete [1706278702.747479][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0012 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747538][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0012 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747561][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747579][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747599][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747615][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747633][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747648][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747669][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747687][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747711][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747726][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0008 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747744][22262:22265] CHIP:DL: Char /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 on service /org/bluez/hci0/dev_0A_62_24_06_50_8D/service0015 [1706278702.747806][22262:22265] CHIP:DL: New BLE connection: conn 0xffff8ce17010, device 0A:62:24:06:50:8D, path /org/bluez/hci0/dev_0A_62_24_06_50_8D [1706278702.748002][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1706278702.748075][22262:22266] CHIP:DIS: Closing all BLE connections [1706278702.748213][22262:22266] CHIP:IN: BleConnectionComplete: endPoint 0xaaaaef1c94c0 [1706278702.749131][22262:22266] CHIP:IN: SecureSession[0xffff84c1fb40]: Allocated Type:1 LSID:6381 [1706278702.749205][22262:22266] CHIP:SC: Assigned local session key ID 6381 [1706278702.749522][22262:22266] CHIP:EM: <<< [E:17675i S:0 M:221639108] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1706278702.749600][22262:22266] CHIP:IN: Message appended to BLE send queue [1706278702.749625][22262:22266] CHIP:SC: Sent PBKDF param request [1706278703.003051][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278703.091822][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1706278703.091911][22262:22266] CHIP:BLE: subscribe complete, ep = 0xaaaaef1c94c0 [1706278703.092553][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278703.092899][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278703.092986][22262:22266] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1706278703.093040][22262:22266] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1706278703.093077][22262:22266] CHIP:BLE: local and remote recv window size = 6 [1706278703.094640][22262:22266] CHIP:IN: BLE EndPoint 0xaaaaef1c94c0 Connection Complete [1706278703.237192][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278703.286828][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278703.287129][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278703.287511][22262:22266] CHIP:EM: >>> [E:17675i S:0 M:190944763] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1706278703.287589][22262:22266] CHIP:EM: Found matching exchange: 17675i, Delegate: 0xffff8ba564d0 [1706278703.287731][22262:22266] CHIP:SC: Received PBKDF param response [1706278703.287856][22262:22266] CHIP:SC: Peer assigned session ID 39053 [1706278703.287947][22262:22266] CHIP:SC: Found MRP parameters in the message [1706278703.322813][22262:22266] CHIP:EM: <<< [E:17675i S:0 M:221639109] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1706278703.323606][22262:22266] CHIP:SC: Sent spake2p msg1 [1706278703.432464][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278703.481703][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278703.482133][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278703.482655][22262:22266] CHIP:EM: >>> [E:17675i S:0 M:190944764] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1706278703.482805][22262:22266] CHIP:EM: Found matching exchange: 17675i, Delegate: 0xffff8ba564d0 [1706278703.482945][22262:22266] CHIP:SC: Received spake2p msg2 [1706278703.487698][22262:22266] CHIP:EM: <<< [E:17675i S:0 M:221639110] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1706278703.489328][22262:22266] CHIP:SC: Sent spake2p msg3 [1706278703.627537][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278703.628819][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278703.629173][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278703.629638][22262:22266] CHIP:EM: >>> [E:17675i S:0 M:190944765] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1706278703.629860][22262:22266] CHIP:EM: Found matching exchange: 17675i, Delegate: 0xffff8ba564d0 [1706278703.631126][22262:22266] CHIP:SC: SecureSession[0xffff84c1fb40, LSID:6381]: State change 'kEstablishing' --> 'kActive' [1706278703.631212][22262:22266] CHIP:IN: SecureSession[0xffff84c1fb40]: Activated - Type:1 LSID:6381 [1706278703.631260][22262:22266] CHIP:IN: New secure session activated for device , LSID:6381 PSID:39053! [1706278703.631329][22262:22266] CHIP:CTL: Remote device completed SPAKE2+ handshake [1706278703.631371][22262:22266] CHIP:TOO: Pairing Success [1706278703.631447][22262:22266] CHIP:TOO: PASE establishment successful [1706278703.631548][22262:22266] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1706278703.631664][22262:22266] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1706278703.631762][22262:22266] CHIP:CTL: Sending read request for commissioning information [1706278703.631936][22262:22266] CHIP:DMG: SendReadRequest ReadClient[0xffff8481b080]: Sending Read Request [1706278703.633013][22262:22266] CHIP:EM: <<< [E:17676i S:6381 M:68287843] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1706278703.634564][22262:22266] CHIP:DMG: MoveToState ReadClient[0xffff8481b080]: Moving to [AwaitingIn] [1706278703.822197][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278703.919804][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278703.920182][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.017207][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.017515][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.017923][22262:22266] CHIP:EM: >>> [E:17676i S:6381 M:362498] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1706278704.017996][22262:22266] CHIP:EM: Found matching exchange: 17676i, Delegate: 0xffff8481b090 [1706278704.018359][22262:22266] CHIP:DMG: ReportDataMessage = [1706278704.018414][22262:22266] CHIP:DMG: { [1706278704.018466][22262:22266] CHIP:DMG: AttributeReportIBs = [1706278704.018528][22262:22266] CHIP:DMG: [ [1706278704.018561][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.018619][22262:22266] CHIP:DMG: { [1706278704.018651][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.018689][22262:22266] CHIP:DMG: { [1706278704.018740][22262:22266] CHIP:DMG: DataVersion = 0x2ad42799, [1706278704.018817][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.018857][22262:22266] CHIP:DMG: { [1706278704.018905][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.018940][22262:22266] CHIP:DMG: Cluster = 0x31, [1706278704.018975][22262:22266] CHIP:DMG: Attribute = 0x0000_0003, [1706278704.019002][22262:22266] CHIP:DMG: } [1706278704.019046][22262:22266] CHIP:DMG: [1706278704.019088][22262:22266] CHIP:DMG: Data = 20, [1706278704.019115][22262:22266] CHIP:DMG: }, [1706278704.019160][22262:22266] CHIP:DMG: [1706278704.019187][22262:22266] CHIP:DMG: }, [1706278704.019259][22262:22266] CHIP:DMG: [1706278704.019288][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.019337][22262:22266] CHIP:DMG: { [1706278704.019367][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.019401][22262:22266] CHIP:DMG: { [1706278704.019439][22262:22266] CHIP:DMG: DataVersion = 0x879127cd, [1706278704.019467][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.019500][22262:22266] CHIP:DMG: { [1706278704.019535][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.019569][22262:22266] CHIP:DMG: Cluster = 0x28, [1706278704.019602][22262:22266] CHIP:DMG: Attribute = 0x0000_0004, [1706278704.019630][22262:22266] CHIP:DMG: } [1706278704.019669][22262:22266] CHIP:DMG: [1706278704.019708][22262:22266] CHIP:DMG: Data = 32769, [1706278704.019735][22262:22266] CHIP:DMG: }, [1706278704.019777][22262:22266] CHIP:DMG: [1706278704.019804][22262:22266] CHIP:DMG: }, [1706278704.019871][22262:22266] CHIP:DMG: [1706278704.019900][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.019950][22262:22266] CHIP:DMG: { [1706278704.019979][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.020009][22262:22266] CHIP:DMG: { [1706278704.020046][22262:22266] CHIP:DMG: DataVersion = 0x879127cd, [1706278704.020074][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.020109][22262:22266] CHIP:DMG: { [1706278704.020144][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.020178][22262:22266] CHIP:DMG: Cluster = 0x28, [1706278704.020211][22262:22266] CHIP:DMG: Attribute = 0x0000_0002, [1706278704.020238][22262:22266] CHIP:DMG: } [1706278704.020278][22262:22266] CHIP:DMG: [1706278704.020314][22262:22266] CHIP:DMG: Data = 65521, [1706278704.020340][22262:22266] CHIP:DMG: }, [1706278704.020383][22262:22266] CHIP:DMG: [1706278704.020410][22262:22266] CHIP:DMG: }, [1706278704.020476][22262:22266] CHIP:DMG: [1706278704.020505][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.020555][22262:22266] CHIP:DMG: { [1706278704.020588][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.020619][22262:22266] CHIP:DMG: { [1706278704.020656][22262:22266] CHIP:DMG: DataVersion = 0x3d182cd5, [1706278704.020684][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.020716][22262:22266] CHIP:DMG: { [1706278704.020750][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.020784][22262:22266] CHIP:DMG: Cluster = 0x30, [1706278704.020817][22262:22266] CHIP:DMG: Attribute = 0x0000_0003, [1706278704.020844][22262:22266] CHIP:DMG: } [1706278704.020882][22262:22266] CHIP:DMG: [1706278704.020919][22262:22266] CHIP:DMG: Data = 2, [1706278704.020945][22262:22266] CHIP:DMG: }, [1706278704.020987][22262:22266] CHIP:DMG: [1706278704.021014][22262:22266] CHIP:DMG: }, [1706278704.021080][22262:22266] CHIP:DMG: [1706278704.021109][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.021158][22262:22266] CHIP:DMG: { [1706278704.021187][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.021218][22262:22266] CHIP:DMG: { [1706278704.021254][22262:22266] CHIP:DMG: DataVersion = 0x3d182cd5, [1706278704.021285][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.021318][22262:22266] CHIP:DMG: { [1706278704.021353][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.021386][22262:22266] CHIP:DMG: Cluster = 0x30, [1706278704.021420][22262:22266] CHIP:DMG: Attribute = 0x0000_0002, [1706278704.021447][22262:22266] CHIP:DMG: } [1706278704.021486][22262:22266] CHIP:DMG: [1706278704.021522][22262:22266] CHIP:DMG: Data = 0, [1706278704.021548][22262:22266] CHIP:DMG: }, [1706278704.021591][22262:22266] CHIP:DMG: [1706278704.021618][22262:22266] CHIP:DMG: }, [1706278704.021689][22262:22266] CHIP:DMG: [1706278704.021718][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.021771][22262:22266] CHIP:DMG: { [1706278704.021801][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.021832][22262:22266] CHIP:DMG: { [1706278704.021868][22262:22266] CHIP:DMG: DataVersion = 0x3d182cd5, [1706278704.021896][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.021928][22262:22266] CHIP:DMG: { [1706278704.021963][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.021996][22262:22266] CHIP:DMG: Cluster = 0x30, [1706278704.022033][22262:22266] CHIP:DMG: Attribute = 0x0000_0001, [1706278704.022060][22262:22266] CHIP:DMG: } [1706278704.022098][22262:22266] CHIP:DMG: [1706278704.022128][22262:22266] CHIP:DMG: Data = [1706278704.022160][22262:22266] CHIP:DMG: { [1706278704.022199][22262:22266] CHIP:DMG: 0x0 = 60, [1706278704.022234][22262:22266] CHIP:DMG: 0x1 = 900, [1706278704.022268][22262:22266] CHIP:DMG: }, [1706278704.022293][22262:22266] CHIP:DMG: }, [1706278704.022340][22262:22266] CHIP:DMG: [1706278704.022367][22262:22266] CHIP:DMG: }, [1706278704.022439][22262:22266] CHIP:DMG: [1706278704.022467][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.022516][22262:22266] CHIP:DMG: { [1706278704.022545][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.022576][22262:22266] CHIP:DMG: { [1706278704.022612][22262:22266] CHIP:DMG: DataVersion = 0x3d182cd5, [1706278704.022640][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.022672][22262:22266] CHIP:DMG: { [1706278704.022708][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.022741][22262:22266] CHIP:DMG: Cluster = 0x30, [1706278704.022804][22262:22266] CHIP:DMG: Attribute = 0x0000_0000, [1706278704.022832][22262:22266] CHIP:DMG: } [1706278704.022874][22262:22266] CHIP:DMG: [1706278704.022913][22262:22266] CHIP:DMG: Data = 0, [1706278704.022940][22262:22266] CHIP:DMG: }, [1706278704.022983][22262:22266] CHIP:DMG: [1706278704.023011][22262:22266] CHIP:DMG: }, [1706278704.023080][22262:22266] CHIP:DMG: [1706278704.023109][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.023158][22262:22266] CHIP:DMG: { [1706278704.023188][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.023219][22262:22266] CHIP:DMG: { [1706278704.023256][22262:22266] CHIP:DMG: DataVersion = 0x2ad42799, [1706278704.023284][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.023316][22262:22266] CHIP:DMG: { [1706278704.023351][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.023385][22262:22266] CHIP:DMG: Cluster = 0x31, [1706278704.023418][22262:22266] CHIP:DMG: Attribute = 0x0000_FFFC, [1706278704.023446][22262:22266] CHIP:DMG: } [1706278704.023485][22262:22266] CHIP:DMG: [1706278704.023521][22262:22266] CHIP:DMG: Data = 1, [1706278704.023547][22262:22266] CHIP:DMG: }, [1706278704.023590][22262:22266] CHIP:DMG: [1706278704.023616][22262:22266] CHIP:DMG: }, [1706278704.023664][22262:22266] CHIP:DMG: [1706278704.023691][22262:22266] CHIP:DMG: ], [1706278704.023859][22262:22266] CHIP:DMG: [1706278704.023895][22262:22266] CHIP:DMG: SuppressResponse = true, [1706278704.023926][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278704.023951][22262:22266] CHIP:DMG: } [1706278704.026706][22262:22266] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1706278704.026901][22262:22266] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ReadCommissioningInfo2' [1706278704.026971][22262:22266] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo2' [1706278704.027106][22262:22266] CHIP:DMG: SendReadRequest ReadClient[0xffff8481aec0]: Sending Read Request [1706278704.027599][22262:22266] CHIP:EM: <<< [E:17677i S:6381 M:68287844] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:02 (IM:ReadRequest) [1706278704.028756][22262:22266] CHIP:DMG: MoveToState ReadClient[0xffff8481aec0]: Moving to [AwaitingIn] [1706278704.163646][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278704.213088][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.213463][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.214018][22262:22266] CHIP:EM: >>> [E:17677i S:6381 M:362499] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1706278704.214117][22262:22266] CHIP:EM: Found matching exchange: 17677i, Delegate: 0xffff8481aed0 [1706278704.214430][22262:22266] CHIP:DMG: ReportDataMessage = [1706278704.214503][22262:22266] CHIP:DMG: { [1706278704.214555][22262:22266] CHIP:DMG: AttributeReportIBs = [1706278704.214642][22262:22266] CHIP:DMG: [ [1706278704.214690][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.214820][22262:22266] CHIP:DMG: { [1706278704.214863][22262:22266] CHIP:DMG: AttributeStatusIB = [1706278704.214908][22262:22266] CHIP:DMG: { [1706278704.215121][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.215174][22262:22266] CHIP:DMG: { [1706278704.215223][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.215263][22262:22266] CHIP:DMG: Cluster = 0x46, [1706278704.215301][22262:22266] CHIP:DMG: Attribute = 0x0000_0007, [1706278704.215336][22262:22266] CHIP:DMG: } [1706278704.215399][22262:22266] CHIP:DMG: [1706278704.215433][22262:22266] CHIP:DMG: StatusIB = [1706278704.215472][22262:22266] CHIP:DMG: { [1706278704.215518][22262:22266] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1706278704.215549][22262:22266] CHIP:DMG: }, [1706278704.215590][22262:22266] CHIP:DMG: [1706278704.215621][22262:22266] CHIP:DMG: }, [1706278704.215672][22262:22266] CHIP:DMG: [1706278704.215705][22262:22266] CHIP:DMG: }, [1706278704.215786][22262:22266] CHIP:DMG: [1706278704.215819][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.215875][22262:22266] CHIP:DMG: { [1706278704.215910][22262:22266] CHIP:DMG: AttributeStatusIB = [1706278704.215946][22262:22266] CHIP:DMG: { [1706278704.215979][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.216018][22262:22266] CHIP:DMG: { [1706278704.216058][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.216096][22262:22266] CHIP:DMG: Cluster = 0x46, [1706278704.216133][22262:22266] CHIP:DMG: Attribute = 0x0000_0006, [1706278704.216167][22262:22266] CHIP:DMG: } [1706278704.216219][22262:22266] CHIP:DMG: [1706278704.216252][22262:22266] CHIP:DMG: StatusIB = [1706278704.216287][22262:22266] CHIP:DMG: { [1706278704.216326][22262:22266] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1706278704.216356][22262:22266] CHIP:DMG: }, [1706278704.216395][22262:22266] CHIP:DMG: [1706278704.216429][22262:22266] CHIP:DMG: }, [1706278704.216479][22262:22266] CHIP:DMG: [1706278704.216509][22262:22266] CHIP:DMG: }, [1706278704.216587][22262:22266] CHIP:DMG: [1706278704.216619][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.216675][22262:22266] CHIP:DMG: { [1706278704.216710][22262:22266] CHIP:DMG: AttributeStatusIB = [1706278704.216745][22262:22266] CHIP:DMG: { [1706278704.216778][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.216813][22262:22266] CHIP:DMG: { [1706278704.216852][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.216894][22262:22266] CHIP:DMG: Cluster = 0x46, [1706278704.216931][22262:22266] CHIP:DMG: Attribute = 0x0000_FFFC, [1706278704.216961][22262:22266] CHIP:DMG: } [1706278704.217013][22262:22266] CHIP:DMG: [1706278704.217046][22262:22266] CHIP:DMG: StatusIB = [1706278704.217085][22262:22266] CHIP:DMG: { [1706278704.217124][22262:22266] CHIP:DMG: status = 0xc3 (UNSUPPORTED_CLUSTER), [1706278704.217155][22262:22266] CHIP:DMG: }, [1706278704.217193][22262:22266] CHIP:DMG: [1706278704.217224][22262:22266] CHIP:DMG: }, [1706278704.217273][22262:22266] CHIP:DMG: [1706278704.217306][22262:22266] CHIP:DMG: }, [1706278704.217383][22262:22266] CHIP:DMG: [1706278704.217415][22262:22266] CHIP:DMG: AttributeReportIB = [1706278704.217472][22262:22266] CHIP:DMG: { [1706278704.217506][22262:22266] CHIP:DMG: AttributeDataIB = [1706278704.217545][22262:22266] CHIP:DMG: { [1706278704.217587][22262:22266] CHIP:DMG: DataVersion = 0x3d182cd5, [1706278704.217618][22262:22266] CHIP:DMG: AttributePathIB = [1706278704.217653][22262:22266] CHIP:DMG: { [1706278704.217695][22262:22266] CHIP:DMG: Endpoint = 0x0, [1706278704.217733][22262:22266] CHIP:DMG: Cluster = 0x30, [1706278704.217770][22262:22266] CHIP:DMG: Attribute = 0x0000_0004, [1706278704.217800][22262:22266] CHIP:DMG: } [1706278704.217844][22262:22266] CHIP:DMG: [1706278704.217892][22262:22266] CHIP:DMG: Data = true, [1706278704.217922][22262:22266] CHIP:DMG: }, [1706278704.217972][22262:22266] CHIP:DMG: [1706278704.218001][22262:22266] CHIP:DMG: }, [1706278704.218052][22262:22266] CHIP:DMG: [1706278704.218084][22262:22266] CHIP:DMG: ], [1706278704.218200][22262:22266] CHIP:DMG: [1706278704.218239][22262:22266] CHIP:DMG: SuppressResponse = true, [1706278704.218277][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278704.218305][22262:22266] CHIP:DMG: } [1706278704.220223][22262:22266] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid = 0 [1706278704.220382][22262:22266] CHIP:SVR: OnReadCommissioningInfo - vendorId=0xFFF1 productId=0x8001 [1706278704.220458][22262:22266] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo2' [1706278704.220522][22262:22266] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo2' -> 'ArmFailSafe' [1706278704.220594][22262:22266] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1706278704.220649][22262:22266] CHIP:CTL: Arming failsafe (60 seconds) [1706278704.220839][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278704.220927][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278704.221295][22262:22266] CHIP:EM: <<< [E:17678i S:6381 M:68287845] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278704.222527][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278704.358606][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278704.361290][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.361692][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.362383][22262:22266] CHIP:EM: >>> [E:17678i S:6381 M:362500] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278704.362483][22262:22266] CHIP:EM: Found matching exchange: 17678i, Delegate: 0xffff8481ad08 [1706278704.362589][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278704.362742][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278704.362843][22262:22266] CHIP:DMG: { [1706278704.362887][22262:22266] CHIP:DMG: suppressResponse = false, [1706278704.362917][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278704.363021][22262:22266] CHIP:DMG: [ [1706278704.363058][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278704.363124][22262:22266] CHIP:DMG: { [1706278704.363158][22262:22266] CHIP:DMG: CommandDataIB = [1706278704.363199][22262:22266] CHIP:DMG: { [1706278704.363229][22262:22266] CHIP:DMG: CommandPathIB = [1706278704.363338][22262:22266] CHIP:DMG: { [1706278704.363440][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278704.363482][22262:22266] CHIP:DMG: ClusterId = 0x30, [1706278704.363515][22262:22266] CHIP:DMG: CommandId = 0x1, [1706278704.363544][22262:22266] CHIP:DMG: }, [1706278704.363631][22262:22266] CHIP:DMG: [1706278704.363671][22262:22266] CHIP:DMG: CommandFields = [1706278704.363711][22262:22266] CHIP:DMG: { [1706278704.363798][22262:22266] CHIP:DMG: 0x0 = 0, [1706278704.363851][22262:22266] CHIP:DMG: 0x1 = "" (0 chars), [1706278704.363888][22262:22266] CHIP:DMG: }, [1706278704.363918][22262:22266] CHIP:DMG: }, [1706278704.363968][22262:22266] CHIP:DMG: [1706278704.363997][22262:22266] CHIP:DMG: }, [1706278704.364094][22262:22266] CHIP:DMG: [1706278704.364129][22262:22266] CHIP:DMG: ], [1706278704.364236][22262:22266] CHIP:DMG: [1706278704.364281][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278704.364309][22262:22266] CHIP:DMG: }, [1706278704.364567][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1706278704.364728][22262:22266] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1706278704.364829][22262:22266] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1706278704.364869][22262:22266] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1706278704.364929][22262:22266] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1706278704.364963][22262:22266] CHIP:CTL: Setting Regulatory Config [1706278704.365018][22262:22266] CHIP:CTL: No regulatory config supplied by controller, leaving as device default (0) [1706278704.365323][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278704.365429][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278704.365873][22262:22266] CHIP:EM: <<< [E:17679i S:6381 M:68287846] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278704.367039][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278704.367295][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278704.505377][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278704.507436][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.507817][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.509102][22262:22266] CHIP:EM: >>> [E:17679i S:6381 M:362501] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278704.509228][22262:22266] CHIP:EM: Found matching exchange: 17679i, Delegate: 0xffff8481ab48 [1706278704.509355][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278704.509727][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278704.509803][22262:22266] CHIP:DMG: { [1706278704.509880][22262:22266] CHIP:DMG: suppressResponse = false, [1706278704.509930][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278704.510031][22262:22266] CHIP:DMG: [ [1706278704.510080][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278704.510181][22262:22266] CHIP:DMG: { [1706278704.510230][22262:22266] CHIP:DMG: CommandDataIB = [1706278704.510303][22262:22266] CHIP:DMG: { [1706278704.510351][22262:22266] CHIP:DMG: CommandPathIB = [1706278704.510429][22262:22266] CHIP:DMG: { [1706278704.510500][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278704.510558][22262:22266] CHIP:DMG: ClusterId = 0x30, [1706278704.510623][22262:22266] CHIP:DMG: CommandId = 0x3, [1706278704.510664][22262:22266] CHIP:DMG: }, [1706278704.510737][22262:22266] CHIP:DMG: [1706278704.510846][22262:22266] CHIP:DMG: CommandFields = [1706278704.510905][22262:22266] CHIP:DMG: { [1706278704.510968][22262:22266] CHIP:DMG: 0x0 = 0, [1706278704.511036][22262:22266] CHIP:DMG: 0x1 = "" (0 chars), [1706278704.511093][22262:22266] CHIP:DMG: }, [1706278704.511133][22262:22266] CHIP:DMG: }, [1706278704.511213][22262:22266] CHIP:DMG: [1706278704.511254][22262:22266] CHIP:DMG: }, [1706278704.511332][22262:22266] CHIP:DMG: [1706278704.511376][22262:22266] CHIP:DMG: ], [1706278704.511465][22262:22266] CHIP:DMG: [1706278704.511526][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278704.511564][22262:22266] CHIP:DMG: }, [1706278704.511979][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1706278704.512207][22262:22266] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1706278704.512344][22262:22266] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1706278704.512396][22262:22266] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1706278704.512485][22262:22266] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1706278704.512533][22262:22266] CHIP:CTL: Sending request for PAI certificate [1706278704.512586][22262:22266] CHIP:CTL: Sending Certificate Chain request to 0xffff8ba56480 device [1706278704.513073][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278704.513214][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278704.513841][22262:22266] CHIP:EM: <<< [E:17680i S:6381 M:68287847] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278704.515186][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278704.515381][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278704.651293][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278704.748680][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.749061][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.897261][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.897630][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.994043][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278704.994409][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278704.995028][22262:22266] CHIP:EM: >>> [E:17680i S:6381 M:362502] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278704.995131][22262:22266] CHIP:EM: Found matching exchange: 17680i, Delegate: 0xffff8481a988 [1706278704.995245][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278704.995422][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278704.995488][22262:22266] CHIP:DMG: { [1706278704.995546][22262:22266] CHIP:DMG: suppressResponse = false, [1706278704.995594][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278704.995718][22262:22266] CHIP:DMG: [ [1706278704.995771][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278704.995888][22262:22266] CHIP:DMG: { [1706278704.995942][22262:22266] CHIP:DMG: CommandDataIB = [1706278704.996025][22262:22266] CHIP:DMG: { [1706278704.996074][22262:22266] CHIP:DMG: CommandPathIB = [1706278704.996128][22262:22266] CHIP:DMG: { [1706278704.996187][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278704.996239][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278704.996314][22262:22266] CHIP:DMG: CommandId = 0x3, [1706278704.996358][22262:22266] CHIP:DMG: }, [1706278704.996422][22262:22266] CHIP:DMG: [1706278704.996498][22262:22266] CHIP:DMG: CommandFields = [1706278704.996555][22262:22266] CHIP:DMG: { [1706278704.996613][22262:22266] CHIP:DMG: 0x0 = [ [1706278704.997416][22262:22266] 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 [1706278704.997496][22262:22266] CHIP:DMG: ] (463 bytes) [1706278704.997555][22262:22266] CHIP:DMG: }, [1706278704.997596][22262:22266] CHIP:DMG: }, [1706278704.997671][22262:22266] CHIP:DMG: [1706278704.997712][22262:22266] CHIP:DMG: }, [1706278704.997790][22262:22266] CHIP:DMG: [1706278704.997835][22262:22266] CHIP:DMG: ], [1706278704.997925][22262:22266] CHIP:DMG: [1706278704.997977][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278704.998017][22262:22266] CHIP:DMG: }, [1706278704.998372][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1706278704.998536][22262:22266] CHIP:CTL: Received certificate chain from the device [1706278704.998622][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1706278704.998724][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1706278704.998850][22262:22266] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1706278704.998925][22262:22266] CHIP:CTL: Sending request for DAC certificate [1706278704.998972][22262:22266] CHIP:CTL: Sending Certificate Chain request to 0xffff8ba56480 device [1706278704.999294][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278704.999381][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278704.999910][22262:22266] CHIP:EM: <<< [E:17681i S:6381 M:68287848] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278705.001316][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278705.001755][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278705.137924][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278705.236151][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278705.236536][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278705.384944][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278705.385329][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278705.481907][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278705.482301][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278705.482936][22262:22266] CHIP:EM: >>> [E:17681i S:6381 M:362503] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278705.483034][22262:22266] CHIP:EM: Found matching exchange: 17681i, Delegate: 0xffff8481a7c8 [1706278705.483149][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278705.483330][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278705.483379][22262:22266] CHIP:DMG: { [1706278705.483428][22262:22266] CHIP:DMG: suppressResponse = false, [1706278705.483483][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278705.483555][22262:22266] CHIP:DMG: [ [1706278705.483609][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278705.483674][22262:22266] CHIP:DMG: { [1706278705.483711][22262:22266] CHIP:DMG: CommandDataIB = [1706278705.483754][22262:22266] CHIP:DMG: { [1706278705.483809][22262:22266] CHIP:DMG: CommandPathIB = [1706278705.483851][22262:22266] CHIP:DMG: { [1706278705.483896][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278705.483955][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278705.483998][22262:22266] CHIP:DMG: CommandId = 0x3, [1706278705.484028][22262:22266] CHIP:DMG: }, [1706278705.484074][22262:22266] CHIP:DMG: [1706278705.484131][22262:22266] CHIP:DMG: CommandFields = [1706278705.484175][22262:22266] CHIP:DMG: { [1706278705.484217][22262:22266] CHIP:DMG: 0x0 = [ [1706278705.484804][22262:22266] 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 [1706278705.484865][22262:22266] CHIP:DMG: ] (491 bytes) [1706278705.484910][22262:22266] CHIP:DMG: }, [1706278705.484940][22262:22266] CHIP:DMG: }, [1706278705.484997][22262:22266] CHIP:DMG: [1706278705.485031][22262:22266] CHIP:DMG: }, [1706278705.485087][22262:22266] CHIP:DMG: [1706278705.485118][22262:22266] CHIP:DMG: ], [1706278705.485182][22262:22266] CHIP:DMG: [1706278705.485221][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278705.485249][22262:22266] CHIP:DMG: }, [1706278705.485526][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1706278705.485663][22262:22266] CHIP:CTL: Received certificate chain from the device [1706278705.485732][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1706278705.485847][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1706278705.485921][22262:22266] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1706278705.485977][22262:22266] CHIP:CTL: Sending Attestation Request to the device. [1706278705.486025][22262:22266] CHIP:CTL: Sending Attestation request to 0xffff8ba56480 device [1706278705.486245][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278705.486356][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278705.486874][22262:22266] CHIP:EM: <<< [E:17682i S:6381 M:68287849] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278705.488033][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278705.488111][22262:22266] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1706278705.488334][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278705.625986][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278705.723606][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278705.723977][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278705.872264][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278705.872626][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278706.017764][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278706.018131][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278706.018713][22262:22266] CHIP:EM: >>> [E:17682i S:6381 M:362504] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278706.018887][22262:22266] CHIP:EM: Found matching exchange: 17682i, Delegate: 0xffff8481a608 [1706278706.018999][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278706.019188][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278706.019260][22262:22266] CHIP:DMG: { [1706278706.019319][22262:22266] CHIP:DMG: suppressResponse = false, [1706278706.019391][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278706.019493][22262:22266] CHIP:DMG: [ [1706278706.019564][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278706.019659][22262:22266] CHIP:DMG: { [1706278706.019709][22262:22266] CHIP:DMG: CommandDataIB = [1706278706.019786][22262:22266] CHIP:DMG: { [1706278706.019833][22262:22266] CHIP:DMG: CommandPathIB = [1706278706.019888][22262:22266] CHIP:DMG: { [1706278706.019968][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278706.020031][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278706.020081][22262:22266] CHIP:DMG: CommandId = 0x1, [1706278706.020143][22262:22266] CHIP:DMG: }, [1706278706.020213][22262:22266] CHIP:DMG: [1706278706.020263][22262:22266] CHIP:DMG: CommandFields = [1706278706.020342][22262:22266] CHIP:DMG: { [1706278706.020401][22262:22266] CHIP:DMG: 0x0 = [ [1706278706.021211][22262:22266] 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 [1706278706.021294][22262:22266] CHIP:DMG: ] (583 bytes) [1706278706.021350][22262:22266] CHIP:DMG: 0x1 = [ [1706278706.021529][22262:22266] CHIP:DMG: 0xbc, 0x98, 0xce, 0x7f, 0xbe, 0xd3, 0x9c, 0x0d, 0xee, 0x7a, 0x03, 0x5b, 0x9d, 0x07, 0x9b, 0x67, 0xe4, 0x1f, 0xcb, 0xd6, 0x68, 0xa0, 0xc0, 0x13, 0x65, 0xda, 0x44, 0xc3, 0xb8, 0x64, 0x99, 0x68, 0xde, 0xa0, 0x9e, 0xdb, 0x9f, 0x2f, 0xb8, 0xbe, 0xf2, 0x81, 0xc7, 0x48, 0x3f, 0xd9, 0x47, 0x5e, 0x0f, 0x82, 0x7c, 0xac, 0x74, 0x9e, 0x15, 0x8e, 0x3a, 0x91, 0xcb, 0x50, 0x98, 0xc8, 0xb1, 0xf2, [1706278706.021582][22262:22266] CHIP:DMG: ] (64 bytes) [1706278706.021642][22262:22266] CHIP:DMG: }, [1706278706.021682][22262:22266] CHIP:DMG: }, [1706278706.021761][22262:22266] CHIP:DMG: [1706278706.021805][22262:22266] CHIP:DMG: }, [1706278706.021917][22262:22266] CHIP:DMG: [1706278706.021966][22262:22266] CHIP:DMG: ], [1706278706.022082][22262:22266] CHIP:DMG: [1706278706.022142][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278706.022180][22262:22266] CHIP:DMG: }, [1706278706.022541][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1706278706.022711][22262:22266] CHIP:CTL: Received Attestation Information from the device [1706278706.022839][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1706278706.022900][22262:22266] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 583/583 [1706278706.023071][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1706278706.023197][22262:22266] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1706278706.023249][22262:22266] CHIP:CTL: Verifying attestation [1706278706.132380][22262:22266] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1706278706.132473][22262:22266] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1706278706.132494][22262:22266] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1706278706.132576][22262:22266] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1706278706.132610][22262:22266] CHIP:CTL: Sending CSR request to 0xffff8ba56480 device [1706278706.132809][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278706.132873][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278706.133192][22262:22266] CHIP:EM: <<< [E:17683i S:6381 M:68287850] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278706.133885][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278706.133938][22262:22266] CHIP:CTL: Sent CSR request, waiting for the CSR [1706278706.134116][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278706.134231][22262:22266] CHIP:DL: Long dispatch time: 116 ms, for event type 16389 [1706278706.259946][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278706.357421][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278706.357815][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278706.457536][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278706.457929][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278706.458484][22262:22266] CHIP:EM: >>> [E:17683i S:6381 M:362505] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278706.458581][22262:22266] CHIP:EM: Found matching exchange: 17683i, Delegate: 0xffff848217c8 [1706278706.458690][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278706.458933][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278706.459024][22262:22266] CHIP:DMG: { [1706278706.459088][22262:22266] CHIP:DMG: suppressResponse = false, [1706278706.459137][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278706.459229][22262:22266] CHIP:DMG: [ [1706278706.459277][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278706.459390][22262:22266] CHIP:DMG: { [1706278706.459442][22262:22266] CHIP:DMG: CommandDataIB = [1706278706.459522][22262:22266] CHIP:DMG: { [1706278706.459574][22262:22266] CHIP:DMG: CommandPathIB = [1706278706.459631][22262:22266] CHIP:DMG: { [1706278706.459714][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278706.459779][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278706.459831][22262:22266] CHIP:DMG: CommandId = 0x5, [1706278706.459896][22262:22266] CHIP:DMG: }, [1706278706.459972][22262:22266] CHIP:DMG: [1706278706.460042][22262:22266] CHIP:DMG: CommandFields = [1706278706.460097][22262:22266] CHIP:DMG: { [1706278706.460161][22262:22266] CHIP:DMG: 0x0 = [ [1706278706.460830][22262:22266] CHIP:DMG: 0x15, 0x30, 0x01, 0xcb, 0x30, 0x81, 0xc8, 0x30, 0x70, 0x02, 0x01, 0x00, 0x30, 0x0e, 0x31, 0x0c, 0x30, 0x0a, 0x06, 0x03, 0x55, 0x04, 0x0a, 0x0c, 0x03, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0xff, 0x90, 0x35, 0x54, 0x08, 0xac, 0x9b, 0x33, 0x92, 0x46, 0x2e, 0x75, 0x0b, 0xef, 0x9f, 0xd1, 0x29, 0x0f, 0xcb, 0x4c, 0xc0, 0xf2, 0x43, 0x69, 0xba, 0x43, 0x23, 0x63, 0x95, 0xb5, 0x90, 0x62, 0xa9, 0x43, 0x73, 0xd2, 0x29, 0xb4, 0x45, 0xb4, 0x39, 0x9b, 0x62, 0x3e, 0x95, 0xdc, 0xb6, 0xf7, 0x10, 0x86, 0x44, 0xab, 0x4c, 0x7f, 0x34, 0x67, 0x03, 0x1a, 0x0a, 0xef, 0x6b, 0x80, 0x1b, 0x21, 0xa0, 0x00, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x03, 0x48, 0x00, 0x30, 0x45, 0x02, 0x21, 0x00, 0x88, 0x78, 0xe3, 0x56, 0x29, 0x50, 0xf2, 0x3d, 0x5a, 0x9d, 0xfa, 0x28, 0x84, 0x3e, 0x00, 0x89, 0x44, 0x19, 0x4a, 0x5a, 0x07, 0x24, 0x30, 0xb8, 0xd1, 0x48, 0xea, 0xeb, 0x21, 0xaf, 0x2c, 0x69, 0x02, 0x20, 0x45, 0x79, 0xce, 0xa3, 0x78, 0xf2, 0x2f, 0x1a, 0x49, 0xd4, 0x59, 0x99, 0x5b, 0xaa, 0x4f, 0x5f, 0x0a, 0x16, 0x0b, 0x95, 0x9a, 0x8e, 0x5f, 0x6b, 0x27, 0xcd, 0x30, 0x6c, 0x5b, 0x42, 0xea, 0x05, 0x30, 0x02, 0x20, 0x9a, 0x06, 0x54, 0x11, 0x69, 0x27, 0xac, 0x7e, 0xf4, 0x76, 0x97, 0x86, 0x33, 0x33, 0x35, 0xf6, 0x91, 0x99, 0x71, 0xf6, 0xde, 0x09, 0x2f, 0xb7, 0x41, 0xab, 0xd6, 0x03, 0x77, 0x95, 0xee, 0x84, 0x18, [1706278706.460910][22262:22266] CHIP:DMG: ] (243 bytes) [1706278706.460967][22262:22266] CHIP:DMG: 0x1 = [ [1706278706.461148][22262:22266] CHIP:DMG: 0x4a, 0x52, 0x1a, 0x75, 0xe6, 0xfd, 0x2c, 0x44, 0xc1, 0xe9, 0xdf, 0xff, 0xfa, 0x3d, 0x10, 0xde, 0x75, 0x66, 0x54, 0x71, 0x1e, 0x26, 0xa0, 0x38, 0xdf, 0xce, 0x02, 0xbd, 0x6e, 0xdc, 0x11, 0x6b, 0xa8, 0x9a, 0x95, 0xa8, 0xc0, 0xb7, 0xf3, 0xcc, 0x00, 0xc4, 0x92, 0xf7, 0xeb, 0x6c, 0x0c, 0x98, 0x1d, 0x9a, 0xda, 0xc7, 0x79, 0x15, 0xa3, 0xa4, 0x77, 0x55, 0xd8, 0x47, 0x90, 0xb1, 0x9d, 0xba, [1706278706.461205][22262:22266] CHIP:DMG: ] (64 bytes) [1706278706.461258][22262:22266] CHIP:DMG: }, [1706278706.461297][22262:22266] CHIP:DMG: }, [1706278706.461375][22262:22266] CHIP:DMG: [1706278706.461418][22262:22266] CHIP:DMG: }, [1706278706.461498][22262:22266] CHIP:DMG: [1706278706.461576][22262:22266] CHIP:DMG: ], [1706278706.461670][22262:22266] CHIP:DMG: [1706278706.461749][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278706.461787][22262:22266] CHIP:DMG: }, [1706278706.462140][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1706278706.462291][22262:22266] CHIP:CTL: Received certificate signing request from the device [1706278706.462411][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1706278706.462465][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1706278706.462546][22262:22266] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1706278706.482636][22262:22266] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1706278706.482706][22262:22266] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1706278706.482780][22262:22266] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1706278706.482814][22262:22266] CHIP:CTL: Getting certificate chain for the device from the issuer [1706278706.495733][22262:22266] CHIP:CTL: Verifying Certificate Signing Request [1706278706.503241][22262:22266] CHIP:CTL: Generating NOC [1706278706.504255][22262:22266] CHIP:CTL: Providing certificate chain to the commissioner [1706278706.504318][22262:22266] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:395: Success [1706278706.504365][22262:22266] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1706278706.505019][22262:22266] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1706278706.505067][22262:22266] CHIP:CTL: Sending root certificate to the device [1706278706.505204][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278706.505296][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278706.505608][22262:22266] CHIP:EM: <<< [E:17684i S:6381 M:68287851] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278706.506299][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278706.506352][22262:22266] CHIP:CTL: Sent root certificate to the device [1706278706.507911][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278706.698962][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278706.795962][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278706.798345][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278706.798687][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278706.799293][22262:22266] CHIP:EM: >>> [E:17684i S:6381 M:362506] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278706.799385][22262:22266] CHIP:EM: Found matching exchange: 17684i, Delegate: 0xffff84820b88 [1706278706.799481][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278706.799632][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278706.799689][22262:22266] CHIP:DMG: { [1706278706.799744][22262:22266] CHIP:DMG: suppressResponse = false, [1706278706.799804][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278706.799882][22262:22266] CHIP:DMG: [ [1706278706.799921][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278706.800020][22262:22266] CHIP:DMG: { [1706278706.800063][22262:22266] CHIP:DMG: CommandStatusIB = [1706278706.800128][22262:22266] CHIP:DMG: { [1706278706.800188][22262:22266] CHIP:DMG: CommandPathIB = [1706278706.800242][22262:22266] CHIP:DMG: { [1706278706.800291][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278706.800362][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278706.800407][22262:22266] CHIP:DMG: CommandId = 0xb, [1706278706.800444][22262:22266] CHIP:DMG: }, [1706278706.800510][22262:22266] CHIP:DMG: [1706278706.800573][22262:22266] CHIP:DMG: StatusIB = [1706278706.800643][22262:22266] CHIP:DMG: { [1706278706.800714][22262:22266] CHIP:DMG: status = 0x00 (SUCCESS), [1706278706.800755][22262:22266] CHIP:DMG: }, [1706278706.800805][22262:22266] CHIP:DMG: [1706278706.800845][22262:22266] CHIP:DMG: }, [1706278706.800927][22262:22266] CHIP:DMG: [1706278706.800965][22262:22266] CHIP:DMG: }, [1706278706.801031][22262:22266] CHIP:DMG: [1706278706.801089][22262:22266] CHIP:DMG: ], [1706278706.801164][22262:22266] CHIP:DMG: [1706278706.801209][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278706.801264][22262:22266] CHIP:DMG: }, [1706278706.801535][22262:22266] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1706278706.801630][22262:22266] CHIP:CTL: Device confirmed that it has received the root certificate [1706278706.801699][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1706278706.801743][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1706278706.801813][22262:22266] CHIP:CTL: Performing next commissioning step 'SendNOC' [1706278706.802097][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278706.802265][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278706.802840][22262:22266] CHIP:EM: <<< [E:17685i S:6381 M:68287852] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278706.804191][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278706.804283][22262:22266] CHIP:CTL: Sent operational certificate to the device [1706278706.804537][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278707.040125][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278707.235126][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278707.381205][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278707.383580][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278707.383921][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278707.384486][22262:22266] CHIP:EM: >>> [E:17685i S:6381 M:362507] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278707.384572][22262:22266] CHIP:EM: Found matching exchange: 17685i, Delegate: 0xffff848209c8 [1706278707.384664][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278707.384823][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278707.384884][22262:22266] CHIP:DMG: { [1706278707.384938][22262:22266] CHIP:DMG: suppressResponse = false, [1706278707.384976][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278707.385056][22262:22266] CHIP:DMG: [ [1706278707.385097][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278707.385203][22262:22266] CHIP:DMG: { [1706278707.385248][22262:22266] CHIP:DMG: CommandDataIB = [1706278707.385323][22262:22266] CHIP:DMG: { [1706278707.385360][22262:22266] CHIP:DMG: CommandPathIB = [1706278707.385410][22262:22266] CHIP:DMG: { [1706278707.385458][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278707.385532][22262:22266] CHIP:DMG: ClusterId = 0x3e, [1706278707.385576][22262:22266] CHIP:DMG: CommandId = 0x8, [1706278707.385610][22262:22266] CHIP:DMG: }, [1706278707.385691][22262:22266] CHIP:DMG: [1706278707.385731][22262:22266] CHIP:DMG: CommandFields = [1706278707.385774][22262:22266] CHIP:DMG: { [1706278707.385826][22262:22266] CHIP:DMG: 0x0 = 0, [1706278707.385898][22262:22266] CHIP:DMG: 0x1 = 1, [1706278707.385943][22262:22266] CHIP:DMG: }, [1706278707.385980][22262:22266] CHIP:DMG: }, [1706278707.386067][22262:22266] CHIP:DMG: [1706278707.386109][22262:22266] CHIP:DMG: }, [1706278707.386197][22262:22266] CHIP:DMG: [1706278707.386238][22262:22266] CHIP:DMG: ], [1706278707.386316][22262:22266] CHIP:DMG: [1706278707.386382][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278707.386416][22262:22266] CHIP:DMG: }, [1706278707.386694][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1706278707.386960][22262:22266] CHIP:CTL: Device returned status 0 on receiving the NOC [1706278707.387037][22262:22266] CHIP:CTL: Operational credentials provisioned on device 0xffff8ba56480 [1706278707.387085][22262:22266] CHIP:TOO: Secure Pairing Success [1706278707.387116][22262:22266] CHIP:TOO: CASE establishment successful [1706278707.387174][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1706278707.387243][22262:22266] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1706278707.387296][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'WiFiNetworkSetup' [1706278707.387366][22262:22266] CHIP:CTL: Performing next commissioning step 'WiFiNetworkSetup' [1706278707.387702][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278707.387893][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278707.388391][22262:22266] CHIP:EM: <<< [E:17686i S:6381 M:68287853] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278707.389635][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278707.389942][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278707.527455][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278707.529905][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278707.530289][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278707.531068][22262:22266] CHIP:EM: >>> [E:17686i S:6381 M:362508] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278707.531172][22262:22266] CHIP:EM: Found matching exchange: 17686i, Delegate: 0xffff84820808 [1706278707.531281][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278707.531449][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278707.531517][22262:22266] CHIP:DMG: { [1706278707.531606][22262:22266] CHIP:DMG: suppressResponse = false, [1706278707.531656][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278707.531743][22262:22266] CHIP:DMG: [ [1706278707.531791][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278707.531904][22262:22266] CHIP:DMG: { [1706278707.531953][22262:22266] CHIP:DMG: CommandDataIB = [1706278707.532009][22262:22266] CHIP:DMG: { [1706278707.532062][22262:22266] CHIP:DMG: CommandPathIB = [1706278707.532141][22262:22266] CHIP:DMG: { [1706278707.532206][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278707.532260][22262:22266] CHIP:DMG: ClusterId = 0x31, [1706278707.532337][22262:22266] CHIP:DMG: CommandId = 0x5, [1706278707.532379][22262:22266] CHIP:DMG: }, [1706278707.532444][22262:22266] CHIP:DMG: [1706278707.532494][22262:22266] CHIP:DMG: CommandFields = [1706278707.532568][22262:22266] CHIP:DMG: { [1706278707.532632][22262:22266] CHIP:DMG: 0x0 = 0, [1706278707.532693][22262:22266] CHIP:DMG: 0x2 = 0, [1706278707.532744][22262:22266] CHIP:DMG: }, [1706278707.532805][22262:22266] CHIP:DMG: }, [1706278707.532887][22262:22266] CHIP:DMG: [1706278707.532929][22262:22266] CHIP:DMG: }, [1706278707.533025][22262:22266] CHIP:DMG: [1706278707.533070][22262:22266] CHIP:DMG: ], [1706278707.533181][22262:22266] CHIP:DMG: [1706278707.533237][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278707.533280][22262:22266] CHIP:DMG: }, [1706278707.533592][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1706278707.533836][22262:22266] CHIP:CTL: Received NetworkConfig response, networkingStatus=0 [1706278707.533925][22262:22266] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkSetup' [1706278707.533979][22262:22266] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkSetup' -> 'FailsafeBeforeWiFiEnable' [1706278707.534059][22262:22266] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeWiFiEnable' [1706278707.534318][22262:22266] CHIP:CTL: Arming failsafe (66 seconds) [1706278707.534617][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278707.534720][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278707.535251][22262:22266] CHIP:EM: <<< [E:17687i S:6381 M:68287854] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278707.536737][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278707.537070][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278707.673855][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278707.676272][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278707.676669][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278707.677354][22262:22266] CHIP:EM: >>> [E:17687i S:6381 M:362509] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278707.677455][22262:22266] CHIP:EM: Found matching exchange: 17687i, Delegate: 0xffff84820648 [1706278707.677559][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278707.677728][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278707.677797][22262:22266] CHIP:DMG: { [1706278707.677855][22262:22266] CHIP:DMG: suppressResponse = false, [1706278707.677931][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278707.678023][22262:22266] CHIP:DMG: [ [1706278707.678072][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278707.678158][22262:22266] CHIP:DMG: { [1706278707.678230][22262:22266] CHIP:DMG: CommandDataIB = [1706278707.678294][22262:22266] CHIP:DMG: { [1706278707.678338][22262:22266] CHIP:DMG: CommandPathIB = [1706278707.678415][22262:22266] CHIP:DMG: { [1706278707.678481][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278707.678535][22262:22266] CHIP:DMG: ClusterId = 0x30, [1706278707.678608][22262:22266] CHIP:DMG: CommandId = 0x1, [1706278707.678653][22262:22266] CHIP:DMG: }, [1706278707.678719][22262:22266] CHIP:DMG: [1706278707.678851][22262:22266] CHIP:DMG: CommandFields = [1706278707.678911][22262:22266] CHIP:DMG: { [1706278707.678998][22262:22266] CHIP:DMG: 0x0 = 0, [1706278707.679068][22262:22266] CHIP:DMG: 0x1 = "" (0 chars), [1706278707.679122][22262:22266] CHIP:DMG: }, [1706278707.679184][22262:22266] CHIP:DMG: }, [1706278707.679268][22262:22266] CHIP:DMG: [1706278707.679309][22262:22266] CHIP:DMG: }, [1706278707.679414][22262:22266] CHIP:DMG: [1706278707.679454][22262:22266] CHIP:DMG: ], [1706278707.679567][22262:22266] CHIP:DMG: [1706278707.679624][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278707.679670][22262:22266] CHIP:DMG: }, [1706278707.680019][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1706278707.680186][22262:22266] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1706278707.680297][22262:22266] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeWiFiEnable' [1706278707.680345][22262:22266] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeWiFiEnable' -> 'WiFiNetworkEnable' [1706278707.680397][22262:22266] CHIP:CTL: Setting wifi connection time min = 20 [1706278707.680468][22262:22266] CHIP:CTL: Performing next commissioning step 'WiFiNetworkEnable' [1706278707.680550][22262:22266] CHIP:CTL: SendCommand kWiFiNetworkEnable, supportsConcurrentConnection=1 [1706278707.680827][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278707.680958][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278707.681525][22262:22266] CHIP:EM: <<< [E:17688i S:6381 M:68287855] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278707.683039][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278707.683377][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278707.820094][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278710.306377][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278710.306836][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278713.085095][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1706278713.671253][22262:22265] CHIP:DL: Indication received, conn = 0xffff8ce17010 [1706278713.671655][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1706278713.672346][22262:22266] CHIP:EM: >>> [E:17688i S:6381 M:362510] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278713.672448][22262:22266] CHIP:EM: Found matching exchange: 17688i, Delegate: 0xffff84820488 [1706278713.672584][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278713.672760][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278713.672833][22262:22266] CHIP:DMG: { [1706278713.672895][22262:22266] CHIP:DMG: suppressResponse = false, [1706278713.672940][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278713.673064][22262:22266] CHIP:DMG: [ [1706278713.673113][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278713.673209][22262:22266] CHIP:DMG: { [1706278713.673258][22262:22266] CHIP:DMG: CommandDataIB = [1706278713.673337][22262:22266] CHIP:DMG: { [1706278713.673386][22262:22266] CHIP:DMG: CommandPathIB = [1706278713.673442][22262:22266] CHIP:DMG: { [1706278713.673502][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278713.673589][22262:22266] CHIP:DMG: ClusterId = 0x31, [1706278713.673641][22262:22266] CHIP:DMG: CommandId = 0x7, [1706278713.673704][22262:22266] CHIP:DMG: }, [1706278713.673773][22262:22266] CHIP:DMG: [1706278713.673823][22262:22266] CHIP:DMG: CommandFields = [1706278713.673897][22262:22266] CHIP:DMG: { [1706278713.673962][22262:22266] CHIP:DMG: 0x0 = 0, [1706278713.674017][22262:22266] CHIP:DMG: 0x2 = NULL [1706278713.674095][22262:22266] CHIP:DMG: }, [1706278713.674140][22262:22266] CHIP:DMG: }, [1706278713.674215][22262:22266] CHIP:DMG: [1706278713.674281][22262:22266] CHIP:DMG: }, [1706278713.674360][22262:22266] CHIP:DMG: [1706278713.674423][22262:22266] CHIP:DMG: ], [1706278713.674517][22262:22266] CHIP:DMG: [1706278713.674570][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278713.674633][22262:22266] CHIP:DMG: }, [1706278713.675060][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1706278713.675250][22262:22266] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0 [1706278713.675341][22262:22266] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkEnable' [1706278713.675428][22262:22266] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkEnable' -> 'FindOperational' [1706278713.675513][22262:22266] CHIP:CTL: Performing next commissioning step 'FindOperational' [1706278713.675595][22262:22266] CHIP:IN: Expiring all sessions for node <00000000000007CE, 1>!! [1706278713.675672][22262:22266] CHIP:CSM: FindOrEstablishSession: PeerId = [1:00000000000007CE] [1706278713.675782][22262:22266] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1706278713.675919][22262:22266] CHIP:DIS: OperationalSessionSetup[1:00000000000007CE]: State change 1 --> 2 [1706278713.679532][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278713.877207][22262:22266] CHIP:DIS: Checking node lookup status after 201 ms [1706278714.740006][22262:22266] CHIP:DIS: UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540: new best score: 7 [1706278714.740055][22262:22266] CHIP:DIS: Lookup clearing interface for non LL address [1706278714.740117][22262:22266] CHIP:DIS: UDP:172.16.62.113%eth0:5540: new best score: 2 [1706278714.740140][22262:22266] CHIP:DIS: Checking node lookup status after 1064 ms [1706278714.740222][22262:22266] CHIP:DIS: OperationalSessionSetup[1:00000000000007CE]: Updating device address to UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540 while in state 2 [1706278714.740246][22262:22266] CHIP:DIS: OperationalSessionSetup[1:00000000000007CE]: State change 2 --> 3 [1706278714.740533][22262:22266] CHIP:IN: SecureSession[0xffff84c2b3c0]: Allocated Type:2 LSID:6382 [1706278714.740650][22262:22266] CHIP:SC: Initiating session on local FabricIndex 1 from 0x000000000001B669 -> 0x00000000000007CE [1706278714.746037][22262:22266] CHIP:EM: <<< [E:17689i S:0 M:221639111] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540] --- Type 0000:30 (SecureChannel:CASE_Sigma1) [1706278714.746548][22262:22266] CHIP:SC: Sent Sigma1 msg [1706278714.746642][22262:22266] CHIP:DIS: OperationalSessionSetup[1:00000000000007CE]: State change 3 --> 4 [1706278715.111999][22262:22266] CHIP:EM: Retransmitting MessageCounter:221639111 on exchange 17689i Send Cnt 1 [1706278715.137555][22262:22266] CHIP:EM: >>> [E:17689i S:0 M:190944766 (Ack:221639111)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) [1706278715.137657][22262:22266] CHIP:EM: Found matching exchange: 17689i, Delegate: 0xffff83e07b08 [1706278715.137785][22262:22266] CHIP:EM: Rxd Ack; Removing MessageCounter:221639111 from Retrans Table on exchange 17689i [1706278715.138143][22262:22266] CHIP:SC: Received Sigma2 msg [1706278715.138278][22262:22266] CHIP:SC: Peer assigned session session ID 39054 [1706278715.156467][22262:22266] CHIP:SC: Found MRP parameters in the message [1706278715.156633][22262:22266] CHIP:SC: Sending Sigma3 [1706278715.158947][22262:22266] CHIP:EM: <<< [E:17689i S:0 M:221639112 (Ack:190944766)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540] --- Type 0000:32 (SecureChannel:CASE_Sigma3) [1706278715.159297][22262:22266] CHIP:SC: Sent Sigma3 msg [1706278715.193552][22262:22266] CHIP:EM: >>> [E:17689i S:0 M:190944767 (Ack:221639112)] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1706278715.193641][22262:22266] CHIP:EM: Found matching exchange: 17689i, Delegate: 0xffff83e07b08 [1706278715.193751][22262:22266] CHIP:EM: Rxd Ack; Removing MessageCounter:221639112 from Retrans Table on exchange 17689i [1706278715.193942][22262:22266] CHIP:SC: Success status report received. Session was established [1706278715.203663][22262:22266] CHIP:SC: SecureSession[0xffff84c2b3c0, LSID:6382]: State change 'kEstablishing' --> 'kActive' [1706278715.203762][22262:22266] CHIP:IN: SecureSession[0xffff84c2b3c0]: Activated - Type:2 LSID:6382 [1706278715.203804][22262:22266] CHIP:IN: New secure session activated for device <00000000000007CE, 1>, LSID:6382 PSID:39054! [1706278715.203862][22262:22266] CHIP:DIS: OperationalSessionSetup[1:00000000000007CE]: State change 4 --> 5 [1706278715.204251][22262:22266] CHIP:CTL: Successfully finished commissioning step 'FindOperational' [1706278715.204353][22262:22266] CHIP:CTL: Commissioning stage next step: 'FindOperational' -> 'SendComplete' [1706278715.204459][22262:22266] CHIP:CTL: Performing next commissioning step 'SendComplete' [1706278715.204708][22262:22266] CHIP:DMG: ICR moving to [AddingComm] [1706278715.204779][22262:22266] CHIP:DMG: ICR moving to [AddedComma] [1706278715.205476][22262:22266] CHIP:EM: <<< [E:17690i S:6382 M:50331511] (S) Msg TX to 1:00000000000007CE [2E76] [UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540] --- Type 0001:08 (IM:InvokeCommandRequest) [1706278715.205801][22262:22266] CHIP:DMG: ICR moving to [AwaitingRe] [1706278715.206012][22262:22266] CHIP:EM: <<< [E:17689i S:0 M:221639113 (Ack:190944767)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [1706278715.206140][22262:22266] CHIP:EM: Flushed pending ack for MessageCounter:190944767 on exchange 17689i [1706278715.266725][22262:22266] CHIP:EM: >>> [E:17690i S:6382 M:184245974 (Ack:50331511)] (S) Msg RX from 1:00000000000007CE [2E76] --- Type 0001:09 (IM:InvokeCommandResponse) [1706278715.266849][22262:22266] CHIP:EM: Found matching exchange: 17690i, Delegate: 0xffff84825c08 [1706278715.266962][22262:22266] CHIP:EM: Rxd Ack; Removing MessageCounter:50331511 from Retrans Table on exchange 17690i [1706278715.267087][22262:22266] CHIP:DMG: ICR moving to [ResponseRe] [1706278715.267249][22262:22266] CHIP:DMG: InvokeResponseMessage = [1706278715.267339][22262:22266] CHIP:DMG: { [1706278715.267394][22262:22266] CHIP:DMG: suppressResponse = false, [1706278715.267454][22262:22266] CHIP:DMG: InvokeResponseIBs = [1706278715.267539][22262:22266] CHIP:DMG: [ [1706278715.267581][22262:22266] CHIP:DMG: InvokeResponseIB = [1706278715.267657][22262:22266] CHIP:DMG: { [1706278715.267695][22262:22266] CHIP:DMG: CommandDataIB = [1706278715.267767][22262:22266] CHIP:DMG: { [1706278715.267808][22262:22266] CHIP:DMG: CommandPathIB = [1706278715.267857][22262:22266] CHIP:DMG: { [1706278715.267929][22262:22266] CHIP:DMG: EndpointId = 0x0, [1706278715.267979][22262:22266] CHIP:DMG: ClusterId = 0x30, [1706278715.268047][22262:22266] CHIP:DMG: CommandId = 0x5, [1706278715.268084][22262:22266] CHIP:DMG: }, [1706278715.268141][22262:22266] CHIP:DMG: [1706278715.268182][22262:22266] CHIP:DMG: CommandFields = [1706278715.268224][22262:22266] CHIP:DMG: { [1706278715.268296][22262:22266] CHIP:DMG: 0x0 = 0, [1706278715.268361][22262:22266] CHIP:DMG: 0x1 = "" (0 chars), [1706278715.268408][22262:22266] CHIP:DMG: }, [1706278715.268464][22262:22266] CHIP:DMG: }, [1706278715.268540][22262:22266] CHIP:DMG: [1706278715.268582][22262:22266] CHIP:DMG: }, [1706278715.268685][22262:22266] CHIP:DMG: [1706278715.268726][22262:22266] CHIP:DMG: ], [1706278715.268833][22262:22266] CHIP:DMG: [1706278715.269042][22262:22266] CHIP:DMG: InteractionModelRevision = 11 [1706278715.269090][22262:22266] CHIP:DMG: }, [1706278715.269446][22262:22266] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0005 [1706278715.269619][22262:22266] CHIP:CTL: Received CommissioningComplete response, errorCode=0 [1706278715.269726][22262:22266] CHIP:CTL: Successfully finished commissioning step 'SendComplete' [1706278715.269777][22262:22266] CHIP:CTL: Commissioning stage next step: 'SendComplete' -> 'Cleanup' [1706278715.269892][22262:22266] CHIP:CTL: Performing next commissioning step 'Cleanup' [1706278715.269960][22262:22266] CHIP:DIS: Closing all BLE connections [1706278715.270047][22262:22266] CHIP:IN: Clearing BLE pending packets. [1706278715.271727][22262:22266] CHIP:BLE: Auto-closing end point's BLE connection. [1706278715.271800][22262:22266] CHIP:DL: Closing BLE GATT connection (con 0xffff8ce17010) [1706278715.272073][22262:22265] CHIP:DL: BluezDisconnect peer=0A:62:24:06:50:8D [1706278717.766330][22262:22266] CHIP:IN: SecureSession[0xffff84c1fb40]: MarkForEviction Type:1 LSID:6381 [1706278717.766427][22262:22266] CHIP:SC: SecureSession[0xffff84c1fb40, LSID:6381]: State change 'kActive' --> 'kPendingEviction' [1706278717.767014][22262:22266] CHIP:IN: SecureSession[0xffff84c1fb40]: Released - Type:1 LSID:6381 [1706278717.767163][22262:22266] CHIP:CTL: Successfully finished commissioning step 'Cleanup' [1706278717.767285][22262:22266] CHIP:TOO: Device commissioning completed with success [1706278717.767597][22262:22266] CHIP:DMG: ICR moving to [AwaitingDe] [1706278717.767879][22262:22265] CHIP:DL: Bluez disconnected [1706278717.767943][22262:22265] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1706278717.768240][22262:22266] CHIP:EM: <<< [E:17690i S:6382 M:50331512 (Ack:184245974)] (S) Msg TX to 1:00000000000007CE [2E76] [UDP:[fe80::e65f:1ff:fec2:47af%eth0]:5540] --- Type 0000:10 (SecureChannel:StandaloneAck) [1706278717.768655][22262:22266] CHIP:EM: Flushed pending ack for MessageCounter:184245974 on exchange 17690i [1706278717.768791][22262:22266] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1706278717.768841][22262:22266] CHIP:BLE: no endpoint for unsub complete [1706278717.769800][22262:22262] CHIP:CTL: Shutting down the commissioner [1706278717.770085][22262:22262] CHIP:CTL: Shutting down the controller [1706278717.770179][22262:22262] CHIP:IN: Expiring all sessions for fabric 0x1!! [1706278717.770223][22262:22262] CHIP:IN: SecureSession[0xffff84c2b3c0]: MarkForEviction Type:2 LSID:6382 [1706278717.770264][22262:22262] CHIP:SC: SecureSession[0xffff84c2b3c0, LSID:6382]: State change 'kActive' --> 'kPendingEviction' [1706278717.770304][22262:22262] CHIP:IN: SecureSession[0xffff84c2b3c0]: Released - Type:2 LSID:6382 [1706278717.770364][22262:22262] CHIP:FP: Forgetting fabric 0x1 [1706278717.770480][22262:22262] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1706278717.771469][22262:22262] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1706278717.771527][22262:22262] CHIP:TS: Reverted Last Known Good Time to previous value [1706278717.771791][22262:22262] CHIP:CTL: Shutting down the commissioner [1706278717.772051][22262:22262] CHIP:CTL: Shutting down the controller [1706278717.772100][22262:22262] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1706278717.773871][22262:22262] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1706278717.774255][22262:22262] CHIP:FP: Shutting down FabricTable [1706278717.774342][22262:22262] CHIP:TS: Pending Last Known Good Time: 2023-10-14T01:16:48 [1706278717.775379][22262:22262] CHIP:TS: Previous Last Known Good Time: 2023-10-14T01:16:48 [1706278717.775439][22262:22262] CHIP:TS: Reverted Last Known Good Time to previous value [1706278717.775887][22262:22262] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-r4d4N6) [1706278717.776539][22262:22262] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1706278717.776636][22262:22262] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1706278717.776663][22262:22262] CHIP:DL: Inet Layer shutdown [1706278717.776679][22262:22262] CHIP:DL: BLE shutdown [1706278717.782838][22262:22262] CHIP:DL: System Layer shutdown