Executing action: monitor
Serial port /dev/ttyS9
/dev/ttyS9 failed to connect: Could not open /dev/ttyS9, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS8
/dev/ttyS8 failed to connect: Could not open /dev/ttyS8, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS7
/dev/ttyS7 failed to connect: Could not open /dev/ttyS7, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS6
/dev/ttyS6 failed to connect: Could not open /dev/ttyS6, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS5
/dev/ttyS5 failed to connect: Could not open /dev/ttyS5, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS4
/dev/ttyS4 failed to connect: Could not open /dev/ttyS4, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS31
/dev/ttyS31 failed to connect: Could not open /dev/ttyS31, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS30
/dev/ttyS30 failed to connect: Could not open /dev/ttyS30, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS3
/dev/ttyS3 failed to connect: Could not open /dev/ttyS3, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS29
/dev/ttyS29 failed to connect: Could not open /dev/ttyS29, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS28
/dev/ttyS28 failed to connect: Could not open /dev/ttyS28, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS27
/dev/ttyS27 failed to connect: Could not open /dev/ttyS27, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS26
/dev/ttyS26 failed to connect: Could not open /dev/ttyS26, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS25
/dev/ttyS25 failed to connect: Could not open /dev/ttyS25, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS24
/dev/ttyS24 failed to connect: Could not open /dev/ttyS24, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS23
/dev/ttyS23 failed to connect: Could not open /dev/ttyS23, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS22
/dev/ttyS22 failed to connect: Could not open /dev/ttyS22, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS21
/dev/ttyS21 failed to connect: Could not open /dev/ttyS21, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS20
/dev/ttyS20 failed to connect: Could not open /dev/ttyS20, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS2
/dev/ttyS2 failed to connect: Could not open /dev/ttyS2, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS19
/dev/ttyS19 failed to connect: Could not open /dev/ttyS19, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS18
/dev/ttyS18 failed to connect: Could not open /dev/ttyS18, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS17
/dev/ttyS17 failed to connect: Could not open /dev/ttyS17, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS16
/dev/ttyS16 failed to connect: Could not open /dev/ttyS16, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS15
/dev/ttyS15 failed to connect: Could not open /dev/ttyS15, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS14
/dev/ttyS14 failed to connect: Could not open /dev/ttyS14, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS13
/dev/ttyS13 failed to connect: Could not open /dev/ttyS13, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS12
/dev/ttyS12 failed to connect: Could not open /dev/ttyS12, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS11
/dev/ttyS11 failed to connect: Could not open /dev/ttyS11, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS10
/dev/ttyS10 failed to connect: Could not open /dev/ttyS10, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS1
/dev/ttyS1 failed to connect: Could not open /dev/ttyS1, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyS0
/dev/ttyS0 failed to connect: Could not open /dev/ttyS0, the port is busy or doesn't exist.
(Could not configure port: (5, 'Input/output error'))

Serial port /dev/ttyACM0
Connecting....
Detecting chip type... ESP32-S3
Running idf_monitor in directory /home/dmitry/esp/v5.2.1/esp-matter/examples/door_lock
Executing "/home/dmitry/.espressif/python_env/idf5.2_py3.10_env/bin/python /home/dmitry/esp/v5.2.1/esp-idf/tools/idf_monitor.py -p /dev/ttyACM0 -b 115200 --toolchain-prefix xtensa-esp32s3-elf- --target esp32s3 --revision 0 /home/dmitry/esp/v5.2.1/esp-matter/examples/door_lock/build/door_lock.elf -m '/home/dmitry/.espressif/python_env/idf5.2_py3.10_env/bin/python' '/home/dmitry/esp/v5.2.1/esp-idf/tools/idf.py'"...
ESP-ROM:esp32s3-20210327
Build:Mar 27 2021
rst:0x1 (POWERON),boot:0x8 (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fce3810,len:0x178c
load:0x403c9700,len:0x4
load:0x403c9704,len:0xcbc
load:0x403cc700,len:0x2da0
entry 0x403c9914
I (27) boot: ESP-IDF v5.2.1-dirty 2nd stage bootloader
I (27) boot: compile time Jul  4 2024 22:46:28
I (27) boot: Multicore bootloader
I (30) boot: chip revision: v0.2
I (34) boot.esp32s3: Boot SPI Speed : 80MHz
I (39) boot.esp32s3: SPI Mode       : DIO
I (44) boot.esp32s3: SPI Flash Size : 4MB
I (48) boot: Enabling RNG early entropy source...
I (54) boot: Partition Table:
I (57) boot: ## Label            Usage          Type ST Offset   Length
I (65) boot:  0 esp_secure_cert  unknown          3f 06 0000d000 00002000
I (72) boot:  1 nvs              WiFi data        01 02 00010000 0000c000
I (80) boot:  2 nvs_keys         NVS keys         01 04 0001c000 00001000
I (87) boot:  3 otadata          OTA data         01 00 0001d000 00002000
I (95) boot:  4 phy_init         RF data          01 01 0001f000 00001000
I (102) boot:  5 ota_0            OTA app          00 10 00020000 001e0000
I (110) boot:  6 ota_1            OTA app          00 11 00200000 001e0000
I (117) boot:  7 fctry            WiFi data        01 02 003e0000 00006000
I (125) boot:  8 coredump         Unknown data     01 03 003e6000 00010000
I (132) boot: End of partition table
I (137) esp_image: segment 0: paddr=00020020 vaddr=3c110020 size=3fa58h (260696) map
I (192) esp_image: segment 1: paddr=0005fa80 vaddr=3fc9a900 size=00598h (  1432) load
I (192) esp_image: segment 2: paddr=00060020 vaddr=42000020 size=1030cch (1061068) map
I (388) esp_image: segment 3: paddr=001630f4 vaddr=3fc9ae98 size=0650ch ( 25868) load
I (394) esp_image: segment 4: paddr=00169608 vaddr=40374000 size=168f0h ( 92400) load
I (424) boot: Loaded app from partition at offset 0x20000
I (424) boot: Disabling RNG early entropy source...
I (436) cpu_start: Multicore app
I (445) cpu_start: Pro cpu start user code
I (445) cpu_start: cpu freq: 160000000 Hz
I (445) cpu_start: Application information:
I (448) cpu_start: Project name:     door_lock
I (453) cpu_start: App version:      1.0
I (458) cpu_start: Compile time:     Jul  4 2024 22:45:53
I (464) cpu_start: ELF file SHA256:  8e058c6a7...
I (469) cpu_start: ESP-IDF:          v5.2.1-dirty
I (475) cpu_start: Min chip rev:     v0.0
I (479) cpu_start: Max chip rev:     v0.99 
I (484) cpu_start: Chip rev:         v0.2
I (489) heap_init: Initializing. RAM available for dynamic allocation:
I (496) heap_init: At 3FCB40E0 len 00035630 (213 KiB): RAM
I (502) heap_init: At 3FCE9710 len 00005724 (21 KiB): RAM
I (508) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM
I (514) heap_init: At 600FE010 len 00001FD8 (7 KiB): RTCRAM
I (522) spi_flash: detected chip: gd
I (525) spi_flash: flash io: dio
W (529) spi_flash: Detected size(8192k) larger than the size in the binary image header(4096k). Using the size in the binary image header.
W (543) i2c: This driver is an old driver, please migrate your application code to adapt `driver/i2c_master.h`
I (554) sleep: Configure to isolate all GPIO pins in sleep state
I (560) sleep: Enable automatic switching of GPIO sleep configuration
I (566) esp_core_dump_flash: Init core dump to flash
I (572) esp_core_dump_flash: Found partition 'coredump' @ 3e6000 65536 bytes
I (580) coexist: coex firmware version: 77cd7f8
I (585) coexist: coexist rom version e7ae62f
I (591) main_task: Started on CPU0
I (601) main_task: Calling app_main()
I (641) app_main: Door lock created with endpoint_id 1
I (641) pp: pp rom version: e7ae62f
I (641) net80211: net80211 rom version: e7ae62f
I (661) wifi:wifi driver task: 3fcc1cc4, prio:23, stack:6656, core=0
I (661) wifi:wifi firmware version: a9f5b59
I (661) wifi:wifi certification version: v7.0
I (661) wifi:config NVS flash: enabled
I (661) wifi:config nano formating: disabled
I (671) wifi:Init data frame dynamic rx buffer num: 32
I (671) wifi:Init static rx mgmt buffer num: 5
I (681) wifi:Init management short buffer num: 32
I (681) wifi:Init dynamic tx buffer num: 32
I (681) wifi:Init static tx FG buffer num: 2
I (691) wifi:Init static rx buffer size: 1600
I (691) wifi:Init static rx buffer num: 10
I (701) wifi:Init dynamic rx buffer num: 32
I (701) wifi_init: rx ba win: 6
I (701) wifi_init: tcpip mbox: 32
I (711) wifi_init: udp mbox: 6
I (711) wifi_init: tcp mbox: 6
I (721) wifi_init: tcp tx win: 5760
I (721) wifi_init: tcp rx win: 5760
I (721) wifi_init: tcp mss: 1440
I (731) wifi_init: WiFi IRAM OP enabled
I (731) wifi_init: WiFi RX IRAM OP enabled
I (751) chip[DL]: NVS set: chip-counters/reboot-count = 2 (0x2)
I (751) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (751) BLE_INIT: BT controller compile version [30b57c4]
I (761) BLE_INIT: Bluetooth MAC: 48:27:e2:1e:07:76
I (761) phy_init: phy_version 640,cd64a1a,Jan 24 2024,17:28:12
I (811) NimBLE: GAP procedure initiated: stop advertising.

I (811) NimBLE: Failed to restore IRKs from store; status=8

I (811) CHIP[DL]: BLE host-controller synced
I (1321) chip[DL]: Starting ESP WiFi layer
I (1321) wifi:mode : sta (48:27:e2:1e:07:74)
I (1321) wifi:enable tsf
W (1321) wifi:Haven't to connect to a suitable AP now!
I (1321) chip[DL]: Done driving station state, nothing else to do...
W (1331) wifi:Haven't to connect to a suitable AP now!
I (1331) chip[DL]: Done driving station state, nothing else to do...
I (1341) chip[SVR]: Initializing subscription resumption storage...
I (1351) chip[SVR]: Server initializing...
I (1351) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (1361) chip[DMG]: AccessControl: initializing
I (1361) chip[DMG]: Examples::AccessControlDelegate::Init
I (1371) chip[DMG]: AccessControl: setting
I (1371) chip[DMG]: DefaultAclStorage: initializing
I (1381) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (1411) chip[ZCL]: Using ZAP configuration...
I (1411) esp_matter_cluster: Cluster plugin init common callback
I (1411) chip[DMG]: AccessControlCluster: initializing
I (1411) chip[ZCL]: 0x3c118ce5 ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported
I (1421) chip[ZCL]: Initiating Admin Commissioning cluster.
I (1431) chip[ZCL]: Door Lock server initialized
I (1441) chip[DIS]: Updating services using commissioning mode 1
I (1441) chip[DIS]: CHIP minimal mDNS started advertising.
I (1451) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 cp=0
I (1461) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: D928522F88F7B7D8.
I (1471) chip[DIS]: mDNS service published: _matterc._udp
I (1471) chip[IN]: CASE Server enabling CASE session setups
I (1481) chip[SVR]: Joining Multicast groups
I (1491) chip[SVR]: Server Listening...
I (1491) esp_matter_core: Dynamic endpoint 0 added
I (1501) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 0 **********
I (1511) chip[ZCL]: Door Lock cluster initialized at endpoint #1
I (1511) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000101's Attribute 0x00000000 is null **********
I (1531) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000101's Attribute 0x00000002 is 1 **********
I (1541) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000101's Attribute 0x00000000 is null **********
I (1551) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000101's Attribute 0x0000001C is 3 **********
I (1561) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000101's Attribute 0x00000011 is 5 **********
I (1571) esp_matter_core: Dynamic endpoint 1 added
I (1581) chip[DL]: WIFI_EVENT_STA_START
W (1581) wifi:Haven't to connect to a suitable AP now!
I (1591) chip[DL]: Done driving station state, nothing else to do...
I (1601) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1601) NimBLE: GAP procedure initiated: advertise; 
I (1611) NimBLE: disc_mode=2
I (1611) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1621) NimBLE: 

I (1631) chip[DL]: CHIPoBLE advertising started
I (1631) app_main: Commissioning window opened
I (1641) doorlock_callback: doorlock example init
> I (2151) main_task: Returned from app_main()
I (31641) chip[DL]: bleAdv Timeout : Start slow advertisement
I (31641) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable)
I (31641) chip[DL]: Device already advertising, stop active advertisement and restart
I (31651) NimBLE: GAP procedure initiated: stop advertising.

I (31661) NimBLE: GAP procedure initiated: advertise; 
I (31661) NimBLE: disc_mode=2
I (31671) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800
I (31681) NimBLE: 

I (206981) chip[DL]: BLE GAP connection established (con 1)
I (206981) chip[DL]: CHIPoBLE advertising stopped
I (207681) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (207691) chip[BLE]: local and remote recv window sizes = 5
I (207701) chip[BLE]: selected BTP version 4
I (207701) chip[BLE]: using BTP fragment sizes rx 244 / tx 244.
I (207711) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1
I (207721) chip[DL]: CHIPoBLE subscribe received
I (207721) NimBLE: GATT procedure initiated: indicate; 
I (207731) NimBLE: att_handle=18

I (207741) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (207771) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (207771) chip[EM]: >>> [E:24872r S:0 M:64440627] (U) Msg RX from 0:C9F09BF2AEFED1DC [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (207791) chip[EM]: <<< [E:24872r S:0 M:164585677] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (207801) NimBLE: GATT procedure initiated: indicate; 
I (207801) NimBLE: att_handle=18

I (207811) chip[SVR]: Commissioning session establishment step started
I (207831) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (207851) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (207851) chip[EM]: >>> [E:24872r S:0 M:64440628] (U) Msg RX from 0:C9F09BF2AEFED1DC [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (208591) chip[EM]: <<< [E:24872r S:0 M:164585678] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (208591) NimBLE: GATT procedure initiated: indicate; 
I (208601) NimBLE: att_handle=18

E (208611) chip[DL]: Long dispatch time: 753 ms, for event type 7
I (208681) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (208681) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (208701) chip[EM]: >>> [E:24872r S:0 M:64440629] (U) Msg RX from 0:C9F09BF2AEFED1DC [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (208711) chip[EM]: <<< [E:24872r S:0 M:164585679] (U) Msg TX to 0:0000000000000000 [0000] [BLE] --- Type 0000:40 (SecureChannel:StatusReport)
I (208721) NimBLE: GATT procedure initiated: indicate; 
I (208731) NimBLE: att_handle=18

I (208731) chip[SC]: SecureSession[0x3fca5778, LSID:44034]: State change 'kEstablishing' --> 'kActive'
I (208741) chip[SVR]: Commissioning completed session establishment step
I (208741) chip[DIS]: Updating services using commissioning mode 0
I (208761) chip[DIS]: CHIP minimal mDNS started advertising.
I (208761) chip[SVR]: Device completed Rendezvous process
I (208771) app_main: Commissioning session started
I (208771) app_main: Commissioning window closed
I (208781) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (208831) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (208831) chip[EM]: >>> [E:24873r S:44034 M:245620531] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (208851) chip[EM]: <<< [E:24873r S:44034 M:254996948] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (208861) NimBLE: GATT procedure initiated: indicate; 
I (208861) NimBLE: att_handle=18

I (208921) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (208931) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (208941) chip[EM]: >>> [E:24874r S:44034 M:245620532] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (208961) chip[EM]: <<< [E:24874r S:44034 M:254996949] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (208961) NimBLE: GATT procedure initiated: indicate; 
I (208971) NimBLE: att_handle=18

I (209021) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (209031) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (209041) chip[EM]: >>> [E:24875r S:44034 M:245620533] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (209051) chip[EM]: <<< [E:24875r S:44034 M:254996950] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (209061) NimBLE: GATT procedure initiated: indicate; 
I (209071) NimBLE: att_handle=18

I (209121) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (209121) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (209141) chip[EM]: >>> [E:24876r S:44034 M:245620534] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (209151) chip[EM]: <<< [E:24876r S:44034 M:254996951] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (209161) NimBLE: GATT procedure initiated: indicate; 
I (209171) NimBLE: att_handle=18

I (209221) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (209221) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (209231) chip[EM]: >>> [E:24877r S:44034 M:245620535] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (209251) chip[EM]: <<< [E:24877r S:44034 M:254996952] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (209251) NimBLE: GATT procedure initiated: indicate; 
I (209261) NimBLE: att_handle=18

I (209311) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (209321) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (209331) chip[EM]: >>> [E:24878r S:44034 M:245620536] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (209351) chip[EM]: <<< [E:24878r S:44034 M:254996953] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (209351) NimBLE: GATT procedure initiated: indicate; 
I (209361) NimBLE: att_handle=18

I (209411) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (209421) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (209431) chip[EM]: >>> [E:24879r S:44034 M:245620537] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (209451) chip[EM]: <<< [E:24879r S:44034 M:254996954] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (209451) NimBLE: GATT procedure initiated: indicate; 
I (209461) NimBLE: att_handle=18

I (209511) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (210581) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (210591) chip[EM]: >>> [E:24880r S:44034 M:245620538] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (210601) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (210611) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (210621) chip[EM]: <<< [E:24880r S:44034 M:254996955] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (210631) NimBLE: GATT procedure initiated: indicate; 
I (210631) NimBLE: att_handle=18

I (210731) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (210731) NimBLE: GATT procedure initiated: indicate; 
I (210741) NimBLE: att_handle=18

I (210831) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (210831) NimBLE: GATT procedure initiated: indicate; 
I (210841) NimBLE: att_handle=18

I (210921) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (210931) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (210941) chip[EM]: >>> [E:24881r S:44034 M:245620539] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (210961) chip[EM]: <<< [E:24881r S:44034 M:254996956] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (210961) NimBLE: GATT procedure initiated: indicate; 
I (210971) NimBLE: att_handle=18

I (211021) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (211021) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (211041) chip[EM]: >>> [E:24882r S:44034 M:245620540] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (211051) chip[EM]: <<< [E:24882r S:44034 M:254996957] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (211061) NimBLE: GATT procedure initiated: indicate; 
I (211071) NimBLE: att_handle=18

I (211121) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (211851) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (211851) chip[EM]: >>> [E:24883r S:44034 M:245620541] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (211871) chip[EM]: <<< [E:24883r S:44034 M:254996958] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (211881) NimBLE: GATT procedure initiated: indicate; 
I (211881) NimBLE: att_handle=18

I (211951) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (211951) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (211961) chip[EM]: >>> [E:24883r S:44034 M:245620542] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (211981) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (211981) chip[FS]: GeneralCommissioning: Received ArmFailSafe (120s)
I (211991) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (212011) chip[EM]: <<< [E:24883r S:44034 M:254996959] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (212021) NimBLE: GATT procedure initiated: indicate; 
I (212021) NimBLE: att_handle=18

I (212141) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212151) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (212161) chip[EM]: >>> [E:24884r S:44034 M:245620543] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (212181) chip[EM]: <<< [E:24884r S:44034 M:254996960] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:05 (IM:ReportData)
I (212181) NimBLE: GATT procedure initiated: indicate; 
I (212191) NimBLE: att_handle=18

I (212291) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212291) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (212311) chip[EM]: >>> [E:24885r S:44034 M:245620544] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:0a (IM:TimedRequest)
I (212321) chip[EM]: <<< [E:24885r S:44034 M:254996961] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:01 (IM:StatusResponse)
I (212321) NimBLE: GATT procedure initiated: indicate; 
I (212331) NimBLE: att_handle=18

I (212391) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212391) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (212401) chip[EM]: >>> [E:24885r S:44034 M:245620545] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (212421) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (212421) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (212431) chip[DL]: NVS set: chip-config/country-code = "RU"
I (212431) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (212451) chip[EM]: <<< [E:24885r S:44034 M:254996962] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (212461) NimBLE: GATT procedure initiated: indicate; 
I (212461) NimBLE: att_handle=18

