vaishali@vaishali-ThinkPad-P14s-Gen-2a:~/esp/esp-matter/examples/light$ idf.py flash monitor
Executing action: flash
Serial port /dev/ttyUSB0
Connecting....
Detecting chip type... ESP32-C3
Running ninja in directory /home/vaishali/esp/esp-matter/examples/light/build
Executing "ninja flash"...
[1/10] Performing build step for 'bootloader'
[1/1] cd /home/vaishali/esp/esp-matter/examples/light/build/bootloader/esp-idf/esptool_py && /home/vaishali/esp/esp-matter/connectedhomeip/connectedhomeip/.environment/pigweed-venv/bin/python /home/vaishali/esp/esp-idf/components/partition_table/check_sizes.py --offset 0xc000 bootloader 0x0 /home/vaishali/esp/esp-matter/examples/light/build/bootloader/bootloader.bin
Bootloader binary size 0x4ee0 bytes. 0x7120 bytes (59%) free.
[2/8] Performing build step for 'chip_gn'
ninja: no work to do.
[3/4] cd /home/vaishali/esp/esp-matter/examples/light/build/esp-idf/esptool_py && /home/vaishali/esp/esp-matter/connectedhomeip/connectedhomeip/.environment/pigweed-venv/bin/python /home/vaishali/esp/esp-idf/components/partition_table/check_sizes.py --offset 0xc000 partition --type app /home/vaishali/esp/esp-matter/examples/light/build/partition_table/partition-table.bin /home/vaishali/esp/esp-matter/examples/light/build/light.bin
light.bin binary size 0x14d560 bytes. Smallest app partition is 0x1e0000 bytes. 0x92aa0 bytes (31%) free.
[3/4] cd /home/vaishali/esp/esp-idf/components/esptool_py && /usr/bin/cmake -D IDF_PATH=/home/vaishali/esp/esp-idf -D "SERIAL_TOOL=/home/vaishali/esp/esp-matter/connectedhomeip/connectedhomeip/.environment/pigweed-venv/bin/python;;/home/vaishali/esp/esp-idf/components/esptool_py/esptool/esptool.py;--chip;esp32c3" -D "SERIAL_TOOL_ARGS=--before=default_reset;--after=hard_reset;write_flash;@flash_args" -D WORKING_DIRECTORY=/home/vaishali/esp/esp-matter/examples/light/build -P /home/vaishali/esp/esp-idf/components/esptool_py/run_serial_tool.cmake
esptool esp32c3 -p /dev/ttyUSB0 -b 460800 --before=default_reset --after=hard_reset write_flash --flash_mode dio --flash_freq 80m --flash_size 4MB 0x0 bootloader/bootloader.bin 0x20000 light.bin 0xc000 partition_table/partition-table.bin 0x17000 ota_data_initial.bin
esptool.py v4.6.1
Serial port /dev/ttyUSB0
Connecting....
Chip is ESP32-C3 (revision v0.3)
Features: WiFi, BLE
Crystal is 40MHz
MAC: 70:04:1d:14:f7:8c
Uploading stub...
Running stub...
Stub running...
Changing baud rate to 460800
Changed.
Configuring flash size...
Flash will be erased from 0x00000000 to 0x00004fff...
Flash will be erased from 0x00020000 to 0x0016dfff...
Flash will be erased from 0x0000c000 to 0x0000cfff...
Flash will be erased from 0x00017000 to 0x00018fff...
Compressed 20192 bytes to 12374...
Writing at 0x00000000... (100 %)
Wrote 20192 bytes (12374 compressed) at 0x00000000 in 0.7 seconds (effective 229.7 kbit/s)...
Hash of data verified.
Compressed 1365344 bytes to 823362...
Writing at 0x00020000... (1 %)
Writing at 0x0002c227... (3 %)
Writing at 0x00036e69... (5 %)
Writing at 0x0003c257... (7 %)
Writing at 0x000433bf... (9 %)
Writing at 0x0004d7ec... (11 %)
Writing at 0x0005988d... (13 %)
Writing at 0x0005fb8f... (15 %)
Writing at 0x00065d4d... (17 %)
Writing at 0x0006d233... (19 %)
Writing at 0x00072cf9... (21 %)
Writing at 0x000788c9... (23 %)
Writing at 0x0007fbbd... (25 %)
Writing at 0x000862ae... (27 %)
Writing at 0x0008cacd... (29 %)
Writing at 0x00092c58... (31 %)
Writing at 0x000991f0... (33 %)
Writing at 0x0009f0fb... (35 %)
Writing at 0x000a5574... (37 %)
Writing at 0x000ac4b6... (39 %)
Writing at 0x000b3190... (41 %)
Writing at 0x000b9419... (43 %)
Writing at 0x000bf12d... (45 %)
Writing at 0x000c5292... (47 %)
Writing at 0x000cb4c6... (49 %)
Writing at 0x000d1542... (50 %)
Writing at 0x000d7725... (52 %)
Writing at 0x000dda99... (54 %)
Writing at 0x000e3d37... (56 %)
Writing at 0x000e9ffe... (58 %)
Writing at 0x000ef647... (60 %)
Writing at 0x000f51dc... (62 %)
Writing at 0x000fb2a8... (64 %)
Writing at 0x00101c0a... (66 %)
Writing at 0x00107c9a... (68 %)
Writing at 0x0010e360... (70 %)
Writing at 0x001157d0... (72 %)
Writing at 0x0011b202... (74 %)
Writing at 0x001215c9... (76 %)
Writing at 0x001272b9... (78 %)
Writing at 0x0012d31d... (80 %)
Writing at 0x00132f12... (82 %)
Writing at 0x00138fd7... (84 %)
Writing at 0x0013ee15... (86 %)
Writing at 0x00144e87... (88 %)
Writing at 0x0014b2e4... (90 %)
Writing at 0x00153288... (92 %)
Writing at 0x00159223... (94 %)
Writing at 0x0015f7fc... (96 %)
Writing at 0x00165866... (98 %)
Writing at 0x0016ba49... (100 %)
Wrote 1365344 bytes (823362 compressed) at 0x00020000 in 24.1 seconds (effective 452.4 kbit/s)...
Hash of data verified.
Compressed 3072 bytes to 178...
Writing at 0x0000c000... (100 %)
Wrote 3072 bytes (178 compressed) at 0x0000c000 in 0.1 seconds (effective 281.5 kbit/s)...
Hash of data verified.
Compressed 8192 bytes to 31...
Writing at 0x00017000... (100 %)
Wrote 8192 bytes (31 compressed) at 0x00017000 in 0.1 seconds (effective 500.8 kbit/s)...
Hash of data verified.

Leaving...
Hard resetting via RTS pin...
Executing action: monitor
Serial port /dev/ttyUSB0
Connecting....
Detecting chip type... ESP32-C3
Running idf_monitor in directory /home/vaishali/esp/esp-matter/examples/light
Executing "/home/vaishali/esp/esp-matter/connectedhomeip/connectedhomeip/.environment/pigweed-venv/bin/python /home/vaishali/esp/esp-idf/tools/idf_monitor.py -p /dev/ttyUSB0 -b 115200 --toolchain-prefix riscv32-esp-elf- --target esp32c3 --decode-panic backtrace /home/vaishali/esp/esp-matter/examples/light/build/light.elf -m '/home/vaishali/esp/esp-matter/connectedhomeip/connectedhomeip/.environment/pigweed-venv/bin/python' '/home/vaishali/esp/esp-idf/tools/idf.py'"...
--- idf_monitor on /dev/ttyUSB0 115200 ---
--- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H ---
ESP-ROM:esp32c3-api1-20210207
Build:Feb  7 2021
rst:0x1 (POWERON),boot:0xc (SPI_FAST_FLASH_BOOT)
SPIWP:0xee
mode:DIO, clock div:1
load:0x3fcd5820,len:0x16a0
load:0x403cc710,len:0x968
load:0x403ce710,len:0x2e78
entry 0x403cc710
I (30) boot: ESP-IDF v5.0.1 2nd stage bootloader
I (30) boot: compile time 15:02:58
I (30) boot: chip revision: v0.3
I (32) boot.esp32c3: SPI Speed      : 80MHz
I (37) boot.esp32c3: SPI Mode       : DIO
I (42) boot.esp32c3: SPI Flash Size : 4MB
I (46) boot: Enabling RNG early entropy source...
I (52) boot: Partition Table:
I (55) boot: ## Label            Usage          Type ST Offset   Length
I (63) boot:  0 esp_secure_cert  unknown          3f 06 0000d000 00002000
I (70) boot:  1 nvs              WiFi data        01 02 00010000 00006000
I (78) boot:  2 nvs_keys         NVS keys         01 04 00016000 00001000
I (85) boot:  3 otadata          OTA data         01 00 00017000 00002000
I (93) boot:  4 phy_init         RF data          01 01 00019000 00001000
I (100) boot:  5 ota_0            OTA app          00 10 00020000 001e0000
I (108) boot:  6 ota_1            OTA app          00 11 00200000 001e0000
I (115) boot:  7 fctry            WiFi data        01 02 003e0000 00006000
I (123) boot: End of partition table
I (127) boot: No factory image, trying OTA 0
I (132) esp_image: segment 0: paddr=00020020 vaddr=3c100020 size=373c0h (226240) map
I (174) esp_image: segment 1: paddr=000573e8 vaddr=3fc94200 size=03164h ( 12644) load
I (177) esp_image: segment 2: paddr=0005a554 vaddr=40380000 size=05ac4h ( 23236) load
I (184) esp_image: segment 3: paddr=00060020 vaddr=42000020 size=feec4h (1044164) map
I (345) esp_image: segment 4: paddr=0015eeec vaddr=40385ac4 size=0e650h ( 58960) load
I (363) boot: Loaded app from partition at offset 0x20000
I (415) boot: Set actual ota_seq=1 in otadata[0]
I (415) boot: Disabling RNG early entropy source...
I (426) cpu_start: Pro cpu up.
I (435) cpu_start: Pro cpu start user code
I (435) cpu_start: cpu freq: 160000000 Hz
I (435) cpu_start: Application information:
I (438) cpu_start: Project name:     light
I (442) cpu_start: App version:      v1.0
I (447) cpu_start: Compile time:     Jun  5 2023 15:02:48
I (453) cpu_start: ELF file SHA256:  17677bc9da6a2fd2...
I (459) cpu_start: ESP-IDF:          v5.0.1
I (464) cpu_start: Min chip rev:     v0.3
I (469) cpu_start: Max chip rev:     v0.99 
I (474) cpu_start: Chip rev:         v0.3
I (478) heap_init: Initializing. RAM available for dynamic allocation:
I (486) heap_init: At 3FCA8C30 len 00033AE0 (206 KiB): DRAM
I (492) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM
I (498) heap_init: At 50000020 len 00001FE0 (7 KiB): RTCRAM
I (505) spi_flash: detected chip: generic
I (510) spi_flash: flash io: dio
W (514) rmt(legacy): legacy driver is deprecated, please migrate to `driver/rmt_tx.h` and/or `driver/rmt_rx.h`
I (525) coexist: coexist rom version 9387209
I (529) cpu_start: Starting scheduler.
I (538) led_driver_ws2812: Initializing light driver
I (538) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0 
W (558) esp_matter_core: Command 0x00000047 on cluster 0x00000300 already exists. Not creating again.
I (558) app_main: Light created with endpoint_id 1
I (568) pp: pp rom version: 9387209
I (568) net80211: net80211 rom version: 9387209
I (588) wifi:wifi driver task: 3fcb4670, prio:23, stack:6656, core=0
I (588) system_api: Base MAC address is not set
I (588) system_api: read default base MAC address from EFUSE
I (588) wifi:wifi firmware version: 17afb16
I (598) wifi:wifi certification version: v7.0
I (598) wifi:config NVS flash: enabled
I (598) wifi:config nano formating: disabled
I (608) wifi:Init data frame dynamic rx buffer num: 32
I (608) wifi:Init management frame dynamic rx buffer num: 32
I (618) wifi:Init management short buffer num: 32
I (618) wifi:Init dynamic tx buffer num: 32
I (628) wifi:Init static tx FG buffer num: 2
I (628) wifi:Init static rx buffer size: 1600
I (628) wifi:Init static rx buffer num: 10
I (638) wifi:Init dynamic rx buffer num: 32
I (638) wifi_init: rx ba win: 6
I (648) wifi_init: tcpip mbox: 32
I (648) wifi_init: udp mbox: 6
I (648) wifi_init: tcp mbox: 6
I (658) wifi_init: tcp tx win: 5744
I (658) wifi_init: tcp rx win: 5744
I (668) wifi_init: tcp mss: 1440
I (668) wifi_init: WiFi IRAM OP enabled
I (668) wifi_init: WiFi RX IRAM OP enabled
I (678) chip[DL]: NVS set: chip-counters/reboot-count = 1 (0x1)
I (688) chip[DL]: NVS set: chip-counters/total-hours = 0 (0x0)
I (688) chip[DL]: NVS set: chip-config/unique-id = "7D7BA5D78DE9621E"
I (698) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (708) BTDM_INIT: BT controller compile version [80abacd]
I (708) phy_init: phy_version 950,11a46e9,Oct 21 2022,08:56:12
W (718) phy_init: failed to load RF calibration data (0x1102), falling back to full calibration
I (768) BTDM_INIT: Bluetooth MAC: 70:04:1d:14:f7:8e

