-
Notifications
You must be signed in to change notification settings - Fork 3
/
matter-esp32-log.txt
260 lines (228 loc) · 14.1 KB
/
matter-esp32-log.txt
1
2
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
98
99
100
101
102
103
104
105
106
107
108
109
110
111
112
113
114
115
116
117
118
119
120
121
122
123
124
125
126
127
128
129
130
131
132
133
134
135
136
137
138
139
140
141
142
143
144
145
146
147
148
149
150
151
152
153
154
155
156
157
158
159
160
161
162
163
164
165
166
167
168
169
170
171
172
173
174
175
176
177
178
179
180
181
182
183
184
185
186
187
188
189
190
191
192
193
194
195
196
197
198
199
200
201
202
203
204
205
206
207
208
209
210
211
212
213
214
215
216
217
218
219
220
221
222
223
224
225
226
227
228
229
230
231
232
233
234
235
236
237
238
239
240
241
242
243
244
245
246
247
248
249
250
251
252
253
254
255
256
257
258
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:6608
load:0x40078000,len:14788
ho 0 tail 12 room 4
load:0x40080400,len:3792
entry 0x40080694
I (28) boot: ESP-IDF v4.4.1 2nd stage bootloader
I (28) boot: compile time 12:08:00
I (28) boot: chip revision: 1
I (31) boot_comm: chip revision: 1, min. bootloader chip revision: 0
I (38) boot.esp32: SPI Speed : 40MHz
I (43) boot.esp32: SPI Mode : DIO
I (47) boot.esp32: SPI Flash Size : 4MB
I (52) boot: Enabling RNG early entropy source...
I (57) boot: Partition Table:
I (61) boot: ## Label Usage Type ST Offset Length
I (68) boot: 0 nvs WiFi data 01 02 00009000 00006000
I (76) boot: 1 otadata OTA data 01 00 0000f000 00002000
I (83) boot: 2 phy_init RF data 01 01 00011000 00001000
I (91) boot: 3 ota_0 OTA app 00 10 00020000 001a9000
I (98) boot: 4 ota_1 OTA app 00 11 001d0000 001a9000
I (105) boot: 5 ot_storage Unknown data 01 3a 00379000 00002000
I (113) boot: End of partition table
I (117) boot_comm: chip revision: 1, min. application chip revision: 0
I (124) esp_image: segment 0: paddr=00020020 vaddr=3f400020 size=477b4h (292788) map
I (239) esp_image: segment 1: paddr=000677dc vaddr=3ffbdb60 size=05690h ( 22160) load
I (248) esp_image: segment 2: paddr=0006ce74 vaddr=40080000 size=031a4h ( 12708) load
I (253) esp_image: segment 3: paddr=00070020 vaddr=400d0020 size=1182f4h (1147636) map
I (671) esp_image: segment 4: paddr=0018831c vaddr=400831a4 size=19790h (104336) load
I (714) esp_image: segment 5: paddr=001a1ab4 vaddr=50000000 size=00010h ( 16) load
I (728) boot: Loaded app from partition at offset 0x20000
I (728) boot: Disabling RNG early entropy source...
I (740) cpu_start: Pro cpu up.
I (741) cpu_start: Starting app cpu, entry point is 0x400813cc
I (0) cpu_start: App cpu up.
I (756) cpu_start: Pro cpu start user code
I (757) cpu_start: cpu freq: 160000000
I (757) cpu_start: Application information:
I (761) cpu_start: Project name: chip-all-clusters-app
I (767) cpu_start: App version: v1.0
I (772) cpu_start: Compile time: Nov 28 2022 12:07:52
I (778) cpu_start: ELF file SHA256: 797ca69cdaeeb3be...
I (784) cpu_start: ESP-IDF: v4.4.1
I (789) heap_init: Initializing. RAM available for dynamic allocation:
I (796) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM
I (802) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM
I (808) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM
I (814) heap_init: At 3FFD6FE8 len 00009018 (36 KiB): DRAM
I (821) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (827) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (833) heap_init: At 4009C934 len 000036CC (13 KiB): IRAM
I (841) spi_flash: detected chip: generic
I (844) spi_flash: flash io: dio
I (854) cpu_start: Starting scheduler on PRO CPU.
I (0) cpu_start: Starting scheduler on APP CPU.
I (976) all-clusters-app: ==================================================
I (976) all-clusters-app: chip-esp32-all-cluster-demo starting
I (976) all-clusters-app: ==================================================
> I (1216) wifi:wifi driver task: 3ffde330, prio:23, stack:6656, core=0
I (1216) system_api: Base MAC address is not set
I (1216) system_api: read default base MAC address from EFUSE
I (1246) wifi:wifi firmware version: 63017e0
I (1246) wifi:wifi certification version: v7.0
I (1246) wifi:config NVS flash: enabled
I (1246) wifi:config nano formating: disabled
I (1256) wifi:Init data frame dynamic rx buffer num: 32
I (1266) wifi:Init management frame dynamic rx buffer num: 32
I (1266) wifi:Init management short buffer num: 32
I (1276) wifi:Init dynamic tx buffer num: 32
I (1276) wifi:Init static rx buffer size: 1600
I (1276) wifi:Init static rx buffer num: 10
I (1286) wifi:Init dynamic rx buffer num: 32
I (1286) wifi_init: rx ba win: 6
I (1286) wifi_init: tcpip mbox: 32
I (1296) wifi_init: udp mbox: 6
I (1296) wifi_init: tcp mbox: 6
I (1306) wifi_init: tcp tx win: 5744
I (1316) wifi_init: tcp rx win: 5744
I (1316) wifi_init: tcp mss: 1440
I (1316) wifi_init: WiFi IRAM OP enabled
I (1326) wifi_init: WiFi RX IRAM OP enabled
I (1346) chip[DL]: NVS set: chip-counters/reboot-count = 4 (0x4)
I (1356) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC)
I (1356) all-clusters-app: ------------------------Starting App Task---------------------------
I (1356) BTDM_INIT: BT controller compile version [5688ed5]
I (1366) app-task: This is ESP32 chip with 2 CPU cores, WiFi/BT/BLE,
I (1386) app-task: silicon revision 1,
I (1386) app-task: 4MB external flash
I (1376) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07
I (1386) chip[SVR]: SetupQRCode: [MT:-24J042C00KA0648G00]
I (1396) chip[SVR]: Copy/paste the below URL in a browser to see the QR Code:
I (1416) chip[SVR]: https://project-chip.github.io/connectedhomeip/qrcode.html?data=MT%3A-24J042C00KA0648G00
I (1416) chip[SVR]: Manual pairing code: [34970112332]
I (1426) app-task: App Task started
I (1786) CHIP[DL]: BLE host-controller synced
I (2296) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (2296) NimBLE: GAP procedure initiated: advertise;
I (2306) NimBLE: disc_mode=2
I (2306) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (2326) NimBLE:
I (2326) chip[DL]: CHIPoBLE advertising started
E (2326) chip[DL]: Long dispatch time: 973 ms, for event type 2
I (2336) chip[DL]: Starting ESP WiFi layer
I (2346) wifi:mode : sta (fc:f5:c4:0e:e1:80)
I (2346) wifi:enable tsf
W (2346) wifi:Haven't to connect to a suitable AP now!
I (2346) chip[DL]: Done driving station state, nothing else to do...
W (2366) wifi:Haven't to connect to a suitable AP now!
I (2366) chip[DL]: Done driving station state, nothing else to do...
I (2376) chip[SVR]: Server initializing...
I (2376) chip[TS]: Last Known Good Time: 2022-11-28T12:09:25
I (2376) chip[DMG]: AccessControl: initializing
I (2396) chip[DMG]: Examples::AccessControlDelegate::Init
I (2396) chip[DMG]: AccessControl: setting
I (2396) chip[DMG]: DefaultAclStorage: initializing
I (2406) chip[DMG]: DefaultAclStorage: 0 entries loaded
I (2436) chip[ZCL]: Using ZAP configuration...
I (2456) chip[DMG]: AccessControlCluster: initializing
I (2466) chip[ZCL]: Initiating Admin Commissioning cluster.
I (2466) chip[ZCL]: Door Lock server initialized
I (2476) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0030', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2486) app-devicecallbacks: Unhandled cluster ID: 48
I (2486) app-devicecallbacks: Current free heap: 81756
I (2506) chip[ZCL]: Initialize PCC Plugin Server Cluster.
I (2506) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x00', Attribute ID: '0x0001'
I (2516) app-devicecallbacks: Current free heap: 81732
I (2516) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2536) app-devicecallbacks: Unhandled cluster ID: 4
I (2546) app-devicecallbacks: Current free heap: 81732
I (2546) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x00', Attribute ID: '0xfffc'
I (2556) app-devicecallbacks: Unhandled cluster ID: 4
I (2556) app-devicecallbacks: Current free heap: 81732
I (2576) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x002b', EndPoint ID: '0x00', Attribute ID: '0x0000'
I (2586) app-devicecallbacks: Unhandled cluster ID: 43
I (2586) app-devicecallbacks: Current free heap: 81732
I (2596) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0003', EndPoint ID: '0x01', Attribute ID: '0x0001'
I (2596) app-devicecallbacks: Current free heap: 81732
I (2616) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2626) app-devicecallbacks: Unhandled cluster ID: 4
I (2626) app-devicecallbacks: Current free heap: 81732
I (2636) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x01', Attribute ID: '0xfffc'
I (2646) app-devicecallbacks: Unhandled cluster ID: 4
I (2656) app-devicecallbacks: Current free heap: 81732
I (2666) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0005', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2666) app-devicecallbacks: Unhandled cluster ID: 5
I (2676) app-devicecallbacks: Current free heap: 81732
I (2676) chip[ZCL]: On/Off set value: 1 0
I (2686) chip[ZCL]: On/off already set to new value
I (2696) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0008', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2706) app-devicecallbacks: Current free heap: 81732
I (2706) chip[ZCL]: Door Lock cluster initialized at endpoint #1
I (2716) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0101', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2736) app-devicecallbacks: Unhandled cluster ID: 257
I (2736) app-devicecallbacks: Current free heap: 81732
I (2746) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0101', EndPoint ID: '0x01', Attribute ID: '0x0002'
I (2746) app-devicecallbacks: Unhandled cluster ID: 257
I (2756) app-devicecallbacks: Current free heap: 81732
I (2766) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0101', EndPoint ID: '0x01', Attribute ID: '0x0003'
I (2776) app-devicecallbacks: Unhandled cluster ID: 257
I (2786) app-devicecallbacks: Current free heap: 78756
I (2786) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0101', EndPoint ID: '0x01', Attribute ID: '0x0000'
I (2806) app-devicecallbacks: Unhandled cluster ID: 257
I (2806) app-devicecallbacks: Current free heap: 78756
I (2816) chip[ZCL]: Initialized new lock door endpoint [id=1,users=10,credentials=10,weekDaySchedulesPerUser=10,yearDaySchedulesPerUser=10,numberOfCredentialsSupportedPerUser=5,holidaySchedules=0]
I (2826) chip[ZCL]: Initialize PCC Server Cluster [EP:1]
I (2836) chip[ZCL]: Constant Pressure Supported
I (2846) chip[ZCL]: PCC Server: Constant Proportional Pressure Supported
I (2856) chip[ZCL]: PCC Server: Constant Flow Supported
I (2856) chip[ZCL]: PCC Server: Constant Temperature Supported
I (2866) chip[ZCL]: PCC Server: Constant Speed Supported
I (2866) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0007'
I (2876) app-devicecallbacks: Unhandled cluster ID: 768
I (2886) app-devicecallbacks: Current free heap: 78756
I (2896) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x0008'
I (2906) app-devicecallbacks: Unhandled cluster ID: 768
I (2906) app-devicecallbacks: Current free heap: 78756
I (2916) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0300', EndPoint ID: '0x01', Attribute ID: '0x4001'
I (2926) app-devicecallbacks: Unhandled cluster ID: 768
I (2936) app-devicecallbacks: Current free heap: 78756
I (2946) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0001'
I (2946) app-devicecallbacks: Unhandled cluster ID: 1030
I (2956) app-devicecallbacks: Current free heap: 78756
I (2956) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x01', Attribute ID: '0x0002'
I (2976) app-devicecallbacks: Unhandled cluster ID: 1030
I (2986) app-devicecallbacks: Current free heap: 78756
I (2986) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x02', Attribute ID: '0x0000'
I (2996) app-devicecallbacks: Unhandled cluster ID: 4
I (3006) app-devicecallbacks: Current free heap: 78756
I (3006) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0004', EndPoint ID: '0x02', Attribute ID: '0xfffc'
I (3026) app-devicecallbacks: Unhandled cluster ID: 4
I (3026) app-devicecallbacks: Current free heap: 78756
I (3036) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0001'
I (3046) app-devicecallbacks: Unhandled cluster ID: 1030
I (3046) app-devicecallbacks: Current free heap: 78756
I (3056) app-devicecallbacks: PostAttributeChangeCallback - Cluster ID: '0x0406', EndPoint ID: '0x02', Attribute ID: '0x0002'
I (3076) app-devicecallbacks: Unhandled cluster ID: 1030
I (3076) app-devicecallbacks: Current free heap: 78756
I (3086) chip[DIS]: Updating services using commissioning mode 1
I (3086) chip[DIS]: CHIP minimal mDNS started advertising.
I (3096) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32769 discriminator=3840/15 cm=1
I (3106) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A69B53FB29D5086C.
I (3126) chip[DIS]: mDNS service published: _matterc._udp
I (3126) chip[IN]: CASE Server enabling CASE session setups
I (3136) chip[SVR]: Joining Multicast groups
I (3136) chip[SVR]: Server Listening...
E (3146) chip[DL]: Long dispatch time: 767 ms, for event type 2
I (3146) app-devicecallbacks: Current free heap: 78064
I (3156) chip[DL]: WIFI_EVENT_STA_START
W (3156) wifi:Haven't to connect to a suitable AP now!
I (3156) chip[DL]: Done driving station state, nothing else to do...
I (3166) app-devicecallbacks: Current free heap: 78064
I (3176) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable)
I (3186) chip[DL]: Device already advertising, stop active advertisement and restart
I (3186) NimBLE: GAP procedure initiated: stop advertising.
I (3206) NimBLE: GAP procedure initiated: advertise;
I (3206) NimBLE: disc_mode=2
I (3206) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40
I (3216) NimBLE:
I (3226) chip[SVR]: Cannot load binding table: a0
I (33086) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable)
I (33096) chip[DL]: Device already advertising, stop active advertisement and restart
I (33106) NimBLE: GAP procedure initiated: stop advertising.
I (33106) NimBLE: GAP procedure initiated: advertise;
I (33106) NimBLE: disc_mode=2
I (33116) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800
I (33136) NimBLE: