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 00:29:42
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=3c100020 size=35410h (218128) map
I (154) esp_image: segment 1: paddr=00055438 vaddr=3fc8f600 size=03908h ( 14600) load
I (157) esp_image: segment 2: paddr=00058d48 vaddr=40380000 size=072d0h ( 29392) load
I (165) esp_image: segment 3: paddr=00060020 vaddr=42000020 size=f8de8h (1019368) map
I (328) esp_image: segment 4: paddr=00158e10 vaddr=403872d0 size=082e8h ( 33512) load
I (334) esp_image: segment 5: paddr=00161100 vaddr=50000010 size=00010h ( 16) load
I (339) boot: Loaded app from partition at offset 0x20000
I (340) boot: Disabling RNG early entropy source...
I (357) cpu_start: Pro cpu up.
I (365) cpu_start: Pro cpu start user code
I (365) cpu_start: cpu freq: 160000000
I (365) cpu_start: Application information:
I (368) cpu_start: Project name: chip-all-clusters-app
I (374) cpu_start: App version: TE9-1048-g126f6b291-dirty
I (380) cpu_start: Compile time: Jul 12 2022 07:56:50
I (387) cpu_start: ELF file SHA256: d3b58a68968b94be...
I (393) cpu_start: ESP-IDF: v4.4.1
I (397) heap_init: Initializing. RAM available for dynamic allocation:
I (405) heap_init: At 3FCA40D0 len 0001BF30 (111 KiB): DRAM
I (411) heap_init: At 3FCC0000 len 0001F060 (124 KiB): STACK/DRAM
I (418) heap_init: At 50000020 len 00001FE0 (7 KiB): RTCRAM
I (424) spi_flash: detected chip: generic
I (429) spi_flash: flash io: dio
I (434) sleep: Configure to isolate all GPIO pins in sleep state
I (439) sleep: Enable automatic switching of GPIO sleep configuration
I (447) coexist: coexist rom version 9387209
I (452) cpu_start: Starting scheduler.
I (463) all-clusters-app: ==================================================
I (463) all-clusters-app: chip-esp32-all-cluster-demo starting
I (473) all-clusters-app: ==================================================
I (483) pp: pp rom version: 9387209
I (483) net80211: net80211 rom version: 9387209
I (503) wifi:wifi driver task: 3fcad210, prio:23, stack:6656, core=0
I (503) system_api: Base MAC address is not set
I (503) system_api: read default base MAC address from EFUSE
I (513) wifi:wifi firmware version: 63017e0
I (513) wifi:wifi certification version: v7.0
I (513) wifi:config NVS flash: enabled
I (523) wifi:config nano formating: disabled
I (523) wifi:Init data frame dynamic rx buffer num: 32
I (523) wifi:Init management frame dynamic rx buffer num: 32
I (533) wifi:Init management short buffer num: 32
I (533) wifi:Init dynamic tx buffer num: 32
I (543) wifi:Init static tx FG buffer num: 2
I (543) wifi:Init static rx buffer size: 1600
I (553) wifi:Init static rx buffer num: 10
I (553) wifi:Init dynamic rx buffer num: 32
I (553) wifi_init: rx ba win: 6
I (563) wifi_init: tcpip mbox: 32
I (563) wifi_init: udp mbox: 6
I (573) wifi_init: tcp mbox: 6
I (573) wifi_init: tcp tx win: 5744
I (573) wifi_init: tcp rx win: 5744
I (583) wifi_init: tcp mss: 1440
I (583) wifi_init: WiFi IRAM OP enabled
I (593) wifi_init: WiFi RX IRAM OP enabled
I (603) chip[DL]: NVS set: chip-counters/reboot-count = 2 (0x2)
I (603) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (613) all-clusters-app: ------------------------Starting App Task---------------------------
W (613) BTDM_INIT: esp_bt_controller_mem_release not implemented, return OK
I (623) BTDM_INIT: BT controller compile version [d913766]
I (633) phy_init: phy_version 909,156dee4,Apr 7 2022,20:27:09
I (623) app-task: This is ESP32 chip with 1 CPU cores, WiFi/BLE,
I (643) app-task: silicon revision 3,
I (663) app-task: 4MB external flash

I (663) chip[SVR]: SetupQRCode: [MT:Y.K9042C00KA0648G00]
I (663) chip[SVR]: Copy/paste the below URL in a browser to see the QR Code:
I (663) chip[SVR]: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3AY.K9042C00KA0648G00
I (683) chip[SVR]: Manual pairing code: [34970112332]
I (683) app-task: App Task started
I (783) BTDM_INIT: Bluetooth MAC: 84:f7:03:60:fb:e6

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