I (768) NimBLE: GAP procedure initiated: stop advertising.

I (768) CHIP[DL]: BLE host-controller synced
I (1278) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1278) NimBLE: GAP procedure initiated: advertise; 
I (1278) NimBLE: disc_mode=2
I (1278) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1288) NimBLE: 

I (1298) chip[DL]: CHIPoBLE advertising started
I (1298) chip[DL]: Starting ESP WiFi layer
I (1308) wifi:mode : sta (70:04:1d:14:f7:8c)
I (1308) wifi:enable tsf
W (1308) wifi:Haven't to connect to a suitable AP now!
I (1318) chip[DL]: Done driving station state, nothing else to do...
W (1318) wifi:Haven't to connect to a suitable AP now!
I (1328) chip[DL]: Done driving station state, nothing else to do...
I (1338) chip[SVR]: Initializing subscription resumption storage...
I (1338) chip[SVR]: Server initializing...
I (1348) chip[TS]: Last Known Good Time: [unknown]
I (1348) chip[TS]: Setting Last Known Good Time to firmware build time 2023-06-05T15:09:21
I (1358) chip[DMG]: AccessControl: initializing
I (1368) chip[DMG]: Examples::AccessControlDelegate::Init
I (1368) chip[DMG]: AccessControl: setting
I (1378) chip[DMG]: DefaultAclStorage: initializing
I (1378) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (1388) chip[ZCL]: Using ZAP configuration...
I (1388) esp_matter_cluster: Cluster plugin init common callback
I (1398) chip[DMG]: AccessControlCluster: initializing
I (1408) chip[ZCL]: 0x3c10993cep 0 clus 0x0000_0030 attr 0x0000_0000 not supported
I (1418) chip[ZCL]: Initiating Admin Commissioning cluster.
E (1418) chip[ZCL]: Duplicate attribute override registration failed
I (1428) chip[DIS]: Updating services using commissioning mode 1
I (1438) chip[DIS]: CHIP minimal mDNS started advertising.
I (1438) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1
I (1448) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: DCAEED13518651C6.
I (1458) chip[DIS]: mDNS service published: _matterc._udp
I (1468) chip[IN]: CASE Server enabling CASE session setups
I (1478) chip[SVR]: Joining Multicast groups
I (1478) chip[SVR]: Server Listening...
I (1488) esp_matter_core: Dynamic endpoint 0 added
I (1488) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 0 **********
I (1498) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 **********
I (1508) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is <invalid type: 0> **********
I (1528) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 **********
I (1538) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x0000FFFC is 1 **********
I (1548) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x00004003 is null **********
I (1558) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x00000000 is 1 **********
I (1568) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000006's Attribute 0x00000000 is 1 **********
I (1578) chip[ZCL]: Endpoint 1 On/off already set to new value
I (1588) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00000002 is 1 **********
I (1598) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00000003 is 254 **********
I (1608) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x0000FFFC is 3 **********
I (1628) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00000000 is 64 **********
I (1638) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00004000 is 64 **********
I (1648) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000008's Attribute 0x00000000 is 64 **********
I (1658) led_driver_ws2812: led set r:0, g:0, b:0
I (1668) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000300's Attribute 0x00004010 is null **********
I (1678) esp_matter_core: Dynamic endpoint 1 added
I (1688) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (1688) chip[DL]: Device already advertising, stop active advertisement and restart
I (1698) NimBLE: GAP procedure initiated: stop advertising.

I (1708) NimBLE: GAP procedure initiated: advertise; 
I (1708) NimBLE: disc_mode=2
I (1718) NimBLE:  adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1728) NimBLE: 

I (1728) app_main: Commissioning window opened
E (1738) esp_matter_core: Failed to open the node nvs_namespace
I (1738) led_driver_ws2812: led set r:0, g:0, b:0
I (1748) chip[DL]: WIFI_EVENT_STA_START
W (1748) wifi:Haven't to connect to a suitable AP now!
I (1748) led_driver_ws2812: led set r:0, g:0, b:0
I (1758) chip[DL]: Done driving station state, nothing else to do...
I (1758) led_driver_ws2812: led set r:63, g:51, b:40
> I (6608) chip[DL]: BLE GAP connection established (con 1)
I (6608) chip[DL]: CHIPoBLE advertising stopped
I (7858) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (7868) chip[BLE]: local and remote recv window sizes = 5
I (7878) chip[BLE]: selected BTP version 4
I (7878) chip[BLE]: using BTP fragment sizes rx 244 / tx 244.
I (7948) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1
I (7948) chip[DL]: CHIPoBLE subscribe received
I (7958) NimBLE: GATT procedure initiated: indicate; 
I (7968) NimBLE: att_handle=14

I (8038) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (8048) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (8058) chip[EM]: >>> [E:60067r S:0 M:80152340] (U) Msg RX from 0:902F997F0BD5DEC2 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest)
I (8068) chip[EM]: <<< [E:60067r S:0 M:46060239] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse)
I (8078) chip[IN]: (U) Sending msg 46060239 to IP address 'BLE'
I (8088) NimBLE: GATT procedure initiated: indicate; 
I (8098) NimBLE: att_handle=14

I (8178) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (8178) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (8188) chip[EM]: >>> [E:60067r S:0 M:80152341] (U) Msg RX from 0:902F997F0BD5DEC2 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1)
I (8828) chip[EM]: <<< [E:60067r S:0 M:46060240] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2)
I (8828) chip[IN]: (U) Sending msg 46060240 to IP address 'BLE'
I (8838) NimBLE: GATT procedure initiated: indicate; 
I (8838) NimBLE: att_handle=14

I (8938) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (8948) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (8958) chip[EM]: >>> [E:60067r S:0 M:80152342] (U) Msg RX from 0:902F997F0BD5DEC2 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3)
I (8968) chip[EM]: <<< [E:60067r S:0 M:46060241] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (8978) chip[IN]: (U) Sending msg 46060241 to IP address 'BLE'
I (8978) NimBLE: GATT procedure initiated: indicate; 
I (8998) NimBLE: att_handle=14

I (8998) chip[SC]: SecureSession[0x3fc9b478, LSID:34445]: State change 'kEstablishing' --> 'kActive'
I (9008) chip[SVR]: Commissioning completed session establishment step
I (9008) chip[DIS]: Updating services using commissioning mode 0
I (9018) chip[DIS]: CHIP minimal mDNS started advertising.
I (9018) chip[SVR]: Device completed Rendezvous process
I (9038) app_main: Commissioning session started
I (9038) app_main: Commissioning window closed
I (9078) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9078) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (9088) chip[EM]: >>> [E:60068r S:34445 M:40438132] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest)
I (9108) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (9108) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (9128) chip[EM]: <<< [E:60068r S:34445 M:229907807] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData)
I (9138) chip[IN]: (S) Sending msg 229907807 on secure session with LSID: 34445
I (9138) NimBLE: GATT procedure initiated: indicate; 
I (9148) NimBLE: att_handle=14

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

I (9298) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9308) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (9318) chip[EM]: >>> [E:60069r S:34445 M:40438133] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (9328) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (9328) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s)
I (9338) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 3 **********
I (9358) chip[EM]: <<< [E:60069r S:34445 M:229907808] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (9368) chip[IN]: (S) Sending msg 229907808 on secure session with LSID: 34445
I (9378) NimBLE: GATT procedure initiated: indicate; 
I (9378) NimBLE: att_handle=14

I (9438) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9438) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (9448) chip[EM]: >>> [E:60070r S:34445 M:40438134] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (9468) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030
I (9468) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0)
I (9478) chip[DL]: NVS set: chip-config/country-code = "XX"
I (9478) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 **********
I (9498) chip[EM]: <<< [E:60070r S:34445 M:229907809] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (9508) chip[IN]: (S) Sending msg 229907809 on secure session with LSID: 34445
I (9518) NimBLE: GATT procedure initiated: indicate; 
I (9518) NimBLE: att_handle=14

I (9618) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9618) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (9628) chip[EM]: >>> [E:60071r S:34445 M:40438135] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (9648) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (9658) chip[ZCL]: OpCreds: Certificate Chain request received for PAI
I (9658) chip[EM]: <<< [E:60071r S:34445 M:229907810] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (9678) chip[IN]: (S) Sending msg 229907810 on secure session with LSID: 34445
I (9678) NimBLE: GATT procedure initiated: indicate; 
I (9688) NimBLE: att_handle=14

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

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

I (9928) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (9938) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (9948) chip[EM]: >>> [E:60072r S:34445 M:40438136] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (9958) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E
I (9958) chip[ZCL]: OpCreds: Certificate Chain request received for DAC
I (9978) chip[EM]: <<< [E:60072r S:34445 M:229907811] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (9988) chip[IN]: (S) Sending msg 229907811 on secure session with LSID: 34445
I (9988) NimBLE: GATT procedure initiated: indicate; 
I (9998) NimBLE: att_handle=14

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

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

I (10248) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10248) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (10258) chip[EM]: >>> [E:60073r S:34445 M:40438137] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (10278) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E
I (10278) chip[ZCL]: OpCreds: Received an AttestationRequest command
I (10348) chip[ZCL]: OpCreds: AttestationRequest successful.
I (10348) chip[EM]: <<< [E:60073r S:34445 M:229907812] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (10368) chip[IN]: (S) Sending msg 229907812 on secure session with LSID: 34445
I (10368) NimBLE: GATT procedure initiated: indicate; 
I (10378) NimBLE: att_handle=14

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

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

I (10608) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (10608) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (10618) chip[EM]: >>> [E:60074r S:34445 M:40438138] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (10638) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E
I (10648) chip[ZCL]: OpCreds: Received a CSRRequest command
I (10758) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded
I (10818) chip[ZCL]: OpCreds: CSRRequest successful.
I (10818) chip[EM]: <<< [E:60074r S:34445 M:229907813] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (10838) chip[IN]: (S) Sending msg 229907813 on secure session with LSID: 34445
I (10838) NimBLE: GATT procedure initiated: indicate; 
I (10848) NimBLE: att_handle=14

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