I (212531) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212541) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (212551) chip[EM]: >>> [E:24886r S:44034 M:245620546] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (212561) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (212571) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (212581) chip[EM]: <<< [E:24886r S:44034 M:254996963] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (212601) NimBLE: GATT procedure initiated: indicate; 
I (212601) NimBLE: att_handle=18

I (212681) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212681) NimBLE: GATT procedure initiated: indicate; 
I (212691) NimBLE: att_handle=18

I (212781) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212781) NimBLE: GATT procedure initiated: indicate; 
I (212791) NimBLE: att_handle=18

I (212871) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (212881) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (212891) chip[EM]: >>> [E:24887r S:44034 M:245620547] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (212901) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (212911) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (212991) chip[ZCL]: OpCreds: AttestationRequest successful.
I (212991) chip[EM]: <<< [E:24887r S:44034 M:254996964] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (213011) NimBLE: GATT procedure initiated: indicate; 
I (213011) NimBLE: att_handle=18

I (213071) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (213071) NimBLE: GATT procedure initiated: indicate; 
I (213081) NimBLE: att_handle=18

I (213171) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (213171) NimBLE: GATT procedure initiated: indicate; 
I (213181) NimBLE: att_handle=18

I (213261) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (213271) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (213281) chip[EM]: >>> [E:24888r S:44034 M:245620548] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (213291) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (213301) chip[ZCL]: OpCreds: Received a CSRRequest command
I (213441) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (213501) chip[ZCL]: OpCreds: CSRRequest successful.
I (213511) chip[EM]: <<< [E:24888r S:44034 M:254996965] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (213521) NimBLE: GATT procedure initiated: indicate; 
I (213521) NimBLE: att_handle=18

I (213601) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (213611) NimBLE: GATT procedure initiated: indicate; 
I (213621) NimBLE: att_handle=18

I (213801) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (214391) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (214481) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (214491) chip[EM]: >>> [E:24889r S:44034 M:245620549] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (214501) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (214501) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (214741) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (214751) chip[EM]: <<< [E:24889r S:44034 M:254996966] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (214761) NimBLE: GATT procedure initiated: indicate; 
I (214761) NimBLE: att_handle=18

I (214871) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (214881) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (214971) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (214981) chip[EM]: >>> [E:24890r S:44034 M:245620550] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (214991) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (214991) chip[ZCL]: OpCreds: Received an AddNOC command
I (215011) chip[FP]: Validating NOC chain
I (215241) chip[FP]: NOC chain validation successful
I (215241) chip[FP]: Added new fabric at index: 0x1
I (215241) chip[FP]: Assigned compressed fabric ID: 0x44AFB26E7CE51B07, node ID: 0x2D379C053E06B543
I (215251) chip[TS]: Last Known Good Time: 2023-10-14T01:16:48
I (215261) chip[TS]: New proposed Last Known Good Time: 2024-06-30T00:00:00
I (215271) chip[TS]: Updating pending Last Known Good Time to 2024-06-30T00:00:00
I (215291) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0xFFFFFFFD00010001
I (215291) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (215301) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (215321) chip[DIS]: mDNS service published: _matter._tcp
I (215321) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC
I (215331) chip[EM]: <<< [E:24890r S:44034 M:254996967] (S) Msg TX to 1:FFFFFFFB00000000 [1B07] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (215351) NimBLE: GATT procedure initiated: indicate; 
I (215351) NimBLE: att_handle=18

