ubuntu@matter-te-7:~/cli$ chip-tool pairing ble-wifi 43214321 WIFI_SSID WIFI_PASSWORD 0 20202021 3840 [1637274175.439766][26615:26615] CHIP:CTL: Generating NOC [1637274175.440131][26615:26615] CHIP:CTL: Generating ICAC [1637274175.445401][26615:26615] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT START ==== [1637274175.445450][26615:26615] CHIP:DL: AUDIT: * Validate buf1 and buf2 are <<>> [1637274175.445470][26615:26615] CHIP:DL: AUDIT: * Validate r1 and r2 are <<>> [1637274175.445494][26615:26615] CHIP:DL: AUDIT: * buf1: 1EA4595B519E79324673FFBF1E9E1FCC [1637274175.445516][26615:26615] CHIP:DL: AUDIT: * buf2: D2DC8BCBC189C297EEB7E81FB0F950F3 [1637274175.445583][26615:26615] CHIP:DL: AUDIT: * r1: 0x2E252C4E r2: 0x8068EB63 [1637274175.445605][26615:26615] CHIP:DL: AUDIT: ===== RANDOM NUMBER GENERATOR AUDIT END ==== [1637274175.446179][26615:26615] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-2OYmYu) [1637274175.446818][26615:26615] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1637274175.446876][26615:26615] CHIP:DL: NVS set: chip-counters/reboot-count = 220 (0xDC) [1637274175.447799][26615:26615] CHIP:DL: Got Ethernet interface: eth0 [1637274175.448360][26615:26615] CHIP:DL: Found the primary Ethernet interface:eth0 [1637274175.448946][26615:26615] CHIP:DL: Got WiFi interface: wlan0 [1637274175.449024][26615:26615] CHIP:DL: Failed to reset WiFi statistic counts [1637274175.449076][26615:26615] CHIP:IN: UDP::Init bind&listen port=5541 [1637274175.449195][26615:26615] CHIP:IN: UDP::Init bound to port=5541 [1637274175.449224][26615:26615] CHIP:IN: UDP::Init bind&listen port=5541 [1637274175.449325][26615:26615] CHIP:IN: UDP::Init bound to port=5541 [1637274175.449372][26615:26615] CHIP:IN: TransportMgr initialized [1637274175.449431][26615:26615] CHIP:DIS: Init fabric pairing table with server storage [1637274175.449826][26615:26615] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-XJaPkK) [1637274175.450405][26615:26615] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1637274175.450464][26615:26615] CHIP:DL: NVS set: chip-counters/GlobalMCTR = 220000 (0x35B60) [1637274175.450617][26615:26615] CHIP:CTL: System State Initialized... [1637274175.455167][26615:26615] CHIP:DL: Avahi client registered [1637274175.456521][26615:26615] CHIP:ZCL: Using ZAP configuration... [1637274175.457271][26615:26615] CHIP:DIS: Verifying the received credentials [1637274175.459460][26615:26615] CHIP:DIS: Added new fabric at index: 1, Initialized: 1 [1637274175.459505][26615:26615] CHIP:DIS: Assigned compressed fabric ID: 0x5ADC44F45DEDE9BC, node ID: 0x000000000001B669 [1637274175.459533][26615:26615] CHIP:CTL: Joined the fabric at index 1. Compressed fabric ID is: 0x5ADC44F45DEDE9BC [1637274175.459967][26615:26620] CHIP:DL: CHIP task running [1637274175.460176][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 32785 [1637274175.460491][26615:26620] CHIP:SC: Assigned local session key ID 271 [1637274175.460605][26615:26620] CHIP:IN: Prepared plaintext message 0xffff9e1f7d20 to 0x0000000000000000 of type 0x20 and protocolId (0, 0) on exchange 29112i with MessageCounter:1334713302. [1637274175.460656][26615:26620] CHIP:IN: Sending plaintext msg 0xffff9e1f7d20 with MessageCounter:1334713302 to 0x0000000000000000 at monotonic time: 344590898 msec [1637274175.460690][26615:26620] CHIP:IN: Message appended to BLE send queue [1637274175.460716][26615:26620] CHIP:SC: Sent PBKDF param request [1637274175.461890][26615:26621] CHIP:DL: TRACE: Bluez mainloop starting Thread [1637274175.462072][26615:26618] CHIP:DL: TRACE: Bus acquired for name C-67f7 [1637274175.473687][26615:26620] CHIP:DL: PlatformBlueZInit init success [1637274175.482520][26615:26618] CHIP:BLE: BLE removing known devices. [1637274175.519545][26615:26618] CHIP:BLE: BLE initiating scan. [1637274175.612774][26615:26618] CHIP:BLE: Device 55:74:3E:F6:53:C5 does not look like a CHIP device. [1637274175.639531][26615:26618] CHIP:BLE: Device 2C:4C:C6:11:03:2D does not look like a CHIP device. [1637274175.642792][26615:26618] CHIP:BLE: Device C1:FB:32:AB:79:50 does not look like a CHIP device. [1637274175.650240][26615:26618] CHIP:BLE: Device 1F:40:90:C1:F6:AA does not look like a CHIP device. [1637274175.652131][26615:26618] CHIP:BLE: New device scanned: D4:4D:A4:A0:02:A5 [1637274175.652181][26615:26618] CHIP:BLE: Device discriminator match. Attempting to connect. [1637274175.655266][26615:26618] CHIP:BLE: Scan complete notification without an active scan. [1637274175.938162][26615:26618] CHIP:DL: ConnectDevice complete [1637274176.577016][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577090][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577159][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577198][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577243][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577282][26615:26618] CHIP:DL: Char1 /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5/service0007 [1637274176.577330][26615:26618] CHIP:DL: New BLE connection 0xffff94032f80, device D4:4D:A4:A0:02:A5, path /org/bluez/hci0/dev_D4_4D_A4_A0_02_A5 [1637274176.577483][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16385 [1637274176.577544][26615:26620] CHIP:IN: BleConnectionComplete: endPoint 0xaaaacab5c160 [1637274176.813856][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.009545][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16388 [1637274177.009628][26615:26620] CHIP:BLE: subscribe complete, ep = 0xaaaacab5c160 [1637274177.058383][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.058632][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.058721][26615:26620] CHIP:BLE: peripheral chose BTP version 4; central expected between 4 and 4 [1637274177.058778][26615:26620] CHIP:BLE: using BTP fragment sizes rx 20 / tx 20. [1637274177.058828][26615:26620] CHIP:BLE: local and remote recv window size = 5 [1637274177.059033][26615:26620] CHIP:IN: BLE EndPoint 0xaaaacab5c160 Connection Complete [1637274177.155413][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.252931][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.350510][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.545520][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.546279][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.546497][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.887374][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.887601][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.888146][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.888357][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.889174][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.889398][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.984320][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274177.985072][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.985278][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.985966][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.986216][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.987260][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.987487][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.987878][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274177.988089][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274177.988348][26615:26620] CHIP:EM: Received message of type 0x21 with protocolId (0, 0) and MessageCounter:4003201916 on exchange 29112i [1637274177.988460][26615:26620] CHIP:SC: Received PBKDF param response [1637274177.988568][26615:26620] CHIP:SC: Peer assigned session ID 1 [1637274177.991035][26615:26620] CHIP:IN: Prepared plaintext message 0xffff9e1f7850 to 0x0000000000000000 of type 0x22 and protocolId (0, 0) on exchange 29112i with MessageCounter:1334713303. [1637274177.991117][26615:26620] CHIP:IN: Sending plaintext msg 0xffff9e1f7850 with MessageCounter:1334713303 to 0x0000000000000000 at monotonic time: 344593429 msec [1637274177.991167][26615:26620] CHIP:SC: Sent spake2p msg1 [1637274178.081867][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274178.179315][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274178.276357][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274178.374519][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274178.374893][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274178.375070][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274178.471643][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274178.569225][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.324280][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.324623][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.324730][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.324851][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.325550][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.325752][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.326421][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.326573][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.421783][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.422651][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.422903][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.423587][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.424021][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.424783][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.424978][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.425102][26615:26620] CHIP:EM: Received message of type 0x23 with protocolId (0, 0) and MessageCounter:4003201917 on exchange 29112i [1637274180.425214][26615:26620] CHIP:SC: Received spake2p msg2 [1637274180.428241][26615:26620] CHIP:IN: Prepared plaintext message 0xffff9e1f7930 to 0x0000000000000000 of type 0x24 and protocolId (0, 0) on exchange 29112i with MessageCounter:1334713304. [1637274180.428343][26615:26620] CHIP:IN: Sending plaintext msg 0xffff9e1f7930 with MessageCounter:1334713304 to 0x0000000000000000 at monotonic time: 344595866 msec [1637274180.428533][26615:26620] CHIP:SC: Sent spake2p msg3 [1637274180.518859][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.616462][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.714291][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.714990][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.715208][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.763434][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274180.763562][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274180.763646][26615:26620] CHIP:EM: Received message of type 0x40 with protocolId (0, 0) and MessageCounter:4003201918 on exchange 29112i [1637274180.763706][26615:26620] CHIP:IN: New secure session created for device 0x00000000029365F1, key 1!! [1637274180.763779][26615:26620] CHIP:CTL: Remote device completed SPAKE2+ handshake [1637274180.763798][26615:26620] CHIP:CTL: Sending Certificate Chain request to 0xffffa921d070 device [1637274180.763895][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274180.763918][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274180.763997][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f7670 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29113i with MessageCounter:0. [1637274180.764030][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f7670 with MessageCounter:0 to 0x00000000029365F1 at monotonic time: 344596202 msec [1637274180.764119][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274180.764142][26615:26620] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate [1637274180.860553][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274180.957854][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.055015][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.152520][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.153004][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.153143][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.250904][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.251394][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.251931][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.252323][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.252663][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.252987][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.348204][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.348716][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.349261][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.350043][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.350497][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.350665][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.350810][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.351509][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.351660][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.494320][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.495044][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.495265][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.543093][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.543477][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.543881][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.544003][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.544728][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.544872][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.689317][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.690069][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.690555][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.690775][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.691046][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.692253][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.692509][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.692769][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.692958][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.786721][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.787556][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.787995][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.788670][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.789561][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.790883][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.791105][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.791338][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.791515][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.884390][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274181.885311][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.885527][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.886857][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.887132][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.887482][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.887658][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274181.887822][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274181.887995][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.030614][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.031338][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.031920][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.032405][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.032925][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.033440][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.033878][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.034256][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.034445][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.127649][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.128305][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.128628][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.128995][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.129126][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.129312][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:1 on exchange 29113i [1637274182.129402][26615:26620] CHIP:DMG: InvokeCommand = [1637274182.129445][26615:26620] CHIP:DMG: { [1637274182.129481][26615:26620] CHIP:DMG: CommandList = [1637274182.129525][26615:26620] CHIP:DMG: [ [1637274182.129565][26615:26620] CHIP:DMG: CommandDataIB = [1637274182.129619][26615:26620] CHIP:DMG: { [1637274182.129709][26615:26620] CHIP:DMG: CommandPathIB = [1637274182.129859][26615:26620] CHIP:DMG: { [1637274182.129914][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274182.129971][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274182.130020][26615:26620] CHIP:DMG: CommandId = 0x3, [1637274182.130072][26615:26620] CHIP:DMG: }, [1637274182.130126][26615:26620] CHIP:DMG: [1637274182.130172][26615:26620] CHIP:DMG: CommandData = [1637274182.130223][26615:26620] CHIP:DMG: { [1637274182.130273][26615:26620] CHIP:DMG: 0x0 = [ [1637274182.130325][26615:26620] CHIP:DMG: ... (byte string too long) ... [1637274182.130386][26615:26620] CHIP:DMG: ] [1637274182.130436][26615:26620] CHIP:DMG: }, [1637274182.130483][26615:26620] CHIP:DMG: }, [1637274182.130533][26615:26620] CHIP:DMG: [1637274182.130573][26615:26620] CHIP:DMG: ], [1637274182.130620][26615:26620] CHIP:DMG: [1637274182.130657][26615:26620] CHIP:DMG: } [1637274182.130728][26615:26620] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1637274182.130863][26615:26620] CHIP:ZCL: CertificateChainResponse: [1637274182.130907][26615:26620] CHIP:ZCL: Certificate: 451 [1637274182.130968][26615:26620] CHIP:CTL: Received certificate chain from the device [1637274182.131008][26615:26620] CHIP:CTL: Sending Certificate Chain request to 0xffffa921d070 device [1637274182.131077][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274182.131121][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274182.131240][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f7110 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29114i with MessageCounter:1. [1637274182.131304][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f7110 with MessageCounter:1 to 0x00000000029365F1 at monotonic time: 344597569 msec [1637274182.131448][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274182.131549][26615:26620] CHIP:CTL: Sent Certificate Chain request, waiting for the DAC Certificate [1637274182.131650][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274182.225426][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.322675][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.420486][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.517970][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.518679][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.518870][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.566974][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.567405][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.567530][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.567618][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.568694][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.568892][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.664976][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.664996][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.665279][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.665930][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.666414][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.666942][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.667384][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.667534][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.667694][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.761960][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.762701][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.762944][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.763313][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.763868][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.764458][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.764762][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.765033][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.765187][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.859410][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.860169][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.860484][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.860993][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.861433][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.862169][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.862573][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.863146][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.863333][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.956843][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274182.959062][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.959605][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.959822][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.959936][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.959948][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.960137][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274182.960389][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274182.960582][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.151686][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.151869][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.152083][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.152805][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.152989][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.153171][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.153557][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.154318][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.154432][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.249336][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.250300][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.250968][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.251103][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.251358][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.251869][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.252303][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.252924][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.253133][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.346792][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.347502][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.348003][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.348661][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.348896][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.349869][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.350110][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.350338][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.350705][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.444281][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.444675][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.444866][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.445113][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:2 on exchange 29114i [1637274183.445242][26615:26620] CHIP:DMG: InvokeCommand = [1637274183.445303][26615:26620] CHIP:DMG: { [1637274183.445357][26615:26620] CHIP:DMG: CommandList = [1637274183.445420][26615:26620] CHIP:DMG: [ [1637274183.445479][26615:26620] CHIP:DMG: CommandDataIB = [1637274183.445545][26615:26620] CHIP:DMG: { [1637274183.445608][26615:26620] CHIP:DMG: CommandPathIB = [1637274183.445766][26615:26620] CHIP:DMG: { [1637274183.445851][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274183.445933][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274183.446015][26615:26620] CHIP:DMG: CommandId = 0x3, [1637274183.446098][26615:26620] CHIP:DMG: }, [1637274183.446178][26615:26620] CHIP:DMG: [1637274183.446241][26615:26620] CHIP:DMG: CommandData = [1637274183.446314][26615:26620] CHIP:DMG: { [1637274183.446388][26615:26620] CHIP:DMG: 0x0 = [ [1637274183.446467][26615:26620] CHIP:DMG: ... (byte string too long) ... [1637274183.446557][26615:26620] CHIP:DMG: ] [1637274183.446631][26615:26620] CHIP:DMG: }, [1637274183.446702][26615:26620] CHIP:DMG: }, [1637274183.446781][26615:26620] CHIP:DMG: [1637274183.446839][26615:26620] CHIP:DMG: ], [1637274183.446910][26615:26620] CHIP:DMG: [1637274183.446965][26615:26620] CHIP:DMG: } [1637274183.447068][26615:26620] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0003 [1637274183.447141][26615:26620] CHIP:ZCL: CertificateChainResponse: [1637274183.447193][26615:26620] CHIP:ZCL: Certificate: 494 [1637274183.447249][26615:26620] CHIP:CTL: Received certificate chain from the device [1637274183.447304][26615:26620] CHIP:CTL: Sending Attestation Request to the device. [1637274183.447355][26615:26620] CHIP:CTL: Sending Attestation request to 0xffffa921d070 device [1637274183.447447][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274183.447514][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274183.447669][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f7100 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29115i with MessageCounter:2. [1637274183.447925][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f7100 with MessageCounter:2 to 0x00000000029365F1 at monotonic time: 344598886 msec [1637274183.448122][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274183.448187][26615:26620] CHIP:CTL: Sent Attestation request, waiting for the Attestation Information [1637274183.448268][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274183.541748][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.638698][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.736339][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.736838][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274183.736976][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274183.834418][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274183.980609][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.077897][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.419752][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.420003][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.468433][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.468925][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.469347][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.469434][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.470113][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.470377][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.566003][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.566770][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.566996][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.567945][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.568239][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.568370][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.568514][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.568973][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.569175][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.663701][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.663770][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.664670][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.665056][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.665284][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.665474][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.665614][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.667162][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.667390][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.759769][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.760398][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.760692][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.761489][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.761916][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.762095][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.762214][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.763246][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.763503][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.858015][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274184.858634][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274184.858832][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274184.859076][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:3 on exchange 29115i [1637274184.859207][26615:26620] CHIP:DMG: InvokeCommand = [1637274184.859271][26615:26620] CHIP:DMG: { [1637274184.859326][26615:26620] CHIP:DMG: CommandList = [1637274184.859390][26615:26620] CHIP:DMG: [ [1637274184.859449][26615:26620] CHIP:DMG: CommandDataIB = [1637274184.859554][26615:26620] CHIP:DMG: { [1637274184.859617][26615:26620] CHIP:DMG: CommandPathIB = [1637274184.859693][26615:26620] CHIP:DMG: { [1637274184.859801][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274184.859893][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274184.859998][26615:26620] CHIP:DMG: CommandId = 0x1, [1637274184.860081][26615:26620] CHIP:DMG: }, [1637274184.860162][26615:26620] CHIP:DMG: [1637274184.860253][26615:26620] CHIP:DMG: CommandData = [1637274184.860337][26615:26620] CHIP:DMG: { [1637274184.860433][26615:26620] CHIP:DMG: 0x0 = [ [1637274184.860567][26615:26620] CHIP:DMG: 0x15, 0x30, 0x1, 0x80, 0x1, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x [1637274184.860682][26615:26620] CHIP:DMG: ] [1637274184.860757][26615:26620] CHIP:DMG: 0x1 = [ [1637274184.860901][26615:26620] CHIP:DMG: 0x59, 0x1f, 0x83, 0x59, 0xda, 0xea, 0x3f, 0x4c, 0xd6, 0xd8, 0x2a, 0x3f, 0x3d, 0xbe, 0x9, 0xda, 0xa1, 0xb4, 0xaa, 0xf9, 0xad, 0x51, 0x7f, 0xc1, 0xc0, 0xcc, 0x7f, 0x6a, 0xeb, 0x1b, 0x9c, 0x3b, 0x8b, 0xe7, 0xe8, 0x45, 0x37, 0x81, 0xc4, 0xbc, 0xfa, 0x86, [1637274184.860975][26615:26620] CHIP:DMG: ] [1637274184.861035][26615:26620] CHIP:DMG: }, [1637274184.861121][26615:26620] CHIP:DMG: }, [1637274184.861199][26615:26620] CHIP:DMG: [1637274184.861258][26615:26620] CHIP:DMG: ], [1637274184.861351][26615:26620] CHIP:DMG: [1637274184.861409][26615:26620] CHIP:DMG: } [1637274184.861514][26615:26620] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0001 [1637274184.861590][26615:26620] CHIP:ZCL: AttestationResponse: [1637274184.861643][26615:26620] CHIP:ZCL: AttestationElements: 171 [1637274184.861733][26615:26620] CHIP:ZCL: Signature: 64 [1637274184.861811][26615:26620] CHIP:CTL: Received Attestation Information from the device [1637274184.869112][26615:26620] CHIP:CTL: Successfully validated 'Attestation Information' command received from the device. [1637274184.869399][26615:26620] CHIP:CTL: Sending 'CSR request' command to the device. [1637274184.869457][26615:26620] CHIP:CTL: Sending OpCSR request to 0xffffa921d070 device [1637274184.869583][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274184.869652][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274184.869915][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f7110 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29116i with MessageCounter:3. [1637274184.870033][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f7110 with MessageCounter:3 to 0x00000000029365F1 at monotonic time: 344600308 msec [1637274184.870262][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274184.870330][26615:26620] CHIP:CTL: Sent OpCSR request, waiting for the CSR [1637274184.870415][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274184.955378][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274185.052549][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274185.150407][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274185.151113][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274185.151327][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274185.247933][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274185.345473][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274185.442934][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.174885][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.175175][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.175469][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.175623][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.175952][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.176493][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.176902][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.177090][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.321210][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.321227][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.322218][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.322821][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.323047][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.323213][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.323324][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.323739][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.323949][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.418161][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.418748][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.419406][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.420079][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.420603][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.420689][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.420828][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.421840][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.422051][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.515473][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.516248][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.516690][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.517349][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.517825][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.518521][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.518834][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.519085][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.519273][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.612384][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.613056][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.613327][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.613995][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274186.614242][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274186.614390][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:4 on exchange 29116i [1637274186.614460][26615:26620] CHIP:DMG: InvokeCommand = [1637274186.614490][26615:26620] CHIP:DMG: { [1637274186.614516][26615:26620] CHIP:DMG: CommandList = [1637274186.614547][26615:26620] CHIP:DMG: [ [1637274186.614575][26615:26620] CHIP:DMG: CommandDataIB = [1637274186.614607][26615:26620] CHIP:DMG: { [1637274186.614637][26615:26620] CHIP:DMG: CommandPathIB = [1637274186.614694][26615:26620] CHIP:DMG: { [1637274186.614733][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274186.614779][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274186.614823][26615:26620] CHIP:DMG: CommandId = 0x5, [1637274186.614862][26615:26620] CHIP:DMG: }, [1637274186.614901][26615:26620] CHIP:DMG: [1637274186.614932][26615:26620] CHIP:DMG: CommandData = [1637274186.614970][26615:26620] CHIP:DMG: { [1637274186.615008][26615:26620] CHIP:DMG: 0x0 = [ [1637274186.615067][26615:26620] CHIP:DMG: 0x15, 0x30, 0x1, 0xcc, 0x30, 0x81, 0xc9, 0x30, 0x70, 0x2, 0x1, 0x0, 0x30, 0xe, 0x31, 0xc, 0x30, 0xa, 0x6, 0x3, 0x55, 0x4, 0xa, 0xc, 0x3, 0x43, 0x53, 0x52, 0x30, 0x59, 0x30, 0x13, 0x6, 0x7, 0x2a, 0x86, 0x48, 0xce, 0x3d, 0x2, 0x1, 0x6, 0x8, 0x2a, 0x86, [1637274186.615111][26615:26620] CHIP:DMG: ] [1637274186.615145][26615:26620] CHIP:DMG: 0x1 = [ [1637274186.615180][26615:26620] CHIP:DMG: [1637274186.615218][26615:26620] CHIP:DMG: ] [1637274186.615253][26615:26620] CHIP:DMG: }, [1637274186.615287][26615:26620] CHIP:DMG: }, [1637274186.615325][26615:26620] CHIP:DMG: [1637274186.615352][26615:26620] CHIP:DMG: ], [1637274186.615386][26615:26620] CHIP:DMG: [1637274186.615411][26615:26620] CHIP:DMG: } [1637274186.615466][26615:26620] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0005 [1637274186.615505][26615:26620] CHIP:ZCL: OpCSRResponse: [1637274186.615530][26615:26620] CHIP:ZCL: NOCSRElements: 253 [1637274186.615553][26615:26620] CHIP:ZCL: AttestationSignature: 0 [1637274186.615581][26615:26620] CHIP:CTL: Received certificate signing request from the device [1637274186.615606][26615:26620] CHIP:CTL: Getting certificate chain for the device from the issuer [1637274186.615632][26615:26620] CHIP:CTL: Verifying Certificate Signing Request [1637274186.616451][26615:26620] CHIP:CTL: Generating NOC [1637274186.616798][26615:26620] CHIP:CTL: Generating ICAC [1637274186.617119][26615:26620] CHIP:CTL: Providing certificate chain to the commissioner [1637274186.617154][26615:26620] CHIP:CTL: Received callback from the CA for NOC Chain generation. Status ../../third_party/connectedhomeip/src/controller/ExampleOperationalCredentialsIssuer.cpp:183: Success [1637274186.617287][26615:26620] CHIP:CTL: Sending root certificate to the device [1637274186.617345][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274186.617379][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274186.617494][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f6e30 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29117i with MessageCounter:4. [1637274186.617540][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f6e30 with MessageCounter:4 to 0x00000000029365F1 at monotonic time: 344602055 msec [1637274186.617689][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274186.617723][26615:26620] CHIP:CTL: Sent root certificate to the device [1637274186.617901][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274186.759232][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.856655][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274186.953912][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.051622][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.052275][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274187.052468][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274187.148414][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.246780][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.344091][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.441872][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.442239][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274187.442432][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274187.539102][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.636575][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.734320][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.831589][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274187.832309][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274187.832524][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274187.929269][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.026774][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.123932][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.220946][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.221616][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.221830][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.319204][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.367532][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.367790][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.367827][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.367923][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.369158][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.369291][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.465603][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.466111][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.466546][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.466739][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:5 on exchange 29117i [1637274188.466870][26615:26620] CHIP:DMG: InvokeCommand = [1637274188.466933][26615:26620] CHIP:DMG: { [1637274188.466987][26615:26620] CHIP:DMG: CommandList = [1637274188.467049][26615:26620] CHIP:DMG: [ [1637274188.467108][26615:26620] CHIP:DMG: CommandDataIB = [1637274188.467201][26615:26620] CHIP:DMG: { [1637274188.467266][26615:26620] CHIP:DMG: CommandPathIB = [1637274188.467338][26615:26620] CHIP:DMG: { [1637274188.467411][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274188.467486][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274188.467559][26615:26620] CHIP:DMG: CommandId = 0xb, [1637274188.467629][26615:26620] CHIP:DMG: }, [1637274188.467706][26615:26620] CHIP:DMG: [1637274188.467769][26615:26620] CHIP:DMG: StatusIB = [1637274188.467837][26615:26620] CHIP:DMG: { [1637274188.467906][26615:26620] CHIP:DMG: status = 0x0, [1637274188.467976][26615:26620] CHIP:DMG: }, [1637274188.468048][26615:26620] CHIP:DMG: [1637274188.468110][26615:26620] CHIP:DMG: }, [1637274188.468182][26615:26620] CHIP:DMG: [1637274188.468240][26615:26620] CHIP:DMG: ], [1637274188.468310][26615:26620] CHIP:DMG: [1637274188.468364][26615:26620] CHIP:DMG: } [1637274188.468460][26615:26620] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_003E Command=0x0000_000B Status=0x0 [1637274188.468525][26615:26620] CHIP:ZCL: DefaultResponse: [1637274188.468575][26615:26620] CHIP:ZCL: Transaction: 0xffff88011db0 [1637274188.468626][26615:26620] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1637274188.468684][26615:26620] CHIP:CTL: Device confirmed that it has received the root certificate [1637274188.468736][26615:26620] CHIP:CTL: Sending operational certificate chain to the device [1637274188.468829][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274188.468904][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274188.469126][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f72c0 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29118i with MessageCounter:5. [1637274188.469217][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f72c0 with MessageCounter:5 to 0x00000000029365F1 at monotonic time: 344603907 msec [1637274188.469412][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274188.469479][26615:26620] CHIP:CTL: Sent operational certificate to the device [1637274188.469547][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274188.563042][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.660398][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.758133][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.758612][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274188.758828][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274188.855383][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274188.953376][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.050116][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.147968][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.148515][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274189.148721][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274189.245446][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.343097][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.440359][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.537224][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.538017][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274189.538148][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274189.635448][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.732922][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.830072][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.927905][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274189.928477][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274189.928689][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274190.025193][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.122661][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.220346][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.317847][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.318640][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274190.318846][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274190.464260][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.609787][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.707990][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.805361][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274190.806161][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274190.806569][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274190.903025][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.000314][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.097841][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.194933][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.196097][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274191.196313][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274191.292926][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.390553][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.487993][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.585429][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274191.586229][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274191.586436][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274191.682296][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.340543][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274193.341038][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274193.341118][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274193.341474][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274193.343107][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274193.343336][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274193.437777][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.438759][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274193.438970][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274193.439162][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:6 on exchange 29118i [1637274193.439290][26615:26620] CHIP:DMG: InvokeCommand = [1637274193.439352][26615:26620] CHIP:DMG: { [1637274193.439406][26615:26620] CHIP:DMG: CommandList = [1637274193.439468][26615:26620] CHIP:DMG: [ [1637274193.439528][26615:26620] CHIP:DMG: CommandDataIB = [1637274193.439593][26615:26620] CHIP:DMG: { [1637274193.439667][26615:26620] CHIP:DMG: CommandPathIB = [1637274193.439823][26615:26620] CHIP:DMG: { [1637274193.439905][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274193.439987][26615:26620] CHIP:DMG: ClusterId = 0x3e, [1637274193.440067][26615:26620] CHIP:DMG: CommandId = 0x8, [1637274193.440150][26615:26620] CHIP:DMG: }, [1637274193.440230][26615:26620] CHIP:DMG: [1637274193.440292][26615:26620] CHIP:DMG: CommandData = [1637274193.440365][26615:26620] CHIP:DMG: { [1637274193.440444][26615:26620] CHIP:DMG: 0x0 = 0, [1637274193.440529][26615:26620] CHIP:DMG: 0x1 = 1, [1637274193.440613][26615:26620] CHIP:DMG: 0x2 = "", [1637274193.440701][26615:26620] CHIP:DMG: }, [1637274193.440770][26615:26620] CHIP:DMG: }, [1637274193.440850][26615:26620] CHIP:DMG: [1637274193.440908][26615:26620] CHIP:DMG: ], [1637274193.440980][26615:26620] CHIP:DMG: [1637274193.441033][26615:26620] CHIP:DMG: } [1637274193.441137][26615:26620] CHIP:DMG: Received Command Response Data, Endpoint=0 Cluster=0x0000_003E Command=0x0000_0008 [1637274193.441214][26615:26620] CHIP:ZCL: NOCResponse: [1637274193.441266][26615:26620] CHIP:ZCL: StatusCode: 0 [1637274193.441313][26615:26620] CHIP:ZCL: FabricIndex: 1 [1637274193.441361][26615:26620] CHIP:ZCL: DebugText: [1637274193.441418][26615:26620] CHIP:CTL: Device returned status 0 on receiving the NOC [1637274193.441471][26615:26620] CHIP:CTL: Operational credentials provisioned on device 0xffffa921d070 [1637274193.445790][26615:26620] CHIP:TOO: Secure Pairing Success [1637274193.448276][26615:26620] CHIP:TOO: Pairing Success [1637274193.448419][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274193.448461][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274193.448525][26615:26620] CHIP:IN: Marking old secure session for device 0x00000000029365F1 as expired [1637274193.448561][26615:26620] CHIP:CTL: OnConnectionExpired was called for unknown device, ignoring it. [1637274193.448597][26615:26620] CHIP:IN: New secure session created for device 0x00000000029365F1, key 1!! [1637274193.448756][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f6ff0 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29119i with MessageCounter:7. [1637274193.448808][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f6ff0 with MessageCounter:7 to 0x00000000029365F1 at monotonic time: 344608887 msec [1637274193.448930][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274193.448986][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274193.535564][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.632988][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.730459][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.731084][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274193.731277][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274193.827494][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274193.925427][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.022761][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.072486][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.072847][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274194.073168][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.073756][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.074259][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274194.074389][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274194.075212][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.075331][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274194.075501][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:7 on exchange 29119i [1637274194.075575][26615:26620] CHIP:DMG: InvokeCommand = [1637274194.075597][26615:26620] CHIP:DMG: { [1637274194.075615][26615:26620] CHIP:DMG: CommandList = [1637274194.075640][26615:26620] CHIP:DMG: [ [1637274194.075661][26615:26620] CHIP:DMG: CommandDataIB = [1637274194.075685][26615:26620] CHIP:DMG: { [1637274194.075706][26615:26620] CHIP:DMG: CommandPathIB = [1637274194.075731][26615:26620] CHIP:DMG: { [1637274194.075756][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274194.075782][26615:26620] CHIP:DMG: ClusterId = 0x31, [1637274194.075807][26615:26620] CHIP:DMG: CommandId = 0x2, [1637274194.075831][26615:26620] CHIP:DMG: }, [1637274194.075858][26615:26620] CHIP:DMG: [1637274194.075881][26615:26620] CHIP:DMG: StatusIB = [1637274194.076282][26615:26620] CHIP:DMG: { [1637274194.076320][26615:26620] CHIP:DMG: status = 0x0, [1637274194.076384][26615:26620] CHIP:DMG: }, [1637274194.076488][26615:26620] CHIP:DMG: [1637274194.076574][26615:26620] CHIP:DMG: }, [1637274194.076664][26615:26620] CHIP:DMG: [1637274194.076735][26615:26620] CHIP:DMG: ], [1637274194.076812][26615:26620] CHIP:DMG: [1637274194.076856][26615:26620] CHIP:DMG: } [1637274194.076907][26615:26620] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_0002 Status=0x0 [1637274194.076934][26615:26620] CHIP:ZCL: DefaultResponse: [1637274194.076954][26615:26620] CHIP:ZCL: Transaction: 0xffff88011db0 [1637274194.076974][26615:26620] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1637274194.077000][26615:26620] CHIP:TOO: AddNetworkResponse [1637274194.077052][26615:26620] CHIP:DMG: ICR moving to [AddingComm] [1637274194.077086][26615:26620] CHIP:DMG: ICR moving to [AddedComma] [1637274194.077188][26615:26620] CHIP:IN: Prepared encrypted message 0xffff9e1f7350 to 0x00000000029365F1 of type 0x8 and protocolId (0, 1) on exchange 29120i with MessageCounter:8. [1637274194.077234][26615:26620] CHIP:IN: Sending encrypted msg 0xffff9e1f7350 with MessageCounter:8 to 0x00000000029365F1 at monotonic time: 344609515 msec [1637274194.077269][26615:26620] CHIP:DMG: ICR moving to [CommandSen] [1637274194.077302][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274194.217874][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.363962][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.461758][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.559184][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.559813][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.560029][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274194.656781][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.754226][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274194.803403][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274194.803695][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274197.386424][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274201.921081][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274201.921582][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274201.922048][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274201.922173][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274201.922661][26615:26618] CHIP:DL: Indication received, conn = 0xffff94032f80 [1637274201.922812][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16389 [1637274201.922954][26615:26620] CHIP:EM: Received message of type 0x9 with protocolId (0, 1) and MessageCounter:8 on exchange 29120i [1637274201.923152][26615:26620] CHIP:DMG: InvokeCommand = [1637274201.923206][26615:26620] CHIP:DMG: { [1637274201.923247][26615:26620] CHIP:DMG: CommandList = [1637274201.923295][26615:26620] CHIP:DMG: [ [1637274201.923340][26615:26620] CHIP:DMG: CommandDataIB = [1637274201.923390][26615:26620] CHIP:DMG: { [1637274201.923438][26615:26620] CHIP:DMG: CommandPathIB = [1637274201.923500][26615:26620] CHIP:DMG: { [1637274201.923559][26615:26620] CHIP:DMG: EndpointId = 0x0, [1637274201.923626][26615:26620] CHIP:DMG: ClusterId = 0x31, [1637274201.923693][26615:26620] CHIP:DMG: CommandId = 0xc, [1637274201.923751][26615:26620] CHIP:DMG: }, [1637274201.923813][26615:26620] CHIP:DMG: [1637274201.923865][26615:26620] CHIP:DMG: StatusIB = [1637274201.923923][26615:26620] CHIP:DMG: { [1637274201.923980][26615:26620] CHIP:DMG: status = 0x0, [1637274201.924038][26615:26620] CHIP:DMG: }, [1637274201.924097][26615:26620] CHIP:DMG: [1637274201.924147][26615:26620] CHIP:DMG: }, [1637274201.924206][26615:26620] CHIP:DMG: [1637274201.924250][26615:26620] CHIP:DMG: ], [1637274201.924303][26615:26620] CHIP:DMG: [1637274201.924343][26615:26620] CHIP:DMG: } [1637274201.924418][26615:26620] CHIP:DMG: Received Command Response Status for Endpoint=0 Cluster=0x0000_0031 Command=0x0000_000C Status=0x0 [1637274201.924467][26615:26620] CHIP:ZCL: DefaultResponse: [1637274201.924504][26615:26620] CHIP:ZCL: Transaction: 0xffff880154d0 [1637274201.924541][26615:26620] CHIP:ZCL: status: EMBER_ZCL_STATUS_SUCCESS (0x00) [1637274201.924648][26615:26620] CHIP:TOO: EnableNetworkResponse [1637274201.924691][26615:26620] CHIP:TOO: Mdns: Updating NodeId: 29365f1 Compressed FabricId: 5adc44f45dede9bc ... [1637274201.926529][26615:26620] CHIP:DMG: ICR moving to [AwaitingDe] [1637274201.928329][26615:26620] CHIP:DL: Avahi resolve found [1637274201.928437][26615:26620] CHIP:DIS: Node ID resolved for 0x00000000029365F1 to [fe80::d64d:a4ff:fea0:2a4]:5540 [1637274201.932288][26615:26620] CHIP:TOO: OnAddressUpdateComplete: 29365f1: ../../third_party/connectedhomeip/src/controller/CHIPDevice.cpp:443: Success [1637274201.932357][26615:26620] CHIP:CTL: OperationalDiscoveryComplete for device ID 43214321 [1637274201.932494][26615:26620] CHIP:CTL: Enabling CASE session establishment for the device [1637274201.937771][26615:26620] CHIP:IN: Marking old secure session for device 0x00000000029365F1 as expired [1637274201.940065][26615:26620] CHIP:IN: Prepared plaintext message 0xaaaaf5414fa0 to 0x0000000000000000 of type 0x30 and protocolId (0, 0) on exchange 29121i with MessageCounter:3028697144. [1637274201.940148][26615:26620] CHIP:IN: Sending plaintext msg 0xaaaaf5414fa0 with MessageCounter:3028697144 to 0x0000000000000000 at monotonic time: 344617378 msec [1637274201.940327][26615:26620] CHIP:SC: Sent Sigma1 msg [1637274204.503532][26615:26620] CHIP:DL: HandlePlatformSpecificBLEEvent 16387 [1637274206.875285][26615:26620] CHIP:IN: Sending plaintext msg 0xaaaaf5414fa0 with MessageCounter:3028697144 to 0x0000000000000000 at monotonic time: 344622313 msec [1637274206.875566][26615:26620] CHIP:EM: Retransmitted MessageCounter:3028697144 on exchange 29121i Send Cnt 1 [1637274207.130069][26615:26620] CHIP:IN: Sending plaintext msg 0xaaaaf5414fa0 with MessageCounter:3028697144 to 0x0000000000000000 at monotonic time: 344622568 msec [1637274207.130310][26615:26620] CHIP:EM: Retransmitted MessageCounter:3028697144 on exchange 29121i Send Cnt 2 [1637274207.386849][26615:26620] CHIP:IN: Sending plaintext msg 0xaaaaf5414fa0 with MessageCounter:3028697144 to 0x0000000000000000 at monotonic time: 344622825 msec [1637274207.387111][26615:26620] CHIP:EM: Retransmitted MessageCounter:3028697144 on exchange 29121i Send Cnt 3 [1637274207.642635][26615:26620] CHIP:EM: Failed to Send CHIP MessageCounter:3028697144 on exchange 29121i sendCount: 3 max retries: 3 [1637274211.941310][26615:26620] CHIP:SC: CASESession timed out while waiting for a response from the peer. Current state was 1 [1637274211.941426][26615:26620] CHIP:CTL: Device connection failed. Error ../../third_party/connectedhomeip/src/protocols/secure_channel/CASESession.cpp:291: CHIP Error 0x00000032: Timeout [1637274211.941494][26615:26620] CHIP:TOO: Device commissioning Failure: ../../third_party/connectedhomeip/src/protocols/secure_channel/CASESession.cpp:291: CHIP Error 0x00000032: Timeout [1637274211.942122][26615:26615] CHIP:-: ../../third_party/connectedhomeip/src/protocols/secure_channel/CASESession.cpp:291: CHIP Error 0x00000032: Timeout at ../../commands/common/CHIPCommand.cpp:84 [1637274211.942251][26615:26615] CHIP:TOO: Run command failure: ../../third_party/connectedhomeip/src/protocols/secure_channel/CASESession.cpp:291: CHIP Error 0x00000032: Timeout [1637274212.071254][26615:26615] CHIP:CTL: Shutting down the System State, this will teardown the CHIP Stack [1637274212.071615][26615:26615] CHIP:DL: writing settings to file (/tmp/chip_counters.ini-yzpQOj) [1637274212.072464][26615:26615] CHIP:DL: renamed tmp file to file (/tmp/chip_counters.ini) [1637274212.072532][26615:26615] CHIP:DL: NVS set: chip-counters/total-operational-hours = 0 (0x0) [1637274212.072559][26615:26615] CHIP:DL: Inet Layer shutdown [1637274212.072662][26615:26615] CHIP:DL: BLE layer shutdown [1637274212.072691][26615:26615] CHIP:IN: Clearing BLE pending packets. [1637274212.072777][26615:26615] CHIP:BLE: Auto-closing end point's BLE connection. [1637274212.072804][26615:26615] CHIP:DL: Closing BLE GATT connection (con 0xffff94032f80) [1637274212.072846][26615:26615] CHIP:DL: System Layer shutdown [1637274212.073055][26615:26618] CHIP:DL: BluezDisconnect peer=D4:4D:A4:A0:02:A5 ubuntu@matter-te-7:~$ avahi-browse -rt _matter._tcp + eth0 IPv6 5ADC44F45DEDE9BC-00000000029365F1 _matter._tcp local = eth0 IPv6 5ADC44F45DEDE9BC-00000000029365F1 _matter._tcp local hostname = [A402A0A44DD4.local] address = [fe80::d64d:a4ff:fea0:2a4] port = [5540] txt = ["T=1" "CRA=300" "CRI=5000"]