I (11008) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11018) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (11098) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (11108) chip[EM]: >>> [E:60075r S:34445 M:40438139] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11118) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E
I (11128) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command
I (11338) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful.
I (11338) chip[EM]: <<< [E:60075r S:34445 M:229907814] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse)
I (11348) chip[IN]: (S) Sending msg 229907814 on secure session with LSID: 34445
I (11358) NimBLE: GATT procedure initiated: indicate; 
I (11358) NimBLE: att_handle=14

I (11418) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (11418) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (11508) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (11598) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (11598) chip[EM]: >>> [E:60076r S:34445 M:40438140] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest)
I (11618) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E
I (11628) chip[ZCL]: OpCreds: Received an AddNOC command
I (11628) chip[FP]: Validating NOC chain
I (12028) chip[FP]: NOC chain validation successful
I (12038) chip[FP]: Added new fabric at index: 0x1
I (12038) chip[FP]: Assigned compressed fabric ID: 0x7DFDC0CC2967173B, node ID: 0x0000000000007283
I (12048) chip[TS]: Last Known Good Time: 2023-06-05T15:09:21
I (12058) chip[TS]: New proposed Last Known Good Time: 2021-01-01T00:00:00
I (12058) chip[TS]: Retaining current Last Known Good Time
I (12078) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669
I (12078) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (12088) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (12098) chip[DIS]: mDNS service published: _matter._tcp
I (12108) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC
I (12118) chip[EM]: <<< [E:60076r S:34445 M:229907815] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (12128) chip[IN]: (S) Sending msg 229907815 on secure session with LSID: 34445
I (12138) NimBLE: GATT procedure initiated: indicate; 
I (12138) NimBLE: att_handle=14

I (12148) app_main: Fabric is updated
I (12228) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (12228) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (12238) chip[EM]: >>> [E:60077r S:34445 M:40438141] (S) Msg RX from 1:FFFFFFFB00000000 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (12258) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 14 **********
I (12268) chip[EM]: <<< [E:60077r S:34445 M:229907816] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (12278) chip[IN]: (S) Sending msg 229907816 on secure session with LSID: 34445
I (12278) NimBLE: GATT procedure initiated: indicate; 
I (12298) NimBLE: att_handle=14

I (12358) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (12368) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (12378) chip[EM]: >>> [E:60078r S:34445 M:40438142] (S) Msg RX from 1:FFFFFFFB00000000 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (12388) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (12398) chip[FS]: GeneralCommissioning: Received ArmFailSafe (66s)
I (12398) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 16 **********
I (12418) chip[EM]: <<< [E:60078r S:34445 M:229907817] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (12428) chip[IN]: (S) Sending msg 229907817 on secure session with LSID: 34445
I (12438) NimBLE: GATT procedure initiated: indicate; 
I (12438) NimBLE: att_handle=14

I (12498) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (12498) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (12508) chip[EM]: >>> [E:60079r S:34445 M:40438143] (S) Msg RX from 1:FFFFFFFB00000000 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (12528) chip[NP]: ESP NetworkCommissioningDelegate: SSID: ES_Staff
W (12528) wifi:Haven't to connect to a suitable AP now!
I (12538) chip[DL]: WiFi station mode change: Enabled -> Disabled
I (12548) chip[DL]: WiFi station mode change: Disabled -> Enabled
W (12548) wifi:Haven't to connect to a suitable AP now!
I (12548) chip[DL]: Attempting to connect WiFi station interface
I (12568) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (12568) chip[DL]: Done driving station state, nothing else to do...
I (12568) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (12578) wifi:state: init -> auth (b0)
I (12878) chip[DL]: Done driving station state, nothing else to do...
I (12878) chip[DL]: Done driving station state, nothing else to do...
I (15008) NimBLE: GATT procedure initiated: indicate; 
I (15008) NimBLE: att_handle=14

I (15148) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (16878) wifi:state: auth -> init (200)
I (16878) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (16878) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (16888) wifi:state: init -> auth (b0)
I (17848) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (20358) NimBLE: GATT procedure initiated: indicate; 
I (20358) NimBLE: att_handle=14

I (20458) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (21198) wifi:state: auth -> init (200)
I (21198) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (21198) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
I (21208) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed
W (21208) wifi:Haven't to connect to a suitable AP now!
I (21218) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected
I (21218) chip[DL]: Next WiFi station reconnect in 100 ms
I (21238) chip[DL]: Done driving station state, nothing else to do...
W (21238) wifi:Haven't to connect to a suitable AP now!
E (21248) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (21248) wifi:Haven't to connect to a suitable AP now!
E (21258) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (21338) wifi:Haven't to connect to a suitable AP now!
I (21338) chip[DL]: Attempting to connect WiFi station interface
I (21338) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (21348) chip[DL]: Done driving station state, nothing else to do...
W (21348) wifi:Haven't to connect to a suitable AP now!
E (21358) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (23158) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (23748) wifi:new:<11,0>, old:<1,0>, ap:<255,255>, sta:<11,0>, prof:1
I (23748) wifi:state: init -> auth (b0)
I (25668) NimBLE: GATT procedure initiated: indicate; 
I (25668) NimBLE: att_handle=14

I (25818) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (28048) wifi:state: auth -> init (200)
I (28048) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (28058) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
I (28068) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed
W (28068) wifi:Haven't to connect to a suitable AP now!
I (28078) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected
I (28078) chip[DL]: Next WiFi station reconnect in 100 ms
I (28088) chip[DL]: Done driving station state, nothing else to do...
W (28088) wifi:Haven't to connect to a suitable AP now!
E (28108) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (28118) wifi:Haven't to connect to a suitable AP now!
E (28118) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (28188) wifi:Haven't to connect to a suitable AP now!
I (28188) chip[DL]: Attempting to connect WiFi station interface
I (28198) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (28208) chip[DL]: Done driving station state, nothing else to do...
I (28198) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (28218) wifi:state: init -> auth (b0)
I (28518) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (29058) wifi:state: auth -> assoc (0)
I (29068) wifi:state: assoc -> run (10)
I (29128) wifi:connected with ES_Staff, aid = 19, channel 11, BW20, bssid = 68:d7:9a:81:31:24
I (29128) wifi:security: WPA3-SAE, phy: bgn, rssi: -67
I (29158) wifi:pm start, type: 1

I (29158) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000
I (29158) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (29168) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (29168) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (29188) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (29188) chip[DL]: WiFi station interface connected
I (29198) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 18 **********
I (29208) chip[EM]: <<< [E:60079r S:34445 M:229907818] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (29228) chip[IN]: (S) Sending msg 229907818 on secure session with LSID: 34445
I (29228) NimBLE: GATT procedure initiated: indicate; 
I (29238) NimBLE: att_handle=14