I (215351) app_main: Fabric is updated
I (215411) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (215411) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (215431) chip[EM]: >>> [E:24891r S:44034 M:245620551] (S) Msg RX from 1:FFFFFFFB00000000 [1B07] --- Type 0001:08 (IM:InvokeCommandRequest)
I (215441) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (215441) chip[EM]: <<< [E:24891r S:44034 M:254996968] (S) Msg TX to 1:FFFFFFFB00000000 [1B07] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (215461) NimBLE: GATT procedure initiated: indicate; 
I (215471) NimBLE: att_handle=18

I (215601) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (215611) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (215621) chip[EM]: >>> [E:24892r S:44034 M:245620552] (S) Msg RX from 1:FFFFFFFB00000000 [1B07] --- Type 0001:02 (IM:ReadRequest)
I (215641) chip[EM]: <<< [E:24892r S:44034 M:254996969] (S) Msg TX to 1:FFFFFFFB00000000 [1B07] [BLE] --- Type 0001:05 (IM:ReportData)
I (215641) NimBLE: GATT procedure initiated: indicate; 
I (215651) NimBLE: att_handle=18

I (215751) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (215751) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 16
I (215771) chip[EM]: >>> [E:24893r S:44034 M:245620553] (S) Msg RX from 1:FFFFFFFB00000000 [1B07] --- Type 0001:08 (IM:InvokeCommandRequest)
I (215781) chip[NP]: ESP NetworkCommissioningDelegate: SSID: HUAWEI VbFtt
W (215781) wifi:Haven't to connect to a suitable AP now!
I (215781) chip[DL]: WiFi station mode change: Enabled -> Disabled
I (215831) chip[DL]: WiFi station mode change: Disabled -> Enabled
W (215831) wifi:Haven't to connect to a suitable AP now!
I (215831) chip[DL]: Attempting to connect WiFi station interface
I (215841) chip[DL]: WiFi station state change: NotConnected -> Connecting
W (215851) wifi:Haven't to connect to a suitable AP now!
E (215861) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (215861) chip[DL]: Done driving station state, nothing else to do...
W (215871) wifi:Haven't to connect to a suitable AP now!
I (215871) chip[DL]: Attempting to connect WiFi station interface
E (215881) wifi:sta is connecting, return error
E (215891) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
W (215901) wifi:Haven't to connect to a suitable AP now!
I (215901) chip[DL]: Attempting to connect WiFi station interface
E (215911) wifi:sta is connecting, return error
E (215911) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN
I (218271) NimBLE: GATT procedure initiated: indicate; 
I (218271) NimBLE: att_handle=18

I (218271) wifi:new:<11,2>, old:<1,0>, ap:<255,255>, sta:<11,2>, prof:1
I (218761) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (218771) wifi:state: init -> auth (b0)
I (218791) wifi:state: auth -> assoc (0)
I (218801) wifi:state: assoc -> run (10)
I (218911) wifi:connected with HUAWEI VbFtt, aid = 8, channel 11, 40D, bssid = 28:87:ba:b4:b4:8e
I (218921) wifi:security: WPA2-PSK, phy: bgn, rssi: -51
I (218941) wifi:pm start, type: 1

I (218941) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us
I (218941) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 25000, mt_pti: 14, mt_time: 10000
I (218961) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (218961) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (218971) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (218971) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (218981) wifi:<ba-add>idx:0 (ifx:0, 28:87:ba:b4:b4:8e), tid:0, ssn:2, winSize:64
I (218991) chip[DL]: WiFi station interface connected
I (219001) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 1 **********
I (219021) chip[EM]: <<< [E:24893r S:44034 M:254996970] (S) Msg TX to 1:FFFFFFFB00000000 [1B07] [BLE] --- Type 0001:09 (IM:InvokeCommandResponse)
I (219031) NimBLE: GATT procedure initiated: indicate; 
I (219031) NimBLE: att_handle=18

