[1710347806.109406][937988:937988] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_kvs [1710347806.109513][937988:937988] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_factory.ini [1710347806.109530][937988:937988] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_config.ini [1710347806.109540][937988:937988] CHIP:DL: ChipLinuxStorage::Init: Using KVS config file: /tmp/chip_counters.ini [1710347806.109616][937988:937988] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-2RnzSg) [1710347806.109726][937988:937988] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1710347806.109731][937988:937988] CHIP:DL: NVS set: chip-counters/reboot-count = 19 (0x13) [1710347806.109893][937988:937988] CHIP:DL: Failed to get Ethernet interface [1710347806.109966][937988:937988] CHIP:DL: Failed to reset Ethernet statistic counts [1710347806.110174][937988:937988] CHIP:DL: Got WiFi interface: wlp164s0 [1710347806.111820][937988:937988] CHIP:DL: Found the primary WiFi interface:wlp164s0 [1710347806.111850][937988:937988] CHIP:IN: UDP::Init bind&listen port=0 [1710347806.111867][937988:937988] CHIP:IN: UDP::Init bound to port=35319 [1710347806.111870][937988:937988] CHIP:IN: UDP::Init bind&listen port=0 [1710347806.111881][937988:937988] CHIP:IN: UDP::Init bound to port=53430 [1710347806.111884][937988:937988] CHIP:IN: BLEBase::Init - setting/overriding transport [1710347806.111887][937988:937988] CHIP:IN: TransportMgr initialized [1710347806.111897][937988:937988] CHIP:FP: Initializing FabricTable from persistent storage [1710347806.111936][937988:937988] CHIP:TS: Last Known Good Time: 2023-10-26T12:12:01 [1710347806.112068][937988:937988] CHIP:FP: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x9E43B8E2CAAE1023, FabricId 0x0000000000000001, NodeId 0x000000000001B669, VendorId 0xFFF1 [1710347806.117585][937988:937988] CHIP:ZCL: Using ZAP configuration... [1710347806.120145][937988:937988] CHIP:DL: Avahi client registered [1710347806.120310][937988:937988] CHIP:CTL: System State Initialized... [1710347806.120335][937988:937988] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1710347806.120343][937988:937988] CHIP:CTL: Setting attestation nonce to random value [1710347806.120346][937988:937988] CHIP:CTL: Setting CSR nonce to random value [1710347806.120506][937988:937991] CHIP:DL: CHIP task running [1710347806.120581][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 32784 [1710347806.120719][937988:937991] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1710347806.120740][937988:937991] CHIP:CTL: Setting attestation nonce to random value [1710347806.120750][937988:937991] CHIP:CTL: Setting CSR nonce to random value [1710347806.123562][937988:937991] CHIP:CTL: Generating NOC [1710347806.125966][937988:937991] CHIP:FP: Validating NOC chain [1710347806.134017][937988:937991] CHIP:FP: NOC chain validation successful [1710347806.134024][937988:937991] CHIP:FP: Updated fabric at index: 0x1, Node ID: 0x000000000001B669 [1710347806.134027][937988:937991] CHIP:TS: Last Known Good Time: 2023-10-26T12:12:01 [1710347806.134029][937988:937991] CHIP:TS: New proposed Last Known Good Time: 2021-01-01T00:00:00 [1710347806.134030][937988:937991] CHIP:TS: Retaining current Last Known Good Time [1710347806.134232][937988:937991] CHIP:FP: Metadata for Fabric 0x1 persisted to storage. [1710347806.134399][937988:937991] CHIP:TS: Committing Last Known Good Time to storage: 2023-10-26T12:12:01 [1710347806.134553][937988:937991] CHIP:CTL: Joined the fabric at index 1. Fabric ID is 0x0000000000000001 (Compressed Fabric ID: 9E43B8E2CAAE1023) [1710347806.136186][937988:937991] CHIP:CTL: Setting wifi credentials from parameters [1710347806.136191][937988:937991] CHIP:CTL: Setting attestation nonce to random value [1710347806.136193][937988:937991] CHIP:CTL: Setting CSR nonce to random value [1710347806.136196][937988:937991] CHIP:CTL: Commission called for node ID 0x0000000000000001 [1710347806.137371][937988:937990] CHIP:DL: TRACE: Bus acquired for name C-5004 [1710347806.138930][937988:937991] CHIP:DL: PlatformBlueZInit init success [1710347806.140058][937988:937990] CHIP:BLE: BLE removing known devices. [1710347806.140308][937988:937990] CHIP:BLE: BLE initiating scan. [1710347806.261936][937988:937991] CHIP:DL: Long dispatch time: 126 ms, for event type 2 [1710347806.282068][937988:937990] CHIP:BLE: New device scanned: FD:5B:4B:D8:77:70 [1710347806.282082][937988:937990] CHIP:BLE: Device discriminator match. Attempting to connect. [1710347806.405344][937988:937990] CHIP:DL: ConnectDevice complete [1710347807.298012][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298030][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298033][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298035][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298038][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service0001 [1710347807.298040][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298042][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service0001 [1710347807.298044][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298046][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service0001 [1710347807.298048][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298051][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298053][937988:937990] CHIP:DL: Char1 /org/bluez/hci0/dev_FD_5B_4B_D8_77_70/service000c [1710347807.298056][937988:937990] CHIP:DL: New BLE connection 0x7f64dc044fc0, device FD:5B:4B:D8:77:70, path /org/bluez/hci0/dev_FD_5B_4B_D8_77_70 [1710347807.298109][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1710347807.298124][937988:937991] CHIP:DIS: Closing all BLE connections [1710347807.298139][937988:937991] CHIP:IN: BleConnectionComplete: endPoint 0x5615f3bfa3c0 [1710347807.298235][937988:937991] CHIP:IN: SecureSession[0x7f64d40095a0]: Allocated Type:1 LSID:8233 [1710347807.298241][937988:937991] CHIP:SC: Assigned local session key ID 8233 [1710347807.298265][937988:937991] CHIP:EM: <<< [E:17434i S:0 M:108495147] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) [1710347807.298275][937988:937991] CHIP:IN: (U) Sending msg 108495147 to IP address 'BLE' [1710347807.298281][937988:937991] CHIP:IN: Message appended to BLE send queue [1710347807.298284][937988:937991] CHIP:SC: Sent PBKDF param request [1710347807.663880][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347807.754441][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1710347807.754471][937988:937991] CHIP:BLE: subscribe complete, ep = 0x5615f3bfa3c0 [1710347807.754442][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347807.754502][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347807.754508][937988:937991] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1710347807.754511][937988:937991] CHIP:BLE: using BTP fragment sizes rx 244 / tx 244. [1710347807.754514][937988:937991] CHIP:BLE: local and remote recv window size = 5 [1710347807.754595][937988:937991] CHIP:IN: BLE EndPoint 0x5615f3bfa3c0 Connection Complete [1710347807.843700][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347807.890196][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347807.890318][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347807.890359][937988:937991] CHIP:EM: >>> [E:17434i S:0 M:213465858] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) [1710347807.890364][937988:937991] CHIP:EM: Found matching exchange: 17434i, Delegate: 0x7f64d400c250 [1710347807.890370][937988:937991] CHIP:SC: Received PBKDF param response [1710347807.890386][937988:937991] CHIP:SC: Peer assigned session ID 54789 [1710347807.890390][937988:937991] CHIP:SC: Found MRP parameters in the message [1710347807.901785][937988:937991] CHIP:EM: <<< [E:17434i S:0 M:108495148] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) [1710347807.901811][937988:937991] CHIP:IN: (U) Sending msg 108495148 to IP address 'BLE' [1710347807.901984][937988:937991] CHIP:SC: Sent spake2p msg1 [1710347807.979079][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347809.375075][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347809.375156][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347809.375197][937988:937991] CHIP:EM: >>> [E:17434i S:0 M:213465859] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) [1710347809.375221][937988:937991] CHIP:EM: Found matching exchange: 17434i, Delegate: 0x7f64d400c250 [1710347809.375234][937988:937991] CHIP:SC: Received spake2p msg2 [1710347809.383635][937988:937991] CHIP:EM: <<< [E:17434i S:0 M:108495149] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) [1710347809.383646][937988:937991] CHIP:IN: (U) Sending msg 108495149 to IP address 'BLE' [1710347809.383824][937988:937991] CHIP:SC: Sent spake2p msg3 [1710347809.463758][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347809.509589][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347809.509671][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347809.509719][937988:937991] CHIP:EM: >>> [E:17434i S:0 M:213465860] (U) Msg RX from 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) [1710347809.509725][937988:937991] CHIP:EM: Found matching exchange: 17434i, Delegate: 0x7f64d400c250 [1710347809.509749][937988:937991] CHIP:SC: SecureSession[0x7f64d40095a0]: Moving from state 'kEstablishing' --> 'kActive' [1710347809.509752][937988:937991] CHIP:IN: SecureSession[0x7f64d40095a0]: Activated - Type:1 LSID:8233 [1710347809.509754][937988:937991] CHIP:IN: New secure session activated for device , LSID:8233 PSID:54789! [1710347809.509758][937988:937991] CHIP:CTL: Remote device completed SPAKE2+ handshake [1710347809.509760][937988:937991] CHIP:TOO: Pairing Success [1710347809.509762][937988:937991] CHIP:TOO: PASE establishment successful [1710347809.509766][937988:937991] CHIP:CTL: Commissioning stage next step: 'SecurePairing' -> 'ReadCommissioningInfo' [1710347809.509768][937988:937991] CHIP:CTL: Performing next commissioning step 'ReadCommissioningInfo' [1710347809.509770][937988:937991] CHIP:CTL: Sending request for commissioning information [1710347809.509780][937988:937991] CHIP:DMG: SendReadRequest ReadClient[0x7f64d401a100]: Sending Read Request [1710347809.509812][937988:937991] CHIP:EM: <<< [E:17435i S:8233 M:200193201] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) [1710347809.509817][937988:937991] CHIP:IN: (S) Sending msg 200193201 on secure session with LSID: 8233 [1710347809.509932][937988:937991] CHIP:DMG: MoveToState ReadClient[0x7f64d401a100]: Moving to [AwaitingIn] [1710347809.598991][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347809.646699][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347809.646752][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347809.734434][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347809.734488][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347809.734533][937988:937991] CHIP:EM: >>> [E:17435i S:8233 M:171809787] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) [1710347809.734539][937988:937991] CHIP:EM: Found matching exchange: 17435i, Delegate: 0x7f64d401a110 [1710347809.734561][937988:937991] CHIP:DMG: ReportDataMessage = [1710347809.734564][937988:937991] CHIP:DMG: { [1710347809.734566][937988:937991] CHIP:DMG: AttributeReportIBs = [1710347809.734580][937988:937991] CHIP:DMG: [ [1710347809.734582][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734585][937988:937991] CHIP:DMG: { [1710347809.734587][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734590][937988:937991] CHIP:DMG: { [1710347809.734593][937988:937991] CHIP:DMG: DataVersion = 0x81d1a383, [1710347809.734595][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734597][937988:937991] CHIP:DMG: { [1710347809.734600][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734602][937988:937991] CHIP:DMG: Cluster = 0x31, [1710347809.734605][937988:937991] CHIP:DMG: Attribute = 0x0000_0003, [1710347809.734607][937988:937991] CHIP:DMG: } [1710347809.734609][937988:937991] CHIP:DMG: [1710347809.734612][937988:937991] CHIP:DMG: Data = 35, [1710347809.734614][937988:937991] CHIP:DMG: }, [1710347809.734617][937988:937991] CHIP:DMG: [1710347809.734619][937988:937991] CHIP:DMG: }, [1710347809.734623][937988:937991] CHIP:DMG: [1710347809.734625][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734628][937988:937991] CHIP:DMG: { [1710347809.734630][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734633][937988:937991] CHIP:DMG: { [1710347809.734635][937988:937991] CHIP:DMG: DataVersion = 0x4539b228, [1710347809.734637][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734639][937988:937991] CHIP:DMG: { [1710347809.734641][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734643][937988:937991] CHIP:DMG: Cluster = 0x28, [1710347809.734645][937988:937991] CHIP:DMG: Attribute = 0x0000_0004, [1710347809.734647][937988:937991] CHIP:DMG: } [1710347809.734650][937988:937991] CHIP:DMG: [1710347809.734652][937988:937991] CHIP:DMG: Data = 32773, [1710347809.734654][937988:937991] CHIP:DMG: }, [1710347809.734657][937988:937991] CHIP:DMG: [1710347809.734659][937988:937991] CHIP:DMG: }, [1710347809.734663][937988:937991] CHIP:DMG: [1710347809.734665][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734668][937988:937991] CHIP:DMG: { [1710347809.734670][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734672][937988:937991] CHIP:DMG: { [1710347809.734675][937988:937991] CHIP:DMG: DataVersion = 0x4539b228, [1710347809.734677][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734679][937988:937991] CHIP:DMG: { [1710347809.734681][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734683][937988:937991] CHIP:DMG: Cluster = 0x28, [1710347809.734685][937988:937991] CHIP:DMG: Attribute = 0x0000_0002, [1710347809.734687][937988:937991] CHIP:DMG: } [1710347809.734690][937988:937991] CHIP:DMG: [1710347809.734692][937988:937991] CHIP:DMG: Data = 65521, [1710347809.734694][937988:937991] CHIP:DMG: }, [1710347809.734697][937988:937991] CHIP:DMG: [1710347809.734698][937988:937991] CHIP:DMG: }, [1710347809.734703][937988:937991] CHIP:DMG: [1710347809.734713][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734718][937988:937991] CHIP:DMG: { [1710347809.734720][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734722][937988:937991] CHIP:DMG: { [1710347809.734724][937988:937991] CHIP:DMG: DataVersion = 0xad0a0806, [1710347809.734726][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734728][937988:937991] CHIP:DMG: { [1710347809.734730][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734732][937988:937991] CHIP:DMG: Cluster = 0x30, [1710347809.734735][937988:937991] CHIP:DMG: Attribute = 0x0000_0003, [1710347809.734736][937988:937991] CHIP:DMG: } [1710347809.734739][937988:937991] CHIP:DMG: [1710347809.734742][937988:937991] CHIP:DMG: Data = 0, [1710347809.734744][937988:937991] CHIP:DMG: }, [1710347809.734747][937988:937991] CHIP:DMG: [1710347809.734748][937988:937991] CHIP:DMG: }, [1710347809.734752][937988:937991] CHIP:DMG: [1710347809.734754][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734757][937988:937991] CHIP:DMG: { [1710347809.734762][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734764][937988:937991] CHIP:DMG: { [1710347809.734766][937988:937991] CHIP:DMG: DataVersion = 0xad0a0806, [1710347809.734768][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734770][937988:937991] CHIP:DMG: { [1710347809.734773][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734775][937988:937991] CHIP:DMG: Cluster = 0x30, [1710347809.734777][937988:937991] CHIP:DMG: Attribute = 0x0000_0002, [1710347809.734779][937988:937991] CHIP:DMG: } [1710347809.734781][937988:937991] CHIP:DMG: [1710347809.734784][937988:937991] CHIP:DMG: Data = 0, [1710347809.734786][937988:937991] CHIP:DMG: }, [1710347809.734789][937988:937991] CHIP:DMG: [1710347809.734791][937988:937991] CHIP:DMG: }, [1710347809.734795][937988:937991] CHIP:DMG: [1710347809.734797][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734801][937988:937991] CHIP:DMG: { [1710347809.734802][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734805][937988:937991] CHIP:DMG: { [1710347809.734807][937988:937991] CHIP:DMG: DataVersion = 0xad0a0806, [1710347809.734809][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734811][937988:937991] CHIP:DMG: { [1710347809.734813][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734815][937988:937991] CHIP:DMG: Cluster = 0x30, [1710347809.734817][937988:937991] CHIP:DMG: Attribute = 0x0000_0001, [1710347809.734819][937988:937991] CHIP:DMG: } [1710347809.734822][937988:937991] CHIP:DMG: [1710347809.734824][937988:937991] CHIP:DMG: Data = [1710347809.734826][937988:937991] CHIP:DMG: { [1710347809.734829][937988:937991] CHIP:DMG: 0x0 = 60, [1710347809.734831][937988:937991] CHIP:DMG: 0x1 = 900, [1710347809.734833][937988:937991] CHIP:DMG: }, [1710347809.734835][937988:937991] CHIP:DMG: }, [1710347809.734839][937988:937991] CHIP:DMG: [1710347809.734840][937988:937991] CHIP:DMG: }, [1710347809.734845][937988:937991] CHIP:DMG: [1710347809.734847][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734850][937988:937991] CHIP:DMG: { [1710347809.734852][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734854][937988:937991] CHIP:DMG: { [1710347809.734856][937988:937991] CHIP:DMG: DataVersion = 0xad0a0806, [1710347809.734858][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734860][937988:937991] CHIP:DMG: { [1710347809.734863][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734865][937988:937991] CHIP:DMG: Cluster = 0x30, [1710347809.734867][937988:937991] CHIP:DMG: Attribute = 0x0000_0000, [1710347809.734869][937988:937991] CHIP:DMG: } [1710347809.734872][937988:937991] CHIP:DMG: [1710347809.734874][937988:937991] CHIP:DMG: Data = 0, [1710347809.734876][937988:937991] CHIP:DMG: }, [1710347809.734879][937988:937991] CHIP:DMG: [1710347809.734880][937988:937991] CHIP:DMG: }, [1710347809.734885][937988:937991] CHIP:DMG: [1710347809.734887][937988:937991] CHIP:DMG: AttributeReportIB = [1710347809.734890][937988:937991] CHIP:DMG: { [1710347809.734892][937988:937991] CHIP:DMG: AttributeDataIB = [1710347809.734894][937988:937991] CHIP:DMG: { [1710347809.734896][937988:937991] CHIP:DMG: DataVersion = 0x81d1a383, [1710347809.734898][937988:937991] CHIP:DMG: AttributePathIB = [1710347809.734900][937988:937991] CHIP:DMG: { [1710347809.734902][937988:937991] CHIP:DMG: Endpoint = 0x0, [1710347809.734905][937988:937991] CHIP:DMG: Cluster = 0x31, [1710347809.734907][937988:937991] CHIP:DMG: Attribute = 0x0000_FFFC, [1710347809.734909][937988:937991] CHIP:DMG: } [1710347809.734911][937988:937991] CHIP:DMG: [1710347809.734914][937988:937991] CHIP:DMG: Data = 1, [1710347809.734916][937988:937991] CHIP:DMG: }, [1710347809.734918][937988:937991] CHIP:DMG: [1710347809.734920][937988:937991] CHIP:DMG: }, [1710347809.734923][937988:937991] CHIP:DMG: [1710347809.734928][937988:937991] CHIP:DMG: ], [1710347809.734938][937988:937991] CHIP:DMG: [1710347809.734941][937988:937991] CHIP:DMG: SuppressResponse = true, [1710347809.734943][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347809.734945][937988:937991] CHIP:DMG: } [1710347809.735122][937988:937991] CHIP:CTL: ----- NetworkCommissioning Features: has WiFi. endpointid = 0 [1710347809.735138][937988:937991] CHIP:CTL: Successfully finished commissioning step 'ReadCommissioningInfo' [1710347809.735143][937988:937991] CHIP:CTL: Commissioning stage next step: 'ReadCommissioningInfo' -> 'ArmFailSafe' [1710347809.735146][937988:937991] CHIP:CTL: Performing next commissioning step 'ArmFailSafe' [1710347809.735149][937988:937991] CHIP:CTL: Arming failsafe (60 seconds) [1710347809.735161][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347809.735170][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347809.735184][937988:937991] CHIP:EM: <<< [E:17436i S:8233 M:200193202] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347809.735188][937988:937991] CHIP:IN: (S) Sending msg 200193202 on secure session with LSID: 8233 [1710347809.735436][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347809.823807][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347809.869798][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347809.869883][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347809.869938][937988:937991] CHIP:EM: >>> [E:17436i S:8233 M:171809788] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347809.869944][937988:937991] CHIP:EM: Found matching exchange: 17436i, Delegate: 0x7f64d4008898 [1710347809.869950][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347809.869960][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347809.869962][937988:937991] CHIP:DMG: { [1710347809.869965][937988:937991] CHIP:DMG: suppressResponse = false, [1710347809.869966][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347809.869970][937988:937991] CHIP:DMG: [ [1710347809.869972][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347809.869976][937988:937991] CHIP:DMG: { [1710347809.869978][937988:937991] CHIP:DMG: CommandDataIB = [1710347809.869981][937988:937991] CHIP:DMG: { [1710347809.869982][937988:937991] CHIP:DMG: CommandPathIB = [1710347809.869985][937988:937991] CHIP:DMG: { [1710347809.869988][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347809.869990][937988:937991] CHIP:DMG: ClusterId = 0x30, [1710347809.869992][937988:937991] CHIP:DMG: CommandId = 0x1, [1710347809.869994][937988:937991] CHIP:DMG: }, [1710347809.869996][937988:937991] CHIP:DMG: [1710347809.869999][937988:937991] CHIP:DMG: CommandFields = [1710347809.870001][937988:937991] CHIP:DMG: { [1710347809.870004][937988:937991] CHIP:DMG: 0x0 = 0, [1710347809.870007][937988:937991] CHIP:DMG: 0x1 = "" (0 chars), [1710347809.870009][937988:937991] CHIP:DMG: }, [1710347809.870011][937988:937991] CHIP:DMG: }, [1710347809.870014][937988:937991] CHIP:DMG: [1710347809.870016][937988:937991] CHIP:DMG: }, [1710347809.870019][937988:937991] CHIP:DMG: [1710347809.870021][937988:937991] CHIP:DMG: ], [1710347809.870025][937988:937991] CHIP:DMG: [1710347809.870027][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347809.870029][937988:937991] CHIP:DMG: }, [1710347809.870040][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1710347809.870052][937988:937991] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1710347809.870057][937988:937991] CHIP:CTL: Successfully finished commissioning step 'ArmFailSafe' [1710347809.870060][937988:937991] CHIP:CTL: Commissioning stage next step: 'ArmFailSafe' -> 'ConfigRegulatory' [1710347809.870064][937988:937991] CHIP:CTL: Performing next commissioning step 'ConfigRegulatory' [1710347809.870066][937988:937991] CHIP:CTL: Setting Regulatory Config [1710347809.870073][937988:937991] CHIP:CTL: Device does not support configurable regulatory location [1710347809.870087][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347809.870095][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347809.870108][937988:937991] CHIP:EM: <<< [E:17437i S:8233 M:200193203] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347809.870112][937988:937991] CHIP:IN: (S) Sending msg 200193203 on secure session with LSID: 8233 [1710347809.870254][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347809.870276][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347809.959279][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347810.004628][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.004680][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.004746][937988:937991] CHIP:EM: >>> [E:17437i S:8233 M:171809789] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347810.004752][937988:937991] CHIP:EM: Found matching exchange: 17437i, Delegate: 0x7f64d4007a08 [1710347810.004762][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347810.004772][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347810.004774][937988:937991] CHIP:DMG: { [1710347810.004777][937988:937991] CHIP:DMG: suppressResponse = false, [1710347810.004779][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347810.004782][937988:937991] CHIP:DMG: [ [1710347810.004784][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347810.004788][937988:937991] CHIP:DMG: { [1710347810.004790][937988:937991] CHIP:DMG: CommandDataIB = [1710347810.004793][937988:937991] CHIP:DMG: { [1710347810.004795][937988:937991] CHIP:DMG: CommandPathIB = [1710347810.004797][937988:937991] CHIP:DMG: { [1710347810.004800][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347810.004802][937988:937991] CHIP:DMG: ClusterId = 0x30, [1710347810.004804][937988:937991] CHIP:DMG: CommandId = 0x3, [1710347810.004806][937988:937991] CHIP:DMG: }, [1710347810.004809][937988:937991] CHIP:DMG: [1710347810.004811][937988:937991] CHIP:DMG: CommandFields = [1710347810.004813][937988:937991] CHIP:DMG: { [1710347810.004816][937988:937991] CHIP:DMG: 0x0 = 0, [1710347810.004819][937988:937991] CHIP:DMG: 0x1 = "" (0 chars), [1710347810.004821][937988:937991] CHIP:DMG: }, [1710347810.004823][937988:937991] CHIP:DMG: }, [1710347810.004826][937988:937991] CHIP:DMG: [1710347810.004829][937988:937991] CHIP:DMG: }, [1710347810.004832][937988:937991] CHIP:DMG: [1710347810.004834][937988:937991] CHIP:DMG: ], [1710347810.004838][937988:937991] CHIP:DMG: [1710347810.004840][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347810.004842][937988:937991] CHIP:DMG: }, [1710347810.004853][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0003 [1710347810.004873][937988:937991] CHIP:CTL: Received SetRegulatoryConfig response errorCode=0 [1710347810.004879][937988:937991] CHIP:CTL: Successfully finished commissioning step 'ConfigRegulatory' [1710347810.004881][937988:937991] CHIP:CTL: Commissioning stage next step: 'ConfigRegulatory' -> 'SendPAICertificateRequest' [1710347810.004885][937988:937991] CHIP:CTL: Performing next commissioning step 'SendPAICertificateRequest' [1710347810.004887][937988:937991] CHIP:CTL: Sending request for PAI certificate [1710347810.004889][937988:937991] CHIP:CTL: Sending Certificate Chain request to 0x7f64d400c200 device [1710347810.004900][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347810.004908][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347810.004921][937988:937991] CHIP:EM: <<< [E:17438i S:8233 M:200193204] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347810.004926][937988:937991] CHIP:IN: (S) Sending msg 200193204 on secure session with LSID: 8233 [1710347810.005063][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347810.005090][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347810.093768][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347810.141900][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.142075][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.231242][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.231337][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.319753][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.319815][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.319868][937988:937991] CHIP:EM: >>> [E:17438i S:8233 M:171809790] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347810.319874][937988:937991] CHIP:EM: Found matching exchange: 17438i, Delegate: 0x7f64d4008898 [1710347810.319881][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347810.319892][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347810.319894][937988:937991] CHIP:DMG: { [1710347810.319897][937988:937991] CHIP:DMG: suppressResponse = false, [1710347810.319899][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347810.319903][937988:937991] CHIP:DMG: [ [1710347810.319905][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347810.319909][937988:937991] CHIP:DMG: { [1710347810.319911][937988:937991] CHIP:DMG: CommandDataIB = [1710347810.319914][937988:937991] CHIP:DMG: { [1710347810.319916][937988:937991] CHIP:DMG: CommandPathIB = [1710347810.319919][937988:937991] CHIP:DMG: { [1710347810.319921][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347810.319924][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347810.319926][937988:937991] CHIP:DMG: CommandId = 0x3, [1710347810.319928][937988:937991] CHIP:DMG: }, [1710347810.319931][937988:937991] CHIP:DMG: [1710347810.319933][937988:937991] CHIP:DMG: CommandFields = [1710347810.319935][937988:937991] CHIP:DMG: { [1710347810.319938][937988:937991] CHIP:DMG: 0x0 = [ [1710347810.319959][937988:937991] 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 [1710347810.319962][937988:937991] CHIP:DMG: ] (463 bytes) [1710347810.319964][937988:937991] CHIP:DMG: }, [1710347810.319966][937988:937991] CHIP:DMG: }, [1710347810.319979][937988:937991] CHIP:DMG: [1710347810.319981][937988:937991] CHIP:DMG: }, [1710347810.319984][937988:937991] CHIP:DMG: [1710347810.319986][937988:937991] CHIP:DMG: ], [1710347810.319990][937988:937991] CHIP:DMG: [1710347810.319992][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347810.319994][937988:937991] CHIP:DMG: }, [1710347810.320005][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1710347810.320011][937988:937991] CHIP:CTL: Received certificate chain from the device [1710347810.320017][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendPAICertificateRequest' [1710347810.320021][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendPAICertificateRequest' -> 'SendDACCertificateRequest' [1710347810.320025][937988:937991] CHIP:CTL: Performing next commissioning step 'SendDACCertificateRequest' [1710347810.320027][937988:937991] CHIP:CTL: Sending request for DAC certificate [1710347810.320029][937988:937991] CHIP:CTL: Sending Certificate Chain request to 0x7f64d400c200 device [1710347810.320042][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347810.320050][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347810.320063][937988:937991] CHIP:EM: <<< [E:17439i S:8233 M:200193205] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347810.320068][937988:937991] CHIP:IN: (S) Sending msg 200193205 on secure session with LSID: 8233 [1710347810.320316][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347810.320334][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347810.409125][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347810.456466][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.456592][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.591411][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.591483][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.679962][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.680075][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.680128][937988:937991] CHIP:EM: >>> [E:17439i S:8233 M:171809791] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347810.680133][937988:937991] CHIP:EM: Found matching exchange: 17439i, Delegate: 0x7f64d4007a08 [1710347810.680140][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347810.680149][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347810.680151][937988:937991] CHIP:DMG: { [1710347810.680154][937988:937991] CHIP:DMG: suppressResponse = false, [1710347810.680156][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347810.680159][937988:937991] CHIP:DMG: [ [1710347810.680161][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347810.680164][937988:937991] CHIP:DMG: { [1710347810.680166][937988:937991] CHIP:DMG: CommandDataIB = [1710347810.680168][937988:937991] CHIP:DMG: { [1710347810.680170][937988:937991] CHIP:DMG: CommandPathIB = [1710347810.680173][937988:937991] CHIP:DMG: { [1710347810.680175][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347810.680177][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347810.680179][937988:937991] CHIP:DMG: CommandId = 0x3, [1710347810.680181][937988:937991] CHIP:DMG: }, [1710347810.680183][937988:937991] CHIP:DMG: [1710347810.680185][937988:937991] CHIP:DMG: CommandFields = [1710347810.680187][937988:937991] CHIP:DMG: { [1710347810.680190][937988:937991] CHIP:DMG: 0x0 = [ [1710347810.680208][937988:937991] CHIP:DMG: 0x30, 0x82, 0x01, 0xe8, 0x30, 0x82, 0x01, 0x8e, 0xa0, 0x03, 0x02, 0x01, 0x02, 0x02, 0x08, 0x6f, 0xdc, 0xb6, 0xed, 0x06, 0xf3, 0x58, 0xf9, 0x30, 0x0a, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x30, 0x3d, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x50, 0x41, 0x49, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x20, 0x6e, 0x6f, 0x20, 0x50, 0x49, 0x44, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x30, 0x20, 0x17, 0x0d, 0x32, 0x32, 0x30, 0x32, 0x30, 0x35, 0x30, 0x30, 0x30, 0x30, 0x30, 0x30, 0x5a, 0x18, 0x0f, 0x39, 0x39, 0x39, 0x39, 0x31, 0x32, 0x33, 0x31, 0x32, 0x33, 0x35, 0x39, 0x35, 0x39, 0x5a, 0x30, 0x53, 0x31, 0x25, 0x30, 0x23, 0x06, 0x03, 0x55, 0x04, 0x03, 0x0c, 0x1c, 0x4d, 0x61, 0x74, 0x74, 0x65, 0x72, 0x20, 0x44, 0x65, 0x76, 0x20, 0x44, 0x41, 0x43, 0x20, 0x30, 0x78, 0x46, 0x46, 0x46, 0x31, 0x2f, 0x30, 0x78, 0x38, 0x30, 0x30, 0x35, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x01, 0x0c, 0x04, 0x46, 0x46, 0x46, 0x31, 0x31, 0x14, 0x30, 0x12, 0x06, 0x0a, 0x2b, 0x06, 0x01, 0x04, 0x01, 0x82, 0xa2, 0x7c, 0x02, 0x02, 0x0c, 0x04, 0x38, 0x30, 0x30, 0x35, 0x30, 0x59, 0x30, 0x13, 0x06, 0x07, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x02, 0x01, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x03, 0x01, 0x07, 0x03, 0x42, 0x00, 0x04, 0x7e, 0xf2, 0xf1, 0x08, 0x2b, 0x2a, 0x56, 0x4a, 0x05, 0x44, 0xc0, 0xa6, 0x13, 0xd2, 0x1b, 0x15, 0x75, 0x00, 0x55, 0x2d, 0x03, 0x5f, 0x23, 0x02, 0x25, 0x2f, 0xce, 0xe8, 0x1d, 0x6e, 0xc6, 0x74, 0x17, 0x34, 0x18, 0xa9, 0x4a, 0x88, 0xdb, 0x [1710347810.680220][937988:937991] CHIP:DMG: ] (492 bytes) [1710347810.680222][937988:937991] CHIP:DMG: }, [1710347810.680224][937988:937991] CHIP:DMG: }, [1710347810.680227][937988:937991] CHIP:DMG: [1710347810.680229][937988:937991] CHIP:DMG: }, [1710347810.680232][937988:937991] CHIP:DMG: [1710347810.680233][937988:937991] CHIP:DMG: ], [1710347810.680237][937988:937991] CHIP:DMG: [1710347810.680239][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347810.680240][937988:937991] CHIP:DMG: }, [1710347810.680250][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1710347810.680255][937988:937991] CHIP:CTL: Received certificate chain from the device [1710347810.680261][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendDACCertificateRequest' [1710347810.680265][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendDACCertificateRequest' -> 'SendAttestationRequest' [1710347810.680269][937988:937991] CHIP:CTL: Performing next commissioning step 'SendAttestationRequest' [1710347810.680271][937988:937991] CHIP:CTL: Sending Attestation Request to the device. [1710347810.680273][937988:937991] CHIP:CTL: Sending Attestation request to 0x7f64d400c200 device [1710347810.680284][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347810.680288][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347810.680301][937988:937991] CHIP:EM: <<< [E:17440i S:8233 M:200193206] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347810.680306][937988:937991] CHIP:IN: (S) Sending msg 200193206 on secure session with LSID: 8233 [1710347810.680567][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347810.680578][937988:937991] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1710347810.680590][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347810.768940][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347810.861382][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.861509][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347810.996487][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347810.996628][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347811.086528][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347811.086871][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347811.086924][937988:937991] CHIP:EM: >>> [E:17440i S:8233 M:171809792] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347811.086944][937988:937991] CHIP:EM: Found matching exchange: 17440i, Delegate: 0x7f64d4008898 [1710347811.086952][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347811.086965][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347811.086973][937988:937991] CHIP:DMG: { [1710347811.086976][937988:937991] CHIP:DMG: suppressResponse = false, [1710347811.086979][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347811.086984][937988:937991] CHIP:DMG: [ [1710347811.086987][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347811.086993][937988:937991] CHIP:DMG: { [1710347811.086995][937988:937991] CHIP:DMG: CommandDataIB = [1710347811.086999][937988:937991] CHIP:DMG: { [1710347811.087001][937988:937991] CHIP:DMG: CommandPathIB = [1710347811.087004][937988:937991] CHIP:DMG: { [1710347811.087006][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347811.087008][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347811.087010][937988:937991] CHIP:DMG: CommandId = 0x1, [1710347811.087012][937988:937991] CHIP:DMG: }, [1710347811.087014][937988:937991] CHIP:DMG: [1710347811.087016][937988:937991] CHIP:DMG: CommandFields = [1710347811.087019][937988:937991] CHIP:DMG: { [1710347811.087021][937988:937991] CHIP:DMG: 0x0 = [ [1710347811.087040][937988:937991] CHIP:DMG: 0x15, 0x31, 0x01, 0x1d, 0x02, 0x30, 0x82, 0x02, 0x19, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x02, 0xa0, 0x82, 0x02, 0x0a, 0x30, 0x82, 0x02, 0x06, 0x02, 0x01, 0x03, 0x31, 0x0d, 0x30, 0x0b, 0x06, 0x09, 0x60, 0x86, 0x48, 0x01, 0x65, 0x03, 0x04, 0x02, 0x01, 0x30, 0x82, 0x01, 0x71, 0x06, 0x09, 0x2a, 0x86, 0x48, 0x86, 0xf7, 0x0d, 0x01, 0x07, 0x01, 0xa0, 0x82, 0x01, 0x62, 0x04, 0x82, 0x01, 0x5e, 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 [1710347811.087043][937988:937991] CHIP:DMG: ] (585 bytes) [1710347811.087045][937988:937991] CHIP:DMG: 0x1 = [ [1710347811.087050][937988:937991] CHIP:DMG: 0x66, 0x51, 0x70, 0xc5, 0x54, 0xb3, 0x61, 0x5c, 0x3b, 0xcc, 0xa4, 0x8c, 0x4d, 0xd9, 0xe1, 0xce, 0xbe, 0x94, 0xbf, 0xa4, 0x55, 0x57, 0x59, 0x24, 0xdd, 0xf7, 0x61, 0x67, 0x0f, 0x07, 0x60, 0x15, 0xc7, 0x66, 0x8e, 0x8f, 0x27, 0xa5, 0xab, 0xf2, 0xb0, 0x6f, 0xed, 0x35, 0x4a, 0x46, 0x4d, 0xdd, 0x3f, 0x44, 0xea, 0xdc, 0x47, 0xc6, 0x32, 0x2c, 0xea, 0x4d, 0x20, 0x86, 0x26, 0x4b, 0x9f, 0x17, [1710347811.087052][937988:937991] CHIP:DMG: ] (64 bytes) [1710347811.087054][937988:937991] CHIP:DMG: }, [1710347811.087056][937988:937991] CHIP:DMG: }, [1710347811.087059][937988:937991] CHIP:DMG: [1710347811.087060][937988:937991] CHIP:DMG: }, [1710347811.087063][937988:937991] CHIP:DMG: [1710347811.087065][937988:937991] CHIP:DMG: ], [1710347811.087069][937988:937991] CHIP:DMG: [1710347811.087071][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347811.087075][937988:937991] CHIP:DMG: }, [1710347811.087086][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1710347811.087091][937988:937991] CHIP:CTL: Received Attestation Information from the device [1710347811.087097][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendAttestationRequest' [1710347811.087101][937988:937991] CHIP:CTL: AutoCommissioner setting attestationElements buffer size 585/585 [1710347811.087105][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendAttestationRequest' -> 'AttestationVerification' [1710347811.087109][937988:937991] CHIP:CTL: Performing next commissioning step 'AttestationVerification' [1710347811.087110][937988:937991] CHIP:CTL: Verifying attestation [1710347811.091508][937988:937991] CHIP:CR: mbedTLS error: ERROR - Generic error [1710347811.103723][937988:937991] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1710347811.103728][937988:937991] CHIP:CTL: Successfully finished commissioning step 'AttestationVerification' [1710347811.103730][937988:937991] CHIP:CTL: Commissioning stage next step: 'AttestationVerification' -> 'SendOpCertSigningRequest' [1710347811.103732][937988:937991] CHIP:CTL: Performing next commissioning step 'SendOpCertSigningRequest' [1710347811.103734][937988:937991] CHIP:CTL: Sending CSR request to 0x7f64d400c200 device [1710347811.103750][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347811.103753][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347811.103768][937988:937991] CHIP:EM: <<< [E:17441i S:8233 M:200193207] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347811.103772][937988:937991] CHIP:IN: (S) Sending msg 200193207 on secure session with LSID: 8233 [1710347811.103838][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347811.103843][937988:937991] CHIP:CTL: Sent CSR request, waiting for the CSR [1710347811.103850][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347811.173604][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347811.401711][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347811.401809][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347811.490616][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347811.490843][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347811.490900][937988:937991] CHIP:EM: >>> [E:17441i S:8233 M:171809793] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347811.490910][937988:937991] CHIP:EM: Found matching exchange: 17441i, Delegate: 0x7f64d401c878 [1710347811.490918][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347811.490930][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347811.490933][937988:937991] CHIP:DMG: { [1710347811.490936][937988:937991] CHIP:DMG: suppressResponse = false, [1710347811.490938][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347811.490943][937988:937991] CHIP:DMG: [ [1710347811.490945][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347811.490949][937988:937991] CHIP:DMG: { [1710347811.490952][937988:937991] CHIP:DMG: CommandDataIB = [1710347811.490954][937988:937991] CHIP:DMG: { [1710347811.490957][937988:937991] CHIP:DMG: CommandPathIB = [1710347811.490960][937988:937991] CHIP:DMG: { [1710347811.490963][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347811.490965][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347811.490968][937988:937991] CHIP:DMG: CommandId = 0x5, [1710347811.490970][937988:937991] CHIP:DMG: }, [1710347811.490973][937988:937991] CHIP:DMG: [1710347811.490975][937988:937991] CHIP:DMG: CommandFields = [1710347811.490978][937988:937991] CHIP:DMG: { [1710347811.490981][937988:937991] CHIP:DMG: 0x0 = [ [1710347811.491000][937988:937991] 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, 0xd1, 0x49, 0x6f, 0xe7, 0x57, 0x42, 0x27, 0x05, 0x62, 0x6f, 0xad, 0xe8, 0x41, 0x52, 0x38, 0xcc, 0xf2, 0xc9, 0xd2, 0xdd, 0xf6, 0xd6, 0x1e, 0xc6, 0x5f, 0xb1, 0x5b, 0xea, 0xf1, 0x15, 0x2e, 0x4a, 0x3f, 0x7c, 0xba, 0x38, 0x0c, 0xa9, 0xd2, 0x73, 0x8a, 0x8a, 0x98, 0xa8, 0xa1, 0x34, 0x0e, 0xc7, 0xa8, 0xb9, 0xaf, 0xf3, 0x8f, 0x84, 0x4d, 0x93, 0x87, 0x13, 0x7f, 0x24, 0x21, 0xec, 0x55, 0xed, 0xa0, 0x00, 0x30, 0x0c, 0x06, 0x08, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x04, 0x03, 0x02, 0x05, 0x00, 0x03, 0x47, 0x00, 0x30, 0x44, 0x02, 0x20, 0x3c, 0x75, 0x96, 0x7f, 0xa6, 0xc4, 0xa5, 0x3f, 0x39, 0x01, 0xd4, 0x68, 0xc9, 0x6e, 0xbd, 0xeb, 0x39, 0xdf, 0xa6, 0xc1, 0x88, 0xcb, 0xbb, 0x66, 0x11, 0xd5, 0xf0, 0x4d, 0x1a, 0x4c, 0xe9, 0x5b, 0x02, 0x20, 0x13, 0x47, 0x68, 0xa5, 0x2f, 0x18, 0x9c, 0xd2, 0x0d, 0x69, 0xc3, 0x2e, 0x70, 0xf3, 0x2c, 0x93, 0x51, 0x9f, 0x86, 0x28, 0xe7, 0x62, 0x27, 0x0a, 0x27, 0x66, 0x94, 0x3e, 0x10, 0x7b, 0x2a, 0x8f, 0x30, 0x02, 0x20, 0xb0, 0x4d, 0x75, 0xfd, 0xdf, 0x16, 0x75, 0xf8, 0x36, 0x99, 0xb4, 0xc3, 0xed, 0x60, 0x97, 0xbc, 0x49, 0xae, 0xf5, 0x37, 0xd6, 0xd3, 0xc4, 0xb8, 0xe5, 0x32, 0x6d, 0x86, 0x8b, 0x1c, 0xe9, 0x20, 0x18, [1710347811.491014][937988:937991] CHIP:DMG: ] (244 bytes) [1710347811.491017][937988:937991] CHIP:DMG: 0x1 = [ [1710347811.491023][937988:937991] CHIP:DMG: 0x8a, 0x92, 0x99, 0x84, 0x42, 0xea, 0x42, 0x2e, 0xe2, 0x47, 0x6e, 0x6e, 0x49, 0x9a, 0x9d, 0x53, 0x94, 0xda, 0x25, 0xf0, 0x9c, 0x20, 0x85, 0x00, 0x73, 0xee, 0xad, 0x2e, 0xe5, 0xb9, 0x19, 0x02, 0x14, 0x99, 0xa6, 0x16, 0xa7, 0x3f, 0x2f, 0x49, 0x16, 0xb4, 0xac, 0x0c, 0x74, 0x03, 0x83, 0xfb, 0xb2, 0x48, 0x52, 0x7e, 0x13, 0x85, 0x27, 0x40, 0xcd, 0x0f, 0xd5, 0x59, 0x51, 0x55, 0xdb, 0xaf, [1710347811.491026][937988:937991] CHIP:DMG: ] (64 bytes) [1710347811.491028][937988:937991] CHIP:DMG: }, [1710347811.491031][937988:937991] CHIP:DMG: }, [1710347811.491034][937988:937991] CHIP:DMG: [1710347811.491038][937988:937991] CHIP:DMG: }, [1710347811.491041][937988:937991] CHIP:DMG: [1710347811.491042][937988:937991] CHIP:DMG: ], [1710347811.491046][937988:937991] CHIP:DMG: [1710347811.491048][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347811.491050][937988:937991] CHIP:DMG: }, [1710347811.491060][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1710347811.491066][937988:937991] CHIP:CTL: Received certificate signing request from the device [1710347811.491072][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendOpCertSigningRequest' [1710347811.491075][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendOpCertSigningRequest' -> 'ValidateCSR' [1710347811.491079][937988:937991] CHIP:CTL: Performing next commissioning step 'ValidateCSR' [1710347811.495164][937988:937991] CHIP:CTL: Successfully finished commissioning step 'ValidateCSR' [1710347811.495168][937988:937991] CHIP:CTL: Commissioning stage next step: 'ValidateCSR' -> 'GenerateNOCChain' [1710347811.495170][937988:937991] CHIP:CTL: Performing next commissioning step 'GenerateNOCChain' [1710347811.495171][937988:937991] CHIP:CTL: Getting certificate chain for the device from the issuer [1710347811.495179][937988:937991] CHIP:CTL: Verifying Certificate Signing Request [1710347811.499365][937988:937991] CHIP:CTL: Generating NOC [1710347811.501758][937988:937991] CHIP:CTL: Providing certificate chain to the commissioner [1710347811.501760][937988:937991] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status src/controller/ExampleOperationalCredentialsIssuer.cpp:396: Success [1710347811.501762][937988:937991] CHIP:CTL: Successfully finished commissioning step 'GenerateNOCChain' [1710347811.501784][937988:937991] CHIP:CTL: Performing next commissioning step 'SendTrustedRootCert' [1710347811.501789][937988:937991] CHIP:CTL: Sending root certificate to the device [1710347811.501800][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347811.501803][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347811.501815][937988:937991] CHIP:EM: <<< [E:17442i S:8233 M:200193208] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347811.501819][937988:937991] CHIP:IN: (S) Sending msg 200193208 on secure session with LSID: 8233 [1710347811.502081][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347811.502085][937988:937991] CHIP:CTL: Sent root certificate to the device [1710347811.502132][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347811.578917][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347811.669236][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347811.760323][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347811.760482][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347811.760530][937988:937991] CHIP:EM: >>> [E:17442i S:8233 M:171809794] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347811.760537][937988:937991] CHIP:EM: Found matching exchange: 17442i, Delegate: 0x7f64d4008898 [1710347811.760542][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347811.760552][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347811.760555][937988:937991] CHIP:DMG: { [1710347811.760558][937988:937991] CHIP:DMG: suppressResponse = false, [1710347811.760560][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347811.760564][937988:937991] CHIP:DMG: [ [1710347811.760566][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347811.760570][937988:937991] CHIP:DMG: { [1710347811.760572][937988:937991] CHIP:DMG: CommandStatusIB = [1710347811.760575][937988:937991] CHIP:DMG: { [1710347811.760577][937988:937991] CHIP:DMG: CommandPathIB = [1710347811.760580][937988:937991] CHIP:DMG: { [1710347811.760583][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347811.760585][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347811.760587][937988:937991] CHIP:DMG: CommandId = 0xb, [1710347811.760590][937988:937991] CHIP:DMG: }, [1710347811.760593][937988:937991] CHIP:DMG: [1710347811.760595][937988:937991] CHIP:DMG: StatusIB = [1710347811.760598][937988:937991] CHIP:DMG: { [1710347811.760600][937988:937991] CHIP:DMG: status = 0x00 (SUCCESS), [1710347811.760602][937988:937991] CHIP:DMG: }, [1710347811.760605][937988:937991] CHIP:DMG: [1710347811.760607][937988:937991] CHIP:DMG: }, [1710347811.760610][937988:937991] CHIP:DMG: [1710347811.760612][937988:937991] CHIP:DMG: }, [1710347811.760616][937988:937991] CHIP:DMG: [1710347811.760618][937988:937991] CHIP:DMG: ], [1710347811.760621][937988:937991] CHIP:DMG: [1710347811.760624][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347811.760626][937988:937991] CHIP:DMG: }, [1710347811.760637][937988:937991] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1710347811.760641][937988:937991] CHIP:CTL: Device confirmed that it has received the root certificate [1710347811.760647][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendTrustedRootCert' [1710347811.760649][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendTrustedRootCert' -> 'SendNOC' [1710347811.760653][937988:937991] CHIP:CTL: Performing next commissioning step 'SendNOC' [1710347811.760669][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347811.760675][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347811.760690][937988:937991] CHIP:EM: <<< [E:17443i S:8233 M:200193209] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347811.760695][937988:937991] CHIP:IN: (S) Sending msg 200193209 on secure session with LSID: 8233 [1710347811.760926][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347811.760940][937988:937991] CHIP:CTL: Sent operational certificate to the device [1710347811.760959][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347811.849179][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347811.939160][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347812.029312][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347812.299589][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347812.299707][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347812.299762][937988:937991] CHIP:EM: >>> [E:17443i S:8233 M:171809795] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347812.299770][937988:937991] CHIP:EM: Found matching exchange: 17443i, Delegate: 0x7f64d401c878 [1710347812.299774][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347812.299788][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347812.299790][937988:937991] CHIP:DMG: { [1710347812.299792][937988:937991] CHIP:DMG: suppressResponse = false, [1710347812.299794][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347812.299797][937988:937991] CHIP:DMG: [ [1710347812.299799][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347812.299803][937988:937991] CHIP:DMG: { [1710347812.299804][937988:937991] CHIP:DMG: CommandDataIB = [1710347812.299807][937988:937991] CHIP:DMG: { [1710347812.299809][937988:937991] CHIP:DMG: CommandPathIB = [1710347812.299811][937988:937991] CHIP:DMG: { [1710347812.299813][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347812.299815][937988:937991] CHIP:DMG: ClusterId = 0x3e, [1710347812.299817][937988:937991] CHIP:DMG: CommandId = 0x8, [1710347812.299819][937988:937991] CHIP:DMG: }, [1710347812.299821][937988:937991] CHIP:DMG: [1710347812.299823][937988:937991] CHIP:DMG: CommandFields = [1710347812.299825][937988:937991] CHIP:DMG: { [1710347812.299828][937988:937991] CHIP:DMG: 0x0 = 0, [1710347812.299830][937988:937991] CHIP:DMG: 0x1 = 1, [1710347812.299832][937988:937991] CHIP:DMG: }, [1710347812.299833][937988:937991] CHIP:DMG: }, [1710347812.299836][937988:937991] CHIP:DMG: [1710347812.299838][937988:937991] CHIP:DMG: }, [1710347812.299841][937988:937991] CHIP:DMG: [1710347812.299842][937988:937991] CHIP:DMG: ], [1710347812.299846][937988:937991] CHIP:DMG: [1710347812.299848][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347812.299849][937988:937991] CHIP:DMG: }, [1710347812.299859][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1710347812.299866][937988:937991] CHIP:CTL: Device returned status 0 on receiving the NOC [1710347812.299868][937988:937991] CHIP:CTL: Operational credentials provisioned on device 0x7f64d400c200 [1710347812.299871][937988:937991] CHIP:TOO: Secure Pairing Success [1710347812.299872][937988:937991] CHIP:TOO: CASE establishment successful [1710347812.299877][937988:937991] CHIP:CTL: Successfully finished commissioning step 'SendNOC' [1710347812.299880][937988:937991] CHIP:CTL: No NetworkScan enabled or WiFi/Thread endpoint not specified, skipping ScanNetworks [1710347812.299882][937988:937991] CHIP:CTL: Commissioning stage next step: 'SendNOC' -> 'WiFiNetworkSetup' [1710347812.299885][937988:937991] CHIP:CTL: Performing next commissioning step 'WiFiNetworkSetup' [1710347812.299898][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347812.299903][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347812.299915][937988:937991] CHIP:EM: <<< [E:17444i S:8233 M:200193210] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347812.299919][937988:937991] CHIP:IN: (S) Sending msg 200193210 on secure session with LSID: 8233 [1710347812.300128][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347812.300146][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347812.389109][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347812.434754][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347812.434822][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347812.434883][937988:937991] CHIP:EM: >>> [E:17444i S:8233 M:171809796] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347812.434891][937988:937991] CHIP:EM: Found matching exchange: 17444i, Delegate: 0x7f64d4008898 [1710347812.434897][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347812.434908][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347812.434911][937988:937991] CHIP:DMG: { [1710347812.434917][937988:937991] CHIP:DMG: suppressResponse = false, [1710347812.434919][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347812.434924][937988:937991] CHIP:DMG: [ [1710347812.434926][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347812.434930][937988:937991] CHIP:DMG: { [1710347812.434933][937988:937991] CHIP:DMG: CommandDataIB = [1710347812.434936][937988:937991] CHIP:DMG: { [1710347812.434938][937988:937991] CHIP:DMG: CommandPathIB = [1710347812.434941][937988:937991] CHIP:DMG: { [1710347812.434944][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347812.434946][937988:937991] CHIP:DMG: ClusterId = 0x31, [1710347812.434949][937988:937991] CHIP:DMG: CommandId = 0x5, [1710347812.434951][937988:937991] CHIP:DMG: }, [1710347812.434954][937988:937991] CHIP:DMG: [1710347812.434957][937988:937991] CHIP:DMG: CommandFields = [1710347812.434959][937988:937991] CHIP:DMG: { [1710347812.434963][937988:937991] CHIP:DMG: 0x0 = 0, [1710347812.434965][937988:937991] CHIP:DMG: 0x2 = 0, [1710347812.434968][937988:937991] CHIP:DMG: }, [1710347812.434970][937988:937991] CHIP:DMG: }, [1710347812.434974][937988:937991] CHIP:DMG: [1710347812.434976][937988:937991] CHIP:DMG: }, [1710347812.434980][937988:937991] CHIP:DMG: [1710347812.434982][937988:937991] CHIP:DMG: ], [1710347812.434986][937988:937991] CHIP:DMG: [1710347812.434989][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347812.434991][937988:937991] CHIP:DMG: }, [1710347812.435003][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0005 [1710347812.435023][937988:937991] CHIP:CTL: Received NetworkConfig response, networkingStatus=0 [1710347812.435030][937988:937991] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkSetup' [1710347812.435033][937988:937991] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkSetup' -> 'FailsafeBeforeWiFiEnable' [1710347812.435037][937988:937991] CHIP:CTL: Performing next commissioning step 'FailsafeBeforeWiFiEnable' [1710347812.435043][937988:937991] CHIP:CTL: Arming failsafe (76 seconds) [1710347812.435056][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347812.435061][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347812.435082][937988:937991] CHIP:EM: <<< [E:17445i S:8233 M:200193211] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347812.435087][937988:937991] CHIP:IN: (S) Sending msg 200193211 on secure session with LSID: 8233 [1710347812.435330][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347812.435349][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347812.524075][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347812.570497][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347812.570565][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347812.570626][937988:937991] CHIP:EM: >>> [E:17445i S:8233 M:171809797] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347812.570632][937988:937991] CHIP:EM: Found matching exchange: 17445i, Delegate: 0x7f64d401c878 [1710347812.570638][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347812.570649][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347812.570650][937988:937991] CHIP:DMG: { [1710347812.570653][937988:937991] CHIP:DMG: suppressResponse = false, [1710347812.570667][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347812.570670][937988:937991] CHIP:DMG: [ [1710347812.570672][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347812.570675][937988:937991] CHIP:DMG: { [1710347812.570677][937988:937991] CHIP:DMG: CommandDataIB = [1710347812.570679][937988:937991] CHIP:DMG: { [1710347812.570681][937988:937991] CHIP:DMG: CommandPathIB = [1710347812.570684][937988:937991] CHIP:DMG: { [1710347812.570686][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347812.570688][937988:937991] CHIP:DMG: ClusterId = 0x30, [1710347812.570690][937988:937991] CHIP:DMG: CommandId = 0x1, [1710347812.570691][937988:937991] CHIP:DMG: }, [1710347812.570693][937988:937991] CHIP:DMG: [1710347812.570696][937988:937991] CHIP:DMG: CommandFields = [1710347812.570698][937988:937991] CHIP:DMG: { [1710347812.570700][937988:937991] CHIP:DMG: 0x0 = 0, [1710347812.570703][937988:937991] CHIP:DMG: 0x1 = "" (0 chars), [1710347812.570715][937988:937991] CHIP:DMG: }, [1710347812.570718][937988:937991] CHIP:DMG: }, [1710347812.570720][937988:937991] CHIP:DMG: [1710347812.570722][937988:937991] CHIP:DMG: }, [1710347812.570724][937988:937991] CHIP:DMG: [1710347812.570726][937988:937991] CHIP:DMG: ], [1710347812.570729][937988:937991] CHIP:DMG: [1710347812.570731][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347812.570733][937988:937991] CHIP:DMG: }, [1710347812.570743][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1710347812.570751][937988:937991] CHIP:CTL: Received ArmFailSafe response errorCode=0 [1710347812.570758][937988:937991] CHIP:CTL: Successfully finished commissioning step 'FailsafeBeforeWiFiEnable' [1710347812.570768][937988:937991] CHIP:CTL: Commissioning stage next step: 'FailsafeBeforeWiFiEnable' -> 'WiFiNetworkEnable' [1710347812.570770][937988:937991] CHIP:CTL: Setting wifi connection time min = 35 [1710347812.570774][937988:937991] CHIP:CTL: Performing next commissioning step 'WiFiNetworkEnable' [1710347812.570790][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347812.570794][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347812.570807][937988:937991] CHIP:EM: <<< [E:17446i S:8233 M:200193212] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347812.570812][937988:937991] CHIP:IN: (S) Sending msg 200193212 on secure session with LSID: 8233 [1710347812.571224][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347812.571326][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347812.658916][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347815.264985][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347815.265103][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347816.263327][937988:937991] CHIP:BLE: BLE scan error: src/platform/Linux/bluez/ChipDeviceScanner.cpp:154: CHIP Error 0x00000032: Timeout [1710347817.968917][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347821.472866][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347821.472923][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347823.820320][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347823.820490][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347823.820536][937988:937991] CHIP:EM: >>> [E:17446i S:8233 M:171809798] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347823.820544][937988:937991] CHIP:EM: Found matching exchange: 17446i, Delegate: 0x7f64d4008898 [1710347823.820551][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347823.820562][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347823.820564][937988:937991] CHIP:DMG: { [1710347823.820567][937988:937991] CHIP:DMG: suppressResponse = false, [1710347823.820569][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347823.820583][937988:937991] CHIP:DMG: [ [1710347823.820588][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347823.820592][937988:937991] CHIP:DMG: { [1710347823.820594][937988:937991] CHIP:DMG: CommandDataIB = [1710347823.820597][937988:937991] CHIP:DMG: { [1710347823.820599][937988:937991] CHIP:DMG: CommandPathIB = [1710347823.820602][937988:937991] CHIP:DMG: { [1710347823.820604][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347823.820607][937988:937991] CHIP:DMG: ClusterId = 0x31, [1710347823.820609][937988:937991] CHIP:DMG: CommandId = 0x7, [1710347823.820611][937988:937991] CHIP:DMG: }, [1710347823.820614][937988:937991] CHIP:DMG: [1710347823.820616][937988:937991] CHIP:DMG: CommandFields = [1710347823.820619][937988:937991] CHIP:DMG: { [1710347823.820623][937988:937991] CHIP:DMG: 0x0 = 0, [1710347823.820635][937988:937991] CHIP:DMG: 0x2 = NULL [1710347823.820637][937988:937991] CHIP:DMG: }, [1710347823.820639][937988:937991] CHIP:DMG: }, [1710347823.820643][937988:937991] CHIP:DMG: [1710347823.820645][937988:937991] CHIP:DMG: }, [1710347823.820649][937988:937991] CHIP:DMG: [1710347823.820651][937988:937991] CHIP:DMG: ], [1710347823.820655][937988:937991] CHIP:DMG: [1710347823.820657][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347823.820659][937988:937991] CHIP:DMG: }, [1710347823.820675][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0007 [1710347823.820683][937988:937991] CHIP:CTL: Received ConnectNetwork response, networkingStatus=0 [1710347823.820689][937988:937991] CHIP:CTL: Successfully finished commissioning step 'WiFiNetworkEnable' [1710347823.820693][937988:937991] CHIP:CTL: Commissioning stage next step: 'WiFiNetworkEnable' -> 'FindOperational' [1710347823.820696][937988:937991] CHIP:CTL: Performing next commissioning step 'FindOperational' [1710347823.820699][937988:937991] CHIP:CSM: FindOrEstablishSession: PeerId = [1:0000000000000001] [1710347823.820702][937988:937991] CHIP:CSM: FindOrEstablishSession: No existing OperationalSessionSetup instance found [1710347823.820708][937988:937991] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: State change 1 --> 2 [1710347823.820713][937988:937991] CHIP:DIS: Resolving 9E43B8E2CAAE1023:0000000000000001 ... [1710347823.821218][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347824.021106][937988:937991] CHIP:DIS: Checking node lookup status after 200 ms [1710347824.044166][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347824.467362][937988:937990] CHIP:DL: Got IP address on interface: wlp164s0 IP: 192.168.2.101 [1710347824.467420][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 32770 [1710347826.692695][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347826.692830][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347827.642351][937988:937990] CHIP:DL: Got IP address on interface: wlp164s0 IP: 192.168.2.101 [1710347827.642473][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 32770 [1710347828.821890][937988:937991] CHIP:DL: Re-trying resolve [1710347829.399447][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347830.817285][937988:937990] CHIP:DL: Got IP address on interface: wlp164s0 IP: 192.168.2.101 [1710347830.817342][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 32770 [1710347831.956509][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347831.956566][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347833.826343][937988:937991] CHIP:DL: Re-trying resolve [1710347834.093424][937988:937990] CHIP:DL: Got IP address on interface: wlp164s0 IP: 192.168.2.101 [1710347834.093481][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 32770 [1710347834.664494][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347837.220141][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347837.220224][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347838.831899][937988:937991] CHIP:DL: Re-trying resolve [1710347839.929598][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347842.488185][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347842.488251][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347843.836283][937988:937991] CHIP:DL: Avahi resolve failed [1710347845.239980][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347847.796544][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347847.803038][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347850.504356][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347853.061158][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347853.061234][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347855.769741][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347858.329230][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347858.329287][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347861.034540][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347863.592762][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347863.592825][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347866.299858][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347868.823483][937988:937991] CHIP:DIS: Checking node lookup status after 45002 ms [1710347868.823523][937988:937991] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout [1710347868.823526][937988:937991] CHIP:DIS: Retrying operational DNS-SD discovery. Attempts remaining: 2 [1710347868.823531][937988:937991] CHIP:DIS: Resolving 9E43B8E2CAAE1023:0000000000000001 ... [1710347868.824330][937988:937991] CHIP:CTL: Session establishment failed for <0000000000000001, 1>, error: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout. Next retry expected to get a response to Sigma1 or fail within 60 seconds [1710347868.824405][937988:937991] CHIP:CTL: Arming failsafe (120 seconds) [1710347868.824510][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347868.824548][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347868.824693][937988:937991] CHIP:EM: <<< [E:17447i S:8233 M:200193213] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347868.824743][937988:937991] CHIP:IN: (S) Sending msg 200193213 on secure session with LSID: 8233 [1710347868.824929][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347868.856846][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347868.856897][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347868.909504][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347868.955548][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347868.955643][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347868.955688][937988:937991] CHIP:EM: >>> [E:17447i S:8233 M:171809799] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347868.955701][937988:937991] CHIP:EM: Found matching exchange: 17447i, Delegate: 0x7f64d401c878 [1710347868.955705][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347868.955731][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347868.955733][937988:937991] CHIP:DMG: { [1710347868.955735][937988:937991] CHIP:DMG: suppressResponse = false, [1710347868.955737][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347868.955740][937988:937991] CHIP:DMG: [ [1710347868.955742][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347868.955745][937988:937991] CHIP:DMG: { [1710347868.955747][937988:937991] CHIP:DMG: CommandDataIB = [1710347868.955749][937988:937991] CHIP:DMG: { [1710347868.955760][937988:937991] CHIP:DMG: CommandPathIB = [1710347868.955763][937988:937991] CHIP:DMG: { [1710347868.955765][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347868.955767][937988:937991] CHIP:DMG: ClusterId = 0x30, [1710347868.955769][937988:937991] CHIP:DMG: CommandId = 0x1, [1710347868.955770][937988:937991] CHIP:DMG: }, [1710347868.955772][937988:937991] CHIP:DMG: [1710347868.955774][937988:937991] CHIP:DMG: CommandFields = [1710347868.955776][937988:937991] CHIP:DMG: { [1710347868.955779][937988:937991] CHIP:DMG: 0x0 = 0, [1710347868.955781][937988:937991] CHIP:DMG: 0x1 = "" (0 chars), [1710347868.955783][937988:937991] CHIP:DMG: }, [1710347868.955785][937988:937991] CHIP:DMG: }, [1710347868.955788][937988:937991] CHIP:DMG: [1710347868.955789][937988:937991] CHIP:DMG: }, [1710347868.955792][937988:937991] CHIP:DMG: [1710347868.955793][937988:937991] CHIP:DMG: ], [1710347868.955797][937988:937991] CHIP:DMG: [1710347868.955798][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347868.955800][937988:937991] CHIP:DMG: }, [1710347868.955809][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1710347868.955815][937988:937991] CHIP:CTL: Status of extending fail-safe for CASE retry: 0 [1710347868.955820][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347869.022943][937988:937991] CHIP:DIS: Checking node lookup status after 200 ms [1710347871.564544][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347873.826197][937988:937991] CHIP:DL: Re-trying resolve [1710347874.124297][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347874.124403][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347876.829545][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347878.829423][937988:937991] CHIP:DL: Re-trying resolve [1710347879.388975][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347879.389066][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347882.094318][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347883.836505][937988:937991] CHIP:DL: Re-trying resolve [1710347884.652881][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347884.652931][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347887.360300][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347888.839109][937988:937991] CHIP:DL: Avahi resolve failed [1710347889.917507][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347889.917576][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347892.625199][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347895.184898][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347895.184958][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347897.890311][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347900.449152][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347900.449216][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347903.200250][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347905.756968][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347905.757145][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347908.465471][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347911.025426][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347911.025495][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347913.730081][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347913.823421][937988:937991] CHIP:DIS: Checking node lookup status after 45000 ms [1710347913.823525][937988:937991] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: operational discovery failed: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout [1710347913.823579][937988:937991] CHIP:DIS: Retrying operational DNS-SD discovery. Attempts remaining: 1 [1710347913.823589][937988:937991] CHIP:DIS: Resolving 9E43B8E2CAAE1023:0000000000000001 ... [1710347913.824679][937988:937991] CHIP:CTL: Session establishment failed for <0000000000000001, 1>, error: src/lib/address_resolve/AddressResolve_DefaultImpl.cpp:114: CHIP Error 0x00000032: Timeout. Next retry expected to get a response to Sigma1 or fail within 60 seconds [1710347913.824708][937988:937991] CHIP:CTL: Arming failsafe (120 seconds) [1710347913.824729][937988:937991] CHIP:DMG: ICR moving to [AddingComm] [1710347913.824751][937988:937991] CHIP:DMG: ICR moving to [AddedComma] [1710347913.824784][937988:937991] CHIP:EM: <<< [E:17448i S:8233 M:200193214] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) [1710347913.824793][937988:937991] CHIP:IN: (S) Sending msg 200193214 on secure session with LSID: 8233 [1710347913.825010][937988:937991] CHIP:DMG: ICR moving to [CommandSen] [1710347913.910355][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347913.955909][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347913.956011][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347913.956055][937988:937991] CHIP:EM: >>> [E:17448i S:8233 M:171809800] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) [1710347913.956061][937988:937991] CHIP:EM: Found matching exchange: 17448i, Delegate: 0x7f64d401c878 [1710347913.956066][937988:937991] CHIP:DMG: ICR moving to [ResponseRe] [1710347913.956076][937988:937991] CHIP:DMG: InvokeResponseMessage = [1710347913.956078][937988:937991] CHIP:DMG: { [1710347913.956080][937988:937991] CHIP:DMG: suppressResponse = false, [1710347913.956082][937988:937991] CHIP:DMG: InvokeResponseIBs = [1710347913.956085][937988:937991] CHIP:DMG: [ [1710347913.956087][937988:937991] CHIP:DMG: InvokeResponseIB = [1710347913.956090][937988:937991] CHIP:DMG: { [1710347913.956092][937988:937991] CHIP:DMG: CommandDataIB = [1710347913.956094][937988:937991] CHIP:DMG: { [1710347913.956096][937988:937991] CHIP:DMG: CommandPathIB = [1710347913.956098][937988:937991] CHIP:DMG: { [1710347913.956101][937988:937991] CHIP:DMG: EndpointId = 0x0, [1710347913.956103][937988:937991] CHIP:DMG: ClusterId = 0x30, [1710347913.956104][937988:937991] CHIP:DMG: CommandId = 0x1, [1710347913.956106][937988:937991] CHIP:DMG: }, [1710347913.956108][937988:937991] CHIP:DMG: [1710347913.956110][937988:937991] CHIP:DMG: CommandFields = [1710347913.956113][937988:937991] CHIP:DMG: { [1710347913.956115][937988:937991] CHIP:DMG: 0x0 = 0, [1710347913.956118][937988:937991] CHIP:DMG: 0x1 = "" (0 chars), [1710347913.956120][937988:937991] CHIP:DMG: }, [1710347913.956121][937988:937991] CHIP:DMG: }, [1710347913.956124][937988:937991] CHIP:DMG: [1710347913.956126][937988:937991] CHIP:DMG: }, [1710347913.956129][937988:937991] CHIP:DMG: [1710347913.956130][937988:937991] CHIP:DMG: ], [1710347913.956134][937988:937991] CHIP:DMG: [1710347913.956135][937988:937991] CHIP:DMG: InteractionModelRevision = 1 [1710347913.956137][937988:937991] CHIP:DMG: }, [1710347913.956148][937988:937991] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_0030 Command=0x0000_0001 [1710347913.956156][937988:937991] CHIP:CTL: Status of extending fail-safe for CASE retry: 0 [1710347913.956164][937988:937991] CHIP:DMG: ICR moving to [AwaitingDe] [1710347914.023462][937988:937991] CHIP:DIS: Checking node lookup status after 200 ms [1710347916.655028][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347918.825672][937988:937991] CHIP:DL: Re-trying resolve [1710347919.213204][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347919.213263][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347921.920533][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1710347923.830114][937988:937991] CHIP:DL: Re-trying resolve [1710347924.476378][937988:937990] CHIP:DL: Indication received, conn = 0x7f64dc044fc0 [1710347924.476479][937988:937991] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1710347926.120973][937988:937988] CHIP:CTL: Shutting down the commissioner [1710347926.121010][937988:937988] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1710347926.121014][937988:937988] CHIP:DIS: Closing all BLE connections [1710347926.121018][937988:937988] CHIP:IN: Clearing BLE pending packets. [1710347926.121498][937988:937988] CHIP:BLE: Auto-closing end point's BLE connection. [1710347926.121507][937988:937988] CHIP:DL: Closing BLE GATT connection (con 0x7f64dc044fc0) [1710347926.121674][937988:937990] CHIP:DL: BluezDisconnect peer=FD:5B:4B:D8:77:70 [1710347926.286374][937988:937988] CHIP:IN: SecureSession[0x7f64d40095a0]: MarkForEviction Type:1 LSID:8233 [1710347926.286401][937988:937988] CHIP:SC: SecureSession[0x7f64d40095a0]: Moving from state 'kActive' --> 'kPendingEviction' [1710347926.286415][937988:937988] CHIP:IN: SecureSession[0x7f64d40095a0]: Released - Type:1 LSID:8233 [1710347926.286424][937988:937988] CHIP:CTL: Shutting down the controller [1710347926.286429][937988:937988] CHIP:DIS: OperationalSessionSetup[1:0000000000000001]: Cancelling incomplete address resolution as device is being deleted. [1710347926.286444][937988:937988] CHIP:CTL: Device connection failed. Error src/app/OperationalSessionSetup.cpp:438: CHIP Error 0x00000074: The operation has been cancelled [1710347926.286449][937988:937988] CHIP:IN: Expiring all sessions for fabric 0x1!! [1710347926.286453][937988:937988] CHIP:FP: Forgetting fabric 0x1 [1710347926.286461][937988:937988] CHIP:TS: Pending Last Known Good Time: 2023-10-26T12:12:01 [1710347926.286517][937988:937988] CHIP:TS: Previous Last Known Good Time: 2023-10-26T12:12:01 [1710347926.286531][937988:937988] CHIP:TS: Reverted Last Known Good Time to previous value [1710347926.286540][937988:937988] CHIP:CTL: Shutting down the commissioner [1710347926.286543][937988:937988] CHIP:CTL: Stopping commissioning discovery over DNS-SD [1710347926.286549][937988:937988] CHIP:CTL: Shutting down the controller [1710347926.286552][937988:937988] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1710347926.286544][937988:937990] CHIP:DL: Bluez disconnected [1710347926.286572][937988:937990] CHIP:DL: Bluez notify CHIPoBluez connection disconnected [1710347926.286788][937988:937988] CHIP:DMG: All ReadHandler-s are clean, clear GlobalDirtySet [1710347926.286827][937988:937988] CHIP:BLE: BleConnectionDelegate::CancelConnection is not implemented. [1710347926.286837][937988:937988] CHIP:FP: Shutting down FabricTable [1710347926.286842][937988:937988] CHIP:TS: Pending Last Known Good Time: 2023-10-26T12:12:01 [1710347926.286868][937988:937988] CHIP:TS: Previous Last Known Good Time: 2023-10-26T12:12:01 [1710347926.286871][937988:937988] CHIP:TS: Reverted Last Known Good Time to previous value [1710347926.286946][937988:937988] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-35JtDO) [1710347926.287110][937988:937988] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1710347926.287122][937988:937988] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1710347926.287126][937988:937988] CHIP:DL: Inet Layer shutdown [1710347926.287129][937988:937988] CHIP:DL: BLE shutdown [1710347926.287362][937988:937988] CHIP:DL: System Layer shutdown [1710347926.287532][937988:937988] CHIP:TOO: Run command failure: examples/chip-tool/commands/common/CHIPCommand.cpp:537: CHIP Error 0x00000032: Timeout