I (29238) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (29248) chip[DL]: Done driving station state, nothing else to do...
I (29248) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (29268) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (29278) chip[DIS]: mDNS service published: _matter._tcp
I (29278) chip[SVR]: Operational advertising enabled
I (29288) chip[DL]: Updating advertising data
I (29418) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (30568) chip[DL]: IP_EVENT_GOT_IP6
I (30568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7204:1dff:fe14:f78c
I (30578) app_main: Interface IP Address changed
I (30578) chip[DIS]: Updating services using commissioning mode 0
I (30588) chip[DIS]: CHIP minimal mDNS started advertising.
I (30598) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (30598) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (30608) chip[DIS]: mDNS service published: _matter._tcp
E (30618) chip[SVR]: Server initialization complete
I (30628) chip[DIS]: Updating services using commissioning mode 0
I (30628) chip[DIS]: CHIP minimal mDNS started advertising.
I (30648) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (30648) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (30658) chip[DIS]: mDNS service published: _matter._tcp
I (30658) chip[IM]: No subscriptions to resume
I (30718) wifi:<ba-add>idx:0 (ifx:0, 68:d7:9a:81:31:24), tid:0, ssn:0, winSize:64
I (31308) chip[EM]: >>> [E:60080r S:0 M:80152343] (U) Msg RX from 0:FB7E3705CE184118 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (31328) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (31328) chip[IN]: CASE Server disabling CASE session setups
I (31338) chip[EM]: <<< [E:60080r S:0 M:46060242 (Ack:80152343)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (31348) chip[IN]: (U) Sending msg 46060242 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
I (31368) chip[SC]: Received Sigma1 msg
I (31368) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (31628) chip[EM]: <<< [E:60080r S:0 M:46060243 (Ack:80152343)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (31638) chip[IN]: (U) Sending msg 46060243 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
I (31648) chip[SC]: Sent Sigma2 msg
I (31658) chip[EM]: >>> [E:60080r S:0 M:80152343] (U) Msg RX from 0:FB7E3705CE184118 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (31668) chip[EM]: <<< [E:60080r S:0 M:46060244 (Ack:80152343)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (31678) chip[IN]: (U) Sending msg 46060244 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
I (31888) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (32048) chip[IN]: (U) Sending msg 46060243 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
E (32048) chip[EM]: Ignoring transient send error: 3000001 on exchange 60080r
I (32428) chip[IN]: (U) Sending msg 46060243 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
E (32428) chip[EM]: Ignoring transient send error: 3000001 on exchange 60080r
I (32448) chip[EM]: >>> [E:60080r S:0 M:80152343] (U) Msg RX from 0:FB7E3705CE184118 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (32458) chip[EM]: <<< [E:60080r S:0 M:46060245 (Ack:80152343)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (32478) chip[IN]: (U) Sending msg 46060245 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
E (32478) chip[EM]: Non-crit err 3000001 sending solitary ack for MessageCounter:80152343 on exchange 60080r
I (32968) chip[IN]: (U) Sending msg 46060243 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
E (32968) chip[EM]: Ignoring transient send error: 3000001 on exchange 60080r
I (33048) chip[EM]: >>> [E:60080r S:0 M:80152343] (U) Msg RX from 0:FB7E3705CE184118 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (33058) chip[EM]: <<< [E:60080r S:0 M:46060246 (Ack:80152343)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (33078) chip[IN]: (U) Sending msg 46060246 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
E (33078) chip[EM]: Non-crit err 3000001 sending solitary ack for MessageCounter:80152343 on exchange 60080r
I (33668) esp_netif_handlers: sta ip: 192.168.20.149, mask: 255.255.255.0, gw: 192.168.20.1
I (33668) chip[DL]: IP_EVENT_STA_GOT_IP
I (33678) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.20.149/255.255.255.0 gateway 192.168.20.1
I (33678) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (33688) app_main: Interface IP Address changed
I (33688) chip[DIS]: Updating services using commissioning mode 0
I (33708) chip[DIS]: CHIP minimal mDNS started advertising.
I (33708) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (33718) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (33728) chip[DIS]: mDNS service published: _matter._tcp
I (33798) ROUTE_HOOK: Received RIO
I (33798) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (33878) chip[IN]: (U) Sending msg 46060243 to IP address 'UDP:[FE80::DC6:45F:B1A0:A3E7%st1]:59399'
I (34398) NimBLE: GATT procedure initiated: indicate; 
I (34398) NimBLE: att_handle=14

I (34498) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (35568) chip[DL]: IP_EVENT_GOT_IP6
I (35568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd4b:021e:cb36:4e52:7204:1dff:fe14:f78c
I (35578) ROUTE_HOOK: Hook already installed on netif, skip...
I (35578) app_main: Interface IP Address changed
I (35588) chip[DIS]: Updating services using commissioning mode 0
I (35588) chip[DIS]: CHIP minimal mDNS started advertising.
I (35618) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (35618) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (35638) chip[DIS]: mDNS service published: _matter._tcp
E (35638) chip[EM]: Failed to Send CHIP MessageCounter:46060243 on exchange 60080r sendCount: 4 max retries: 4
I (37108) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (37118) chip[EM]: >>> [E:60081r S:34445 M:40438144] (S) Msg RX from 1:FFFFFFFB00000000 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (37128) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (37138) chip[FS]: GeneralCommissioning: Received ArmFailSafe (68s)
I (37138) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 20 **********
I (37158) chip[EM]: <<< [E:60081r S:34445 M:229907819] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (37168) chip[IN]: (S) Sending msg 229907819 on secure session with LSID: 34445
I (37178) NimBLE: GATT procedure initiated: indicate; 
I (37178) NimBLE: att_handle=14

I (37288) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (37978) ROUTE_HOOK: Received RIO
I (37978) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (39078) chip[EM]: >>> [E:60082r S:0 M:80152344] (U) Msg RX from 0:FD5D703F370857F4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (39088) chip[EM]: <<< [E:60082r S:0 M:46060247 (Ack:80152344)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (39098) chip[IN]: (U) Sending msg 46060247 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (39858) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (41988) chip[EM]: >>> [E:60080r S:0 M:80152345 (Ack:46060243)] (U) Msg RX from 0:FB7E3705CE184118 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (42358) NimBLE: GATT procedure initiated: indicate; 
I (42358) NimBLE: att_handle=14

I (42468) ROUTE_HOOK: Received RIO
I (42468) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (42508) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (45168) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (45298) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (45308) chip[EM]: >>> [E:60083r S:34445 M:40438145] (S) Msg RX from 1:FFFFFFFB00000000 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (45318) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030
I (45328) chip[FS]: GeneralCommissioning: Received ArmFailSafe (103s)
I (45328) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 20 **********
I (45348) chip[EM]: <<< [E:60083r S:34445 M:229907820] (S) Msg TX to 1:FFFFFFFB00000000 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (45358) chip[IN]: (S) Sending msg 229907820 on secure session with LSID: 34445
I (45368) NimBLE: GATT procedure initiated: indicate; 
I (45368) NimBLE: att_handle=14

I (45478) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (46758) ROUTE_HOOK: Received RIO
I (46758) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (46988) ROUTE_HOOK: Received RIO
I (46988) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (47958) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (50458) NimBLE: GATT procedure initiated: indicate; 
I (50458) NimBLE: att_handle=14

I (50568) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (53268) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (55768) NimBLE: GATT procedure initiated: indicate; 
I (55768) NimBLE: att_handle=14

I (55918) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (58528) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (61038) NimBLE: GATT procedure initiated: indicate; 
I (61038) NimBLE: att_handle=14

I (61138) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (63798) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
E (65848) chip[SC]: CASESession timed out while waiting for a response from the peer. Current state was 2
E (65848) chip[IN]: CASE Session establishment failed: 32
I (65868) chip[IN]: CASE Server enabling CASE session setups
I (66298) NimBLE: GATT procedure initiated: indicate; 
I (66298) NimBLE: att_handle=14

I (66448) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (69108) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (71608) NimBLE: GATT procedure initiated: indicate; 
I (71608) NimBLE: att_handle=14

I (71758) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (74368) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (76878) NimBLE: GATT procedure initiated: indicate; 
I (76878) NimBLE: att_handle=14

I (76978) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (79678) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12
I (82188) NimBLE: GATT procedure initiated: indicate; 
I (82188) NimBLE: att_handle=14

I (82288) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 1) status= 14 
I (82498) chip[EM]: >>> [E:60084r S:0 M:80152346] (U) Msg RX from 0:0AC422E6A1C39AC9 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (82508) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (82508) chip[IN]: CASE Server disabling CASE session setups
I (82518) chip[EM]: <<< [E:60084r S:0 M:46060248 (Ack:80152346)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (82538) chip[IN]: (U) Sending msg 46060248 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (82548) chip[SC]: Received Sigma1 msg
I (82548) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (82808) chip[EM]: <<< [E:60084r S:0 M:46060249 (Ack:80152346)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (82818) chip[IN]: (U) Sending msg 46060249 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (82838) chip[SC]: Sent Sigma2 msg
I (82998) chip[EM]: >>> [E:60084r S:0 M:80152347 (Ack:46060249)] (U) Msg RX from 0:0AC422E6A1C39AC9 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (83018) chip[EM]: <<< [E:60084r S:0 M:46060250 (Ack:80152347)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (83028) chip[IN]: (U) Sending msg 46060250 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (83038) chip[SC]: Received Sigma3 msg
E (83048) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (83658) chip[EM]: <<< [E:60084r S:0 M:46060251 (Ack:80152347)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (83668) chip[IN]: (U) Sending msg 46060251 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (83678) chip[SC]: SecureSession[0x3fc9b530, LSID:34447]: State change 'kEstablishing' --> 'kActive'
I (83688) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (83698) chip[IN]: CASE Server enabling CASE session setups
I (84028) chip[IN]: (U) Sending msg 46060251 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59399'
I (84038) chip[EM]: >>> [E:60085r S:34447 M:215000569] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:08 (IM:InvokeCommandRequest)
I (84058) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x00000030
I (84058) chip[FS]: GeneralCommissioning: Received CommissioningComplete
I (84068) chip[FP]: Metadata for Fabric 0x1 persisted to storage.
I (84078) chip[TS]: Committing Last Known Good Time to storage: 2023-06-05T15:09:21
I (84078) chip[ZCL]: OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x7DFDC0CC2967173B, FabricId 0000000000000001, NodeId 0000000000007283, VendorId 0xFFF1
I (84118) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data
I (84118) chip[FS]: Fail-safe cleanly disarmed
I (84128) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 **********
I (84138) chip[EM]: <<< [E:60085r S:34447 M:225907033 (Ack:215000569)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:09 (IM:InvokeCommandResponse)
I (84148) chip[IN]: (S) Sending msg 225907033 on secure session with LSID: 34447
I (84158) chip[EM]: >>> [E:60084r S:0 M:80152348 (Ack:46060251)] (U) Msg RX from 0:0AC422E6A1C39AC9 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (84168) app_main: Fabric is committed
I (84178) chip[SVR]: Commissioning completed successfully
I (84178) chip[DIS]: Updating services using commissioning mode 0
I (84198) chip[DIS]: CHIP minimal mDNS started advertising.
I (84208) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (84208) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (84228) chip[DIS]: mDNS service published: _matter._tcp
I (84228) chip[SC]: SecureSession[0x3fc9b478, LSID:34445]: State change 'kActive' --> 'kPendingEviction'
I (84238) chip[BLE]: Releasing end point's BLE connection back to application.
I (84248) app_main: Commissioning complete
I (84258) esp_matter_core: Commissioning Complete
I (84258) chip[EM]: >>> [E:60084r S:0 M:80152349 (Ack:46060251)] (U) Msg RX from 0:0AC422E6A1C39AC9 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (84268) app_main: Commissioning window closed
I (84268) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0
I (84288) chip[DL]: CHIPoBLE unsubscribe received
E (84288) chip[BLE]: no endpoint for unsub recvd
I (84498) chip[IN]: (S) Sending msg 225907033 on secure session with LSID: 34447
I (84908) chip[IN]: (S) Sending msg 225907033 on secure session with LSID: 34447
I (85478) chip[IN]: (S) Sending msg 225907033 on secure session with LSID: 34447
I (86208) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (86208) NimBLE: GAP procedure initiated: stop advertising.

I (86218) esp_matter_core: BLE deinit successful and memory reclaimed
I (86358) chip[EM]: >>> [E:60085r S:34447 M:215000570 (Ack:225907033)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (94648) ROUTE_HOOK: Received RIO
I (94648) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (118498) ROUTE_HOOK: Ignore invalid ICMP packet
I (118698) ROUTE_HOOK: Received RIO
I (118698) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (131588) ROUTE_HOOK: Received RIO
I (131588) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (149278) ROUTE_HOOK: Ignore invalid ICMP packet
I (149708) ROUTE_HOOK: Received RIO
I (149708) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (168328) chip[EM]: >>> [E:33408r S:0 M:207528718] (U) Msg RX from 0:109F876C6D1DA629 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (168338) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (168338) chip[IN]: CASE Server disabling CASE session setups
I (168358) chip[EM]: <<< [E:33408r S:0 M:46060252 (Ack:207528718)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (168368) chip[IN]: (U) Sending msg 46060252 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:53385'
I (168378) chip[SC]: Received Sigma1 msg
I (168388) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (168658) chip[EM]: <<< [E:33408r S:0 M:46060253 (Ack:207528718)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (168668) chip[IN]: (U) Sending msg 46060253 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:53385'
I (168678) chip[SC]: Sent Sigma2 msg
I (168848) chip[EM]: >>> [E:33408r S:0 M:207528719 (Ack:46060253)] (U) Msg RX from 0:109F876C6D1DA629 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (168858) chip[EM]: <<< [E:33408r S:0 M:46060254 (Ack:207528719)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (168868) chip[IN]: (U) Sending msg 46060254 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:53385'
I (168888) chip[SC]: Received Sigma3 msg
E (168888) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (169508) chip[EM]: <<< [E:33408r S:0 M:46060255 (Ack:207528719)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (169518) chip[IN]: (U) Sending msg 46060255 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:53385'
I (169538) chip[SC]: SecureSession[0x3fc9b5e8, LSID:34448]: State change 'kEstablishing' --> 'kActive'
I (169538) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (169548) chip[IN]: CASE Server enabling CASE session setups
I (169868) chip[EM]: >>> [E:33409r S:34448 M:180721010] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (169878) chip[EM]: >>> [E:33408r S:0 M:207528720 (Ack:46060255)] (U) Msg RX from 0:109F876C6D1DA629 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (169888) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x0000FFFD is 4 **********
I (169898) chip[EM]: <<< [E:33409r S:34448 M:51221838 (Ack:180721010)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (169918) chip[IN]: (S) Sending msg 51221838 on secure session with LSID: 34448
I (169958) chip[EM]: >>> [E:33409r S:34448 M:180721011 (Ack:51221838)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (192478) ROUTE_HOOK: Received RIO
I (192478) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (193828) chip[EM]: >>> [E:43525r S:0 M:55506809] (U) Msg RX from 0:C02498EB9989E0AB [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (193838) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (193838) chip[IN]: CASE Server disabling CASE session setups
I (193848) chip[EM]: <<< [E:43525r S:0 M:46060256 (Ack:55506809)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (193868) chip[IN]: (U) Sending msg 46060256 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:57176'
I (193878) chip[SC]: Received Sigma1 msg
I (193878) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (194158) chip[EM]: <<< [E:43525r S:0 M:46060257 (Ack:55506809)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (194168) chip[IN]: (U) Sending msg 46060257 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:57176'
I (194178) chip[SC]: Sent Sigma2 msg
I (194348) chip[EM]: >>> [E:43525r S:0 M:55506810 (Ack:46060257)] (U) Msg RX from 0:C02498EB9989E0AB [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (194358) chip[EM]: <<< [E:43525r S:0 M:46060258 (Ack:55506810)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (194368) chip[IN]: (U) Sending msg 46060258 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:57176'
I (194388) chip[SC]: Received Sigma3 msg
E (194388) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (195018) chip[EM]: <<< [E:43525r S:0 M:46060259 (Ack:55506810)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (195028) chip[IN]: (U) Sending msg 46060259 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:57176'
I (195048) chip[SC]: SecureSession[0x3fc9b478, LSID:34449]: State change 'kEstablishing' --> 'kActive'
I (195048) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (195058) chip[IN]: CASE Server enabling CASE session setups
I (195338) chip[EM]: >>> [E:43526r S:34449 M:42192387] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (195338) chip[EM]: >>> [E:43525r S:0 M:55506811 (Ack:46060259)] (U) Msg RX from 0:C02498EB9989E0AB [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (195358) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x0000FFFC is 0 **********
I (195368) chip[EM]: <<< [E:43526r S:34449 M:16217024 (Ack:42192387)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (195378) chip[IN]: (S) Sending msg 16217024 on secure session with LSID: 34449
I (195718) chip[EM]: >>> [E:43526r S:34449 M:42192388 (Ack:16217024)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (206298) ROUTE_HOOK: Received RIO
I (206298) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (209348) ROUTE_HOOK: Received RIO
I (209358) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (214608) chip[EM]: >>> [E:45288r S:0 M:71886657] (U) Msg RX from 0:F983FBAD2D080AE8 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (214618) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (214618) chip[IN]: CASE Server disabling CASE session setups
I (214638) chip[EM]: <<< [E:45288r S:0 M:46060260 (Ack:71886657)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (214648) chip[IN]: (U) Sending msg 46060260 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59451'
I (214658) chip[SC]: Received Sigma1 msg
I (214668) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (214938) chip[EM]: <<< [E:45288r S:0 M:46060261 (Ack:71886657)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (214948) chip[IN]: (U) Sending msg 46060261 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59451'
I (214958) chip[SC]: Sent Sigma2 msg
I (215218) chip[EM]: >>> [E:45288r S:0 M:71886658 (Ack:46060261)] (U) Msg RX from 0:F983FBAD2D080AE8 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (215228) chip[EM]: <<< [E:45288r S:0 M:46060262 (Ack:71886658)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (215238) chip[IN]: (U) Sending msg 46060262 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59451'
I (215248) chip[SC]: Received Sigma3 msg
E (215258) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (215878) chip[EM]: <<< [E:45288r S:0 M:46060263 (Ack:71886658)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (215888) chip[IN]: (U) Sending msg 46060263 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:59451'
I (215908) chip[SC]: SecureSession[0x3fc9b6a0, LSID:34450]: State change 'kEstablishing' --> 'kActive'
I (215908) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (215918) chip[IN]: CASE Server enabling CASE session setups
I (216118) chip[EM]: >>> [E:45289r S:34450 M:255634307] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (216118) chip[EM]: >>> [E:45288r S:0 M:71886659 (Ack:46060263)] (U) Msg RX from 0:F983FBAD2D080AE8 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (216148) chip[EM]: <<< [E:45289r S:34450 M:113956775 (Ack:255634307)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (216158) chip[IN]: (S) Sending msg 113956775 on secure session with LSID: 34450
I (216328) chip[EM]: >>> [E:45289r S:34450 M:255634308 (Ack:113956775)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (223068) ROUTE_HOOK: Received RIO
I (223068) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (265348) ROUTE_HOOK: Received RIO
I (265348) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (339118) ROUTE_HOOK: Received RIO
I (339118) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (437658) ROUTE_HOOK: Received RIO
I (437658) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (455358) ROUTE_HOOK: Received RIO
I (455358) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (495888) ROUTE_HOOK: Received RIO
I (495888) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (549818) ROUTE_HOOK: Ignore invalid ICMP packet
I (550428) ROUTE_HOOK: Received RIO
I (550428) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (557488) ROUTE_HOOK: Received RIO
I (557488) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (630248) ROUTE_HOOK: Received RIO
I (630248) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (635598) ROUTE_HOOK: Received RIO
I (635598) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (643258) ROUTE_HOOK: Received RIO
I (643258) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (647138) ROUTE_HOOK: Received RIO
I (647138) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (704708) ROUTE_HOOK: Received RIO
I (704708) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (715388) ROUTE_HOOK: Received RIO
I (715388) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (744938) wifi:bcn_timeout,ap_probe_send_start
I (747438) wifi:ap_probe_send over, resett wifi status to disassoc
I (747438) wifi:state: run -> init (c800)
I (747438) wifi:pm stop, total sleep time: 260576997 us / 718284086 us

I (747448) wifi:<ba-del>idx
I (747448) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (747458) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
W (747468) wifi:Haven't to connect to a suitable AP now!
I (747468) chip[DL]: WiFi station state change: Connected -> NotConnected
I (747478) chip[DL]: WiFi station interface disconnected
I (747478) chip[ZCL]: WiFiDiagnosticsDelegate: OnDisconnectionDetected
I (747488) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (747498) chip[DL]: IPv4 Internet connectivity LOST
I (747508) chip[DL]: Attempting to connect WiFi station interface
I (747508) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (747518) chip[DL]: Done driving station state, nothing else to do...
I (747518) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (747528) wifi:state: init -> auth (b0)
I (747838) chip[DL]: Updating advertising data
I (748318) wifi:state: auth -> assoc (0)
I (748338) wifi:state: assoc -> run (10)
I (748378) wifi:connected with ES_Staff, aid = 16, channel 11, BW20, bssid = b4:fb:e4:4d:6e:22
I (748378) wifi:security: WPA3-SAE, phy: bgn, rssi: -52
I (748388) wifi:pm start, type: 1

I (748388) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000
I (748388) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (748408) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (748408) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (748418) chip[DL]: WiFi station interface connected
I (748418) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (748428) chip[DL]: Done driving station state, nothing else to do...
I (748438) chip[DL]: Updating advertising data
I (748608) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (749568) chip[DL]: IP_EVENT_GOT_IP6
I (749568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7204:1dff:fe14:f78c
I (749578) ROUTE_HOOK: Hook already installed on netif, skip...
I (749578) app_main: Interface IP Address changed
I (749588) chip[DIS]: Updating services using commissioning mode 0
I (749588) chip[DIS]: CHIP minimal mDNS started advertising.
I (749608) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (749608) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (749618) chip[DIS]: mDNS service published: _matter._tcp
I (749928) ROUTE_HOOK: Received RIO
I (749928) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (749938) ROUTE_HOOK: Received RIO
I (749938) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (751568) chip[DL]: IP_EVENT_GOT_IP6
I (751568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd4b:021e:cb36:4e52:7204:1dff:fe14:f78c
I (751578) ROUTE_HOOK: Hook already installed on netif, skip...
I (751578) app_main: Interface IP Address changed
I (751588) chip[DIS]: Updating services using commissioning mode 0
I (751588) chip[DIS]: CHIP minimal mDNS started advertising.
I (751608) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (751608) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (751628) chip[DIS]: mDNS service published: _matter._tcp
I (752888) esp_netif_handlers: sta ip: 192.168.20.149, mask: 255.255.255.0, gw: 192.168.20.1
I (752888) chip[DL]: IP_EVENT_STA_GOT_IP
I (752898) chip[DL]: IPv4 address ready on WiFi station interface: 192.168.20.149/255.255.255.0 gateway 192.168.20.1
I (752898) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (752908) app_main: Interface IP Address changed
I (752908) chip[DIS]: Updating services using commissioning mode 0
I (752928) chip[DIS]: CHIP minimal mDNS started advertising.
I (752938) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (752938) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (752958) chip[DIS]: mDNS service published: _matter._tcp
I (754548) ROUTE_HOOK: Received RIO
I (754548) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (758978) ROUTE_HOOK: Received RIO
I (758988) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (763268) ROUTE_HOOK: Received RIO
I (763268) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (777678) ROUTE_HOOK: Received RIO
I (777678) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (791688) ROUTE_HOOK: Received RIO
I (791688) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (804698) ROUTE_HOOK: Received RIO
I (804698) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (816348) ROUTE_HOOK: Received RIO
I (816348) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (841838) ROUTE_HOOK: Received RIO
I (841838) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (851448) ROUTE_HOOK: Received RIO
I (851448) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (860268) ROUTE_HOOK: Received RIO
I (860268) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (865058) ROUTE_HOOK: Received RIO
I (865058) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (890148) ROUTE_HOOK: Received RIO
I (890148) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (925028) ROUTE_HOOK: Received RIO
I (925028) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (996658) ROUTE_HOOK: Received RIO
I (996658) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (997008) ROUTE_HOOK: Received RIO
I (997008) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1029518) ROUTE_HOOK: Received RIO
I (1029518) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1061948) ROUTE_HOOK: Ignore invalid ICMP packet
I (1062278) ROUTE_HOOK: Received RIO
I (1062278) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1070238) ROUTE_HOOK: Received RIO
I (1070238) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1081798) ROUTE_HOOK: Received RIO
I (1081798) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1088158) ROUTE_HOOK: Received RIO
I (1088158) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1144438) ROUTE_HOOK: Received RIO
I (1144438) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1155588) ROUTE_HOOK: Received RIO
I (1155588) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1177278) ROUTE_HOOK: Received RIO
I (1177278) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1229888) ROUTE_HOOK: Received RIO
I (1229888) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1290458) ROUTE_HOOK: Received RIO
I (1290458) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1299458) ROUTE_HOOK: Received RIO
I (1299458) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1306638) ROUTE_HOOK: Received RIO
I (1306638) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1357908) ROUTE_HOOK: Received RIO
I (1357908) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1363228) ROUTE_HOOK: Received RIO
I (1363228) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1367918) ROUTE_HOOK: Ignore invalid ICMP packet
I (1378558) ROUTE_HOOK: Received RIO
I (1378568) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1437718) ROUTE_HOOK: Received RIO
I (1437718) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1447858) ROUTE_HOOK: Received RIO
I (1447858) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1464008) ROUTE_HOOK: Received RIO
I (1464008) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1511908) ROUTE_HOOK: Received RIO
I (1511908) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1521038) ROUTE_HOOK: Received RIO
I (1521038) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1538218) ROUTE_HOOK: Received RIO
I (1538218) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1558478) ROUTE_HOOK: Ignore invalid ICMP packet
I (1558978) ROUTE_HOOK: Received RIO
I (1558978) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1594098) ROUTE_HOOK: Received RIO
I (1594098) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1609528) ROUTE_HOOK: Ignore invalid ICMP packet
I (1644038) ROUTE_HOOK: Ignore invalid ICMP packet
I (1644538) ROUTE_HOOK: Received RIO
I (1644538) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1655378) ROUTE_HOOK: Received RIO
I (1655378) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1667758) ROUTE_HOOK: Received RIO
I (1667758) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1718838) ROUTE_HOOK: Received RIO
I (1718838) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1730918) ROUTE_HOOK: Received RIO
I (1730918) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1739798) ROUTE_HOOK: Received RIO
I (1739798) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1743478) ROUTE_HOOK: Received RIO
I (1743478) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1805088) ROUTE_HOOK: Received RIO
I (1805088) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1817178) ROUTE_HOOK: Received RIO
I (1817178) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1878878) ROUTE_HOOK: Received RIO
I (1878878) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1889728) ROUTE_HOOK: Received RIO
I (1889728) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1899438) ROUTE_HOOK: Received RIO
I (1899438) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (1910608) ROUTE_HOOK: Ignore invalid ICMP packet
I (1910908) ROUTE_HOOK: Received RIO
I (1910908) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1952448) ROUTE_HOOK: Received RIO
I (1952448) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (1961758) ROUTE_HOOK: Received RIO
I (1961768) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2025008) ROUTE_HOOK: Received RIO
I (2025008) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2039548) ROUTE_HOOK: Received RIO
I (2039548) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2077808) ROUTE_HOOK: Received RIO
I (2077808) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2079848) ROUTE_HOOK: Received RIO
I (2079848) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (2097958) ROUTE_HOOK: Received RIO
I (2097968) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2104308) ROUTE_HOOK: Received RIO
I (2104308) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2110358) ROUTE_HOOK: Received RIO
I (2110358) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2128148) ROUTE_HOOK: Ignore invalid ICMP packet
I (2128558) ROUTE_HOOK: Received RIO
I (2128558) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2171448) ROUTE_HOOK: Received RIO
I (2171458) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2180148) ROUTE_HOOK: Received RIO
I (2180148) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2184328) ROUTE_HOOK: Received RIO
I (2184328) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2197668) ROUTE_HOOK: Ignore invalid ICMP packet
I (2197968) ROUTE_HOOK: Received RIO
I (2197968) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2245348) ROUTE_HOOK: Received RIO
I (2245348) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2255558) ROUTE_HOOK: Received RIO
I (2255558) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2260778) ROUTE_HOOK: Received RIO
I (2260778) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2288948) ROUTE_HOOK: Received RIO
I (2288948) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2309088) ROUTE_HOOK: Ignore invalid ICMP packet
I (2309398) ROUTE_HOOK: Received RIO
I (2309398) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2316958) ROUTE_HOOK: Received RIO
I (2316958) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2320028) ROUTE_HOOK: Received RIO
I (2320028) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2329338) ROUTE_HOOK: Received RIO
I (2329338) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2376408) ROUTE_HOOK: Received RIO
I (2376418) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2391558) ROUTE_HOOK: Received RIO
I (2391558) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2463808) ROUTE_HOOK: Received RIO
I (2463808) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2466768) ROUTE_HOOK: Received RIO
I (2466768) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2536968) ROUTE_HOOK: Ignore invalid ICMP packet
I (2536968) ROUTE_HOOK: Received RIO
I (2536978) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2540038) ROUTE_HOOK: Received RIO
I (2540038) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2545488) ROUTE_HOOK: Received RIO
I (2545488) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2549158) ROUTE_HOOK: Received RIO
I (2549158) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2555288) ROUTE_HOOK: Received RIO
I (2555288) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2610858) ROUTE_HOOK: Received RIO
I (2610858) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2620268) ROUTE_HOOK: Received RIO
I (2620268) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (2631428) ROUTE_HOOK: Received RIO
I (2631428) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2663968) ROUTE_HOOK: Received RIO
I (2663968) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2694788) ROUTE_HOOK: Received RIO
I (2694788) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2699478) ROUTE_HOOK: Received RIO
I (2699478) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2742558) ROUTE_HOOK: Received RIO
I (2742558) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2756268) ROUTE_HOOK: Received RIO
I (2756268) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2767528) ROUTE_HOOK: Received RIO
I (2767528) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2783738) ROUTE_HOOK: Ignore invalid ICMP packet
I (2784018) ROUTE_HOOK: Received RIO
I (2784018) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2800398) ROUTE_HOOK: Received RIO
I (2800398) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (2839598) ROUTE_HOOK: Received RIO
I (2839598) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2901388) ROUTE_HOOK: Received RIO
I (2901388) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2912538) ROUTE_HOOK: Received RIO
I (2912538) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2917458) ROUTE_HOOK: Received RIO
I (2917458) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2964218) ROUTE_HOOK: Ignore invalid ICMP packet
I (2964418) ROUTE_HOOK: Received RIO
I (2964418) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2974668) ROUTE_HOOK: Received RIO
I (2974668) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (2987028) ROUTE_HOOK: Received RIO
I (2987028) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3048938) ROUTE_HOOK: Received RIO
I (3048938) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3061018) ROUTE_HOOK: Received RIO
I (3061018) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3094708) ROUTE_HOOK: Received RIO
I (3094718) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3121608) ROUTE_HOOK: Received RIO
I (3121608) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3131628) ROUTE_HOOK: Received RIO
I (3131628) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3135528) ROUTE_HOOK: Received RIO
I (3135528) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3153018) ROUTE_HOOK: Ignore invalid ICMP packet
I (3153528) ROUTE_HOOK: Received RIO
I (3153528) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3158028) ROUTE_HOOK: Received RIO
I (3158028) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3161718) ROUTE_HOOK: Received RIO
I (3161728) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (3206038) ROUTE_HOOK: Received RIO
I (3206038) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3206128) ROUTE_HOOK: Ignore invalid ICMP packet
I (3209008) ROUTE_HOOK: Received RIO
I (3209008) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3266418) ROUTE_HOOK: Received RIO
I (3266418) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3279708) ROUTE_HOOK: Received RIO
I (3279708) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3333948) ROUTE_HOOK: Received RIO
I (3333948) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3339998) ROUTE_HOOK: Received RIO
I (3339998) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3355648) ROUTE_HOOK: Received RIO
I (3355648) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3369958) ROUTE_HOOK: Received RIO
I (3369958) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3412218) ROUTE_HOOK: Received RIO
I (3412228) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3426168) ROUTE_HOOK: Received RIO
I (3426168) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3447958) ROUTE_HOOK: Received RIO
I (3447958) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3486208) ROUTE_HOOK: Received RIO
I (3486208) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3494928) ROUTE_HOOK: Received RIO
I (3494928) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3497978) ROUTE_HOOK: Received RIO
I (3497978) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3508628) ROUTE_HOOK: Ignore invalid ICMP packet
I (3513638) ROUTE_HOOK: Received RIO
I (3513638) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3523148) ROUTE_HOOK: Received RIO
I (3523148) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (3550598) ROUTE_HOOK: Ignore invalid ICMP packet
I (3550888) ROUTE_HOOK: Received RIO
I (3550888) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3551408) ROUTE_HOOK: Ignore invalid ICMP packet
I (3553858) ROUTE_HOOK: Received RIO
I (3553858) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3560928) ROUTE_HOOK: Received RIO
I (3560928) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3575038) ROUTE_HOOK: Received RIO
I (3575038) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3592858) ROUTE_HOOK: Received RIO
I (3592858) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3628358) ROUTE_HOOK: Ignore invalid ICMP packet
I (3628758) ROUTE_HOOK: Received RIO
I (3628758) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3631828) ROUTE_HOOK: Ignore invalid ICMP packet
I (3632548) ROUTE_HOOK: Received RIO
I (3632548) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3636738) ROUTE_HOOK: Received RIO
I (3636738) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3645458) ROUTE_HOOK: Received RIO
I (3645458) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3660598) ROUTE_HOOK: Ignore invalid ICMP packet
I (3660698) ROUTE_HOOK: Received RIO
I (3660698) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3670428) ROUTE_HOOK: Ignore invalid ICMP packet
I (3670818) ROUTE_HOOK: Received RIO
I (3670818) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3681988) ROUTE_HOOK: Ignore invalid ICMP packet
I (3697948) ROUTE_HOOK: Ignore invalid ICMP packet
I (3698148) ROUTE_HOOK: Received RIO
I (3698148) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3703578) ROUTE_HOOK: Received RIO
I (3703578) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (3706138) ROUTE_HOOK: Received RIO
I (3706138) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3711868) ROUTE_HOOK: Ignore invalid ICMP packet
I (3712158) ROUTE_HOOK: Received RIO
I (3712158) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3716978) ROUTE_HOOK: Received RIO
I (3716978) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3719938) ROUTE_HOOK: Received RIO
I (3719938) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3754848) ROUTE_HOOK: Ignore invalid ICMP packet
I (3755158) ROUTE_HOOK: Received RIO
I (3755158) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3772648) ROUTE_HOOK: Received RIO
I (3772658) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3778688) ROUTE_HOOK: Received RIO
I (3778688) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3780828) ROUTE_HOOK: Ignore invalid ICMP packet
I (3781648) ROUTE_HOOK: Received RIO
I (3781648) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3790968) ROUTE_HOOK: Received RIO
I (3790968) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3862898) ROUTE_HOOK: Received RIO
I (3862898) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3879338) wifi:bcn_timeout,ap_probe_send_start
I (3880828) ROUTE_HOOK: Ignore invalid ICMP packet
I (3881138) ROUTE_HOOK: Received RIO
I (3881138) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3883978) ROUTE_HOOK: Received RIO
I (3883978) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (3886758) ROUTE_HOOK: Ignore invalid ICMP packet
I (3887048) ROUTE_HOOK: Received RIO
I (3887048) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3924298) ROUTE_HOOK: Received RIO
I (3924298) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3929128) ROUTE_HOOK: Ignore invalid ICMP packet
I (3929218) ROUTE_HOOK: Received RIO
I (3929228) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3936588) ROUTE_HOOK: Received RIO
I (3936588) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3943438) ROUTE_HOOK: Received RIO
I (3943438) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3984278) ROUTE_HOOK: Ignore invalid ICMP packet
I (3984668) ROUTE_HOOK: Received RIO
I (3984678) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3992868) ROUTE_HOOK: Received RIO
I (3992868) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (3995928) ROUTE_HOOK: Received RIO
I (3995928) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4014758) ROUTE_HOOK: Received RIO
I (4014758) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4019898) ROUTE_HOOK: Received RIO
I (4019898) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4058268) ROUTE_HOOK: Ignore invalid ICMP packet
I (4058988) ROUTE_HOOK: Received RIO
I (4058988) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4071248) ROUTE_HOOK: Received RIO
I (4071248) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4079388) ROUTE_HOOK: Ignore invalid ICMP packet
I (4079658) ROUTE_HOOK: Received RIO
I (4079658) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4084648) ROUTE_HOOK: Received RIO
I (4084648) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4087718) ROUTE_HOOK: Received RIO
I (4087728) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4116678) ROUTE_HOOK: Received RIO
I (4116688) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4117878) wifi:bcn_timeout,ap_probe_send_start
I (4145028) ROUTE_HOOK: Received RIO
I (4145028) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4158868) ROUTE_HOOK: Received RIO
I (4158868) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4194268) ROUTE_HOOK: Ignore invalid ICMP packet
I (4209718) ROUTE_HOOK: Received RIO
I (4209718) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4231708) ROUTE_HOOK: Received RIO
I (4231708) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4237748) ROUTE_HOOK: Received RIO
I (4237758) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4244698) ROUTE_HOOK: Received RIO
I (4244698) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4249318) ROUTE_HOOK: Ignore invalid ICMP packet
I (4249818) ROUTE_HOOK: Received RIO
I (4249818) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4290958) ROUTE_HOOK: Ignore invalid ICMP packet
I (4293008) ROUTE_HOOK: Received RIO
I (4293008) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4302328) ROUTE_HOOK: Received RIO
I (4302328) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4322388) ROUTE_HOOK: Ignore invalid ICMP packet
I (4356238) ROUTE_HOOK: Ignore invalid ICMP packet
I (4356648) ROUTE_HOOK: Received RIO
I (4356658) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4357078) ROUTE_HOOK: Ignore invalid ICMP packet
I (4359648) ROUTE_HOOK: Received RIO
I (4359648) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4363918) ROUTE_HOOK: Received RIO
I (4363918) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4369448) ROUTE_HOOK: Received RIO
I (4369448) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4376198) ROUTE_HOOK: Received RIO
I (4376198) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4406808) ROUTE_HOOK: Ignore invalid ICMP packet
I (4407208) ROUTE_HOOK: Received RIO
I (4407208) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4425108) ROUTE_HOOK: Received RIO
I (4425118) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4438518) ROUTE_HOOK: Received RIO
I (4438518) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4451818) ROUTE_HOOK: Received RIO
I (4451818) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4495448) ROUTE_HOOK: Ignore invalid ICMP packet
I (4495928) ROUTE_HOOK: Received RIO
I (4495928) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4511078) ROUTE_HOOK: Received RIO
I (4511078) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4522058) ROUTE_HOOK: Ignore invalid ICMP packet
I (4522538) ROUTE_HOOK: Received RIO
I (4522538) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4526418) ROUTE_HOOK: Received RIO
I (4526428) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4583838) ROUTE_HOOK: Received RIO
I (4583838) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4592948) ROUTE_HOOK: Received RIO
I (4592948) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4597648) ROUTE_HOOK: Received RIO
I (4597648) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4605328) ROUTE_HOOK: Received RIO
I (4605328) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4623848) ROUTE_HOOK: Ignore invalid ICMP packet
I (4624268) ROUTE_HOOK: Received RIO
I (4624268) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4644618) ROUTE_HOOK: Ignore invalid ICMP packet
I (4649328) ROUTE_HOOK: Received RIO
I (4649328) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4658378) ROUTE_HOOK: Received RIO
I (4658378) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4665498) ROUTE_HOOK: Ignore invalid ICMP packet
I (4665798) ROUTE_HOOK: Received RIO
I (4665798) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4669078) ROUTE_HOOK: Received RIO
I (4669078) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4673988) ROUTE_HOOK: Received RIO
I (4673988) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4684938) ROUTE_HOOK: Ignore invalid ICMP packet
I (4685348) ROUTE_HOOK: Received RIO
I (4685348) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4698658) ROUTE_HOOK: Ignore invalid ICMP packet
I (4698868) ROUTE_HOOK: Received RIO
I (4698868) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4725768) ROUTE_HOOK: Ignore invalid ICMP packet
I (4726278) ROUTE_HOOK: Received RIO
I (4726278) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4729678) ROUTE_HOOK: Received RIO
I (4729678) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4770388) ROUTE_HOOK: Ignore invalid ICMP packet
I (4770898) ROUTE_HOOK: Received RIO
I (4770898) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4785748) ROUTE_HOOK: Received RIO
I (4785748) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4803848) ROUTE_HOOK: Received RIO
I (4803848) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4811538) ROUTE_HOOK: Received RIO
I (4811538) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4854818) ROUTE_HOOK: Ignore invalid ICMP packet
I (4859108) wifi:bcn_timeout,ap_probe_send_start
I (4861608) wifi:ap_probe_send over, resett wifi status to disassoc
I (4861608) wifi:state: run -> init (c800)
I (4861608) wifi:pm stop, total sleep time: 926405276 us / 4113223626 us