I (783) CHIP[DL]: BLE host-controller synced
I (1283) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840)
I (1283) NimBLE: GAP procedure initiated: advertise;
I (1283) NimBLE: disc_mode=2
I (1283) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (1293) NimBLE:

I (1303) chip[DL]: CHIPoBLE advertising started
E (1303) chip[DL]: Long dispatch time: 689 ms, for event type 2
I (1313) chip[DL]: Starting ESP WiFi layer
I (1323) wifi:mode : sta (84:f7:03:60:fb:e4)
I (1323) wifi:enable tsf
W (1323) wifi:Haven't to connect to a suitable AP now!
I (1333) chip[DL]: Done driving station state, nothing else to do...
I (1333) phy: chan:1,max_power:80
I (1343) phy: chan:2,max_power:80
I (1343) phy: chan:3,max_power:80
I (1343) phy: chan:4,max_power:80
I (1353) phy: chan:5,max_power:80
I (1353) phy: chan:6,max_power:80
I (1363) phy: chan:7,max_power:80
I (1363) phy: chan:8,max_power:80
I (1363) phy: chan:9,max_power:80
I (1373) phy: chan:10,max_power:80
I (1373) phy: chan:11,max_power:80
I (1383) phy: chan:12,max_power:80
I (1383) phy: chan:13,max_power:80
I (1383) phy: chan:14,max_power:80
W (1393) wifi:Haven't to connect to a suitable AP now!
I (1393) chip[DL]: Done driving station state, nothing else to do...
I (1403) phy: chan:1,max_power:80
I (1403) phy: chan:2,max_power:80
I (1413) phy: chan:3,max_power:80
I (1413) phy: chan:4,max_power:80
I (1423) phy: chan:5,max_power:80
I (1423) phy: chan:6,max_power:80
I (1423) phy: chan:7,max_power:80
I (1433) phy: chan:8,max_power:80
I (1433) phy: chan:9,max_power:80
I (1443) phy: chan:10,max_power:80
I (1443) phy: chan:11,max_power:80
I (1443) phy: chan:12,max_power:80
I (1453) phy: chan:13,max_power:80
I (1453) phy: chan:14,max_power:80
I (1463) chip[SVR]: Server initializing...
I (1463) chip[TS]: Last Known Good Time: 2022-07-12T08:11:45
I (1473) chip[DMG]: AccessControl: initializing
I (1473) chip[DMG]: Examples::AccessControlDelegate::Init
I (1483) chip[DMG]: AccessControl: setting
I (1483) chip[DMG]: DefaultAclStorage: initializing
I (1493) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (1503) chip[ZCL]: Using ZAP configuration...
I (1513) chip[DMG]: AccessControlCluster: initializing
I (1513) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (1523) app-devicecallbacks: Unhandled cluster ID: 48
I (1533) app-devicecallbacks: Current free heap: 125380

E (1533) chip[ZCL]: GeneralDiagnostics: Failed to record BootReason event: 3
I (1543) chip[ZCL]: Initiating Admin Commissioning cluster.
I (1553) chip[ZCL]: Door Lock server initialized
I (1553) chip[ZCL]: Initialize PCC Plugin Server Cluster.
I (1563) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x00', Attribute ID: '0x0001'
I (1573) app-devicecallbacks: Current free heap: 125344

I (1583) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (1593) app-devicecallbacks: Unhandled cluster ID: 4
I (1593) app-devicecallbacks: Current free heap: 125344

I (1603) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x002b', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (1613) app-devicecallbacks: Unhandled cluster ID: 43
I (1623) app-devicecallbacks: Current free heap: 125344

I (1623) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x01', Attribute ID: '0x0001'
I (1643) app-devicecallbacks: Current free heap: 125344

I (1643) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (1653) app-devicecallbacks: Unhandled cluster ID: 4
I (1663) app-devicecallbacks: Current free heap: 125344

I (1673) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0005', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (1683) app-devicecallbacks: Unhandled cluster ID: 5
I (1683) app-devicecallbacks: Current free heap: 125344

I (1693) chip[ZCL]: On/Off set value: 1 0
I (1693) chip[ZCL]: On/off already set to new value
I (1703) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0008', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (1713) app-devicecallbacks: Current free heap: 125344

I (1723) chip[ZCL]: Window Covering Cluster init
I (1723) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0102', EndPoint ID: '0x01', Attribute ID: '0x0007'
I (1733) app-devicecallbacks: Unhandled cluster ID: 258
I (1743) app-devicecallbacks: Current free heap: 125344