I (219031) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (219041) chip[DL]: Done driving station state, nothing else to do...
I (219051) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (219061) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (219071) chip[DIS]: mDNS service published: _matter._tcp
I (219071) chip[SVR]: Operational advertising enabled
I (219081) chip[DL]: Updating advertising data
I (219211) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (220141) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (220141) chip[DL]: CHIPoBLE unsubscribe received
I (220141) chip[BLE]: Releasing end point's BLE connection back to application.
I (220151) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (220161) esp_matter_core: BLE Disconnected
I (220461) esp_netif_handlers: sta ip: 192.168.1.119, mask: 255.255.255.0, gw: 192.168.1.1
I (220461) chip[DL]: IP_EVENT_STA_GOT_IP
I (220461) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.1.119/255.255.255.0 gateway 192.168.1.1
I (220471) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (220481) app_main: Interface IP Address changed
I (220481) chip[DIS]: Updating services using commissioning mode 0
I (220501) chip[DIS]: CHIP minimal mDNS started advertising.
I (220501) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (220511) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (220521) chip[DIS]: mDNS service published: _matter._tcp
I (220641) chip[DL]: IP_EVENT_GOT_IP6
I (220641) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:4a27:e2ff:fe1e:0774
I (220651) app_main: Interface IP Address changed
I (220651) chip[DIS]: Updating services using commissioning mode 0
I (220661) chip[DIS]: CHIP minimal mDNS started advertising.
I (220671) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (220681) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (220691) chip[DIS]: mDNS service published: _matter._tcp
I (220701) chip[SVR]: Server initialization complete
I (220701) chip[DIS]: Updating services using commissioning mode 0
I (220711) chip[DIS]: CHIP minimal mDNS started advertising.
I (220721) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (220721) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (220741) chip[DIS]: mDNS service published: _matter._tcp
I (220741) chip[IM]: No subscriptions to resume
I (221551) chip[EM]: >>> [E:24894r S:0 M:64440630] (U) Msg RX from 0:EF48DABC1ED8CEB0 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (221561) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fca7128
I (221571) chip[EM]: <<< [E:24894r S:0 M:164585680 (Ack:64440630)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.118:50009] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (221581) chip[SC]: Received Sigma1 msg
I (221601) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x2D379C053E06B543
I (221891) chip[EM]: <<< [E:24894r S:0 M:164585681 (Ack:64440630)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.118:50009] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (221911) chip[SC]: Sent Sigma2 msg
I (221911) chip[EM]: >>> [E:24894r S:0 M:64440630] (U) Msg RX from 0:EF48DABC1ED8CEB0 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (221921) chip[EM]: <<< [E:24894r S:0 M:164585682 (Ack:64440630)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.118:50009] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (221971) chip[EM]: >>> [E:24894r S:0 M:64440631 (Ack:164585681)] (U) Msg RX from 0:EF48DABC1ED8CEB0 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (221981) chip[EM]: <<< [E:24894r S:0 M:164585683 (Ack:64440631)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.118:50009] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (222001) chip[SC]: Received Sigma3 msg
E (222001) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (222481) chip[EM]: <<< [E:24894r S:0 M:164585684 (Ack:64440631)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.118:50009] --- Type 0000:40 (SecureChannel:StatusReport)
I (222501) chip[SC]: SecureSession[0x3fca5848, LSID:44035]: State change 'kEstablishing' --> 'kActive'
I (222501) chip[IN]: CASE Session established to peer: <391B754FE0D0599E, 1>
I (222511) chip[EM]: >>> [E:24894r S:0 M:64440632 (Ack:164585684)] (U) Msg RX from 0:EF48DABC1ED8CEB0 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (222531) chip[EM]: >>> [E:24895r S:44035 M:88505843] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:02 (IM:ReadRequest)
I (222541) chip[EM]: <<< [E:24895r S:44035 M:125699624 (Ack:88505843)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:05 (IM:ReportData)
I (222571) chip[EM]: >>> [E:24895r S:44035 M:88505844 (Ack:125699624)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (222641) chip[EM]: >>> [E:24896r S:44035 M:88505845] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:02 (IM:ReadRequest)
I (222651) chip[EM]: <<< [E:24896r S:44035 M:125699625 (Ack:88505845)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:05 (IM:ReportData)
I (222681) chip[EM]: >>> [E:24896r S:44035 M:88505846 (Ack:125699625)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (222771) chip[EM]: >>> [E:24897r S:44035 M:88505847] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:08 (IM:InvokeCommandRequest)
I (222781) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (222791) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (222801) chip[FP]: Metadata for Fabric 0x1 persisted to storage.
I (222821) chip[TS]: Committing Last Known Good Time to storage: 2024-06-30T00:00:00
I (222831) chip[ZCL]: OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x44AFB26E7CE51B07, FabricId 082F53CC089B1CC4, NodeId 2D379C053E06B543, VendorId 0x6006
I (222841) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (222841) chip[FS]: Fail-safe cleanly disarmed
I (222861) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (222871) chip[EM]: <<< [E:24897r S:44035 M:125699626 (Ack:88505847)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:09 (IM:InvokeCommandResponse)
I (222891) app_main: Fabric is committed
I (222891) chip[SVR]: Commissioning completed successfully
I (222891) chip[DIS]: Updating services using commissioning mode 0
I (222901) chip[DIS]: CHIP minimal mDNS started advertising.
I (222911) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (222911) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (222931) chip[DIS]: mDNS service published: _matter._tcp
I (222931) chip[SC]: SecureSession[0x3fca5778, LSID:44034]: State change 'kActive' --> 'kPendingEviction'
I (222951) app_main: Commissioning complete
I (222951) esp_matter_core: Commissioning Complete
I (222951) chip[EM]: >>> [E:24897r S:44035 M:88505848 (Ack:125699626)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (222961) app_main: Commissioning window closed
I (222981) chip[EM]: >>> [E:24898r S:44035 M:88505849] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:02 (IM:ReadRequest)
I (222991) NimBLE: GAP procedure initiated: stop advertising.

I (223001) chip[DL]: BLE deinit successful and memory reclaimed
I (223001) chip[EM]: <<< [E:24898r S:44035 M:125699627 (Ack:88505849)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:05 (IM:ReportData)
I (223021) app_main: BLE deinitialized and memory reclaimed
I (223041) chip[EM]: >>> [E:24898r S:44035 M:88505850 (Ack:125699627)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (223061) chip[EM]: >>> [E:24899r S:44035 M:88505851] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:02 (IM:ReadRequest)
I (223061) chip[EM]: <<< [E:24899r S:44035 M:125699628 (Ack:88505851)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:05 (IM:ReportData)
I (223091) chip[EM]: >>> [E:24899r S:44035 M:88505852 (Ack:125699628)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (223111) chip[EM]: >>> [E:24900r S:44035 M:88505853] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:02 (IM:ReadRequest)
I (223111) chip[EM]: <<< [E:24900r S:44035 M:125699629 (Ack:88505853)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:05 (IM:ReportData)
I (223141) chip[EM]: >>> [E:24900r S:44035 M:88505854 (Ack:125699629)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (223191) chip[EM]: >>> [E:24901r S:44035 M:88505855] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:0a (IM:TimedRequest)
I (223191) chip[EM]: <<< [E:24901r S:44035 M:125699630 (Ack:88505855)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:01 (IM:StatusResponse)
I (223211) chip[EM]: >>> [E:24901r S:44035 M:88505856 (Ack:125699630)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0001:08 (IM:InvokeCommandRequest)
I (223231) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003C
I (223231) chip[ZCL]: Received command to open commissioning window
I (223241) chip[DIS]: Updating services using commissioning mode 2
I (223251) chip[DIS]: CHIP minimal mDNS started advertising.
I (223261) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (223271) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (223281) chip[DIS]: mDNS service published: _matter._tcp
I (223291) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3118/12 cm=2 cp=0
I (223301) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 120130F510238024.
I (223321) chip[DIS]: mDNS service published: _matterc._udp
I (223321) chip[ZCL]: Commissioning window is now open
I (223321) chip[EM]: <<< [E:24901r S:44035 M:125699631 (Ack:88505856)] (S) Msg TX to 1:391B754FE0D0599E [1B07] [UDP:192.168.1.118:50009] --- Type 0001:09 (IM:InvokeCommandResponse)
I (223341) app_main: Commissioning window opened
I (223401) chip[EM]: >>> [E:24901r S:44035 M:88505857 (Ack:125699631)] (S) Msg RX from 1:391B754FE0D0599E [1B07] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (224841) chip[EM]: >>> [E:60253r S:0 M:28393041] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (224851) chip[EM]: <<< [E:60253r S:0 M:164585685 (Ack:28393041)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (224871) chip[EM]: <<< [E:60253r S:0 M:164585686 (Ack:28393041)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (224891) chip[SVR]: Commissioning session establishment step started
I (225151) chip[EM]: >>> [E:60253r S:0 M:28393042 (Ack:164585686)] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (225161) chip[EM]: <<< [E:60253r S:0 M:164585687 (Ack:28393042)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (225891) chip[EM]: <<< [E:60253r S:0 M:164585688 (Ack:28393042)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:23 (SecureChannel:PASE_Pake2)
E (225901) chip[DL]: Long dispatch time: 759 ms, for event type 3
I (225911) chip[EM]: >>> [E:60253r S:0 M:28393042 (Ack:164585686)] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (225921) chip[EM]: <<< [E:60253r S:0 M:164585689 (Ack:28393042)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (226311) chip[EM]: Retransmitting MessageCounter:164585688 on exchange 60253r Send Cnt 1
I (226481) chip[EM]: >>> [E:60253r S:0 M:28393043 (Ack:164585688)] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (226501) chip[EM]: <<< [E:60253r S:0 M:164585690 (Ack:28393043)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (226511) chip[EM]: <<< [E:60253r S:0 M:164585691 (Ack:28393043)] (U) Msg TX to 0:0000000000000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:40 (SecureChannel:StatusReport)
I (226531) chip[SC]: SecureSession[0x3fca5778, LSID:44037]: State change 'kEstablishing' --> 'kActive'
I (226531) chip[SVR]: Commissioning completed session establishment step
I (226541) chip[DIS]: Updating services using commissioning mode 0
I (226551) chip[DIS]: CHIP minimal mDNS started advertising.
I (226571) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (226581) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (226591) chip[DIS]: mDNS service published: _matter._tcp
I (226601) chip[SVR]: Device completed Rendezvous process
I (226601) app_main: Commissioning session started
I (226611) app_main: Commissioning window closed
I (226761) chip[EM]: >>> [E:60253r S:0 M:28393044 (Ack:164585688)] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (226781) chip[EM]: >>> [E:60254r S:44037 M:4888250] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (226781) chip[EM]: >>> [E:60253r S:0 M:28393045 (Ack:164585691)] (U) Msg RX from 0:BDBD0EF079DD93C4 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (226801) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (226821) chip[EM]: <<< [E:60254r S:44037 M:159760208 (Ack:4888250)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:05 (IM:ReportData)
I (227161) chip[EM]: Retransmitting MessageCounter:159760208 on exchange 60254r Send Cnt 1
I (227181) chip[EM]: >>> [E:60254r S:44037 M:4888252 (Ack:159760208)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (227211) chip[EM]: >>> [E:60254r S:44037 M:4888253 (Ack:159760208)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (227471) chip[EM]: >>> [E:60255r S:44037 M:4888251] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (227471) chip[EM]: <<< [E:60255r S:44037 M:159760209 (Ack:4888251)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:05 (IM:ReportData)
I (227871) chip[EM]: Retransmitting MessageCounter:159760209 on exchange 60255r Send Cnt 1
I (227881) chip[EM]: >>> [E:60255r S:44037 M:4888251] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (227891) chip[EM]: <<< [E:60255r S:44037 M:159760210 (Ack:4888251)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (227911) chip[EM]: >>> [E:60255r S:44037 M:4888255 (Ack:159760209)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (228391) chip[EM]: >>> [E:60256r S:44037 M:4888254] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (228391) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (228401) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s)
I (228411) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 **********
I (228431) chip[EM]: <<< [E:60256r S:44037 M:159760211 (Ack:4888254)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (228791) chip[EM]: Retransmitting MessageCounter:159760211 on exchange 60256r Send Cnt 1
I (228801) chip[EM]: >>> [E:60257r S:44037 M:4888256] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (228811) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (228811) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (228841) chip[DL]: NVS set: chip-config/country-code = "XX"
I (228841) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 5 **********
I (228851) chip[EM]: <<< [E:60257r S:44037 M:159760212 (Ack:4888256)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (228871) chip[EM]: >>> [E:60256r S:44037 M:4888257 (Ack:159760211)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229201) chip[EM]: >>> [E:60257r S:44037 M:4888256] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (229211) chip[EM]: <<< [E:60257r S:44037 M:159760213 (Ack:4888256)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229231) chip[EM]: >>> [E:60256r S:44037 M:4888258 (Ack:159760211)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229251) chip[EM]: >>> [E:60258r S:44037 M:4888259] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (229251) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (229261) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (229281) chip[EM]: <<< [E:60258r S:44037 M:159760214 (Ack:4888259)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (229301) chip[EM]: >>> [E:60257r S:44037 M:4888260 (Ack:159760212)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229411) chip[EM]: >>> [E:60258r S:44037 M:4888259] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (229411) chip[EM]: <<< [E:60258r S:44037 M:159760215 (Ack:4888259)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229441) chip[EM]: >>> [E:60259r S:44037 M:4888261] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (229441) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (229451) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (229471) chip[EM]: <<< [E:60259r S:44037 M:159760216 (Ack:4888261)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (229491) chip[EM]: >>> [E:60258r S:44037 M:4888262 (Ack:159760214)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229821) chip[EM]: >>> [E:60260r S:44037 M:4888263] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (229821) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (229831) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (229851) chip[EM]: <<< [E:60260r S:44037 M:159760217 (Ack:4888263)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (229931) chip[ZCL]: OpCreds: AttestationRequest successful.
I (229941) chip[EM]: <<< [E:60260r S:44037 M:159760218 (Ack:4888263)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (229951) chip[EM]: >>> [E:60259r S:44037 M:4888264 (Ack:159760216)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230021) chip[EM]: >>> [E:60260r S:44037 M:4888263] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (230021) chip[EM]: <<< [E:60260r S:44037 M:159760219 (Ack:4888263)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230091) chip[EM]: >>> [E:60261r S:44037 M:4888265] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (230091) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (230111) chip[ZCL]: OpCreds: Received a CSRRequest command
I (230121) chip[EM]: <<< [E:60261r S:44037 M:159760220 (Ack:4888265)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230261) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (230331) chip[ZCL]: OpCreds: CSRRequest successful.
I (230331) chip[EM]: <<< [E:60261r S:44037 M:159760221 (Ack:4888265)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (230351) chip[EM]: >>> [E:60260r S:44037 M:4888266 (Ack:159760218)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230641) chip[EM]: >>> [E:60262r S:44037 M:4888267] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (230641) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (230651) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (230671) chip[EM]: <<< [E:60262r S:44037 M:159760222 (Ack:4888267)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230911) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (230911) chip[EM]: <<< [E:60262r S:44037 M:159760223 (Ack:4888267)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (230931) chip[EM]: >>> [E:60261r S:44037 M:4888268 (Ack:159760221)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230951) chip[EM]: >>> [E:60262r S:44037 M:4888267] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (230961) chip[EM]: <<< [E:60262r S:44037 M:159760224 (Ack:4888267)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (230981) chip[EM]: >>> [E:60263r S:44037 M:4888269] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (230981) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (231001) chip[ZCL]: OpCreds: Received an AddNOC command
I (231011) chip[EM]: <<< [E:60263r S:44037 M:159760225 (Ack:4888269)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] [UDP:192.168.1.117:56465] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (231021) chip[FP]: Validating NOC chain
I (231481) chip[FP]: NOC chain validation successful
I (231481) chip[FP]: Added new fabric at index: 0x2
I (231491) chip[FP]: Assigned compressed fabric ID: 0x0439027E541A6872, node ID: 0x0000000000000013
I (231501) chip[TS]: Last Known Good Time: 2024-06-30T00:00:00
I (231511) chip[TS]: New proposed Last Known Good Time: 2021-01-01T00:00:00
I (231511) chip[TS]: Retaining current Last Known Good Time
I (231551) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x2 CASE Admin Subject 0x000000000001B669
I (231551) chip[DIS]: Advertise operational node 44AFB26E7CE51B07-2D379C053E06B543
I (231561) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 44AFB26E7CE51B07-2D379C053E06B543.
I (231581) chip[DIS]: mDNS service published: _matter._tcp
I (231581) chip[DIS]: Advertise operational node 0439027E541A6872-0000000000000013
I (231591) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 0439027E541A6872-0000000000000013.
I (231601) chip[DIS]: mDNS service published: _matter._tcp
I (231601) chip[ZCL]: OpCreds: successfully created fabric index 0x2 via AddNOC
I (231621) chip[EM]: <<< [E:60263r S:44037 M:159760226 (Ack:4888269)] (S) Msg TX to 2:FFFFFFFB00000000 [6872] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (231631) chip[EM]: >>> [E:60262r S:44037 M:4888270 (Ack:159760223)] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (231651) app_main: Fabric is updated
I (231861) chip[EM]: >>> [E:60263r S:44037 M:4888271 (Ack:159760226)] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (231871) wifi:<ba-add>idx:1 (ifx:0, 28:87:ba:b4:b4:8e), tid:6, ssn:1, winSize:64
I (276951) chip[EM]: >>> [E:60264r S:44037 M:4888272] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0001:08 (IM:InvokeCommandRequest)
I (276961) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (276971) chip[FS]: GeneralCommissioning: Received ArmFailSafe (120s)
I (276981) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 29 **********
I (277001) chip[EM]: <<< [E:60264r S:44037 M:159760227 (Ack:4888272)] (S) Msg TX to 2:FFFFFFFB00000000 [6872] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (277161) chip[EM]: >>> [E:60264r S:44037 M:4888273 (Ack:159760227)] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (321881) chip[EM]: >>> [E:60265r S:44037 M:4888274] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0001:08 (IM:InvokeCommandRequest)
I (321881) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (321891) chip[FS]: GeneralCommissioning: Received ArmFailSafe (120s)
I (321901) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 29 **********
I (321921) chip[EM]: <<< [E:60265r S:44037 M:159760228 (Ack:4888274)] (S) Msg TX to 2:FFFFFFFB00000000 [6872] [UDP:192.168.1.117:56465] --- Type 0001:09 (IM:InvokeCommandResponse)
I (321991) chip[EM]: >>> [E:60265r S:44037 M:4888275 (Ack:159760228)] (S) Msg RX from 2:FFFFFFFB00000000 [6872] --- Type 0000:10 (SecureChannel:StandaloneAck)