I (4861628) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4861628) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
W (4861638) wifi:Haven't to connect to a suitable AP now!
I (4861638) chip[DL]: WiFi station state change: Connected -> NotConnected
I (4861638) chip[DL]: WiFi station interface disconnected
I (4861648) chip[ZCL]: WiFiDiagnosticsDelegate: OnDisconnectionDetected
I (4861668) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (4861668) chip[DL]: IPv4 Internet connectivity LOST
I (4861678) chip[DL]: Attempting to connect WiFi station interface
I (4861678) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (4861688) chip[DL]: Done driving station state, nothing else to do...
W (4861688) wifi:Haven't to connect to a suitable AP now!
E (4861708) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (4861708) chip[DL]: Updating advertising data
W (4861718) wifi:Haven't to connect to a suitable AP now!
E (4861718) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (4861778) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4861778) wifi:state: init -> auth (b0)
I (4866078) wifi:state: auth -> init (200)
I (4866078) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4866088) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
I (4866098) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed
W (4866098) wifi:Haven't to connect to a suitable AP now!
I (4866108) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected
I (4866108) chip[DL]: Next WiFi station reconnect in 100 ms
I (4866118) chip[DL]: Done driving station state, nothing else to do...
W (4866118) wifi:Haven't to connect to a suitable AP now!
E (4866138) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (4866148) wifi:Haven't to connect to a suitable AP now!
E (4866148) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
W (4866218) wifi:Haven't to connect to a suitable AP now!
I (4866218) chip[DL]: Attempting to connect WiFi station interface
I (4866228) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (4866238) chip[DL]: Done driving station state, nothing else to do...
I (4866228) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4866248) wifi:state: init -> auth (b0)
I (4866248) wifi:state: auth -> assoc (0)
I (4866268) wifi:state: assoc -> run (10)
I (4867378) wifi:connected with ES_Staff, aid = 19, channel 11, BW20, bssid = 68:d7:9a:81:31:24
I (4867378) wifi:security: WPA3-SAE, phy: bgn, rssi: -59
I (4867388) wifi:pm start, type: 1

