Executing action: monitor Serial port /dev/ttyUSB0 Connecting....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:0x3fcd5810,len:0x16b4 load:0x403cc710,len:0x930 load:0x403ce710,len:0x2d28 entry 0x403cc710 I (30) boot: ESP-IDF v4.4.3 2nd stage bootloader I (30) boot: compile time 11:29:00 I (30) boot: chip revision: 3 I (32) boot.esp32c3: SPI Speed : 80MHz I (37) boot.esp32c3: SPI Mode : DIO I (41) 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 (62) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000 I (70) boot: 1 nvs WiFi data 01 02 00010000 00006000 I (77) boot: 2 nvs_keys NVS keys 01 04 00016000 00001000 I (85) boot: 3 otadata OTA data 01 00 00017000 00002000 I (92) boot: 4 phy_init RF data 01 01 00019000 00001000 I (100) boot: 5 ota_0 OTA app 00 10 00020000 001e0000 I (107) boot: 6 ota_1 OTA app 00 11 00200000 001e0000 I (115) boot: 7 fctry WiFi data 01 02 003e0000 00006000 I (122) boot: End of partition table I (127) esp_image: segment 0: paddr=00020020 vaddr=3c100020 size=2f1f0h (193008) map I (165) esp_image: segment 1: paddr=0004f218 vaddr=3fc91c00 size=00e00h ( 3584) load I (166) esp_image: segment 2: paddr=00050020 vaddr=42000020 size=f6b74h (1010548) map I (329) esp_image: segment 3: paddr=00146b9c vaddr=3fc92a00 size=02918h ( 10520) load I (331) esp_image: segment 4: paddr=001494bc vaddr=40380000 size=11a9ch ( 72348) load I (348) esp_image: segment 5: paddr=0015af60 vaddr=50000010 size=00010h ( 16) load I (354) boot: Loaded app from partition at offset 0x20000 I (354) boot: Disabling RNG early entropy source... I (368) cpu_start: Pro cpu up. I (378) cpu_start: Pro cpu start user code I (378) cpu_start: cpu freq: 160000000 I (378) cpu_start: Application information: I (381) cpu_start: Project name: light I (385) cpu_start: App version: v1.0 I (390) cpu_start: Compile time: Feb 9 2023 11:46:51 I (396) cpu_start: ELF file SHA256: 4e8cd70664255155... I (402) cpu_start: ESP-IDF: v4.4.3 I (407) heap_init: Initializing. RAM available for dynamic allocation: I (414) heap_init: At 3FCA5D90 len 00036980 (218 KiB): DRAM I (420) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM I (427) heap_init: At 50000020 len 00001FE0 (7 KiB): RTCRAM I (434) spi_flash: detected chip: generic I (438) spi_flash: flash io: dio I (443) sleep: Configure to isolate all GPIO pins in sleep state I (449) sleep: Enable automatic switching of GPIO sleep configuration I (456) coexist: coexist rom version 9387209 I (461) cpu_start: Starting scheduler. I (486) led_driver_ws2812: Initializing light driver I (486) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0  W (496) esp_matter_core: Command 0x0047 on cluster 0x0300 already exists. Not creating again. I (496) app_main: Light created with endpoint_id 1 I (506) pp: pp rom version: 9387209 I (506) net80211: net80211 rom version: 9387209 I (516) wifi:wifi driver task: 3fcb1dc0, prio:23, stack:6656, core=0 I (516) system_api: Base MAC address is not set I (526) system_api: read default base MAC address from EFUSE I (536) wifi:wifi firmware version: 8cb87ff I (536) wifi:wifi certification version: v7.0 I (536) wifi:config NVS flash: enabled I (536) wifi:config nano formating: disabled I (546) wifi:Init data frame dynamic rx buffer num: 32 I (546) wifi:Init management frame dynamic rx buffer num: 32 I (556) wifi:Init management short buffer num: 32 I (556) wifi:Init dynamic tx buffer num: 32 I (566) wifi:Init static tx FG buffer num: 2 I (566) wifi:Init static rx buffer size: 1600 I (566) wifi:Init static rx buffer num: 10 I (576) wifi:Init dynamic rx buffer num: 32 I (576) wifi_init: rx ba win: 6 I (586) wifi_init: tcpip mbox: 32 I (586) wifi_init: udp mbox: 6 I (586) wifi_init: tcp mbox: 6 I (596) wifi_init: tcp tx win: 5744 I (596) wifi_init: tcp rx win: 5744 I (596) wifi_init: tcp mss: 1440 I (606) wifi_init: WiFi IRAM OP enabled I (606) wifi_init: WiFi RX IRAM OP enabled I (626) chip[DL]: NVS set: chip-counters/reboot-count = 2 (0x2) I (626) CHIP[DL]: WiFi station already provisioned, not initializing BLE W (626) BTDM_INIT: esp_bt_mem_release not implemented, return OK I (636) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (646) chip[DL]: Starting ESP WiFi layer I (646) phy_init: phy_version 912,d001756,Jun 2 2022,16:28:07 I (686) wifi:mode : sta (7c:df:a1:c2:10:14) I (686) wifi:enable tsf W (686) wifi:Haven't to connect to a suitable AP now! I (686) chip[DL]: Attempting to connect WiFi station interface I (696) chip[DL]: WiFi station state change: NotConnected -> Connecting I (696) chip[DL]: Done driving station state, nothing else to do... I (706) chip[SVR]: Subscription persistence not supported I (706) chip[SVR]: Server initializing... I (716) chip[TS]: Last Known Good Time: 2023-02-09T11:47:23 I (726) chip[DMG]: AccessControl: initializing I (726) chip[DMG]: Examples::AccessControlDelegate::Init I (736) chip[DMG]: AccessControl: setting I (736) chip[DMG]: DefaultAclStorage: initializing I (746) chip[DMG]: DefaultAclStorage: 0 entries loaded I (766) chip[ZCL]: Using ZAP configuration... I (766) esp_matter_cluster: Cluster plugin init common callback I (766) chip[DMG]: AccessControlCluster: initializing I (776) chip[ZCL]: 0x3c10bc10ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (776) chip[ZCL]: Initiating Admin Commissioning cluster. E (786) chip[ZCL]: Duplicate attribute override registration failed I (796) chip[SVR]: BLE networking available but BLE advertising is not supported I (806) chip[DIS]: Updating services using commissioning mode 1 I (806) chip[DIS]: CHIP minimal mDNS started advertising. I (816) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 I (826) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: D36EF5A2121FC3A3. I (836) chip[DIS]: mDNS service published: _matterc._udp I (846) chip[IN]: CASE Server enabling CASE session setups I (846) chip[SVR]: Joining Multicast groups I (856) chip[SVR]: Server Listening... I (856) esp_matter_core: Dynamic endpoint 0 added I (866) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0003's Attribute 0x0001 is 0 ********** I (876) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0004's Attribute 0x0000 is 128 ********** I (886) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0004's Attribute 0xFFFC is ********** I (896) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0005's Attribute 0x0000 is 0 ********** I (906) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0xFFFC is 1 ********** I (916) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x4003 is null ********** I (926) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x0000 is 1 ********** I (936) chip[ZCL]: On/Off set value: 1 1 I (946) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x0000 is 1 ********** I (956) chip[ZCL]: On/off already set to new value I (956) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0002 is 1 ********** I (966) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0003 is 254 ********** I (976) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0xFFFC is 3 ********** I (986) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0000 is 64 ********** I (996) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x4000 is 64 ********** I (1016) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0000 is 64 ********** I (1026) led_driver_ws2812: led set r:0, g:0, b:0 I (1026) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0300's Attribute 0x4010 is null ********** I (1036) esp_matter_core: Dynamic endpoint 1 added E (1046) chip[DL]: Long dispatch time: 341 ms, for event type 2 W (1046) wifi:Haven't to connect to a suitable AP now! E (1056) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F I (1066) chip[DL]: WIFI_EVENT_STA_START I (1066) phy: chan:1,max_power:80 I (1076) phy: chan:2,max_power:80 I (1076) phy: chan:3,max_power:80 I (1086) phy: chan:4,max_power:80 I (1086) phy: chan:5,max_power:80 I (1086) phy: chan:6,max_power:80 I (1096) phy: chan:7,max_power:80 I (1096) phy: chan:8,max_power:80 I (1106) phy: chan:9,max_power:80 I (1106) phy: chan:10,max_power:80 I (1106) phy: chan:11,max_power:80 I (1116) phy: chan:12,max_power:80 I (1116) phy: chan:13,max_power:80 I (1126) phy: chan:14,max_power:80 W (1126) wifi:Haven't to connect to a suitable AP now! I (1136) chip[DL]: Attempting to connect WiFi station interface E (1136) wifi:sta is connecting, return error E (1146) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN I (1146) app_main: Commissioning window opened I (1156) led_driver_ws2812: led set r:0, g:0, b:0 I (1156) led_driver_ws2812: led set r:0, g:0, b:0 I (1166) led_driver_ws2812: led set r:25, g:20, b:16 \00\00\00\00> \00E (1506) wifi:AP has neither DSSS parameter nor HT Information, drop it E (1536) wifi:AP has neither DSSS parameter nor HT Information, drop it I (3106) wifi:new:<11,0>, old:<1,0>, ap:<255,255>, sta:<11,0>, prof:1 I (3106) wifi:state: init -> auth (b0) I (3846) wifi:state: auth -> assoc (0) I (3866) wifi:state: assoc -> run (10) I (3926) wifi:connected with ES_Staff, aid = 16, channel 11, BW20, bssid = 68:d7:9a:81:31:24 I (3926) wifi:security: WPA3-SAE, phy: bgn, rssi: -56 I (3936) wifi:pm start, type: 1 I (3936) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000 I (3936) chip[DL]: WIFI_EVENT_STA_CONNECTED I (3956) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (3956) phy: chan:1,max_power:80 I (3966) phy: chan:2,max_power:80 I (3966) phy: chan:3,max_power:80 I (3966) phy: chan:4,max_power:80 I (3976) phy: chan:5,max_power:80 I (3976) phy: chan:6,max_power:80 I (3976) phy: chan:7,max_power:80 I (3986) phy: chan:8,max_power:80 I (3986) phy: chan:9,max_power:80 I (3986) phy: chan:10,max_power:80 I (3996) phy: chan:11,max_power:80 I (3996) phy: chan:12,max_power:80 I (3996) phy: chan:13,max_power:80 I (4016) phy: chan:14,max_power:80 I (3986) wifi:BcnInt:102400, DTIM:1 I (4016) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (4026) chip[DL]: WiFi station interface connected I (4026) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (4036) chip[DL]: Done driving station state, nothing else to do... I (4036) chip[DL]: Updating advertising data I (5496) chip[DL]: IP_EVENT_GOT_IP6 I (5496) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7edf:a1ff:fec2:1014 I (5506) app_main: Interface IP Address changed I (5506) chip[DIS]: Updating services using commissioning mode 1 I (5516) chip[DIS]: CHIP minimal mDNS started advertising. I (5526) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 I (5526) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: D36EF5A2121FC3A3. I (5546) chip[DIS]: mDNS service published: _matterc._udp W (5666) wifi:idx:0 (ifx:0, 68:d7:9a:81:31:24), tid:0, ssn:0, winSize:64 I (5666) ROUTE_HOOK: Received RIO I (5666) ROUTE_HOOK: prefix FDE7:1C57:5FD3:: lifetime 1800 W (7006) wifi:idx:1 (ifx:0, 68:d7:9a:81:31:24), tid:6, ssn:2, winSize:64 I (7996) esp_netif_handlers: sta ip: 192.168.20.225, mask: 255.255.255.0, gw: 192.168.20.1 I (7996) chip[DL]: IP_EVENT_STA_GOT_IP I (8006) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.20.225/255.255.255.0 gateway 192.168.20.1 I (8006) chip[DL]: IPv4 Internet connectivity ESTABLISHED I (8016) app_main: Interface IP Address changed I (8016) chip[DIS]: Updating services using commissioning mode 1 I (8026) chip[DIS]: CHIP minimal mDNS started advertising. I (8046) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 I (8046) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: D36EF5A2121FC3A3. I (8066) chip[DIS]: mDNS service published: _matterc._udp I (10126) ROUTE_HOOK: Received RIO I (10126) ROUTE_HOOK: prefix FDE7:1C57:5FD3:: lifetime 1800 I (14116) ROUTE_HOOK: Received RIO I (14116) ROUTE_HOOK: prefix FDE7:1C57:5FD3:: lifetime 1800 I (18826) ROUTE_HOOK: Received RIO I (18826) ROUTE_HOOK: prefix FDE7:1C57:5FD3:: lifetime 1800 I (19026) ROUTE_HOOK: Ignore invalid ICMP packet I (46056) ROUTE_HOOK: Ignore invalid ICMP packet I (54016) ROUTE_HOOK: Ignore invalid ICMP packet I (54126) ROUTE_HOOK: Received RIO I (54126) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (55056) ROUTE_HOOK: Ignore invalid ICMP packet I (55496) chip[DL]: IP_EVENT_GOT_IP6 I (55496) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd7b:45e9:1c24:b879:7edf:a1ff:fec2:1014 I (55506) ROUTE_HOOK: Hook already installed on netif, skip... I (55506) app_main: Interface IP Address changed I (55516) chip[DIS]: Updating services using commissioning mode 1 I (55516) chip[DIS]: CHIP minimal mDNS started advertising. I (55546) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 I (55556) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: D36EF5A2121FC3A3. I (55576) chip[DIS]: mDNS service published: _matterc._udp I (57106) ROUTE_HOOK: Received RIO I (57106) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (60156) ROUTE_HOOK: Received RIO I (60156) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (63136) ROUTE_HOOK: Received RIO I (63136) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (119516) ROUTE_HOOK: Ignore invalid ICMP packet I (119826) ROUTE_HOOK: Received RIO I (119826) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (120336) chip[EM]: >>> [E:47665r M:116771419] (U) Msg RX from 0:96A38F5956325EC1 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I (120356) chip[EM]: <<< [E:47665r M:72555336 (Ack:116771419)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (120366) chip[IN]: (U) Sending msg 72555336 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (120376) chip[EM]: <<< [E:47665r M:72555337 (Ack:116771419)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I (120396) chip[IN]: (U) Sending msg 72555337 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (120596) chip[EM]: >>> [E:47665r M:116771420 (Ack:72555337)] (U) Msg RX from 0:96A38F5956325EC1 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I (120606) chip[EM]: <<< [E:47665r M:72555338 (Ack:116771420)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (120626) chip[IN]: (U) Sending msg 72555338 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (121466) chip[EM]: <<< [E:47665r M:72555339 (Ack:116771420)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I (121486) chip[IN]: (U) Sending msg 72555339 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' E (121486) chip[DL]: Long dispatch time: 888 ms, for event type 3 I (121876) chip[EM]: >>> [E:47665r M:116771421 (Ack:72555339)] (U) Msg RX from 0:96A38F5956325EC1 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I (121896) chip[EM]: <<< [E:47665r M:72555340 (Ack:116771421)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (121906) chip[IN]: (U) Sending msg 72555340 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (121916) chip[EM]: <<< [E:47665r M:72555341 (Ack:116771421)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I (121936) chip[IN]: (U) Sending msg 72555341 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (121946) chip[SC]: SecureSession[0x3fc98d50]: Moving from state 'kEstablishing' --> 'kActive' I (121946) chip[SVR]: Commissioning completed session establishment step I (121956) chip[DIS]: Updating services using commissioning mode 0 I (121956) chip[DIS]: CHIP minimal mDNS started advertising. I (121996) chip[SVR]: Device completed Rendezvous process E (121996) chip[DL]: Long dispatch time: 117 ms, for event type 3 I (121996) chip[EM]: >>> [E:47666r M:180112293] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) I (122016) chip[EM]: >>> [E:47665r M:116771422 (Ack:72555341)] (U) Msg RX from 0:96A38F5956325EC1 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122026) app_main: Commissioning session started I (122036) app_main: Commissioning window closed I (122036) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0) I (122046) esp_matter_attribute: ********** Endpoint 0x0000's Cluster 0x0030's Attribute 0x0000 is 0 ********** I (122056) chip[EM]: <<< [E:47666r M:254205743 (Ack:180112293)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I (122066) chip[IN]: (S) Sending msg 254205743 on secure session with LSID: 45897 I (122286) chip[EM]: >>> [E:47667r M:180112294] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122286) esp_matter_command: Received command 0x0000 for enpoint 0x0000's cluster 0x00000030 I (122296) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s) I (122316) esp_matter_attribute: ********** Endpoint 0x0000's Cluster 0x0030's Attribute 0x0000 is 3 ********** I (122326) chip[EM]: <<< [E:47667r M:254205744 (Ack:180112294)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (122336) chip[IN]: (S) Sending msg 254205744 on secure session with LSID: 45897 I (122346) chip[EM]: >>> [E:47666r M:180112295 (Ack:254205743)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122386) chip[EM]: >>> [E:47668r M:180112296] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122396) esp_matter_command: Received command 0x0002 for enpoint 0x0000's cluster 0x00000030 I (122406) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0) I (122416) chip[DL]: NVS set: chip-config/country-code = "XX" I (122416) esp_matter_attribute: ********** Endpoint 0x0000's Cluster 0x0030's Attribute 0x0000 is 4 ********** I (122436) chip[EM]: <<< [E:47668r M:254205745 (Ack:180112296)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (122446) chip[IN]: (S) Sending msg 254205745 on secure session with LSID: 45897 I (122456) chip[EM]: >>> [E:47667r M:180112297 (Ack:254205744)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122476) chip[EM]: >>> [E:47669r M:180112298] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122476) esp_matter_command: Received command 0x0002 for enpoint 0x0000's cluster 0x0000003E I (122486) chip[ZCL]: OpCreds: Certificate Chain request received for PAI I (122506) chip[EM]: <<< [E:47669r M:254205746 (Ack:180112298)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (122516) chip[IN]: (S) Sending msg 254205746 on secure session with LSID: 45897 I (122516) chip[EM]: >>> [E:47668r M:180112299 (Ack:254205745)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122556) chip[EM]: >>> [E:47670r M:180112300] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122556) esp_matter_command: Received command 0x0002 for enpoint 0x0000's cluster 0x0000003E I (122566) chip[ZCL]: OpCreds: Certificate Chain request received for DAC I (122586) chip[EM]: <<< [E:47670r M:254205747 (Ack:180112300)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (122596) chip[IN]: (S) Sending msg 254205747 on secure session with LSID: 45897 I (122596) chip[EM]: >>> [E:47669r M:180112301 (Ack:254205746)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122616) chip[EM]: >>> [E:47671r M:180112302] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122626) esp_matter_command: Received command 0x0000 for enpoint 0x0000's cluster 0x0000003E I (122636) chip[ZCL]: OpCreds: Received an AttestationRequest command I (122646) chip[EM]: <<< [E:47671r M:254205748 (Ack:180112302)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122656) chip[IN]: (S) Sending msg 254205748 on secure session with LSID: 45897 I (122836) chip[ZCL]: OpCreds: AttestationRequest successful. I (122846) chip[EM]: <<< [E:47671r M:254205749 (Ack:180112302)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (122856) chip[IN]: (S) Sending msg 254205749 on secure session with LSID: 45897 E (122856) chip[DL]: Long dispatch time: 243 ms, for event type 3 I (122866) chip[EM]: >>> [E:47670r M:180112303 (Ack:254205747)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122916) chip[EM]: >>> [E:47672r M:180112304] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (122916) esp_matter_command: Received command 0x0004 for enpoint 0x0000's cluster 0x0000003E I (122926) chip[ZCL]: OpCreds: Received a CSRRequest command I (122936) chip[EM]: <<< [E:47672r M:254205750 (Ack:180112304)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (122956) chip[IN]: (S) Sending msg 254205750 on secure session with LSID: 45897 I (123286) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded I (123456) chip[ZCL]: OpCreds: CSRRequest successful. I (123456) chip[EM]: <<< [E:47672r M:254205751 (Ack:180112304)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (123466) chip[IN]: (S) Sending msg 254205751 on secure session with LSID: 45897 E (123476) chip[DL]: Long dispatch time: 562 ms, for event type 3 I (123486) chip[EM]: >>> [E:47671r M:180112305 (Ack:254205749)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (123496) chip[EM]: >>> [E:47673r M:180112306] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (123516) esp_matter_command: Received command 0x000B for enpoint 0x0000's cluster 0x0000003E I (123516) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command I (123526) chip[EM]: <<< [E:47673r M:254205752 (Ack:180112306)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (123536) chip[IN]: (S) Sending msg 254205752 on secure session with LSID: 45897 I (123896) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful. I (123896) chip[EM]: <<< [E:47673r M:254205753 (Ack:180112306)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (123916) chip[IN]: (S) Sending msg 254205753 on secure session with LSID: 45897 E (123926) chip[DL]: Long dispatch time: 425 ms, for event type 3 I (123926) chip[EM]: >>> [E:47672r M:180112307 (Ack:254205751)] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (123896) ROUTE_HOOK: Received RIO I (123936) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (123956) chip[EM]: >>> [E:47674r M:180112308] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (123966) esp_matter_command: Received command 0x0006 for enpoint 0x0000's cluster 0x0000003E I (123976) chip[ZCL]: OpCreds: Received an AddNOC command I (123986) chip[EM]: <<< [E:47674r M:254205754 (Ack:180112308)] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (123996) chip[IN]: (S) Sending msg 254205754 on secure session with LSID: 45897 I (124006) chip[FP]: Validating NOC chain I (124686) chip[FP]: NOC chain validation successful I (124686) chip[FP]: Added new fabric at index: 0x1 I (124686) chip[FP]: Assigned compressed fabric ID: 0x25360332D3C029DF, node ID: 0x0000000000000001 I (124696) chip[TS]: Last Known Good Time: 2023-02-09T11:47:23 I (124706) chip[TS]: New proposed Last Known Good Time: 2021-01-01T00:00:00 I (124716) chip[TS]: Retaining current Last Known Good Time I (124726) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 I (124736) chip[DIS]: Advertise operational node 25360332D3C029DF-0000000000000001 I (124746) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 25360332D3C029DF-0000000000000001. I (124766) chip[DIS]: mDNS service published: _matter._tcp I (124766) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC I (124776) chip[EM]: <<< [E:47674r M:254205755 (Ack:180112308)] (S) Msg TX to 1:FFFFFFFB00000000 [29DF] --- Type 0001:09 (IM:InvokeCommandResponse) I (124786) chip[IN]: (S) Sending msg 254205755 on secure session with LSID: 45897 E (124796) chip[DL]: Long dispatch time: 836 ms, for event type 3 I (124806) chip[EM]: >>> [E:47673r M:180112309 (Ack:254205753)] (S) Msg RX from 1:FFFFFFFB00000000 [29DF] --- Type 0000:10 (SecureChannel:StandaloneAck) I (124816) chip[EM]: >>> [E:47674r M:180112310 (Ack:254205755)] (S) Msg RX from 1:FFFFFFFB00000000 [29DF] --- Type 0000:10 (SecureChannel:StandaloneAck) I (125046) chip[EM]: >>> [E:47675r M:116771423] (U) Msg RX from 0:1D64530C92D8967D [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I (125056) chip[IN]: CASE Server received Sigma1 message. Starting handshake. EC 0x3fc9a578 I (125066) chip[IN]: CASE Server disabling CASE session setups I (125076) chip[EM]: <<< [E:47675r M:72555342 (Ack:116771423)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (125086) chip[IN]: (U) Sending msg 72555342 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (125096) chip[SC]: Received Sigma1 msg I (125106) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 I (125596) chip[EM]: <<< [E:47675r M:72555343 (Ack:116771423)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I (125616) chip[IN]: (U) Sending msg 72555343 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (125616) chip[SC]: Sent Sigma2 msg E (125626) chip[DL]: Long dispatch time: 579 ms, for event type 3 I (125626) chip[EM]: >>> [E:47675r M:116771424 (Ack:72555343)] (U) Msg RX from 0:1D64530C92D8967D [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) I (125646) chip[EM]: <<< [E:47675r M:72555344 (Ack:116771424)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (125656) chip[IN]: (U) Sending msg 72555344 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (125666) chip[SC]: Received Sigma3 msg E (125676) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time I (126706) chip[EM]: <<< [E:47675r M:72555345 (Ack:116771424)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I (126716) chip[IN]: (U) Sending msg 72555345 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:54157' I (126726) chip[SC]: SecureSession[0x3fc98e08]: Moving from state 'kEstablishing' --> 'kActive' I (126726) chip[IN]: CASE Session established to peer: <000000000001B669, 1> I (126746) chip[IN]: CASE Server enabling CASE session setups E (126746) chip[DL]: Long dispatch time: 1116 ms, for event type 3 I (126896) chip[EM]: >>> [E:47676r M:173792110] (S) Msg RX from 1:000000000001B669 [29DF] --- Type 0001:08 (IM:InvokeCommandRequest) I (126896) esp_matter_command: Received command 0x0004 for enpoint 0x0000's cluster 0x00000030 I (126906) chip[FS]: GeneralCommissioning: Received CommissioningComplete I (126916) chip[FP]: Metadata for Fabric 0x1 persisted to storage. I (126926) chip[TS]: Committing Last Known Good Time to storage: 2023-02-09T11:47:23 I (126926) chip[ZCL]: OpCreds: Fabric index 0x1 was committed to storage. Compressed Fabric Id 0x25360332D3C029DF, FabricId 0000000000000001, NodeId 0000000000000001, VendorId 0xFFF1 I (126956) chip[FS]: GeneralCommissioning: Successfully commited pending fabric data I (126956) chip[FS]: Fail-safe cleanly disarmed I (126966) esp_matter_attribute: ********** Endpoint 0x0000's Cluster 0x0030's Attribute 0x0000 is 0 ********** I (126976) chip[EM]: <<< [E:47676r M:18459190 (Ack:173792110)] (S) Msg TX to 1:000000000001B669 [29DF] --- Type 0001:09 (IM:InvokeCommandResponse) I (126986) chip[IN]: (S) Sending msg 18459190 on secure session with LSID: 45898 E (126996) chip[DL]: Long dispatch time: 104 ms, for event type 3 I (127006) chip[EM]: >>> [E:47675r M:116771425 (Ack:72555345)] (U) Msg RX from 0:1D64530C92D8967D [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (127016) chip[SVR]: Commissioning completed successfully I (127016) chip[DIS]: Updating services using commissioning mode 0 I (127036) chip[DIS]: CHIP minimal mDNS started advertising. I (127046) chip[DIS]: Advertise operational node 25360332D3C029DF-0000000000000001 I (127046) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 25360332D3C029DF-0000000000000001. I (127066) chip[DIS]: mDNS service published: _matter._tcp I (127066) chip[SC]: SecureSession[0x3fc98d50]: Moving from state 'kActive' --> 'kPendingEviction' I (127076) app_main: Commissioning complete I (127076) esp_matter_core: BLE already deinited I (127086) app_main: Commissioning window closed I (127096) chip[EM]: >>> [E:47676r M:173792111 (Ack:18459190)] (S) Msg RX from 1:000000000001B669 [29DF] --- Type 0000:10 (SecureChannel:StandaloneAck) I (128016) ROUTE_HOOK: Received RIO I (128016) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (146336) ROUTE_HOOK: Received RIO I (146336) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (168226) ROUTE_HOOK: Received RIO I (168226) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (248866) ROUTE_HOOK: Ignore invalid ICMP packet I (253266) ROUTE_HOOK: Received RIO I (253266) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 I (277626) ROUTE_HOOK: Received RIO I (277626) ROUTE_HOOK: prefix FDE7:1C57:5FD3:: lifetime 1800 I (278446) ROUTE_HOOK: Ignore invalid ICMP packet I (282736) ROUTE_HOOK: Received RIO I (282736) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 \00\00\00\00> \00 Detecting chip type... ESP32-C3 Running idf_monitor in directory /home/vaishali/esp/esp-matter/examples/light Executing "/home/vaishali/.espressif/python_env/idf4.4_py3.10_env/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/.espressif/python_env/idf4.4_py3.10_env/bin/python' '/home/vaishali/esp/esp-idf/tools/idf.py'"... Executing action: monitor Serial port /dev/ttyUSB0 Connecting....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:0x3fcd5810,len:0x16b4 load:0x403cc710,len:0x930 load:0x403ce710,len:0x2d28 entry 0x403cc710 I (30) boot: ESP-IDF v4.4.3 2nd stage bootloader I (30) boot: compile time 11:29:00 I (30) boot: chip revision: 3 I (32) boot.esp32c3: SPI Speed : 80MHz I (37) boot.esp32c3: SPI Mode : DIO I (41) 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 (62) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000 I (70) boot: 1 nvs WiFi data 01 02 00010000 00006000 I (77) boot: 2 nvs_keys NVS keys 01 04 00016000 00001000 I (85) boot: 3 otadata OTA data 01 00 00017000 00002000 I (92) boot: 4 phy_init RF data 01 01 00019000 00001000 I (100) boot: 5 ota_0 OTA app 00 10 00020000 001e0000 I (107) boot: 6 ota_1 OTA app 00 11 00200000 001e0000 I (115) boot: 7 fctry WiFi data 01 02 003e0000 00006000 I (122) boot: End of partition table I (127) esp_image: segment 0: paddr=00020020 vaddr=3c100020 size=2f1f0h (193008) map I (165) esp_image: segment 1: paddr=0004f218 vaddr=3fc91c00 size=00e00h ( 3584) load I (166) esp_image: segment 2: paddr=00050020 vaddr=42000020 size=f6b74h (1010548) map I (329) esp_image: segment 3: paddr=00146b9c vaddr=3fc92a00 size=02918h ( 10520) load I (331) esp_image: segment 4: paddr=001494bc vaddr=40380000 size=11a9ch ( 72348) load I (348) esp_image: segment 5: paddr=0015af60 vaddr=50000010 size=00010h ( 16) load I (354) boot: Loaded app from partition at offset 0x20000 I (354) boot: Disabling RNG early entropy source... I (368) cpu_start: Pro cpu up. I (378) cpu_start: Pro cpu start user code I (378) cpu_start: cpu freq: 160000000 I (378) cpu_start: Application information: I (381) cpu_start: Project name: light I (385) cpu_start: App version: v1.0 I (390) cpu_start: Compile time: Feb 9 2023 11:46:51 I (396) cpu_start: ELF file SHA256: 4e8cd70664255155... I (402) cpu_start: ESP-IDF: v4.4.3 I (407) heap_init: Initializing. RAM available for dynamic allocation: I (414) heap_init: At 3FCA5D90 len 00036980 (218 KiB): DRAM I (420) heap_init: At 3FCDC710 len 00002950 (10 KiB): STACK/DRAM I (427) heap_init: At 50000020 len 00001FE0 (7 KiB): RTCRAM I (434) spi_flash: detected chip: generic I (438) spi_flash: flash io: dio I (443) sleep: Configure to isolate all GPIO pins in sleep state I (449) sleep: Enable automatic switching of GPIO sleep configuration I (456) coexist: coexist rom version 9387209 I (461) cpu_start: Starting scheduler. I (488) led_driver_ws2812: Initializing light driver I (488) gpio: GPIO[9]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:0  W (498) esp_matter_core: Command 0x0047 on cluster 0x0300 already exists. Not creating again. I (498) app_main: Light created with endpoint_id 1 I (508) pp: pp rom version: 9387209 I (508) net80211: net80211 rom version: 9387209 I (528) wifi:wifi driver task: 3fcb1ec8, prio:23, stack:6656, core=0 I (528) system_api: Base MAC address is not set I (528) system_api: read default base MAC address from EFUSE I (538) wifi:wifi firmware version: 8cb87ff I (538) wifi:wifi certification version: v7.0 I (538) wifi:config NVS flash: enabled I (548) wifi:config nano formating: disabled I (548) wifi:Init data frame dynamic rx buffer num: 32 I (548) wifi:Init management frame dynamic rx buffer num: 32 I (558) wifi:Init management short buffer num: 32 I (558) wifi:Init dynamic tx buffer num: 32 I (568) wifi:Init static tx FG buffer num: 2 I (568) wifi:Init static rx buffer size: 1600 I (578) wifi:Init static rx buffer num: 10 I (578) wifi:Init dynamic rx buffer num: 32 I (578) wifi_init: rx ba win: 6 I (588) wifi_init: tcpip mbox: 32 I (588) wifi_init: udp mbox: 6 I (588) wifi_init: tcp mbox: 6 I (598) wifi_init: tcp tx win: 5744 I (598) wifi_init: tcp rx win: 5744 I (608) wifi_init: tcp mss: 1440 I (608) wifi_init: WiFi IRAM OP enabled I (618) wifi_init: WiFi RX IRAM OP enabled I (628) chip[DL]: NVS set: chip-counters/reboot-count = 3 (0x3) I (628) CHIP[DL]: WiFi station already provisioned, not initializing BLE W (628) BTDM_INIT: esp_bt_mem_release not implemented, return OK I (638) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (648) chip[DL]: Starting ESP WiFi layer I (648) phy_init: phy_version 912,d001756,Jun 2 2022,16:28:07 I (688) wifi:mode : sta (7c:df:a1:c2:10:14) I (688) wifi:enable tsf W (688) wifi:Haven't to connect to a suitable AP now! I (688) chip[DL]: Attempting to connect WiFi station interface I (698) chip[DL]: WiFi station state change: NotConnected -> Connecting I (708) chip[DL]: Done driving station state, nothing else to do... I (708) chip[SVR]: Subscription persistence not supported I (718) chip[SVR]: Server initializing... I (718) chip[TS]: Last Known Good Time: 2023-02-09T11:47:23 I (728) chip[FP]: Fabric index 0x1 was retrieved from storage. Compressed FabricId 0x25360332D3C029DF, FabricId 0x0000000000000001, NodeId 0x0000000000000001, VendorId 0xFFF1 I (748) chip[DMG]: AccessControl: initializing I (748) chip[DMG]: Examples::AccessControlDelegate::Init I (758) chip[DMG]: AccessControl: setting I (758) chip[DMG]: DefaultAclStorage: initializing I (768) chip[DMG]: DefaultAclStorage: 1 entries loaded I (788) chip[ZCL]: Using ZAP configuration... I (788) esp_matter_cluster: Cluster plugin init common callback I (788) chip[DMG]: AccessControlCluster: initializing I (798) chip[ZCL]: 0x3c10bc10ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (798) chip[ZCL]: Initiating Admin Commissioning cluster. E (808) chip[ZCL]: Duplicate attribute override registration failed I (818) chip[SVR]: Fabric already commissioned. Disabling BLE advertisement I (828) chip[IN]: CASE Server enabling CASE session setups I (828) chip[SVR]: Joining Multicast groups I (838) chip[SVR]: Server Listening... I (838) esp_matter_core: Dynamic endpoint 0 added I (848) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0003's Attribute 0x0001 is 0 ********** I (858) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0004's Attribute 0x0000 is 128 ********** I (868) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0004's Attribute 0xFFFC is ********** I (878) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0005's Attribute 0x0000 is 0 ********** I (888) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0xFFFC is 1 ********** I (898) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x4003 is null ********** I (908) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x0000 is 1 ********** I (918) chip[ZCL]: On/Off set value: 1 1 I (928) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0006's Attribute 0x0000 is 1 ********** I (938) chip[ZCL]: On/off already set to new value I (938) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0002 is 1 ********** I (948) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0003 is 254 ********** I (958) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0xFFFC is 3 ********** I (968) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0000 is 64 ********** I (978) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x4000 is 64 ********** I (988) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0008's Attribute 0x0000 is 64 ********** I (1008) led_driver_ws2812: led set r:0, g:0, b:0 I (1008) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0300's Attribute 0x4010 is null ********** I (1018) esp_matter_core: Dynamic endpoint 1 added E (1028) chip[DL]: Long dispatch time: 316 ms, for event type 2 W (1028) wifi:Haven't to connect to a suitable AP now! E (1038) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F I (1048) chip[DL]: WIFI_EVENT_STA_START I (1048) phy: chan:1,max_power:80 I (1058) phy: chan:2,max_power:80 I (1058) phy: chan:3,max_power:80 I (1068) phy: chan:4,max_power:80 I (1068) phy: chan:5,max_power:80 I (1068) phy: chan:6,max_power:80 I (1078) phy: chan:7,max_power:80 I (1078) phy: chan:8,max_power:80 I (1078) phy: chan:9,max_power:80 I (1088) phy: chan:10,max_power:80 I (1088) phy: chan:11,max_power:80 I (1098) phy: chan:12,max_power:80 I (1098) phy: chan:13,max_power:80 I (1108) phy: chan:14,max_power:80 W (1108) wifi:Haven't to connect to a suitable AP now! I (1108) chip[DL]: Attempting to connect WiFi station interface E (1118) wifi:sta is connecting, return error E (1118) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN I (1128) led_driver_ws2812: led set r:0, g:0, b:0 I (1138) led_driver_ws2812: led set r:0, g:0, b:0 I (1138) led_driver_ws2812: led set r:25, g:20, b:16 \00\00\00\00> \00I (3108) wifi:new:<11,0>, old:<1,0>, ap:<255,255>, sta:<11,0>, prof:1 I (3108) wifi:state: init -> auth (b0) I (3878) wifi:state: auth -> assoc (0) I (3888) wifi:state: assoc -> run (10) I (3918) wifi:connected with ES_Staff, aid = 17, channel 11, BW20, bssid = 68:d7:9a:81:26:1e I (3918) wifi:security: WPA3-SAE, phy: bgn, rssi: -58 I (3928) wifi:pm start, type: 1 I (3928) wifi:set rx beacon pti, rx_bcn_pti: 14, bcn_timeout: 14, mt_pti: 25000, mt_time: 10000 I (3928) chip[DL]: WIFI_EVENT_STA_CONNECTED I (3948) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (3948) phy: chan:1,max_power:80 I (3958) phy: chan:2,max_power:80 I (3958) phy: chan:3,max_power:80 I (3958) phy: chan:4,max_power:80 I (3968) phy: chan:5,max_power:80 I (3968) phy: chan:6,max_power:80 I (3968) phy: chan:7,max_power:80 I (3978) phy: chan:8,max_power:80 I (3978) phy: chan:9,max_power:80 I (3978) phy: chan:10,max_power:80 I (3988) phy: chan:11,max_power:80 I (3988) phy: chan:12,max_power:80 I (3988) phy: chan:13,max_power:80 I (3998) phy: chan:14,max_power:80 I (3988) wifi:BcnInt:102400, DTIM:1 I (4008) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (4018) chip[DL]: WiFi station interface connected I (4018) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (4028) chip[DL]: Done driving station state, nothing else to do... I (4028) chip[DL]: Updating advertising data I (5508) chip[DL]: IP_EVENT_GOT_IP6 I (5508) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:7edf:a1ff:fec2:1014 I (5518) app_main: Interface IP Address changed I (5518) chip[DIS]: Updating services using commissioning mode 0 I (5528) chip[DIS]: CHIP minimal mDNS started advertising. I (5538) chip[DIS]: Advertise operational node 25360332D3C029DF-0000000000000001 I (5548) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 25360332D3C029DF-0000000000000001. I (5548) chip[DIS]: mDNS service published: _matter._tcp I (6028) ROUTE_HOOK: Received RIO I (6028) ROUTE_HOOK: prefix FDB1:B465:854B:1:: lifetime 1800 W (7028) wifi:idx:0 (ifx:0, 68:d7:9a:81:26:1e), tid:6, ssn:2, winSize:64 I (7508) chip[DL]: IP_EVENT_GOT_IP6 I (7508) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fd7b:45e9:1c24:b879:7edf:a1ff:fec2:1014 I (7518) ROUTE_HOOK: Hook already installed on netif, skip... I (7518) app_main: Interface IP Address changed I (7528) chip[DIS]: Updating services using commissioning mode 0 I (7528) chip[DIS]: CHIP minimal mDNS started advertising. I (7548) chip[DIS]: Advertise operational node 25360332D3C029DF-0000000000000001 I (7548) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 25360332D3C029DF-0000000000000001. I (7568) chip[DIS]: mDNS service published: _matter._tcp I (8008) esp_netif_handlers: sta ip: 192.168.20.225, mask: 255.255.255.0, gw: 192.168.20.1 I (8008) chip[DL]: IP_EVENT_STA_GOT_IP I (8008) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.20.225/255.255.255.0 gateway 192.168.20.1 I (8018) chip[DL]: IPv4 Internet connectivity ESTABLISHED I (8028) app_main: Interface IP Address changed I (8028) chip[DIS]: Updating services using commissioning mode 0 I (8048) chip[DIS]: CHIP minimal mDNS started advertising. I (8058) chip[DIS]: Advertise operational node 25360332D3C029DF-0000000000000001 I (8058) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 25360332D3C029DF-0000000000000001. I (8078) chip[DIS]: mDNS service published: _matter._tcp W (35398) wifi:idx:1 (ifx:0, 68:d7:9a:81:26:1e), tid:0, ssn:4, winSize:64 I (35418) chip[EM]: >>> [E:62684r M:262290422] (U) Msg RX from 0:6E45CE0971D6DDA5 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I (35428) chip[IN]: CASE Server received Sigma1 message. Starting handshake. EC 0x3fc9a578 I (35438) chip[IN]: CASE Server disabling CASE session setups I (35448) chip[EM]: <<< [E:62684r M:124917664 (Ack:262290422)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (35458) chip[IN]: (U) Sending msg 124917664 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (35468) chip[SC]: Received Sigma1 msg I (35478) chip[SC]: CASE matched destination ID: fabricIndex 1, NodeID 0x0000000000000001 I (35968) chip[EM]: <<< [E:62684r M:124917665 (Ack:262290422)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:31 (SecureChannel:CASE_Sigma2) I (35988) chip[IN]: (U) Sending msg 124917665 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (35988) chip[SC]: Sent Sigma2 msg E (35998) chip[DL]: Long dispatch time: 579 ms, for event type 3 I (35998) chip[EM]: >>> [E:62684r M:262290422] (U) Msg RX from 0:6E45CE0971D6DDA5 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I (36018) chip[EM]: <<< [E:62684r M:124917666 (Ack:262290422)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (36028) chip[IN]: (U) Sending msg 124917666 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (36228) chip[EM]: >>> [E:62684r M:262290422] (U) Msg RX from 0:6E45CE0971D6DDA5 [0000] --- Type 0000:30 (SecureChannel:CASE_Sigma1) I (36228) chip[EM]: <<< [E:62684r M:124917667 (Ack:262290422)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (36258) chip[IN]: (U) Sending msg 124917667 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (36268) chip[EM]: >>> [E:62684r M:262290423 (Ack:124917665)] (U) Msg RX from 0:6E45CE0971D6DDA5 [0000] --- Type 0000:32 (SecureChannel:CASE_Sigma3) I (36278) chip[EM]: <<< [E:62684r M:124917668 (Ack:262290423)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:10 (SecureChannel:StandaloneAck) I (36298) chip[IN]: (U) Sending msg 124917668 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (36308) chip[SC]: Received Sigma3 msg E (36308) chip[SC]: The device does not support GetClock_RealTimeMS() API: 6c. Falling back to Last Known Good UTC Time I (37348) chip[EM]: <<< [E:62684r M:124917669 (Ack:262290423)] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I (37358) chip[IN]: (U) Sending msg 124917669 to IP address 'UDP:[FD7B:45E9:1C24:B879:AF5E:AF4D:3161:B52A%st1]:47599' I (37378) chip[SC]: SecureSession[0x3fc98d50]: Moving from state 'kEstablishing' --> 'kActive' I (37378) chip[IN]: CASE Session established to peer: <000000000001B669, 1> I (37388) chip[IN]: CASE Server enabling CASE session setups E (37388) chip[DL]: Long dispatch time: 1123 ms, for event type 3 I (37558) chip[EM]: >>> [E:62685r M:159788968] (S) Msg RX from 1:000000000001B669 [29DF] --- Type 0001:08 (IM:InvokeCommandRequest) I (37568) esp_matter_command: Received command 0x000A for enpoint 0x0000's cluster 0x0000003E I (37578) chip[ZCL]: OpCreds: Received a RemoveFabric Command for FabricIndex 0x1 I (37588) chip[EM]: <<< [E:62685r M:179354693 (Ack:159788968)] (S) Msg TX to 1:000000000001B669 [29DF] --- Type 0000:10 (SecureChannel:StandaloneAck) I (37598) chip[IN]: (S) Sending msg 179354693 on secure session with LSID: 5955 I (37628) chip[FP]: Fabric (0x1) deleted. I (37628) chip[ZCL]: OpCreds: Fabric index 0x1 was removed I (37628) chip[DIS]: Updating services using commissioning mode 0 I (37638) chip[DIS]: CHIP minimal mDNS started advertising. I (37688) chip[ZCL]: OpCreds: RemoveFabric successful I (37688) chip[SC]: SecureSession[0x3fc98d50]: Moving from state 'kActive' --> 'kPendingEviction' I (37698) chip[EM]: <<< [E:62685r M:179354694 (Ack:159788968)] (S) Msg TX to 1:000000000001B669 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (37708) chip[IN]: (S) Sending msg 179354694 on secure session with LSID: 5955 ----------------------------------- ----------------------------------- vaishali@vaishali-ThinkPad-P14s-Gen-2a:~/esp/esp-matter/examples/light$ avahi-browse -rt _matter._tcp + wlp3s0 IPv6 D9992EE06EB4FB1F-0000000000000001 _matter._tcp local + wlp3s0 IPv6 25360332D3C029DF-0000000000000001 _matter._tcp local + wlp3s0 IPv6 C29BCA5D7483FCAB-25BDDA014E4CD981 _matter._tcp local + wlp3s0 IPv4 6B97CDB00B6E8700-0000000000000001 _matter._tcp local + wlp3s0 IPv4 C29BCA5D7483FCAB-25BDDA014E4CD981 _matter._tcp local = wlp3s0 IPv6 D9992EE06EB4FB1F-0000000000000001 _matter._tcp local hostname = [7CDFA18782D8.local] address = [fe80::7edf:a1ff:fe87:82d8] port = [5540] txt = ["T=1"] = wlp3s0 IPv6 25360332D3C029DF-0000000000000001 _matter._tcp local hostname = [7CDFA1C21014.local] address = [192.168.20.225] port = [5540] txt = ["T=1"] = wlp3s0 IPv4 6B97CDB00B6E8700-0000000000000001 _matter._tcp local hostname = [7CDFA18782D8.local] address = [192.168.20.150] port = [5540] txt = ["T=1"] + wlp3s0 IPv6 C8ED793D859D5B09-000000000004FE44 _matter._tcp local + wlp3s0 IPv4 D9992EE06EB4FB1F-0000000000000001 _matter._tcp local = wlp3s0 IPv6 C8ED793D859D5B09-000000000004FE44 _matter._tcp local hostname = [7CDFA1BCFA0C.local] address = [192.168.20.179] port = [5540] txt = ["T=1"] = wlp3s0 IPv4 D9992EE06EB4FB1F-0000000000000001 _matter._tcp local hostname = [7CDFA18782D8.local] address = [192.168.20.150] port = [5540] txt = ["T=1"] + wlp3s0 IPv4 6CF9F4C9A56A07D6-0000000074576B42 _matter._tcp local + wlp3s0 IPv4 AD61242D23AD3D06-00000000717D7C90 _matter._tcp local = wlp3s0 IPv4 6CF9F4C9A56A07D6-0000000074576B42 _matter._tcp local hostname = [C049EF816760.local] address = [192.168.20.103] port = [5540] txt = ["T=1"] = wlp3s0 IPv4 AD61242D23AD3D06-00000000717D7C90 _matter._tcp local hostname = [C049EF816760.local] address = [192.168.20.103] port = [5540] txt = ["T=1"] + wlp3s0 IPv4 C29BCA5D7483FCAB-0000000036FC4802 _matter._tcp local + wlp3s0 IPv4 EC9E7F2457FCF0F2-00000000EC5E247E _matter._tcp local = wlp3s0 IPv4 C29BCA5D7483FCAB-0000000036FC4802 _matter._tcp local hostname = [7CDFA1C21990.local] address = [192.168.20.110] port = [5540] txt = ["T=1"] = wlp3s0 IPv4 EC9E7F2457FCF0F2-00000000EC5E247E _matter._tcp local hostname = [7CDFA1C21990.local] address = [192.168.20.110] port = [5540] txt = ["T=1"] Failed to resolve service 'C29BCA5D7483FCAB-25BDDA014E4CD981' of type '_matter._tcp' in domain 'local': Timeout reached Failed to resolve service 'C29BCA5D7483FCAB-25BDDA014E4CD981' of type '_matter._tcp' in domain 'local': Timeout reached -------------------------------------------------- -------------------------------------------------- vaishali@vaishali-ThinkPad-P14s-Gen-2a:~/esp/esp-matter/examples/light$ idf.py erase-flash Executing action: erase-flash Serial port /dev/ttyUSB0 Connecting.... Detecting chip type... ESP32-C3 Running esptool.py in directory /home/vaishali/esp/esp-matter/examples/light/build Executing "/home/vaishali/.espressif/python_env/idf4.4_py3.10_env/bin/python /home/vaishali/esp/esp-idf/components/esptool_py/esptool/esptool.py -p /dev/ttyUSB0 -b 460800 --before default_reset --after hard_reset --chip esp32c3 erase_flash"... esptool.py v3.3.2 Serial port /dev/ttyUSB0 Connecting.... Chip is ESP32-C3 (revision 3) Features: Wi-Fi Crystal is 40MHz MAC: 7c:df:a1:c2:10:14 Uploading stub... Running stub... Stub running... Changing baud rate to 460800 Changed. Erasing flash (this may take a while)... Chip erase completed successfully in 19.0s Hard resetting via RTS pin... Done