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:0x3fcd6100,len:0x16b4 load:0x403ce000,len:0x930 load:0x403d0000,len:0x2d28 entry 0x403ce000 I (30) boot: ESP-IDF v4.4.1 2nd stage bootloader I (30) boot: compile time 08:45:10 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 nvs WiFi data 01 02 00009000 00006000 I (70) boot: 1 otadata OTA data 01 00 0000f000 00002000 I (77) boot: 2 phy_init RF data 01 01 00011000 00001000 I (85) boot: 3 ota_0 OTA app 00 10 00020000 00177000 I (92) boot: 4 ota_1 OTA app 00 11 001a0000 00177000 I (100) boot: 5 ot_storage Unknown data 01 3a 00317000 00002000 I (107) boot: End of partition table I (112) esp_image: segment 0: paddr=00020020 vaddr=3c110020 size=375e0h (226784) map I (155) esp_image: segment 1: paddr=00057608 vaddr=3fc8fa00 size=03918h ( 14616) load I (158) esp_image: segment 2: paddr=0005af28 vaddr=40380000 size=050f0h ( 20720) load I (165) esp_image: segment 3: paddr=00060020 vaddr=42000020 size=103034h (1060916) map I (336) esp_image: segment 4: paddr=0016305c vaddr=403850f0 size=0a890h ( 43152) load I (344) esp_image: segment 5: paddr=0016d8f4 vaddr=50000010 size=00010h ( 16) load I (349) boot: Loaded app from partition at offset 0x20000 I (349) boot: Disabling RNG early entropy source... I (365) cpu_start: Pro cpu up. I (373) cpu_start: Pro cpu start user code I (373) cpu_start: cpu freq: 160000000 I (373) cpu_start: Application information: I (376) cpu_start: Project name: chip-all-clusters-app I (382) cpu_start: App version: TE9-1148-g51e41f9df I (388) cpu_start: Compile time: Jul 15 2022 08:44:45 I (394) cpu_start: ELF file SHA256: 8d99985f3daedcc7... I (400) cpu_start: ESP-IDF: v4.4.1 I (405) heap_init: Initializing. RAM available for dynamic allocation: I (412) heap_init: At 3FCA5020 len 0001AFE0 (107 KiB): DRAM I (418) heap_init: At 3FCC0000 len 0001F060 (124 KiB): STACK/DRAM I (425) heap_init: At 50000020 len 00001FE0 (7 KiB): RTCRAM I (432) spi_flash: detected chip: generic I (436) spi_flash: flash io: dio I (441) sleep: Configure to isolate all GPIO pins in sleep state I (447) sleep: Enable automatic switching of GPIO sleep configuration I (454) coexist: coexist rom version 9387209 I (459) cpu_start: Starting scheduler. I (471) all-clusters-app: ================================================== I (471) all-clusters-app: chip-esp32-all-cluster-demo starting I (481) all-clusters-app: ================================================== I (661) pp: pp rom version: 9387209 I (661) net80211: net80211 rom version: 9387209 I (671) wifi:wifi driver task: 3fcb0154, prio:23, stack:6656, core=0 I (671) system_api: Base MAC address is not set I (681) system_api: read default base MAC address from EFUSE I (681) wifi:wifi firmware version: 63017e0 I (681) wifi:wifi certification version: v7.0 I (691) wifi:config NVS flash: enabled I (691) wifi:config nano formating: disabled I (691) wifi:Init data frame dynamic rx buffer num: 32 I (701) wifi:Init management frame dynamic rx buffer num: 32 I (701) wifi:Init management short buffer num: 32 I (701) wifi:Init dynamic tx buffer num: 32 I (711) wifi:Init static tx FG buffer num: 2 I (721) wifi:Init static rx buffer size: 1600 I (721) wifi:Init static rx buffer num: 10 I (731) wifi:Init dynamic rx buffer num: 32 I (741) wifi_init: rx ba win: 6 I (741) wifi_init: tcpip mbox: 32 I (741) wifi_init: udp mbox: 6 I (741) wifi_init: tcp mbox: 6 I (751) wifi_init: tcp tx win: 5744 I (751) wifi_init: tcp rx win: 5744 I (751) wifi_init: tcp mss: 1440 I (761) wifi_init: WiFi IRAM OP enabled I (761) wifi_init: WiFi RX IRAM OP enabled I (791) chip[DL]: NVS set: chip-counters/reboot-count = 2 (0x2) I (791) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (801) all-clusters-app: ------------------------Starting App Task--------------------------- W (801) BTDM_INIT: esp_bt_controller_mem_release not implemented, return OK I (811) BTDM_INIT: BT controller compile version [d913766] I (811) phy_init: phy_version 909,156dee4,Apr 7 2022,20:27:09 I (801) app-task: This is ESP32 chip with 1 CPU cores, WiFi/BLE, I (831) app-task: silicon revision 3, I (851) app-task: 4MB external flash I (851) chip[SVR]: SetupQRCode: [MT:-24J042C00KA0648G00] I (871) chip[SVR]: Copy/paste the below URL in a browser to see the QR Code: I (871) chip[SVR]: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J042C00KA0648G00 I (891) chip[SVR]: Manual pairing code: [34970112332] I (891) app-task: App Task started > I (941) BTDM_INIT: Bluetooth MAC: 84:f7:03:60:fb:e6 I (951) NimBLE: GAP procedure initiated: stop advertising. I (951) CHIP[DL]: BLE host-controller synced I (1451) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840) I (1451) NimBLE: GAP procedure initiated: advertise; I (1461) NimBLE: disc_mode=2 I (1461) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (1471) NimBLE: I (1471) chip[DL]: CHIPoBLE advertising started E (1481) chip[DL]: Long dispatch time: 681 ms, for event type 2 I (1481) chip[DL]: Starting ESP WiFi layer I (1491) wifi:mode : sta (84:f7:03:60:fb:e4) I (1501) wifi:enable tsf W (1501) wifi:Haven't to connect to a suitable AP now! I (1501) chip[DL]: Done driving station state, nothing else to do... I (1511) phy: chan:1,max_power:80 I (1511) phy: chan:2,max_power:80 I (1521) phy: chan:3,max_power:80 I (1521) phy: chan:4,max_power:80 I (1521) phy: chan:5,max_power:80 I (1531) phy: chan:6,max_power:80 I (1531) phy: chan:7,max_power:80 I (1531) phy: chan:8,max_power:80 I (1541) phy: chan:9,max_power:80 I (1541) phy: chan:10,max_power:80 I (1541) phy: chan:11,max_power:80 I (1561) phy: chan:12,max_power:80 I (1561) phy: chan:13,max_power:80 I (1561) phy: chan:14,max_power:80 W (1571) wifi:Haven't to connect to a suitable AP now! I (1571) chip[DL]: Done driving station state, nothing else to do... I (1581) phy: chan:1,max_power:80 I (1581) phy: chan:2,max_power:80 I (1581) phy: chan:3,max_power:80 I (1591) phy: chan:4,max_power:80 I (1591) phy: chan:5,max_power:80 I (1591) phy: chan:6,max_power:80 I (1601) phy: chan:7,max_power:80 I (1601) phy: chan:8,max_power:80 I (1601) phy: chan:9,max_power:80 I (1611) phy: chan:10,max_power:80 I (1611) phy: chan:11,max_power:80 I (1621) phy: chan:12,max_power:80 I (1631) phy: chan:13,max_power:80 I (1631) phy: chan:14,max_power:80 I (1641) chip[SVR]: Server initializing... I (1641) chip[TS]: Last Known Good Time: 2022-07-15T09:07:11 I (1641) chip[DMG]: AccessControl: initializing I (1651) chip[DMG]: Examples::AccessControlDelegate::Init I (1651) chip[DMG]: AccessControl: setting I (1661) chip[DMG]: DefaultAclStorage: initializing I (1661) chip[DMG]: DefaultAclStorage: 0 entries loaded I (1691) chip[ZCL]: Using ZAP configuration... I (1701) chip[DMG]: AccessControlCluster: initializing I (1701) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000' I (1721) app-devicecallbacks: Unhandled cluster ID: 48 I (1721) app-devicecallbacks: Current free heap: 112644 I (1731) chip[ZCL]: Initiating Admin Commissioning cluster. I (1731) chip[ZCL]: Door Lock server initialized I (1741) chip[ZCL]: Initialize PCC Plugin Server Cluster. I (1741) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x00', Attribute ID: '0x0001' I (1751) app-devicecallbacks: Current free heap: 112616 I (1771) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0x0000' I (1781) app-devicecallbacks: Unhandled cluster ID: 4 I (1781) app-devicecallbacks: Current free heap: 112616 I (1791) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0xfffc' I (1801) app-devicecallbacks: Unhandled cluster ID: 4 I (1801) app-devicecallbacks: Current free heap: 112616 I (1811) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x002b', EndPoint ID: '0x00', Attribute ID: '0x0000' I (1831) app-devicecallbacks: Unhandled cluster ID: 43 I (1831) app-devicecallbacks: Current free heap: 112616 I (1841) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x01', Attribute ID: '0x0001' I (1851) app-devicecallbacks: Current free heap: 112616 I (1851) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0x0000' I (1861) app-devicecallbacks: Unhandled cluster ID: 4 I (1871) app-devicecallbacks: Current free heap: 112616 I (1871) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0xfffc' I (1891) app-devicecallbacks: Unhandled cluster ID: 4 I (1901) app-devicecallbacks: Current free heap: 112616 I (1901) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0005', EndPoint ID: '0x01', Attribute ID: '0x0000' I (1911) app-devicecallbacks: Unhandled cluster ID: 5 I (1921) app-devicecallbacks: Current free heap: 112616 I (1921) chip[ZCL]: On/Off set value: 1 0 I (1931) chip[ZCL]: On/off already set to new value I (1931) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0008', EndPoint ID: '0x01', Attribute ID: '0x0000' I (1951) app-devicecallbacks: Current free heap: 112616 I (1961) chip[ZCL]: Window Covering Cluster init I (1961) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0102', EndPoint ID: '0x01', Attribute ID: '0x0007' I (1971) app-devicecallbacks: Unhandled cluster ID: 258 I (1971) app-devicecallbacks: Current free heap: 112616 I (1981) chip[ZCL]: WC POST ATTRIBUTE=7 I (1981) chip[ZCL]: ConfigStatus 0x1B Operational=1 OnlineReserved=1 I (1991) chip[ZCL]: Lift(PA=1 Encoder=0 Reversed=0) Tilt(PA=1 Encoder=0) I (2011) chip[ZCL]: Initialize PCC Server Cluster [EP:1] I (2011) chip[ZCL]: Constant Pressure Supported I (2011) chip[ZCL]: PCC Server: Constant Proportional Pressure Supported I (2021) chip[ZCL]: PCC Server: Constant Flow Supported I (2031) chip[ZCL]: PCC Server: Constant Temperature Supported I (2031) chip[ZCL]: PCC Server: Constant Speed Supported I (2041) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0007' I (2051) app-devicecallbacks: Unhandled AttributeId ID: '0x0007 I (2051) app-devicecallbacks: Current free heap: 112616 I (2071) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0008' I (2081) app-devicecallbacks: Unhandled AttributeId ID: '0x0008 I (2081) app-devicecallbacks: Current free heap: 112616 I (2091) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x4001' I (2101) app-devicecallbacks: Unhandled AttributeId ID: '0x4001 I (2101) app-devicecallbacks: Current free heap: 112616 I (2111) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0001' I (2121) app-devicecallbacks: Unhandled cluster ID: 1030 I (2131) app-devicecallbacks: Current free heap: 112616 I (2141) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0002' I (2151) app-devicecallbacks: Unhandled cluster ID: 1030 I (2151) app-devicecallbacks: Current free heap: 112616 I (2161) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x02', Attribute ID: '0x0000' I (2171) app-devicecallbacks: Unhandled cluster ID: 4 I (2171) app-devicecallbacks: Current free heap: 112616 I (2181) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x02', Attribute ID: '0xfffc' I (2201) app-devicecallbacks: Unhandled cluster ID: 4 I (2201) app-devicecallbacks: Current free heap: 112616 I (2211) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0001' I (2221) app-devicecallbacks: Unhandled cluster ID: 1030 I (2221) app-devicecallbacks: Current free heap: 112616 I (2231) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0002' I (2241) app-devicecallbacks: Unhandled cluster ID: 1030 I (2251) app-devicecallbacks: Current free heap: 112616 I (2261) chip[DIS]: Updating services using commissioning mode 1 I (2261) chip[DIS]: CHIP minimal mDNS started advertising. I (2271) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15 I (2281) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'. I (2281) chip[DIS]: mDNS service published: _matterc._udp I (2291) chip[IN]: CASE Server enabling CASE session setups I (2301) chip[SVR]: Joining Multicast groups I (2311) chip[SVR]: Server Listening... E (2311) chip[DL]: Long dispatch time: 673 ms, for event type 2 I (2311) app-devicecallbacks: Current free heap: 111900 I (2321) chip[DL]: WIFI_EVENT_STA_START I (2321) phy: chan:1,max_power:80 I (2331) phy: chan:2,max_power:80 I (2331) phy: chan:3,max_power:80 I (2331) phy: chan:4,max_power:80 I (2351) phy: chan:5,max_power:80 I (2351) phy: chan:6,max_power:80 I (2351) phy: chan:7,max_power:80 I (2361) phy: chan:8,max_power:80 I (2361) phy: chan:9,max_power:80 I (2361) phy: chan:10,max_power:80 I (2371) phy: chan:11,max_power:80 I (2371) phy: chan:12,max_power:80 I (2371) phy: chan:13,max_power:80 I (2381) phy: chan:14,max_power:80 W (2381) wifi:Haven't to connect to a suitable AP now! I (2381) chip[DL]: Done driving station state, nothing else to do... I (2391) app-devicecallbacks: Current free heap: 111900 I (2401) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840) I (2411) chip[DL]: Device already advertising, stop active advertisement and restart I (2421) NimBLE: GAP procedure initiated: stop advertising. I (2431) NimBLE: GAP procedure initiated: advertise; I (2431) NimBLE: disc_mode=2 I (2431) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2441) NimBLE: I (2441) chip[SVR]: Cannot load binding table: a0 I (32261) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable, device name MATTER-3840) I (32261) chip[DL]: Device already advertising, stop active advertisement and restart I (32271) NimBLE: GAP procedure initiated: stop advertising. I (32281) NimBLE: GAP procedure initiated: advertise; I (32281) NimBLE: disc_mode=2 I (32281) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800 I (32291) NimBLE: I (71701) chip[DL]: BLE GAP connection established (con 1) I (71701) chip[DL]: CHIPoBLE advertising stopped I (71701) app-devicecallbacks: Current free heap: 111904 I (73041) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 1 12 I (73041) chip[BLE]: Releasing end point's BLE connection back to application. E (73051) chip[BLE]: HandleChipConnectionReceived failed, err = 417 E (73051) chip[BLE]: failed handle new chip BLE connection, status = 417 I (73141) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 1 notify = 1 I (73141) chip[DL]: CHIPoBLE subscribe received E (73141) chip[BLE]: no endpoint for sub recvd I (73151) app-devicecallbacks: CHIPoBLE connection established I (73161) app-devicecallbacks: Current free heap: 111888 I (87891) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 1 ) indicate = 0 notify = 0 I (87891) chip[DL]: CHIPoBLE unsubscribe received E (87891) chip[BLE]: no endpoint for unsub recvd I (87901) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840) I (87911) NimBLE: GAP procedure initiated: advertise; I (87931) NimBLE: disc_mode=2 I (87931) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (87941) NimBLE: I (87941) chip[DL]: CHIPoBLE advertising started I (87941) app-devicecallbacks: Current free heap: 111904 I (89591) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213) I (89591) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840) I (89601) chip[DL]: Device already advertising, stop active advertisement and restart I (89611) NimBLE: GAP procedure initiated: stop advertising. I (89611) NimBLE: GAP procedure initiated: advertise; I (89631) NimBLE: disc_mode=2 I (89631) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (89641) NimBLE: I (89641) app-devicecallbacks: CHIPoBLE disconnected I (89641) app-devicecallbacks: Current free heap: 111904