I (4867388) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000
I (4867388) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (4867398) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (4867408) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (4867418) chip[DL]: WiFi station interface connected
I (4867428) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (4867428) chip[DL]: Done driving station state, nothing else to do...
I (4867438) chip[DL]: Updating advertising data
I (4867768) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4868568) chip[DL]: IP_EVENT_GOT_IP6
I (4868568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7204:1dff:fe14:f78c
I (4868578) ROUTE_HOOK: Hook already installed on netif, skip...
I (4868578) app_main: Interface IP Address changed
I (4868588) chip[DIS]: Updating services using commissioning mode 0
I (4868588) chip[DIS]: CHIP minimal mDNS started advertising.
I (4868608) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4868608) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4868618) chip[DIS]: mDNS service published: _matter._tcp
I (4869888) ROUTE_HOOK: Received RIO
I (4869888) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4870008) ROUTE_HOOK: Received RIO
I (4870018) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4870388) esp_netif_handlers: sta ip: 192.168.20.149, mask: 255.255.255.0, gw: 192.168.20.1
I (4870388) chip[DL]: IP_EVENT_STA_GOT_IP
I (4870398) chip[DL]: IPv4 address ready on WiFi station interface: 192.168.20.149/255.255.255.0 gateway 192.168.20.1
I (4870398) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (4870408) app_main: Interface IP Address changed
I (4870408) chip[DIS]: Updating services using commissioning mode 0
I (4870418) chip[DIS]: CHIP minimal mDNS started advertising.
I (4870438) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4870438) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4870448) chip[DIS]: mDNS service published: _matter._tcp
I (4871568) chip[DL]: IP_EVENT_GOT_IP6
I (4871568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd4b:021e:cb36:4e52:7204:1dff:fe14:f78c
I (4871578) ROUTE_HOOK: Hook already installed on netif, skip...
I (4871578) app_main: Interface IP Address changed
I (4871588) chip[DIS]: Updating services using commissioning mode 0
I (4871588) chip[DIS]: CHIP minimal mDNS started advertising.
I (4871618) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4871618) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4871638) chip[DIS]: mDNS service published: _matter._tcp
I (4873308) ROUTE_HOOK: Received RIO
I (4873308) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4876578) ROUTE_HOOK: Received RIO
I (4876578) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4878828) ROUTE_HOOK: Received RIO
I (4878828) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4885378) ROUTE_HOOK: Received RIO
I (4885378) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4885588) ROUTE_HOOK: Received RIO
I (4885588) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4915328) wifi:bcn_timeout,ap_probe_send_start
I (4917828) wifi:ap_probe_send over, resett wifi status to disassoc
I (4917828) wifi:state: run -> init (c800)
I (4917828) wifi:pm stop, total sleep time: 3598925 us / 50441967 us