I (1753) chip[ZCL]: WC POST ATTRIBUTE=7
I (1753) chip[ZCL]: ConfigStatus 0x1B Operational=1 OnlineReserved=1
I (1763) chip[ZCL]: Lift(PA=1 Encoder=0 Reversed=0) Tilt(PA=1 Encoder=0)
I (1773) chip[ZCL]: Initialize PCC Server Cluster [EP:1]
I (1773) chip[ZCL]: Constant Pressure Supported
I (1783) chip[ZCL]: PCC Server: Constant Proportional Pressure Supported
I (1783) chip[ZCL]: PCC Server: Constant Flow Supported
I (1793) chip[ZCL]: PCC Server: Constant Temperature Supported
I (1803) chip[ZCL]: PCC Server: Constant Speed Supported
I (1803) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0007'
I (1813) app-devicecallbacks: Unhandled AttributeId ID: '0x0007
I (1823) app-devicecallbacks: Current free heap: 125344

I (1833) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0008'
I (1843) app-devicecallbacks: Unhandled AttributeId ID: '0x0008
I (1853) app-devicecallbacks: Current free heap: 125344

I (1853) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x4001'
I (1863) app-devicecallbacks: Unhandled AttributeId ID: '0x4001
I (1873) app-devicecallbacks: Current free heap: 125344

I (1883) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0001'
I (1893) app-devicecallbacks: Unhandled cluster ID: 1030
I (1893) app-devicecallbacks: Current free heap: 125344

I (1903) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0002'
I (1913) app-devicecallbacks: Unhandled cluster ID: 1030
I (1923) app-devicecallbacks: Current free heap: 125344

I (1923) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x02', Attribute ID: '0x0000'
I (1943) app-devicecallbacks: Unhandled cluster ID: 4
I (1943) app-devicecallbacks: Current free heap: 125344

I (1953) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0001'
I (1963) app-devicecallbacks: Unhandled cluster ID: 1030
I (1973) app-devicecallbacks: Current free heap: 125344

I (1973) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0002'
I (1983) app-devicecallbacks: Unhandled cluster ID: 1030
I (1993) app-devicecallbacks: Current free heap: 125344

I (2013) chip[DIS]: Updating services using commissioning mode 1
I (2013) chip[DIS]: CHIP minimal mDNS started advertising.
I (2013) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15
I (2023) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'.
I (2033) chip[DIS]: mDNS service published: _matterc._udp
I (2043) chip[IN]: CASE Server enabling CASE session setups
I (2043) chip[SVR]: Joining Multicast groups
I (2053) chip[SVR]: Server Listening...
E (2053) chip[DL]: Long dispatch time: 597 ms, for event type 2
I (2063) app-devicecallbacks: Current free heap: 124384

I (2073) chip[DL]: WIFI_EVENT_STA_START
I (2073) phy: chan:1,max_power:80
I (2073) phy: chan:2,max_power:80
I (2083) phy: chan:3,max_power:80
I (2083) phy: chan:4,max_power:80
I (2093) phy: chan:5,max_power:80
I (2093) phy: chan:6,max_power:80
I (2093) phy: chan:7,max_power:80
I (2103) phy: chan:8,max_power:80
I (2103) phy: chan:9,max_power:80
I (2113) phy: chan:10,max_power:80
I (2113) phy: chan:11,max_power:80
I (2113) phy: chan:12,max_power:80
I (2123) phy: chan:13,max_power:80
I (2123) phy: chan:14,max_power:80
W (2133) wifi:Haven't to connect to a suitable AP now!
I (2133) chip[DL]: Done driving station state, nothing else to do...
I (2143) app-devicecallbacks: Current free heap: 124384

I (2153) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable, device name MATTER-3840)
I (2163) chip[DL]: Device already advertising, stop active advertisement and restart
I (2163) NimBLE: GAP procedure initiated: stop advertising.

I (2173) NimBLE: GAP procedure initiated: advertise;
I (2183) NimBLE: disc_mode=2
I (2183) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (2193) NimBLE:

I (2193) chip[SVR]: Cannot load binding table: a0
I (10633) chip[DL]: BLE GAP connection established (con 1)
I (10633) chip[DL]: CHIPoBLE advertising stopped
I (10633) app-devicecallbacks: Current free heap: 124392

I (42863) chip[DL]: BLE GAP connection terminated (con 1 reason 0x213)
I (42863) app-devicecallbacks: CHIPoBLE disconnected
I (42863) app-devicecallbacks: Current free heap: 124392

I (42873) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable, device name MATTER-3840)
I (42883) NimBLE: GAP procedure initiated: advertise;
I (42893) NimBLE: disc_mode=2
I (42893) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800
I (42903) NimBLE:

I (42903) chip[DL]: CHIPoBLE advertising started
I (42913) app-devicecallbacks: Current free heap: 124384