I (4917838) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4917848) chip[DL]: WIFI_EVENT_STA_DISCONNECTED
W (4917848) wifi:Haven't to connect to a suitable AP now!
I (4917858) chip[DL]: WiFi station state change: Connected -> NotConnected
I (4917858) chip[DL]: WiFi station interface disconnected
I (4917868) chip[ZCL]: WiFiDiagnosticsDelegate: OnDisconnectionDetected
I (4917878) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (4917888) chip[DL]: IPv4 Internet connectivity LOST
I (4917898) chip[DL]: Attempting to connect WiFi station interface
I (4917898) chip[DL]: WiFi station state change: NotConnected -> Connecting
I (4917908) chip[DL]: Done driving station state, nothing else to do...
W (4917908) wifi:Haven't to connect to a suitable AP now!
E (4917918) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (4917918) chip[DL]: Updating advertising data
W (4917938) wifi:Haven't to connect to a suitable AP now!
E (4917938) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F
I (4917968) wifi:new:<11,0>, old:<11,0>, ap:<255,255>, sta:<11,0>, prof:1
I (4917968) wifi:state: init -> auth (b0)
I (4918688) wifi:state: auth -> assoc (0)
I (4918698) wifi:state: assoc -> run (10)
I (4918738) wifi:connected with ES_Staff, aid = 4, channel 11, BW20, bssid = 68:d7:9a:81:26:1e
I (4918738) wifi:security: WPA3-SAE, phy: bgn, rssi: -58
I (4918748) wifi:pm start, type: 1

I (4918758) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000
I (4918758) chip[DL]: WIFI_EVENT_STA_CONNECTED
I (4918768) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded
I (4918768) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected
I (4918778) chip[DL]: WiFi station interface connected
I (4918768) wifi:AP's beacon interval = 102400 us, DTIM period = 1
I (4918778) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged
I (4918808) chip[DL]: Done driving station state, nothing else to do...
I (4918808) chip[DL]: Updating advertising data
I (4920568) chip[DL]: IP_EVENT_GOT_IP6
I (4920568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7204:1dff:fe14:f78c
I (4920578) ROUTE_HOOK: Hook already installed on netif, skip...
I (4920578) app_main: Interface IP Address changed
I (4920588) chip[DIS]: Updating services using commissioning mode 0
I (4920588) chip[DIS]: CHIP minimal mDNS started advertising.
I (4920608) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4920608) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4920618) chip[DIS]: mDNS service published: _matter._tcp
I (4921738) wifi:<ba-add>idx:0 (ifx:0, 68:d7:9a:81:26:1e), tid:0, ssn:0, winSize:64
I (4921748) ROUTE_HOOK: Received RIO
I (4921748) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4922068) ROUTE_HOOK: Received RIO
I (4922068) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4922308) wifi:<ba-add>idx:1 (ifx:0, 68:d7:9a:81:26:1e), tid:6, ssn:2, winSize:64
I (4923258) esp_netif_handlers: sta ip: 192.168.20.149, mask: 255.255.255.0, gw: 192.168.20.1
I (4923258) chip[DL]: IP_EVENT_STA_GOT_IP
I (4923268) chip[DL]: IPv4 address ready on WiFi station interface: 192.168.20.149/255.255.255.0 gateway 192.168.20.1
I (4923268) chip[DL]: IPv4 Internet connectivity ESTABLISHED
I (4923278) app_main: Interface IP Address changed
I (4923278) chip[DIS]: Updating services using commissioning mode 0
I (4923298) chip[DIS]: CHIP minimal mDNS started advertising.
I (4923308) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4923308) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4923318) chip[DIS]: mDNS service published: _matter._tcp
I (4923568) chip[DL]: IP_EVENT_GOT_IP6
I (4923568) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd4b:021e:cb36:4e52:7204:1dff:fe14:f78c
I (4923578) ROUTE_HOOK: Hook already installed on netif, skip...
I (4923578) app_main: Interface IP Address changed
I (4923588) chip[DIS]: Updating services using commissioning mode 0
I (4923588) chip[DIS]: CHIP minimal mDNS started advertising.
I (4923618) chip[DIS]: Advertise operational node 7DFDC0CC2967173B-0000000000007283
I (4923618) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 7DFDC0CC2967173B-0000000000007283.
I (4923638) chip[DIS]: mDNS service published: _matter._tcp
I (4926048) ROUTE_HOOK: Received RIO
I (4926048) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4930468) ROUTE_HOOK: Received RIO
I (4930468) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4935258) ROUTE_HOOK: Received RIO
I (4935258) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (4946398) ROUTE_HOOK: Received RIO
I (4946398) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4957158) ROUTE_HOOK: Received RIO
I (4957158) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4960738) ROUTE_HOOK: Received RIO
I (4960738) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (4965958) ROUTE_HOOK: Received RIO
I (4965958) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (5020418) ROUTE_HOOK: Received RIO
I (5020418) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5024338) ROUTE_HOOK: Ignore invalid ICMP packet
I (5024788) ROUTE_HOOK: Received RIO
I (5024788) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5031888) ROUTE_HOOK: Received RIO
I (5031888) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5044748) ROUTE_HOOK: Received RIO
I (5044748) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5047698) ROUTE_HOOK: Received RIO
I (5047698) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5084818) wifi:bcn_timeout,ap_probe_send_start
I (5093548) ROUTE_HOOK: Received RIO
I (5093548) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5099788) ROUTE_HOOK: Ignore invalid ICMP packet
I (5100408) ROUTE_HOOK: Received RIO
I (5100408) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5105538) ROUTE_HOOK: Received RIO
I (5105538) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5134288) ROUTE_HOOK: Received RIO
I (5134288) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5144428) ROUTE_HOOK: Ignore invalid ICMP packet
I (5145018) ROUTE_HOOK: Received RIO
I (5145018) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5146658) ROUTE_HOOK: Received RIO
I (5146658) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800
I (5162208) ROUTE_HOOK: Received RIO
I (5162208) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5166528) ROUTE_HOOK: Received RIO
I (5166528) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5173588) chip[EM]: >>> [E:11682r S:0 M:116268232] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5173598) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (5173598) chip[IN]: CASE Server disabling CASE session setups
I (5173618) chip[EM]: <<< [E:11682r S:0 M:46060264 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5173628) chip[IN]: (U) Sending msg 46060264 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5173638) chip[SC]: Received Sigma1 msg
I (5173648) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (5173918) chip[EM]: <<< [E:11682r S:0 M:46060265 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (5173928) chip[IN]: (U) Sending msg 46060265 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5173938) chip[SC]: Sent Sigma2 msg
I (5173938) chip[EM]: >>> [E:11682r S:0 M:116268232] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5173958) chip[EM]: <<< [E:11682r S:0 M:46060266 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5173968) chip[IN]: (U) Sending msg 46060266 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5173978) chip[EM]: >>> [E:11682r S:0 M:116268232] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5173998) chip[EM]: <<< [E:11682r S:0 M:46060267 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5174008) chip[IN]: (U) Sending msg 46060267 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
E (5174018) chip[EM]: Non-crit err 3000001 sending solitary ack for MessageCounter:116268232 on exchange 11682r
I (5174028) chip[EM]: >>> [E:11682r S:0 M:116268232] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5174048) chip[EM]: <<< [E:11682r S:0 M:46060268 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5174058) chip[IN]: (U) Sending msg 46060268 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
E (5174068) chip[EM]: Non-crit err 3000001 sending solitary ack for MessageCounter:116268232 on exchange 11682r
I (5174348) chip[IN]: (U) Sending msg 46060265 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
E (5174348) chip[EM]: Ignoring transient send error: 3000001 on exchange 11682r
I (5174618) chip[EM]: >>> [E:11682r S:0 M:116268232] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5174628) chip[EM]: <<< [E:11682r S:0 M:46060269 (Ack:116268232)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5174648) chip[IN]: (U) Sending msg 46060269 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5174658) chip[EM]: >>> [E:11682r S:0 M:116268233 (Ack:46060265)] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (5174668) chip[EM]: <<< [E:11682r S:0 M:46060270 (Ack:116268233)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5174688) chip[IN]: (U) Sending msg 46060270 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5174698) chip[SC]: Received Sigma3 msg
E (5174698) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (5175328) chip[EM]: <<< [E:11682r S:0 M:46060271 (Ack:116268233)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (5175338) chip[IN]: (U) Sending msg 46060271 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:45873'
I (5175358) chip[SC]: SecureSession[0x3fc9b758, LSID:34451]: State change 'kEstablishing' --> 'kActive'
I (5175358) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (5175368) chip[IN]: CASE Server enabling CASE session setups
I (5175688) chip[EM]: >>> [E:11683r S:34451 M:15577599] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (5175688) chip[EM]: >>> [E:11682r S:0 M:116268234 (Ack:46060271)] (U) Msg RX from 0:75FD9F13710995D4 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5175718) chip[EM]: <<< [E:11683r S:34451 M:153013129 (Ack:15577599)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (5175728) chip[IN]: (S) Sending msg 153013129 on secure session with LSID: 34451
I (5175748) chip[EM]: >>> [E:11683r S:34451 M:15577600 (Ack:153013129)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5178118) ROUTE_HOOK: Received RIO
I (5178118) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5206348) chip[EM]: >>> [E:28574r S:0 M:9594406] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5206358) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (5206358) chip[IN]: CASE Server disabling CASE session setups
I (5206368) chip[EM]: <<< [E:28574r S:0 M:46060272 (Ack:9594406)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5206388) chip[IN]: (U) Sending msg 46060272 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206398) chip[SC]: Received Sigma1 msg
I (5206398) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (5206668) chip[EM]: <<< [E:28574r S:0 M:46060273 (Ack:9594406)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (5206688) chip[IN]: (U) Sending msg 46060273 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206698) chip[SC]: Sent Sigma2 msg
I (5206698) chip[EM]: >>> [E:28574r S:0 M:9594406] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5206708) chip[EM]: <<< [E:28574r S:0 M:46060274 (Ack:9594406)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5206728) chip[IN]: (U) Sending msg 46060274 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206738) chip[EM]: >>> [E:28574r S:0 M:9594406] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5206748) chip[EM]: <<< [E:28574r S:0 M:46060275 (Ack:9594406)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5206768) chip[IN]: (U) Sending msg 46060275 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206778) chip[EM]: >>> [E:28574r S:0 M:9594406] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5206788) chip[EM]: <<< [E:28574r S:0 M:46060276 (Ack:9594406)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5206798) chip[IN]: (U) Sending msg 46060276 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206818) chip[EM]: >>> [E:28574r S:0 M:9594407 (Ack:46060273)] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (5206828) chip[EM]: <<< [E:28574r S:0 M:46060277 (Ack:9594407)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5206838) chip[IN]: (U) Sending msg 46060277 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5206858) chip[SC]: Received Sigma3 msg
E (5206858) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (5207488) chip[EM]: <<< [E:28574r S:0 M:46060278 (Ack:9594407)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (5207498) chip[IN]: (U) Sending msg 46060278 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5207518) chip[SC]: SecureSession[0x3fc9b810, LSID:34452]: State change 'kEstablishing' --> 'kActive'
I (5207518) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (5207528) chip[IN]: CASE Server enabling CASE session setups
I (5207858) chip[IN]: (U) Sending msg 46060278 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:34839'
I (5207878) chip[EM]: >>> [E:28575r S:34452 M:75854432] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (5207878) chip[EM]: >>> [E:28574r S:0 M:9594408 (Ack:46060278)] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5207898) chip[EM]: <<< [E:28575r S:34452 M:71023543 (Ack:75854432)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (5207918) chip[IN]: (S) Sending msg 71023543 on secure session with LSID: 34452
I (5207918) chip[EM]: >>> [E:28574r S:0 M:9594409 (Ack:46060278)] (U) Msg RX from 0:CAA1E08C8AC6554B [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5207938) chip[EM]: >>> [E:28575r S:34452 M:75854433 (Ack:71023543)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5239678) ROUTE_HOOK: Received RIO
I (5239678) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5249808) ROUTE_HOOK: Received RIO
I (5249818) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5255848) ROUTE_HOOK: Received RIO
I (5255848) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5267948) chip[EM]: >>> [E:61450r S:0 M:176533104] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5267948) chip[IN]: CASE Server received Sigma1 message . Starting handshake. EC 0x3fc9cd30
I (5267968) chip[IN]: CASE Server disabling CASE session setups
I (5267978) chip[EM]: <<< [E:61450r S:0 M:46060279 (Ack:176533104)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5267988) chip[IN]: (U) Sending msg 46060279 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5267998) chip[SC]: Received Sigma1 msg
I (5268008) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000007283
I (5268278) chip[EM]: <<< [E:61450r S:0 M:46060280 (Ack:176533104)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2)
I (5268288) chip[IN]: (U) Sending msg 46060280 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5268298) chip[SC]: Sent Sigma2 msg
I (5268308) chip[EM]: >>> [E:61450r S:0 M:176533104] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5268318) chip[EM]: <<< [E:61450r S:0 M:46060281 (Ack:176533104)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5268328) chip[IN]: (U) Sending msg 46060281 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5268338) chip[EM]: >>> [E:61450r S:0 M:176533104] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5268358) chip[EM]: <<< [E:61450r S:0 M:46060282 (Ack:176533104)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5268368) chip[IN]: (U) Sending msg 46060282 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5268378) chip[EM]: >>> [E:61450r S:0 M:176533104] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1)
I (5268398) chip[EM]: <<< [E:61450r S:0 M:46060283 (Ack:176533104)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5268408) chip[IN]: (U) Sending msg 46060283 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5268468) chip[EM]: >>> [E:61450r S:0 M:176533105 (Ack:46060280)] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3)
I (5268478) chip[EM]: <<< [E:61450r S:0 M:46060284 (Ack:176533105)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5268488) chip[IN]: (U) Sending msg 46060284 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5268508) chip[SC]: Received Sigma3 msg
E (5268508) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c.  Falling back to Last Known Good UTC Time
I (5269138) chip[EM]: <<< [E:61450r S:0 M:46060285 (Ack:176533105)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport)
I (5269148) chip[IN]: (U) Sending msg 46060285 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5269168) chip[SC]: SecureSession[0x3fc9b8c8, LSID:34453]: State change 'kEstablishing' --> 'kActive'
I (5269168) chip[IN]: CASE Session established to peer: <000000000001B669, 1>
I (5269178) chip[IN]: CASE Server enabling CASE session setups
I (5269528) chip[IN]: (U) Sending msg 46060285 to IP address 'UDP:[FD4B:21E:CB36:4E52:DB65:EAD0:FF3D:7C5C%st1]:43250'
I (5269548) chip[EM]: >>> [E:61451r S:34453 M:246509117] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (5269558) chip[EM]: >>> [E:61450r S:0 M:176533106 (Ack:46060285)] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5269568) chip[EM]: <<< [E:61451r S:34453 M:7707353 (Ack:246509117)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0001:05 (IM:ReportData)
I (5269588) chip[IN]: (S) Sending msg 7707353 on secure session with LSID: 34453
I (5269658) chip[EM]: >>> [E:61451r S:34453 M:246509117] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0001:02 (IM:ReadRequest)
I (5269658) chip[EM]: <<< [E:61451r S:34453 M:7707354 (Ack:246509117)] (S) Msg TX to 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5269678) chip[IN]: (S) Sending msg 7707354 on secure session with LSID: 34453
I (5269688) chip[EM]: >>> [E:61450r S:0 M:176533107 (Ack:46060285)] (U) Msg RX from 0:0A0A10F7021BF717 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5269728) chip[EM]: >>> [E:61451r S:34453 M:246509118 (Ack:7707353)] (S) Msg RX from 1:000000000001B669 [173B] --- Type 0000:10 (SecureChannel:StandaloneAck)
I (5313558) ROUTE_HOOK: Received RIO
I (5313558) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5324828) ROUTE_HOOK: Received RIO
I (5324828) ROUTE_HOOK: prefix FDDC:2BCA:55BF:1:: lifetime 1800
I (5327168) ROUTE_HOOK: Received RIO
I (5327178) ROUTE_HOOK: prefix FDA9:D93B:1F2B:: lifetime 1800