I (548582) app_main: - CAN Task: 1694 I (549002) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (549642) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (550282) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (550922) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (551562) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (552202) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (552842) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (553482) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (553702) app_main: High Water Marks I (553702) app_main: - APP Task: 1768 I (553702) app_main: - CAN Task: 1694 I (554122) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (554762) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (554982) app_main: advertise operational done I (555402) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (556042) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (556582) chip[DL]: BLE GAP connection established (con 0) I (556592) chip[DL]: CHIPoBLE advertising stopped I (556682) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (557322) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (557962) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (558132) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (558132) chip[BLE]: local and remote recv window sizes = 5 I (558132) chip[BLE]: selected BTP version 4 I (558142) chip[BLE]: using BTP fragment sizes rx 244 / tx 244. I (558232) chip[DL]: Write request/command received for CHIPoBLE TX CCCD characteristic (con 0 ) indicate = 1 I (558232) chip[DL]: CHIPoBLE subscribe received I (558232) NimBLE: GATT procedure initiated: indicate; I (558242) NimBLE: att_handle=14 I (558322) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (558332) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (558342) chip[EM]: >>> [E:22033r S:0 M:46790371] (U) Msg RX from 0:4165309BE8C0EA94 [0000] --- Type 0000:20 (SecureChannel:PBKDFParamRequest) I (558352) chip[EM]: <<< [E:22033r S:0 M:223660569] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:21 (SecureChannel:PBKDFParamResponse) I (558362) chip[IN]: (U) Sending msg 223660569 to IP address 'BLE' I (558372) NimBLE: GATT procedure initiated: indicate; I (558372) NimBLE: att_handle=14 I (558522) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (558572) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (558572) chip[EM]: >>> [E:22033r S:0 M:46790372] (U) Msg RX from 0:4165309BE8C0EA94 [0000] --- Type 0000:22 (SecureChannel:PASE_Pake1) I (558602) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (559342) chip[EM]: <<< [E:22033r S:0 M:223660570] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:23 (SecureChannel:PASE_Pake2) I (559342) chip[IN]: (U) Sending msg 223660570 to IP address 'BLE' I (559352) NimBLE: GATT procedure initiated: indicate; I (559352) NimBLE: att_handle=14 E (559362) chip[DL]: Long dispatch time: 793 ms, for event type 7 I (559492) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (559502) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (559512) chip[EM]: >>> [E:22033r S:0 M:46790373] (U) Msg RX from 0:4165309BE8C0EA94 [0000] --- Type 0000:24 (SecureChannel:PASE_Pake3) I (559522) chip[EM]: <<< [E:22033r S:0 M:223660571] (U) Msg TX to 0:0000000000000000 [0000] --- Type 0000:40 (SecureChannel:StatusReport) I (559532) chip[IN]: (U) Sending msg 223660571 to IP address 'BLE' I (559542) NimBLE: GATT procedure initiated: indicate; I (559542) NimBLE: att_handle=14 I (559552) chip[SC]: SecureSession[0x3ffc6458]: Moving from state 'kEstablishing' --> 'kActive' I (559552) chip[SVR]: Commissioning completed session establishment step I (559562) chip[DIS]: Updating services using commissioning mode 0 I (559572) chip[DIS]: CHIP minimal mDNS started advertising. I (559582) chip[SVR]: Device completed Rendezvous process I (559582) app_main: Commissioning session started I (559592) app_main: High Water Marks I (559592) app_main: Commissioning window closed I (559592) app_main: - APP Task: 1768 I (559602) app_main: - CAN Task: 1694 I (559602) app_task: first updateRequest for window 0 I (559612) app_task: reporting window (endpoint 1) 0 position: 5000 I (559622) app_task: first updateRequest for window rev 0 I (559622) app_task: reporting window reversed (endpoint 2) 0 position: 0 I (559632) app_task: updating segments for window 0: tc=0 tt=0 bc=2 bt=2 I (559642) app_task: app_update_segments: number=0 segments=12 I (559642) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (559652) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (559672) chip[EM]: >>> [E:22034r S:64853 M:26531216] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:02 (IM:ReadRequest) I (559692) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0) I (559692) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 0 ********** I (559702) chip[EM]: <<< [E:22034r S:64853 M:141199945] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:05 (IM:ReportData) I (559712) chip[IN]: (S) Sending msg 141199945 on secure session with LSID: 64853 I (559722) NimBLE: GATT procedure initiated: indicate; I (559722) NimBLE: att_handle=14 I (559882) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (559882) NimBLE: GATT procedure initiated: indicate; I (559892) NimBLE: att_handle=14 I (559982) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (559982) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (559992) chip[EM]: >>> [E:22035r S:64853 M:26531217] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (560002) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030 I (560012) chip[FS]: GeneralCommissioning: Received ArmFailSafe (60s) I (560022) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 3 ********** I (560032) chip[EM]: <<< [E:22035r S:64853 M:141199946] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (560042) chip[IN]: (S) Sending msg 141199946 on secure session with LSID: 64853 I (560052) NimBLE: GATT procedure initiated: indicate; I (560062) NimBLE: att_handle=14 I (560132) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (560132) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (560142) chip[EM]: >>> [E:22036r S:64853 M:26531218] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (560152) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x00000030 I (560162) chip[DL]: NVS set: chip-config/reg-location = 0 (0x0) I (560182) chip[DL]: NVS set: chip-config/country-code = "XX" I (560182) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 4 ********** I (560192) chip[EM]: <<< [E:22036r S:64853 M:141199947] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (560202) chip[IN]: (S) Sending msg 141199947 on secure session with LSID: 64853 I (560212) NimBLE: GATT procedure initiated: indicate; I (560212) NimBLE: att_handle=14 I (560322) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (560322) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (560342) chip[EM]: >>> [E:22037r S:64853 M:26531219] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (560352) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E I (560352) chip[ZCL]: OpCreds: Certificate Chain request received for PAI I (560372) chip[EM]: <<< [E:22037r S:64853 M:141199948] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (560382) chip[IN]: (S) Sending msg 141199948 on secure session with LSID: 64853 I (560392) NimBLE: GATT procedure initiated: indicate; I (560392) NimBLE: att_handle=14 I (560572) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (560572) NimBLE: GATT procedure initiated: indicate; I (560572) NimBLE: att_handle=14 I (560762) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (560762) NimBLE: GATT procedure initiated: indicate; I (560772) NimBLE: att_handle=14 I (560862) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (560912) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (560912) chip[EM]: >>> [E:22038r S:64853 M:26531220] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (560922) esp_matter_command: Received command 0x00000002 for endpoint 0x0000's cluster 0x0000003E I (560932) chip[ZCL]: OpCreds: Certificate Chain request received for DAC I (560942) chip[EM]: <<< [E:22038r S:64853 M:141199949] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (560952) chip[IN]: (S) Sending msg 141199949 on secure session with LSID: 64853 I (560962) NimBLE: GATT procedure initiated: indicate; I (560962) NimBLE: att_handle=14 I (561152) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (561152) NimBLE: GATT procedure initiated: indicate; I (561162) NimBLE: att_handle=14 I (561352) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (561352) NimBLE: GATT procedure initiated: indicate; I (561352) NimBLE: att_handle=14 I (561442) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (561452) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (561462) chip[EM]: >>> [E:22039r S:64853 M:26531221] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (561472) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x0000003E I (561482) chip[ZCL]: OpCreds: Received an AttestationRequest command I (561572) chip[ZCL]: OpCreds: AttestationRequest successful. I (561582) chip[EM]: <<< [E:22039r S:64853 M:141199950] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (561582) chip[IN]: (S) Sending msg 141199950 on secure session with LSID: 64853 I (561592) NimBLE: GATT procedure initiated: indicate; I (561592) NimBLE: att_handle=14 I (561782) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (561792) NimBLE: GATT procedure initiated: indicate; I (561792) NimBLE: att_handle=14 I (561932) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (561982) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (561992) chip[EM]: >>> [E:22040r S:64853 M:26531222] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (561992) esp_matter_command: Received command 0x00000004 for endpoint 0x0000's cluster 0x0000003E I (562002) chip[ZCL]: OpCreds: Received a CSRRequest command I (562152) chip[ZCL]: OpCreds: AllocatePendingOperationalKey succeeded I (562232) chip[ZCL]: OpCreds: CSRRequest successful. I (562242) chip[EM]: <<< [E:22040r S:64853 M:141199951] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (562242) chip[IN]: (S) Sending msg 141199951 on secure session with LSID: 64853 I (562252) NimBLE: GATT procedure initiated: indicate; I (562252) NimBLE: att_handle=14 I (562422) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (562422) NimBLE: GATT procedure initiated: indicate; I (562422) NimBLE: att_handle=14 I (562622) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (562622) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (562712) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (562722) chip[EM]: >>> [E:22041r S:64853 M:26531223] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (562722) esp_matter_command: Received command 0x0000000B for endpoint 0x0000's cluster 0x0000003E I (562732) chip[ZCL]: OpCreds: Received an AddTrustedRootCertificate command I (563002) chip[ZCL]: OpCreds: AddTrustedRootCertificate successful. I (563002) chip[EM]: <<< [E:22041r S:64853 M:141199952] (S) Msg TX to 0:FFFFFFFB00000000 [0000] --- Type 0001:09 (IM:InvokeCommandResponse) I (563012) chip[IN]: (S) Sending msg 141199952 on secure session with LSID: 64853 I (563022) NimBLE: GATT procedure initiated: indicate; I (563022) NimBLE: att_handle=14 I (563102) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (563102) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (563202) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (563302) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (563302) chip[EM]: >>> [E:22042r S:64853 M:26531224] (S) Msg RX from 0:FFFFFFFB00000000 [0000] --- Type 0001:08 (IM:InvokeCommandRequest) I (563312) esp_matter_command: Received command 0x00000006 for endpoint 0x0000's cluster 0x0000003E I (563322) chip[ZCL]: OpCreds: Received an AddNOC command I (563332) chip[FP]: Validating NOC chain I (563842) chip[FP]: NOC chain validation successful I (563842) chip[FP]: Added new fabric at index: 0x1 I (563842) chip[FP]: Assigned compressed fabric ID: 0x011625538D69FC5B, node ID: 0x2A203C7E0D13A8AD I (563852) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (563862) chip[TS]: New proposed Last Known Good Time: 2021-01-01T00:00:00 I (563872) chip[TS]: Retaining current Last Known Good Time I (563892) chip[ZCL]: OpCreds: ACL entry created for Fabric index 0x1 CASE Admin Subject 0x000000000001B669 I (563892) chip[DIS]: Advertise operational node 011625538D69FC5B-2A203C7E0D13A8AD I (563902) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 011625538D69FC5B-2A203C7E0D13A8AD. I (563912) chip[DIS]: mDNS service published: _matter._tcp I (563922) chip[ZCL]: OpCreds: successfully created fabric index 0x1 via AddNOC I (563932) chip[EM]: <<< [E:22042r S:64853 M:141199953] (S) Msg TX to 1:FFFFFFFB00000000 [FC5B] --- Type 0001:09 (IM:InvokeCommandResponse) I (563942) chip[IN]: (S) Sending msg 141199953 on secure session with LSID: 64853 I (563952) NimBLE: GATT procedure initiated: indicate; I (563952) NimBLE: att_handle=14 I (563962) app_main: Fabric is updated I (564032) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (564032) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (564042) chip[EM]: >>> [E:22043r S:64853 M:26531225] (S) Msg RX from 1:FFFFFFFB00000000 [FC5B] --- Type 0001:08 (IM:InvokeCommandRequest) I (564052) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 14 ********** I (564062) chip[EM]: <<< [E:22043r S:64853 M:141199954] (S) Msg TX to 1:FFFFFFFB00000000 [FC5B] --- Type 0001:09 (IM:InvokeCommandResponse) I (564082) chip[IN]: (S) Sending msg 141199954 on secure session with LSID: 64853 I (564082) NimBLE: GATT procedure initiated: indicate; I (564092) NimBLE: att_handle=14 I (564172) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (564182) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (564192) chip[EM]: >>> [E:22044r S:64853 M:26531226] (S) Msg RX from 1:FFFFFFFB00000000 [FC5B] --- Type 0001:08 (IM:InvokeCommandRequest) I (564202) esp_matter_command: Received command 0x00000000 for endpoint 0x0000's cluster 0x00000030 I (564202) chip[FS]: GeneralCommissioning: Received ArmFailSafe (66s) I (564212) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 16 ********** I (564232) chip[EM]: <<< [E:22044r S:64853 M:141199955] (S) Msg TX to 1:FFFFFFFB00000000 [FC5B] --- Type 0001:09 (IM:InvokeCommandResponse) I (564242) chip[IN]: (S) Sending msg 141199955 on secure session with LSID: 64853 I (564252) NimBLE: GATT procedure initiated: indicate; I (564252) NimBLE: att_handle=14 I (564322) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (564322) CHIP[DL]: Write request received for CHIPoBLE RX characteristic con 0 12 I (564332) chip[EM]: >>> [E:22045r S:64853 M:26531227] (S) Msg RX from 1:FFFFFFFB00000000 [FC5B] --- Type 0001:08 (IM:InvokeCommandRequest) I (564342) chip[NP]: ESP NetworkCommissioningDelegate: SSID: SmartProducts W (564352) wifi:Haven't to connect to a suitable AP now! I (564362) chip[DL]: WiFi station mode change: Enabled -> Disabled I (564402) chip[DL]: WiFi station mode change: Disabled -> Enabled W (564412) wifi:Haven't to connect to a suitable AP now! I (564412) chip[DL]: Attempting to connect WiFi station interface I (564422) chip[DL]: WiFi station state change: NotConnected -> Connecting I (564422) chip[DL]: Done driving station state, nothing else to do... W (564432) wifi:Haven't to connect to a suitable AP now! I (564432) chip[DL]: Attempting to connect WiFi station interface E (564442) wifi:sta is connecting, return error E (564442) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN W (564452) wifi:Haven't to connect to a suitable AP now! I (564462) chip[DL]: Attempting to connect WiFi station interface E (564462) wifi:sta is connecting, return error E (564472) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN W (564472) wifi:Haven't to connect to a suitable AP now! E (564482) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F I (564702) app_main: High Water Marks I (564702) app_main: - APP Task: 1768 I (564702) app_main: - CAN Task: 1694 I (565122) chip[DIS]: Advertise operational node 011625538D69FC5B-2A203C7E0D13A8AD I (565122) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 011625538D69FC5B-2A203C7E0D13A8AD. I (565132) chip[DIS]: mDNS service published: _matter._tcp I (565132) app_main: advertise operational done I (565762) app_task: starting combineWait for window 0: 1 I (565762) app_task: starting combineWait for window_rev 0: 1 I (565962) app_task: waiting if inverted state will be set: 0 I (566362) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (566362) wifi:state: init -> auth (b0) I (566832) NimBLE: GATT procedure initiated: indicate; I (566832) NimBLE: att_handle=14 I (567202) wifi:state: auth -> assoc (0) I (567212) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (567212) wifi:state: assoc -> run (10) I (567222) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (567222) wifi:security: WPA3-SAE, phy: bgn, rssi: -32 I (567242) wifi:pm start, type: 1 I (567252) chip[DL]: WIFI_EVENT_STA_CONNECTED I (567252) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (567252) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (567262) chip[DL]: WiFi station interface connected I (567272) esp_matter_attribute: ********** W : Endpoint 0x0000's Cluster 0x00000030's Attribute 0x00000000 is 18 ********** I (567282) chip[EM]: <<< [E:22045r S:64853 M:141199956] (S) Msg TX to 1:FFFFFFFB00000000 [FC5B] --- Type 0001:09 (IM:InvokeCommandResponse) I (567292) chip[IN]: (S) Sending msg 141199956 on secure session with LSID: 64853 I (567302) NimBLE: GATT procedure initiated: indicate; I (567302) NimBLE: att_handle=14 I (567312) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (567322) chip[DL]: Done driving station state, nothing else to do... I (567322) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (567332) chip[DIS]: Advertise operational node 011625538D69FC5B-2A203C7E0D13A8AD I (567342) chip[DIS]: CHIP minimal mDNS configured as 'Operational device'; instance name: 011625538D69FC5B-2A203C7E0D13A8AD. I (567352) chip[DIS]: mDNS service published: _matter._tcp I (567362) chip[SVR]: Operational advertising enabled I (567362) chip[DL]: Updating advertising data I (567492) chip[DL]: Confirm received for CHIPoBLE TX characteristic indication (con 0) status= 14 I (568252) esp_netif_handlers: sta ip: 192.168.1.127, mask: 255.255.255.0, gw: 192.168.1.1 I (568252) chip[DL]: IP_EVENT_STA_GOT_IP I (568252) chip[DL]: IPv4 address changed on WiFi station interface: 192.168.1.127/255.255.255.0 gateway 192.168.1.1 I (568262) chip[DL]: IPv4 Internet connectivity ESTABLISHED I (568272) app_main: Interface IP Address changed I (568282) chip[DIS]: Updating services using commissioning mode 0 assert failed: igmp_joingroup_netif /IDF/components/lwip/lwip/src/core/ipv4/igmp.c:495 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8eb0 0x400927f9:0x3ffe8ed0 0x400994a1:0x3ffe8ef0 0x401575d9:0x3ffe9010 0x40129484:0x3ffe9030 0x40129015:0x3ffe9060 0x40118ff4:0x3ffe9090 0x40107369:0x3ffe9100 0x40105aae:0x3ffe9130 0x400e4096:0x3ffe9150 0x400e412a:0x3ffe9170 0x400e1109:0x3ffe9190 0x401c62a5:0x3ffe91b0 0x401147ad:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (720) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3858 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:18 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 08 05 81 4f 00 60 05 00 00 00 00 29 6b I (1612) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1612) app_main: CAN key: inbuffer=a5 48 05 81 43 00 20 08 e0 93 04 00 5e 24 I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 88 05 81 60 00 22 00 00 00 00 00 c0 6c I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 c8 05 81 60 00 20 08 00 00 00 00 0d d4 I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdee88, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 2 (0x2) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2372) NimBLE: Failed to restore IRKs from store; status=8 I (2372) CHIP[DL]: BLE host-controller synced I (2882) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2882) NimBLE: GAP procedure initiated: advertise; I (2892) NimBLE: disc_mode=2 I (2892) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2902) NimBLE: I (2912) chip[DL]: CHIPoBLE advertising started E (2912) chip[DL]: Long dispatch time: 919 ms, for event type 2 I (2912) chip[DL]: Starting ESP WiFi layer I (2922) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2922) wifi:enable tsf W (2932) wifi:Haven't to connect to a suitable AP now! I (2932) chip[DL]: Attempting to connect WiFi station interface I (2942) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2952) chip[DL]: Done driving station state, nothing else to do... I (2952) chip[SVR]: Initializing subscription resumption storage... I (2952) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2972) wifi:state: init -> auth (b0) I (3072) chip[SVR]: Server initializing... I (3132) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3132) chip[DMG]: AccessControl: initializing I (3132) chip[DMG]: Examples::AccessControlDelegate::Init I (3132) chip[DMG]: AccessControl: setting I (3152) chip[DMG]: DefaultAclStorage: initializing I (3152) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3472) chip[ZCL]: Using ZAP configuration... I (3472) esp_matter_cluster: Cluster plugin init common callback I (3472) chip[DMG]: AccessControlCluster: initializing I (3472) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3482) chip[ZCL]: Initiating Admin Commissioning cluster. E (3492) chip[ZCL]: Duplicate attribute override registration failed E (3502) chip[ZCL]: Duplicate attribute override registration failed I (3512) chip[DIS]: Updating services using commissioning mode 1 I (3512) chip[DIS]: CHIP minimal mDNS started advertising. I (3592) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3592) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 1B2EF4B3DB352A22. I (3612) chip[DIS]: mDNS service published: _matterc._udp I (3612) chip[IN]: CASE Server enabling CASE session setups I (3612) chip[SVR]: Joining Multicast groups I (3612) chip[SVR]: Server Listening... I (3632) esp_matter_core: Dynamic endpoint 0 added I (3632) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3652) app_driver: Window endpoint ID in attribute update I (3652) app_driver: Cluster_ID=3 I (3652) app_driver: Attribute_ID=1 I (3652) app_driver: Attribute_Val_type=15 I (3672) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3672) app_driver: Window endpoint ID in attribute update I (3672) app_driver: Cluster_ID=4 I (3692) app_driver: Attribute_ID=0 I (3692) app_driver: Attribute_Val_type=16 I (3692) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3712) app_driver: Window endpoint ID in attribute update I (3712) app_driver: Cluster_ID=4 I (3712) app_driver: Attribute_ID=65532 I (3712) app_driver: Attribute_Val_type=0 I (3732) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3732) app_driver: Window endpoint ID in attribute update I (3752) app_driver: Cluster_ID=5 I (3752) app_driver: Attribute_ID=0 I (3752) app_driver: Attribute_Val_type=8 W (3752) app_driver: unimplemented cluster 5 at endpoint 1 I (3772) esp_matter_core: Dynamic endpoint 1 added I (3772) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3772) app_driver: Window endpoint ID in attribute update I (3792) app_driver: Cluster_ID=3 I (3792) app_driver: Attribute_ID=1 I (3792) app_driver: Attribute_Val_type=15 I (3792) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3812) app_driver: Window endpoint ID in attribute update I (3812) app_driver: Cluster_ID=4 I (3812) app_driver: Attribute_ID=0 I (3832) app_driver: Attribute_Val_type=16 I (3832) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3852) app_driver: Window endpoint ID in attribute update I (3852) app_driver: Cluster_ID=4 I (3852) app_driver: Attribute_ID=65532 I (3852) app_driver: Attribute_Val_type=0 I (3852) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3872) app_driver: Window endpoint ID in attribute update I (3872) app_driver: Cluster_ID=5 I (3892) app_driver: Attribute_ID=0 I (3892) app_driver: Attribute_Val_type=8 W (3892) app_driver: unimplemented cluster 5 at endpoint 2 I (3892) esp_matter_core: Dynamic endpoint 2 added E (3912) chip[DL]: Long dispatch time: 955 ms, for event type 2 I (3912) chip[DL]: WIFI_EVENT_STA_START I (4042) chip[DL]: Done driving station state, nothing else to do... I (4042) wifi:state: auth -> assoc (0) I (4052) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4052) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4062) chip[DL]: Device already advertising, stop active advertisement and restart I (4072) NimBLE: GAP procedure initiated: stop advertising. I (4082) NimBLE: GAP procedure initiated: advertise; I (4082) NimBLE: disc_mode=2 I (4082) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4092) NimBLE: I (4102) app_main: Commissioning window opened I (4102) app_main: last runtime was (15=normal startup) 15s after 0 restarts I (4112) app_main: 3 seconds after restart I (4112) app_main: successfully created APP task I (4122) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4132) main_task: Returned from app_main() I (4132) app_main: advertise operational done I (4792) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5022) app_task: starting combineWait for window_rev 0: 1 I (5082) wifi:state: assoc -> assoc (0) I (5152) wifi:state: assoc -> run (10) I (5162) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5162) wifi:security: WPA3-SAE, phy: bgn, rssi: -31 I (5172) wifi:pm start, type: 1 I (5172) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5182) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5182) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5182) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5192) chip[DL]: WiFi station interface connected I (5202) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5212) chip[DL]: Done driving station state, nothing else to do... I (5212) chip[DL]: Updating advertising data I (5222) app_main: 4 seconds after restart I (5232) app_task: waiting if inverted state will be set: 0 I (5232) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5242) chip[DL]: Device already advertising, stop active advertisement and restart I (5242) NimBLE: GAP procedure initiated: stop advertising. I (5252) NimBLE: GAP procedure initiated: advertise; I (5262) NimBLE: disc_mode=2 I (5262) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5272) NimBLE: I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1858 I (5872) app_main: - CAN Task: 1694 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6812) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8f10 0x400927f9:0x3ffe8f30 0x400994a1:0x3ffe8f50 0x4014edca:0x3ffe9070 0x401171d6:0x3ffe9090 0x401166e4:0x3ffe90e0 0x401167f1:0x3ffe9170 0x401132d5:0x3ffe9190 0x4011475e:0x3ffe91b0 0x4011479c:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1012) app_main: CAN debug: end getFullAccess I (1012) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1052) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1062) app_main: CAN key: date=352454633 I (1072) app_main: CAN key: time=3858 I (1082) app_main: CAN key: valid=255 I (1082) app_main: system time: 21.2.2025 15:18 I (1092) app_main: CAN task inited successfully I (1102) app_main: CAN debug: wrong address I (1102) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1612) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1612) app_main: CAN key: inbuffer=a5 48 05 81 4f 00 60 05 00 00 00 00 6d be I (1622) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1622) app_main: CAN key: inbuffer=a5 88 05 81 43 00 20 08 e0 93 04 00 93 5b I (1632) app_main: CAN debug: start getFullAccess I (1652) app_main: CAN debug: end getFullAccess I (1652) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1652) app_main: CAN key: inbuffer=a5 c8 05 81 60 00 22 00 00 00 00 00 84 b9 I (1672) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1672) app_main: CAN key: inbuffer=a5 08 05 81 60 00 20 08 00 00 00 00 c0 ab I (1672) app_main: CAN debug: start getFullAccess I (1692) app_main: CAN debug: end getFullAccess I (1692) app_main: changed reset button time in NXP to prevent reset I (1742) app_main: CAN key: inverted_state=direct I (1742) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1772) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1772) app_task: app_update_segments: number=0 segments=0 I (1772) app_task: initWindow: number=0 height=60 segment_count=4 I (1782) app_task: Window 0 created with endpoint_id 1 I (1792) app_task: Window 0 created with endpoint_id 2 I (1792) app_task: restored window 0 from bottom: 5000 E (1792) app_task: window 0 not configured I (1802) app_task: restored window_rev 0 from top: 0 E (1802) app_task: window 0 not configured I (1812) app_task: detected stop command for window 0 I (1812) app_task: full control window 0 from top: 0 I (1822) app_main: Starting Matter... I (1842) wifi:wifi driver task: 3ffdef0c, prio:23, stack:6656, core=0 I (1872) wifi:wifi firmware version: ce9244d I (1872) wifi:wifi certification version: v7.0 I (1872) wifi:config NVS flash: enabled I (1872) wifi:config nano formating: disabled I (1882) wifi:Init data frame dynamic rx buffer num: 32 I (1882) wifi:Init management frame dynamic rx buffer num: 32 I (1892) wifi:Init management short buffer num: 32 I (1892) wifi:Init dynamic tx buffer num: 32 I (1902) wifi:Init static rx buffer size: 1600 I (1902) wifi:Init static rx buffer num: 10 I (1902) wifi:Init dynamic rx buffer num: 32 I (1912) wifi_init: rx ba win: 6 I (1912) wifi_init: tcpip mbox: 32 I (1922) wifi_init: udp mbox: 6 I (1922) wifi_init: tcp mbox: 6 I (1922) wifi_init: tcp tx win: 5744 I (1932) wifi_init: tcp rx win: 5744 I (1932) wifi_init: tcp mss: 1440 I (1942) wifi_init: WiFi IRAM OP enabled I (1942) wifi_init: WiFi RX IRAM OP enabled I (1982) chip[DL]: NVS set: chip-counters/reboot-count = 3 (0x3) I (2002) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (2002) BTDM_INIT: BT controller compile version [946b762] I (2002) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2012) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2372) NimBLE: Failed to restore IRKs from store; status=8 I (2372) CHIP[DL]: BLE host-controller synced I (2882) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2882) NimBLE: GAP procedure initiated: advertise; I (2892) NimBLE: disc_mode=2 I (2892) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2902) NimBLE: I (2912) chip[DL]: CHIPoBLE advertising started E (2912) chip[DL]: Long dispatch time: 909 ms, for event type 2 I (2912) chip[DL]: Starting ESP WiFi layer I (2922) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2922) wifi:enable tsf W (2932) wifi:Haven't to connect to a suitable AP now! I (2932) chip[DL]: Attempting to connect WiFi station interface I (2952) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2952) wifi:state: init -> auth (b0) I (3392) chip[DL]: WiFi station state change: NotConnected -> Connecting I (3392) chip[DL]: Done driving station state, nothing else to do... I (3402) chip[SVR]: Initializing subscription resumption storage... I (3412) chip[SVR]: Server initializing... I (3412) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3422) chip[DMG]: AccessControl: initializing I (3422) chip[DMG]: Examples::AccessControlDelegate::Init I (3432) chip[DMG]: AccessControl: setting I (3432) chip[DMG]: DefaultAclStorage: initializing I (3442) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3812) wifi:state: auth -> assoc (0) I (3812) wifi:Association refused temporarily, comeback time 1000 (TUs) I (3832) chip[ZCL]: Using ZAP configuration... I (3832) esp_matter_cluster: Cluster plugin init common callback I (3832) chip[DMG]: AccessControlCluster: initializing I (3842) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3842) chip[ZCL]: Initiating Admin Commissioning cluster. E (3852) chip[ZCL]: Duplicate attribute override registration failed E (3862) chip[ZCL]: Duplicate attribute override registration failed I (3872) chip[DIS]: Updating services using commissioning mode 1 I (3872) chip[DIS]: CHIP minimal mDNS started advertising. I (3882) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3892) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 383E33C76369F4FA. I (3902) chip[DIS]: mDNS service published: _matterc._udp I (3902) chip[IN]: CASE Server enabling CASE session setups I (3912) chip[SVR]: Joining Multicast groups I (3922) chip[SVR]: Server Listening... I (3922) esp_matter_core: Dynamic endpoint 0 added I (3932) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3942) app_driver: Window endpoint ID in attribute update I (3942) app_driver: Cluster_ID=3 I (3952) app_driver: Attribute_ID=1 I (3952) app_driver: Attribute_Val_type=15 I (3962) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3972) app_driver: Window endpoint ID in attribute update I (3972) app_driver: Cluster_ID=4 I (3982) app_driver: Attribute_ID=0 I (3982) app_driver: Attribute_Val_type=16 I (3992) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (4002) app_driver: Window endpoint ID in attribute update I (4012) app_driver: Cluster_ID=4 I (4012) app_driver: Attribute_ID=65532 I (4022) app_driver: Attribute_Val_type=0 I (4022) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (4032) app_driver: Window endpoint ID in attribute update I (4042) app_driver: Cluster_ID=5 I (4042) app_driver: Attribute_ID=0 I (4052) app_driver: Attribute_Val_type=8 W (4052) app_driver: unimplemented cluster 5 at endpoint 1 I (4062) esp_matter_core: Dynamic endpoint 1 added I (4062) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (4072) app_driver: Window endpoint ID in attribute update I (4082) app_driver: Cluster_ID=3 I (4082) app_driver: Attribute_ID=1 I (4092) app_driver: Attribute_Val_type=15 I (4092) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (4102) app_driver: Window endpoint ID in attribute update I (4112) app_driver: Cluster_ID=4 I (4122) app_driver: Attribute_ID=0 I (4122) app_driver: Attribute_Val_type=16 I (4122) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (4142) app_driver: Window endpoint ID in attribute update I (4142) app_driver: Cluster_ID=4 I (4152) app_driver: Attribute_ID=65532 I (4152) app_driver: Attribute_Val_type=0 I (4162) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (4172) app_driver: Window endpoint ID in attribute update I (4172) app_driver: Cluster_ID=5 I (4182) app_driver: Attribute_ID=0 I (4182) app_driver: Attribute_Val_type=8 W (4192) app_driver: unimplemented cluster 5 at endpoint 2 I (4192) esp_matter_core: Dynamic endpoint 2 added E (4202) chip[DL]: Long dispatch time: 804 ms, for event type 2 I (4212) chip[DL]: WIFI_EVENT_STA_START I (4212) chip[DL]: Done driving station state, nothing else to do... I (4222) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4232) chip[DL]: Device already advertising, stop active advertisement and restart I (4232) NimBLE: GAP procedure initiated: stop advertising. I (4242) NimBLE: GAP procedure initiated: advertise; I (4252) NimBLE: disc_mode=2 I (4252) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4262) NimBLE: I (4262) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4272) app_main: Commissioning window opened I (4282) app_main: 3 seconds after restart I (4282) app_main: successfully created APP task I (4292) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4292) app_main: advertise operational done I (4292) main_task: Returned from app_main() I (4842) wifi:state: assoc -> assoc (0) I (4922) wifi:state: assoc -> run (10) I (4942) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (4942) wifi:security: WPA3-SAE, phy: bgn, rssi: -30 I (4952) wifi:pm start, type: 1 I (4952) chip[DL]: WIFI_EVENT_STA_CONNECTED I (4952) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (4962) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (4962) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (4972) chip[DL]: WiFi station interface connected I (4982) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (4992) chip[DL]: Done driving station state, nothing else to do... I (4992) chip[DL]: Updating advertising data I (5002) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5002) chip[DL]: Device already advertising, stop active advertisement and restart I (5012) NimBLE: GAP procedure initiated: stop advertising. I (5022) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5032) NimBLE: GAP procedure initiated: advertise; I (5032) NimBLE: disc_mode=2 I (5032) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5042) NimBLE: I (5292) app_task: starting combineWait for window_rev 0: 1 I (5492) app_main: 4 seconds after restart I (5502) app_task: waiting if inverted state will be set: 0 I (5712) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5932) app_main: High Water Marks I (5932) app_main: - APP Task: 185c I (5932) app_main: - CAN Task: 1690 I (6352) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6572) app_main: 5 seconds after restart I (6822) chip[DL]: IP_EVENT_GOT_IP6 I (6822) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8fd0 0x400927f9:0x3ffe8ff0 0x400994a1:0x3ffe9010 0x4014edca:0x3ffe9130 0x401171d6:0x3ffe9150 0x401166e4:0x3ffe91a0 0x401167f1:0x3ffe9230 0x401132d5:0x3ffe9250 0x4011475e:0x3ffe9270 0x4011479c:0x3ffe9290 0x40114acc:0x3ffe92b0 0x40114b01:0x3ffe9320 0x40094fad:0x3ffe9340 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1012) app_main: CAN debug: end getFullAccess I (1012) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1052) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1062) app_main: CAN key: date=352454633 I (1072) app_main: CAN key: time=3859 I (1082) app_main: CAN key: valid=255 I (1082) app_main: system time: 21.2.2025 15:19 I (1092) app_main: CAN task inited successfully I (1102) app_main: CAN debug: wrong address I (1102) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1612) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1612) app_main: CAN key: inbuffer=a5 08 05 81 4f 00 60 05 00 00 00 00 29 6b I (1622) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1622) app_main: CAN key: inbuffer=a5 48 05 81 43 00 20 08 e0 93 04 00 5e 24 I (1632) app_main: CAN debug: start getFullAccess I (1652) app_main: CAN debug: end getFullAccess I (1652) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1652) app_main: CAN key: inbuffer=a5 88 05 81 60 00 22 00 00 00 00 00 c0 6c I (1672) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1672) app_main: CAN key: inbuffer=a5 c8 05 81 60 00 20 08 00 00 00 00 0d d4 I (1672) app_main: CAN debug: start getFullAccess I (1692) app_main: CAN debug: end getFullAccess I (1692) app_main: changed reset button time in NXP to prevent reset I (1742) app_main: CAN key: inverted_state=direct I (1742) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1772) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1772) app_task: app_update_segments: number=0 segments=0 I (1772) app_task: initWindow: number=0 height=60 segment_count=4 I (1782) app_task: Window 0 created with endpoint_id 1 I (1792) app_task: Window 0 created with endpoint_id 2 I (1792) app_task: restored window 0 from bottom: 5000 E (1792) app_task: window 0 not configured I (1802) app_task: restored window_rev 0 from top: 0 E (1802) app_task: window 0 not configured I (1812) app_task: detected stop command for window 0 I (1812) app_task: full control window 0 from top: 0 I (1822) app_main: Starting Matter... I (1842) wifi:wifi driver task: 3ffdef0c, prio:23, stack:6656, core=0 I (1872) wifi:wifi firmware version: ce9244d I (1872) wifi:wifi certification version: v7.0 I (1872) wifi:config NVS flash: enabled I (1872) wifi:config nano formating: disabled I (1882) wifi:Init data frame dynamic rx buffer num: 32 I (1882) wifi:Init management frame dynamic rx buffer num: 32 I (1892) wifi:Init management short buffer num: 32 I (1892) wifi:Init dynamic tx buffer num: 32 I (1902) wifi:Init static rx buffer size: 1600 I (1902) wifi:Init static rx buffer num: 10 I (1902) wifi:Init dynamic rx buffer num: 32 I (1912) wifi_init: rx ba win: 6 I (1912) wifi_init: tcpip mbox: 32 I (1922) wifi_init: udp mbox: 6 I (1922) wifi_init: tcp mbox: 6 I (1922) wifi_init: tcp tx win: 5744 I (1932) wifi_init: tcp rx win: 5744 I (1932) wifi_init: tcp mss: 1440 I (1942) wifi_init: WiFi IRAM OP enabled I (1942) wifi_init: WiFi RX IRAM OP enabled I (1982) chip[DL]: NVS set: chip-counters/reboot-count = 4 (0x4) I (2002) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (2002) BTDM_INIT: BT controller compile version [946b762] I (2002) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2012) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2382) NimBLE: Failed to restore IRKs from store; status=8 I (2382) CHIP[DL]: BLE host-controller synced I (2892) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2892) NimBLE: GAP procedure initiated: advertise; I (2902) NimBLE: disc_mode=2 I (2902) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2912) NimBLE: I (2922) chip[DL]: CHIPoBLE advertising started E (2922) chip[DL]: Long dispatch time: 918 ms, for event type 2 I (2922) chip[DL]: Starting ESP WiFi layer I (2932) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2932) wifi:enable tsf W (2942) wifi:Haven't to connect to a suitable AP now! I (2942) chip[DL]: Attempting to connect WiFi station interface I (2952) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2962) chip[DL]: Done driving station state, nothing else to do... I (2962) chip[SVR]: Initializing subscription resumption storage... I (2962) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2982) wifi:state: init -> auth (b0) I (3082) chip[SVR]: Server initializing... I (3142) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3142) chip[DMG]: AccessControl: initializing I (3142) chip[DMG]: Examples::AccessControlDelegate::Init I (3142) chip[DMG]: AccessControl: setting I (3162) chip[DMG]: DefaultAclStorage: initializing I (3162) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3672) chip[ZCL]: Using ZAP configuration... I (3672) esp_matter_cluster: Cluster plugin init common callback I (3672) chip[DMG]: AccessControlCluster: initializing I (3672) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3692) chip[ZCL]: Initiating Admin Commissioning cluster. E (3692) chip[ZCL]: Duplicate attribute override registration failed E (3712) chip[ZCL]: Duplicate attribute override registration failed I (3862) chip[DIS]: Updating services using commissioning mode 1 I (3862) chip[DIS]: CHIP minimal mDNS started advertising. I (3922) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3922) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 45D10E643376494E. I (3932) chip[DIS]: mDNS service published: _matterc._udp I (3932) chip[IN]: CASE Server enabling CASE session setups I (3942) chip[SVR]: Joining Multicast groups I (3942) chip[SVR]: Server Listening... I (3952) esp_matter_core: Dynamic endpoint 0 added I (3962) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3972) app_driver: Window endpoint ID in attribute update I (3972) app_driver: Cluster_ID=3 I (3982) app_driver: Attribute_ID=1 I (3982) app_driver: Attribute_Val_type=15 I (3952) wifi:state: auth -> assoc (0) I (3992) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (4002) app_driver: Window endpoint ID in attribute update I (4012) app_driver: Cluster_ID=4 I (4012) app_driver: Attribute_ID=0 I (4012) app_driver: Attribute_Val_type=16 I (4022) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (4032) app_driver: Window endpoint ID in attribute update I (4042) app_driver: Cluster_ID=4 I (4042) app_driver: Attribute_ID=65532 I (4052) app_driver: Attribute_Val_type=0 I (4052) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (4062) app_driver: Window endpoint ID in attribute update I (4072) app_driver: Cluster_ID=5 I (4072) app_driver: Attribute_ID=0 I (4082) app_driver: Attribute_Val_type=8 W (4082) app_driver: unimplemented cluster 5 at endpoint 1 I (4092) esp_matter_core: Dynamic endpoint 1 added I (4102) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (4112) app_driver: Window endpoint ID in attribute update I (4112) app_driver: Cluster_ID=3 I (4122) app_driver: Attribute_ID=1 I (4122) app_driver: Attribute_Val_type=15 I (4132) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (4142) app_driver: Window endpoint ID in attribute update I (4142) app_driver: Cluster_ID=4 I (4152) app_driver: Attribute_ID=0 I (4152) app_driver: Attribute_Val_type=16 I (4162) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (4172) app_driver: Window endpoint ID in attribute update I (4182) app_driver: Cluster_ID=4 I (4182) app_driver: Attribute_ID=65532 I (4182) app_driver: Attribute_Val_type=0 I (4192) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (4202) app_driver: Window endpoint ID in attribute update I (4212) app_driver: Cluster_ID=5 I (4212) app_driver: Attribute_ID=0 I (4212) app_driver: Attribute_Val_type=8 W (4222) app_driver: unimplemented cluster 5 at endpoint 2 I (4232) esp_matter_core: Dynamic endpoint 2 added E (4232) chip[DL]: Long dispatch time: 1271 ms, for event type 2 I (4242) chip[DL]: WIFI_EVENT_STA_START I (4012) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4252) chip[DL]: Done driving station state, nothing else to do... I (4262) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4262) chip[DL]: Device already advertising, stop active advertisement and restart I (4272) NimBLE: GAP procedure initiated: stop advertising. I (4282) NimBLE: GAP procedure initiated: advertise; I (4282) NimBLE: disc_mode=2 I (4292) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4302) NimBLE: I (4302) app_main: Commissioning window opened I (4312) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4312) app_main: 3 seconds after restart I (4322) app_main: successfully created APP task I (4322) main_task: Returned from app_main() I (4332) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4342) app_main: advertise operational done I (5002) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5232) app_task: starting combineWait for window_rev 0: 1 I (5272) wifi:state: assoc -> assoc (0) I (5322) wifi:state: assoc -> run (10) I (5332) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5332) wifi:security: WPA3-SAE, phy: bgn, rssi: -31 I (5342) wifi:pm start, type: 1 I (5342) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5342) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5352) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5352) chip[DL]: WiFi station interface connected I (5362) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5372) chip[DL]: Done driving station state, nothing else to do... I (5382) chip[DL]: Updating advertising data I (5382) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5392) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5392) chip[DL]: Device already advertising, stop active advertisement and restart I (5402) NimBLE: GAP procedure initiated: stop advertising. I (5412) NimBLE: GAP procedure initiated: advertise; I (5412) NimBLE: disc_mode=2 I (5422) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5432) NimBLE: I (5432) app_main: 4 seconds after restart I (5452) app_task: waiting if inverted state will be set: 0 I (5662) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5882) app_main: High Water Marks I (5882) app_main: - APP Task: 1820 I (5882) app_main: - CAN Task: 1690 I (6302) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6532) app_main: 5 seconds after restart I (6822) chip[DL]: IP_EVENT_GOT_IP6 I (6822) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8fd0 0x400927f9:0x3ffe8ff0 0x400994a1:0x3ffe9010 0x4014edca:0x3ffe9130 0x401171d6:0x3ffe9150 0x401166e4:0x3ffe91a0 0x401167f1:0x3ffe9230 0x401132d5:0x3ffe9250 0x4011475e:0x3ffe9270 0x4011479c:0x3ffe9290 0x40114acc:0x3ffe92b0 0x40114b01:0x3ffe9320 0x40094fad:0x3ffe9340 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (952) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 c8 05 81 4f 00 60 05 00 00 00 00 e4 14 I (1612) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1612) app_main: CAN key: inbuffer=a5 08 05 81 43 00 20 08 e0 93 04 00 1a f1 I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 48 05 81 60 00 22 00 00 00 00 00 0d 13 I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 88 05 81 60 00 20 08 00 00 00 00 49 01 I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdef0c, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 5 (0x5) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2362) NimBLE: Failed to restore IRKs from store; status=8 I (2362) CHIP[DL]: BLE host-controller synced I (2872) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2872) NimBLE: GAP procedure initiated: advertise; I (2882) NimBLE: disc_mode=2 I (2882) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2892) NimBLE: I (2902) chip[DL]: CHIPoBLE advertising started E (2902) chip[DL]: Long dispatch time: 910 ms, for event type 2 I (2902) chip[DL]: Starting ESP WiFi layer I (2912) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2912) wifi:enable tsf W (2922) wifi:Haven't to connect to a suitable AP now! I (2922) chip[DL]: Attempting to connect WiFi station interface I (2932) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2942) chip[DL]: Done driving station state, nothing else to do... I (2942) chip[SVR]: Initializing subscription resumption storage... I (2942) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2962) wifi:state: init -> auth (b0) I (3052) chip[SVR]: Server initializing... I (3112) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3112) chip[DMG]: AccessControl: initializing I (3112) chip[DMG]: Examples::AccessControlDelegate::Init I (3112) chip[DMG]: AccessControl: setting I (3132) chip[DMG]: DefaultAclStorage: initializing I (3132) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3462) chip[ZCL]: Using ZAP configuration... I (3462) esp_matter_cluster: Cluster plugin init common callback I (3462) chip[DMG]: AccessControlCluster: initializing I (3472) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3472) chip[ZCL]: Initiating Admin Commissioning cluster. E (3482) chip[ZCL]: Duplicate attribute override registration failed E (3492) chip[ZCL]: Duplicate attribute override registration failed I (3502) chip[DIS]: Updating services using commissioning mode 1 I (3502) chip[DIS]: CHIP minimal mDNS started advertising. I (3582) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3582) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: C8024E68887B01F4. I (3602) chip[DIS]: mDNS service published: _matterc._udp I (3602) chip[IN]: CASE Server enabling CASE session setups I (3602) chip[SVR]: Joining Multicast groups I (3602) chip[SVR]: Server Listening... I (3622) esp_matter_core: Dynamic endpoint 0 added I (3622) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3642) app_driver: Window endpoint ID in attribute update I (3642) app_driver: Cluster_ID=3 I (3642) app_driver: Attribute_ID=1 I (3642) app_driver: Attribute_Val_type=15 I (3642) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3662) app_driver: Window endpoint ID in attribute update I (3662) app_driver: Cluster_ID=4 I (3682) app_driver: Attribute_ID=0 I (3682) app_driver: Attribute_Val_type=16 I (3682) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3702) app_driver: Window endpoint ID in attribute update I (3702) app_driver: Cluster_ID=4 I (3702) app_driver: Attribute_ID=65532 I (3722) app_driver: Attribute_Val_type=0 I (3722) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3722) app_driver: Window endpoint ID in attribute update I (3742) app_driver: Cluster_ID=5 I (3742) app_driver: Attribute_ID=0 I (3742) app_driver: Attribute_Val_type=8 W (3742) app_driver: unimplemented cluster 5 at endpoint 1 I (3762) esp_matter_core: Dynamic endpoint 1 added I (3762) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3762) app_driver: Window endpoint ID in attribute update I (3782) app_driver: Cluster_ID=3 I (3782) app_driver: Attribute_ID=1 I (3782) app_driver: Attribute_Val_type=15 I (3782) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3802) app_driver: Window endpoint ID in attribute update I (3802) app_driver: Cluster_ID=4 I (3822) app_driver: Attribute_ID=0 I (3822) app_driver: Attribute_Val_type=16 I (3822) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3842) app_driver: Window endpoint ID in attribute update I (3842) app_driver: Cluster_ID=4 I (3842) app_driver: Attribute_ID=65532 I (3842) app_driver: Attribute_Val_type=0 I (3862) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3862) app_driver: Window endpoint ID in attribute update I (3862) app_driver: Cluster_ID=5 I (3882) app_driver: Attribute_ID=0 I (3882) app_driver: Attribute_Val_type=8 W (3882) app_driver: unimplemented cluster 5 at endpoint 2 I (3882) esp_matter_core: Dynamic endpoint 2 added E (3902) chip[DL]: Long dispatch time: 955 ms, for event type 2 I (3902) chip[DL]: WIFI_EVENT_STA_START I (4032) chip[DL]: Done driving station state, nothing else to do... I (4032) wifi:state: auth -> assoc (0) I (4042) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4042) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4052) chip[DL]: Device already advertising, stop active advertisement and restart I (4052) NimBLE: GAP procedure initiated: stop advertising. I (4062) NimBLE: GAP procedure initiated: advertise; I (4072) NimBLE: disc_mode=2 I (4072) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4082) NimBLE: I (4092) app_main: Commissioning window opened I (4092) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4102) app_main: 3 seconds after restart I (4102) app_main: successfully created APP task I (4112) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4112) app_main: advertise operational done I (4122) main_task: Returned from app_main() I (4782) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5012) app_task: starting combineWait for window_rev 0: 1 I (5072) wifi:state: assoc -> assoc (0) I (5152) wifi:state: assoc -> run (10) I (5162) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5162) wifi:security: WPA3-SAE, phy: bgn, rssi: -30 I (5172) wifi:pm start, type: 1 I (5172) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5172) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5182) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5192) chip[DL]: WiFi station interface connected I (5192) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5202) chip[DL]: Done driving station state, nothing else to do... I (5212) app_main: 4 seconds after restart I (5212) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5212) chip[DL]: Updating advertising data I (5222) app_task: waiting if inverted state will be set: 0 I (5232) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5242) chip[DL]: Device already advertising, stop active advertisement and restart I (5242) NimBLE: GAP procedure initiated: stop advertising. I (5252) NimBLE: GAP procedure initiated: advertise; I (5262) NimBLE: disc_mode=2 I (5262) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5272) NimBLE: I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1820 I (5872) app_main: - CAN Task: 1690 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6152) wifi:idx:0 (ifx:0, b0:39:56:0b:f4:a0), tid:0, ssn:0, winSize:64 I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6812) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8fd0 0x400927f9:0x3ffe8ff0 0x400994a1:0x3ffe9010 0x4014edca:0x3ffe9130 0x401171d6:0x3ffe9150 0x401166e4:0x3ffe91a0 0x401167f1:0x3ffe9230 0x401132d5:0x3ffe9250 0x4011475e:0x3ffe9270 0x4011479c:0x3ffe9290 0x40114acc:0x3ffe92b0 0x40114b01:0x3ffe9320 0x40094fad:0x3ffe9340 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (720) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 08 05 81 4f 00 60 05 00 00 00 00 29 6b I (1612) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1612) app_main: CAN key: inbuffer=a5 48 05 81 43 00 20 08 e0 93 04 00 5e 24 I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 88 05 81 60 00 22 00 00 00 00 00 c0 6c I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 c8 05 81 60 00 20 08 00 00 00 00 0d d4 I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdee88, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 6 (0x6) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2352) NimBLE: Failed to restore IRKs from store; status=8 I (2352) CHIP[DL]: BLE host-controller synced I (2862) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2862) NimBLE: GAP procedure initiated: advertise; I (2872) NimBLE: disc_mode=2 I (2872) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2882) NimBLE: I (2892) chip[DL]: CHIPoBLE advertising started E (2892) chip[DL]: Long dispatch time: 900 ms, for event type 2 I (2892) chip[DL]: Starting ESP WiFi layer I (2902) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2902) wifi:enable tsf W (2912) wifi:Haven't to connect to a suitable AP now! I (2912) chip[DL]: Attempting to connect WiFi station interface I (2922) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2922) chip[DL]: Done driving station state, nothing else to do... I (2932) chip[SVR]: Initializing subscription resumption storage... I (2942) chip[SVR]: Server initializing... I (2942) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (2952) chip[DMG]: AccessControl: initializing I (2952) chip[DMG]: Examples::AccessControlDelegate::Init I (2962) chip[DMG]: AccessControl: setting I (2962) chip[DMG]: DefaultAclStorage: initializing I (2972) chip[DMG]: DefaultAclStorage: 0 entries loaded I (2982) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2992) wifi:state: init -> auth (b0) I (3462) chip[ZCL]: Using ZAP configuration... I (3462) esp_matter_cluster: Cluster plugin init common callback I (3462) chip[DMG]: AccessControlCluster: initializing I (3472) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3472) chip[ZCL]: Initiating Admin Commissioning cluster. E (3482) chip[ZCL]: Duplicate attribute override registration failed E (3492) chip[ZCL]: Duplicate attribute override registration failed I (3502) chip[DIS]: Updating services using commissioning mode 1 I (3502) chip[DIS]: CHIP minimal mDNS started advertising. I (3572) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3572) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 95732B36AB233DA2. I (3592) chip[DIS]: mDNS service published: _matterc._udp I (3592) chip[IN]: CASE Server enabling CASE session setups I (3592) chip[SVR]: Joining Multicast groups I (3592) chip[SVR]: Server Listening... I (3612) esp_matter_core: Dynamic endpoint 0 added I (3612) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3632) app_driver: Window endpoint ID in attribute update I (3632) app_driver: Cluster_ID=3 I (3632) app_driver: Attribute_ID=1 I (3632) app_driver: Attribute_Val_type=15 I (3652) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3652) app_driver: Window endpoint ID in attribute update I (3652) app_driver: Cluster_ID=4 I (3672) app_driver: Attribute_ID=0 I (3672) app_driver: Attribute_Val_type=16 I (3672) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3692) app_driver: Window endpoint ID in attribute update I (3692) app_driver: Cluster_ID=4 I (3692) app_driver: Attribute_ID=65532 I (3692) app_driver: Attribute_Val_type=0 I (3712) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3712) app_driver: Window endpoint ID in attribute update I (3732) app_driver: Cluster_ID=5 I (3732) app_driver: Attribute_ID=0 I (3732) app_driver: Attribute_Val_type=8 W (3732) app_driver: unimplemented cluster 5 at endpoint 1 I (3752) esp_matter_core: Dynamic endpoint 1 added I (3752) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3752) app_driver: Window endpoint ID in attribute update I (3772) app_driver: Cluster_ID=3 I (3772) app_driver: Attribute_ID=1 I (3772) app_driver: Attribute_Val_type=15 I (3772) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3792) app_driver: Window endpoint ID in attribute update I (3792) app_driver: Cluster_ID=4 I (3812) app_driver: Attribute_ID=0 I (3812) app_driver: Attribute_Val_type=16 I (3812) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3832) app_driver: Window endpoint ID in attribute update I (3832) app_driver: Cluster_ID=4 I (3832) app_driver: Attribute_ID=65532 I (3832) app_driver: Attribute_Val_type=0 I (3852) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3852) app_driver: Window endpoint ID in attribute update I (3852) app_driver: Cluster_ID=5 I (3872) app_driver: Attribute_ID=0 I (3872) app_driver: Attribute_Val_type=8 W (3872) app_driver: unimplemented cluster 5 at endpoint 2 I (3872) esp_matter_core: Dynamic endpoint 2 added E (3892) chip[DL]: Long dispatch time: 958 ms, for event type 2 I (3892) chip[DL]: WIFI_EVENT_STA_START I (4032) chip[DL]: Done driving station state, nothing else to do... I (4042) wifi:state: auth -> assoc (0) I (4042) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4052) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4052) chip[DL]: Device already advertising, stop active advertisement and restart I (4052) NimBLE: GAP procedure initiated: stop advertising. I (4062) NimBLE: GAP procedure initiated: advertise; I (4072) NimBLE: disc_mode=2 I (4072) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4082) NimBLE: I (4092) app_main: Commissioning window opened I (4092) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4102) app_main: 3 seconds after restart I (4102) app_main: successfully created APP task I (4112) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4122) main_task: Returned from app_main() I (4122) app_main: advertise operational done I (4782) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5012) app_task: starting combineWait for window_rev 0: 1 I (5072) wifi:state: assoc -> assoc (0) I (5152) wifi:state: assoc -> run (10) I (5162) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5162) wifi:security: WPA3-SAE, phy: bgn, rssi: -31 I (5172) wifi:pm start, type: 1 I (5182) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5182) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5182) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5192) chip[DL]: WiFi station interface connected I (5202) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5202) chip[DL]: Done driving station state, nothing else to do... I (5212) app_main: 4 seconds after restart I (5212) chip[DL]: Updating advertising data I (5232) app_task: waiting if inverted state will be set: 0 I (5232) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5232) chip[DL]: Device already advertising, stop active advertisement and restart I (5242) NimBLE: GAP procedure initiated: stop advertising. I (5252) NimBLE: GAP procedure initiated: advertise; I (5252) NimBLE: disc_mode=2 I (5262) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5272) NimBLE: I (5262) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1854 I (5872) app_main: - CAN Task: 1694 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6812) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8f10 0x400927f9:0x3ffe8f30 0x400994a1:0x3ffe8f50 0x4014edca:0x3ffe9070 0x401171d6:0x3ffe9090 0x401166e4:0x3ffe90e0 0x401167f1:0x3ffe9170 0x401132d5:0x3ffe9190 0x4011475e:0x3ffe91b0 0x4011479c:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 48 05 81 4f 00 60 05 00 00 00 00 6d be I (1612) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1612) app_main: CAN key: inbuffer=a5 88 05 81 43 00 20 08 e0 93 04 00 93 5b I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 c8 05 81 60 00 22 00 00 00 00 00 84 b9 I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 08 05 81 60 00 20 08 00 00 00 00 c0 ab I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdee88, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 7 (0x7) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2362) NimBLE: Failed to restore IRKs from store; status=8 I (2362) CHIP[DL]: BLE host-controller synced I (2862) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2862) NimBLE: GAP procedure initiated: advertise; I (2872) NimBLE: disc_mode=2 I (2872) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2882) NimBLE: I (2892) chip[DL]: CHIPoBLE advertising started E (2892) chip[DL]: Long dispatch time: 900 ms, for event type 2 I (2892) chip[DL]: Starting ESP WiFi layer I (2902) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2902) wifi:enable tsf W (2912) wifi:Haven't to connect to a suitable AP now! I (2912) chip[DL]: Attempting to connect WiFi station interface I (2922) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2922) chip[DL]: Done driving station state, nothing else to do... I (2932) chip[SVR]: Initializing subscription resumption storage... I (2942) chip[SVR]: Server initializing... I (2942) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (2952) chip[DMG]: AccessControl: initializing I (2952) chip[DMG]: Examples::AccessControlDelegate::Init I (2962) chip[DMG]: AccessControl: setting I (2962) chip[DMG]: DefaultAclStorage: initializing I (2972) chip[DMG]: DefaultAclStorage: 0 entries loaded I (2982) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2992) wifi:state: init -> auth (b0) I (3462) chip[ZCL]: Using ZAP configuration... I (3462) esp_matter_cluster: Cluster plugin init common callback I (3462) chip[DMG]: AccessControlCluster: initializing I (3472) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3472) chip[ZCL]: Initiating Admin Commissioning cluster. E (3482) chip[ZCL]: Duplicate attribute override registration failed E (3492) chip[ZCL]: Duplicate attribute override registration failed I (3502) chip[DIS]: Updating services using commissioning mode 1 I (3502) chip[DIS]: CHIP minimal mDNS started advertising. I (3572) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3572) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 62280EE4AD07D4DC. I (3592) chip[DIS]: mDNS service published: _matterc._udp I (3592) chip[IN]: CASE Server enabling CASE session setups I (3592) chip[SVR]: Joining Multicast groups I (3592) chip[SVR]: Server Listening... I (3612) esp_matter_core: Dynamic endpoint 0 added I (3612) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3632) app_driver: Window endpoint ID in attribute update I (3632) app_driver: Cluster_ID=3 I (3632) app_driver: Attribute_ID=1 I (3632) app_driver: Attribute_Val_type=15 I (3652) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3652) app_driver: Window endpoint ID in attribute update I (3652) app_driver: Cluster_ID=4 I (3672) app_driver: Attribute_ID=0 I (3672) app_driver: Attribute_Val_type=16 I (3672) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3692) app_driver: Window endpoint ID in attribute update I (3692) app_driver: Cluster_ID=4 I (3692) app_driver: Attribute_ID=65532 I (3712) app_driver: Attribute_Val_type=0 I (3712) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3712) app_driver: Window endpoint ID in attribute update I (3732) app_driver: Cluster_ID=5 I (3732) app_driver: Attribute_ID=0 I (3732) app_driver: Attribute_Val_type=8 W (3732) app_driver: unimplemented cluster 5 at endpoint 1 I (3752) esp_matter_core: Dynamic endpoint 1 added I (3752) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3752) app_driver: Window endpoint ID in attribute update I (3772) app_driver: Cluster_ID=3 I (3772) app_driver: Attribute_ID=1 I (3772) app_driver: Attribute_Val_type=15 I (3772) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3792) app_driver: Window endpoint ID in attribute update I (3792) app_driver: Cluster_ID=4 I (3812) app_driver: Attribute_ID=0 I (3812) app_driver: Attribute_Val_type=16 I (3812) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3832) app_driver: Window endpoint ID in attribute update I (3832) app_driver: Cluster_ID=4 I (3832) app_driver: Attribute_ID=65532 I (3832) app_driver: Attribute_Val_type=0 I (3852) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3852) app_driver: Window endpoint ID in attribute update I (3852) app_driver: Cluster_ID=5 I (3872) app_driver: Attribute_ID=0 I (3872) app_driver: Attribute_Val_type=8 W (3872) app_driver: unimplemented cluster 5 at endpoint 2 I (3872) esp_matter_core: Dynamic endpoint 2 added E (3892) chip[DL]: Long dispatch time: 957 ms, for event type 2 I (3892) chip[DL]: WIFI_EVENT_STA_START I (4032) chip[DL]: Done driving station state, nothing else to do... I (4042) wifi:state: auth -> assoc (0) I (4042) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4042) chip[DL]: Device already advertising, stop active advertisement and restart I (4052) NimBLE: GAP procedure initiated: stop advertising. I (4052) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4072) NimBLE: GAP procedure initiated: advertise; I (4072) NimBLE: disc_mode=2 I (4072) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4082) NimBLE: I (4092) app_main: Commissioning window opened I (4092) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4102) app_main: 3 seconds after restart I (4102) app_main: successfully created APP task I (4112) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4122) main_task: Returned from app_main() I (4122) app_main: advertise operational done I (4782) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5012) app_task: starting combineWait for window_rev 0: 1 I (5092) wifi:state: assoc -> assoc (0) I (5172) wifi:state: assoc -> run (10) I (5182) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5192) wifi:security: WPA3-SAE, phy: bgn, rssi: -30 I (5192) wifi:pm start, type: 1 I (5202) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5202) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5202) chip[DL]: I (5212) app_main: 4 seconds after restart I (5212) wifi:AP's beacon interval = 103424 us, DTIM period = 2 WiFi station state change: Connecting_Succeeded -> Connected I (5222) app_task: waiting if inverted state will be set: 0 I (5222) chip[DL]: WiFi station interface connected I (5232) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5242) chip[DL]: Done driving station state, nothing else to do... I (5252) chip[DL]: Updating advertising data I (5262) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5262) chip[DL]: Device already advertising, stop active advertisement and restart I (5272) NimBLE: GAP procedure initiated: stop advertising. I (5282) NimBLE: GAP procedure initiated: advertise; I (5282) NimBLE: disc_mode=2 I (5282) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5292) NimBLE: I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1850 I (5872) app_main: - CAN Task: 1694 I (6062) wifi:idx:0 (ifx:0, b0:39:56:0b:f4:a0), tid:0, ssn:0, winSize:64 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6812) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8f10 0x400927f9:0x3ffe8f30 0x400994a1:0x3ffe8f50 0x4014edca:0x3ffe9070 0x401171d6:0x3ffe9090 0x401166e4:0x3ffe90e0 0x401167f1:0x3ffe9170 0x401132d5:0x3ffe9190 0x4011475e:0x3ffe91b0 0x4011479c:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 88 05 81 4f 00 60 05 00 00 00 00 a0 c1 I (1612) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1612) app_main: CAN key: inbuffer=a5 c8 05 81 43 00 20 08 e0 93 04 00 d7 8e I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 08 05 81 60 00 22 00 00 00 00 00 49 c6 I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 48 05 81 60 00 20 08 00 00 00 00 84 7e I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdee88, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 8 (0x8) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2362) NimBLE: Failed to restore IRKs from store; status=8 I (2362) CHIP[DL]: BLE host-controller synced I (2862) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2862) NimBLE: GAP procedure initiated: advertise; I (2872) NimBLE: disc_mode=2 I (2872) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2882) NimBLE: I (2892) chip[DL]: CHIPoBLE advertising started E (2892) chip[DL]: Long dispatch time: 899 ms, for event type 2 I (2892) chip[DL]: Starting ESP WiFi layer I (2902) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2902) wifi:enable tsf W (2912) wifi:Haven't to connect to a suitable AP now! I (2912) chip[DL]: Attempting to connect WiFi station interface I (2922) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2932) chip[DL]: Done driving station state, nothing else to do... I (2932) chip[SVR]: Initializing subscription resumption storage... I (2932) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2942) wifi:state: init -> auth (b0) I (3042) chip[SVR]: Server initializing... I (3102) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3102) chip[DMG]: AccessControl: initializing I (3102) chip[DMG]: Examples::AccessControlDelegate::Init I (3102) chip[DMG]: AccessControl: setting I (3122) chip[DMG]: DefaultAclStorage: initializing I (3122) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3452) chip[ZCL]: Using ZAP configuration... I (3452) esp_matter_cluster: Cluster plugin init common callback I (3452) chip[DMG]: AccessControlCluster: initializing I (3462) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3462) chip[ZCL]: Initiating Admin Commissioning cluster. E (3472) chip[ZCL]: Duplicate attribute override registration failed E (3482) chip[ZCL]: Duplicate attribute override registration failed I (3492) chip[DIS]: Updating services using commissioning mode 1 I (3492) chip[DIS]: CHIP minimal mDNS started advertising. I (3572) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3572) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 5D73F371A6FC9179. I (3592) chip[DIS]: mDNS service published: _matterc._udp I (3592) chip[IN]: CASE Server enabling CASE session setups I (3592) chip[SVR]: Joining Multicast groups I (3592) chip[SVR]: Server Listening... I (3612) esp_matter_core: Dynamic endpoint 0 added I (3612) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3632) app_driver: Window endpoint ID in attribute update I (3632) app_driver: Cluster_ID=3 I (3632) app_driver: Attribute_ID=1 I (3632) app_driver: Attribute_Val_type=15 I (3652) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3652) app_driver: Window endpoint ID in attribute update I (3672) app_driver: Cluster_ID=4 I (3672) app_driver: Attribute_ID=0 I (3672) app_driver: Attribute_Val_type=16 I (3672) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3692) app_driver: Window endpoint ID in attribute update I (3692) app_driver: Cluster_ID=4 I (3692) app_driver: Attribute_ID=65532 I (3712) app_driver: Attribute_Val_type=0 I (3712) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3712) app_driver: Window endpoint ID in attribute update I (3732) app_driver: Cluster_ID=5 I (3732) app_driver: Attribute_ID=0 I (3732) app_driver: Attribute_Val_type=8 W (3732) app_driver: unimplemented cluster 5 at endpoint 1 I (3752) esp_matter_core: Dynamic endpoint 1 added I (3752) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3752) app_driver: Window endpoint ID in attribute update I (3772) app_driver: Cluster_ID=3 I (3772) app_driver: Attribute_ID=1 I (3772) app_driver: Attribute_Val_type=15 I (3772) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3792) app_driver: Window endpoint ID in attribute update I (3792) app_driver: Cluster_ID=4 I (3812) app_driver: Attribute_ID=0 I (3812) app_driver: Attribute_Val_type=16 I (3812) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3832) app_driver: Window endpoint ID in attribute update I (3832) app_driver: Cluster_ID=4 I (3832) app_driver: Attribute_ID=65532 I (3832) app_driver: Attribute_Val_type=0 I (3852) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3852) app_driver: Window endpoint ID in attribute update I (3872) app_driver: Cluster_ID=5 I (3872) app_driver: Attribute_ID=0 I (3872) app_driver: Attribute_Val_type=8 W (3872) app_driver: unimplemented cluster 5 at endpoint 2 I (3872) esp_matter_core: Dynamic endpoint 2 added E (3892) chip[DL]: Long dispatch time: 957 ms, for event type 2 I (3892) chip[DL]: WIFI_EVENT_STA_START I (4022) chip[DL]: Done driving station state, nothing else to do... I (4032) wifi:state: auth -> assoc (0) I (4032) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4042) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4042) chip[DL]: Device already advertising, stop active advertisement and restart I (4052) NimBLE: GAP procedure initiated: stop advertising. I (4062) NimBLE: GAP procedure initiated: advertise; I (4062) NimBLE: disc_mode=2 I (4062) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4072) NimBLE: I (4082) app_main: Commissioning window opened I (4082) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4092) app_main: 3 seconds after restart I (4092) app_main: successfully created APP task I (4102) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4112) main_task: Returned from app_main() I (4112) app_main: advertise operational done I (4772) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5002) app_task: starting combineWait for window_rev 0: 1 I (5062) wifi:state: assoc -> assoc (0) I (5152) wifi:state: assoc -> run (10) I (5172) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5172) wifi:security: WPA3-SAE, phy: bgn, rssi: -32 I (5182) wifi:pm start, type: 1 I (5182) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5182) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5192) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5192) chip[DL]: WiFi station interface connected I (5202) app_main: 4 seconds after restart I (5202) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5212) chip[DL]: Done driving station state, nothing else to do... I (5222) app_task: waiting if inverted state will be set: 0 I (5222) chip[DL]: Updating advertising data I (5232) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5242) chip[DL]: Device already advertising, stop active advertisement and restart I (5252) NimBLE: GAP procedure initiated: stop advertising. I (5252) NimBLE: GAP procedure initiated: advertise; I (5262) NimBLE: disc_mode=2 I (5262) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5272) NimBLE: I (5262) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1854 I (5872) app_main: - CAN Task: 1694 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6822) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8f10 0x400927f9:0x3ffe8f30 0x400994a1:0x3ffe8f50 0x4014edca:0x3ffe9070 0x401171d6:0x3ffe9090 0x401166e4:0x3ffe90e0 0x401167f1:0x3ffe9170 0x401132d5:0x3ffe9190 0x4011475e:0x3ffe91b0 0x4011479c:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (720) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK I (1602) app_main: CAN key: outbuffer=a5 08 06 01 40 00 60 05 00 00 00 00 fe d4 I (1602) app_main: CAN key: inbuffer=a5 c8 05 81 4f 00 60 05 00 00 00 00 e4 14 I (1622) app_main: CAN key: outbuffer=a5 88 06 01 40 00 20 08 00 00 00 00 3c 14 I (1622) app_main: CAN key: inbuffer=a5 08 05 81 43 00 20 08 e0 93 04 00 1a f1 I (1622) app_main: CAN debug: start getFullAccess I (1642) app_main: CAN debug: end getFullAccess I (1642) app_main: CAN key: outbuffer=a5 08 06 01 23 00 22 00 69 46 69 57 e7 16 I (1642) app_main: CAN key: inbuffer=a5 48 05 81 60 00 22 00 00 00 00 00 0d 13 I (1662) app_main: CAN key: outbuffer=a5 88 06 01 23 00 20 08 e0 93 04 00 10 9e I (1662) app_main: CAN key: inbuffer=a5 88 05 81 60 00 20 08 00 00 00 00 49 01 I (1662) app_main: CAN debug: start getFullAccess I (1682) app_main: CAN debug: end getFullAccess I (1682) app_main: changed reset button time in NXP to prevent reset I (1732) app_main: CAN key: inverted_state=direct I (1732) app_main: Window 0 Firmware: V5.68 r0 / V4.1.6 I (1762) app_task: updating segments for window 0: tc=0 tt=0 bc=0 bt=0 I (1762) app_task: app_update_segments: number=0 segments=0 I (1762) app_task: initWindow: number=0 height=60 segment_count=4 I (1772) app_task: Window 0 created with endpoint_id 1 I (1782) app_task: Window 0 created with endpoint_id 2 I (1782) app_task: restored window 0 from bottom: 5000 E (1782) app_task: window 0 not configured I (1792) app_task: restored window_rev 0 from top: 0 E (1792) app_task: window 0 not configured I (1802) app_task: detected stop command for window 0 I (1802) app_task: full control window 0 from top: 0 I (1812) app_main: Starting Matter... I (1832) wifi:wifi driver task: 3ffdee88, prio:23, stack:6656, core=0 I (1862) wifi:wifi firmware version: ce9244d I (1862) wifi:wifi certification version: v7.0 I (1862) wifi:config NVS flash: enabled I (1862) wifi:config nano formating: disabled I (1872) wifi:Init data frame dynamic rx buffer num: 32 I (1872) wifi:Init management frame dynamic rx buffer num: 32 I (1882) wifi:Init management short buffer num: 32 I (1882) wifi:Init dynamic tx buffer num: 32 I (1892) wifi:Init static rx buffer size: 1600 I (1892) wifi:Init static rx buffer num: 10 I (1892) wifi:Init dynamic rx buffer num: 32 I (1902) wifi_init: rx ba win: 6 I (1902) wifi_init: tcpip mbox: 32 I (1912) wifi_init: udp mbox: 6 I (1912) wifi_init: tcp mbox: 6 I (1912) wifi_init: tcp tx win: 5744 I (1922) wifi_init: tcp rx win: 5744 I (1922) wifi_init: tcp mss: 1440 I (1932) wifi_init: WiFi IRAM OP enabled I (1932) wifi_init: WiFi RX IRAM OP enabled I (1972) chip[DL]: NVS set: chip-counters/reboot-count = 9 (0x9) I (1992) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (1992) BTDM_INIT: BT controller compile version [946b762] I (1992) BTDM_INIT: Bluetooth MAC: e8:9f:6d:e6:33:da I (2002) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (2362) NimBLE: Failed to restore IRKs from store; status=8 I (2362) CHIP[DL]: BLE host-controller synced I (2872) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (2872) NimBLE: GAP procedure initiated: advertise; I (2882) NimBLE: disc_mode=2 I (2882) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (2892) NimBLE: I (2902) chip[DL]: CHIPoBLE advertising started E (2902) chip[DL]: Long dispatch time: 910 ms, for event type 2 I (2902) chip[DL]: Starting ESP WiFi layer I (2912) wifi:mode : sta (e8:9f:6d:e6:33:d8) I (2912) wifi:enable tsf W (2922) wifi:Haven't to connect to a suitable AP now! I (2922) chip[DL]: Attempting to connect WiFi station interface I (2932) chip[DL]: WiFi station state change: NotConnected -> Connecting I (2942) chip[DL]: Done driving station state, nothing else to do... I (2942) chip[SVR]: Initializing subscription resumption storage... I (2942) wifi:new:<9,0>, old:<1,0>, ap:<255,255>, sta:<9,0>, prof:1 I (2962) wifi:state: init -> auth (b0) I (3062) chip[SVR]: Server initializing... I (3122) chip[TS]: Last Known Good Time: 2024-02-28T13:26:44 I (3122) chip[DMG]: AccessControl: initializing I (3122) chip[DMG]: Examples::AccessControlDelegate::Init I (3122) chip[DMG]: AccessControl: setting I (3142) chip[DMG]: DefaultAclStorage: initializing I (3142) chip[DMG]: DefaultAclStorage: 0 entries loaded I (3452) chip[ZCL]: Using ZAP configuration... I (3452) esp_matter_cluster: Cluster plugin init common callback I (3452) chip[DMG]: AccessControlCluster: initializing I (3452) chip[ZCL]: 0x3f40bd8eep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (3462) chip[ZCL]: Initiating Admin Commissioning cluster. E (3472) chip[ZCL]: Duplicate attribute override registration failed E (3472) chip[ZCL]: Duplicate attribute override registration failed I (3492) chip[DIS]: Updating services using commissioning mode 1 I (3492) chip[DIS]: CHIP minimal mDNS started advertising. I (3502) chip[DIS]: Advertise commission parameter vendorID=5186 productID=4369 discriminator=1862/07 cm=1 I (3522) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: F0A482AAD618A76A. I (3522) chip[DIS]: mDNS service published: _matterc._udp I (3522) chip[IN]: CASE Server enabling CASE session setups I (3542) chip[SVR]: Joining Multicast groups I (3542) chip[SVR]: Server Listening... I (3542) esp_matter_core: Dynamic endpoint 0 added I (3562) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3562) app_driver: Window endpoint ID in attribute update I (3562) app_driver: Cluster_ID=3 I (3582) app_driver: Attribute_ID=1 I (3582) app_driver: Attribute_Val_type=15 I (3582) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3602) app_driver: Window endpoint ID in attribute update I (3602) app_driver: Cluster_ID=4 I (3602) app_driver: Attribute_ID=0 I (3602) app_driver: Attribute_Val_type=16 I (3622) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3622) app_driver: Window endpoint ID in attribute update I (3642) app_driver: Cluster_ID=4 I (3642) app_driver: Attribute_ID=65532 I (3642) app_driver: Attribute_Val_type=0 I (3642) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3662) app_driver: Window endpoint ID in attribute update I (3662) app_driver: Cluster_ID=5 I (3662) app_driver: Attribute_ID=0 I (3682) app_driver: Attribute_Val_type=8 W (3682) app_driver: unimplemented cluster 5 at endpoint 1 I (3682) esp_matter_core: Dynamic endpoint 1 added I (3682) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000003's Attribute 0x00000001 is 5 ********** I (3702) app_driver: Window endpoint ID in attribute update I (3702) app_driver: Cluster_ID=3 I (3722) app_driver: Attribute_ID=1 I (3722) app_driver: Attribute_Val_type=15 I (3722) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x00000000 is 128 ********** I (3742) app_driver: Window endpoint ID in attribute update I (3742) app_driver: Cluster_ID=4 I (3742) app_driver: Attribute_ID=0 I (3742) app_driver: Attribute_Val_type=16 I (3762) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000004's Attribute 0x0000FFFC is ********** I (3762) app_driver: Window endpoint ID in attribute update I (3762) app_driver: Cluster_ID=4 I (3782) app_driver: Attribute_ID=65532 I (3782) app_driver: Attribute_Val_type=0 I (3782) esp_matter_attribute: ********** W : Endpoint 0x0002's Cluster 0x00000005's Attribute 0x00000000 is 0 ********** I (3802) app_driver: Window endpoint ID in attribute update I (3802) app_driver: Cluster_ID=5 I (3802) app_driver: Attribute_ID=0 I (3802) app_driver: Attribute_Val_type=8 W (3822) app_driver: unimplemented cluster 5 at endpoint 2 I (3822) esp_matter_core: Dynamic endpoint 2 added E (3822) chip[DL]: Long dispatch time: 882 ms, for event type 2 I (3842) chip[DL]: WIFI_EVENT_STA_START I (4022) chip[DL]: Done driving station state, nothing else to do... I (4032) wifi:state: auth -> assoc (0) I (4032) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (4032) chip[DL]: Device already advertising, stop active advertisement and restart I (4042) NimBLE: GAP procedure initiated: stop advertising. I (4042) wifi:Association refused temporarily, comeback time 1000 (TUs) I (4052) NimBLE: GAP procedure initiated: advertise; I (4062) NimBLE: disc_mode=2 I (4062) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (4072) NimBLE: I (4082) app_main: Commissioning window opened I (4082) app_main: last runtime was (15=normal startup) 5s after 0 restarts I (4092) app_main: 3 seconds after restart I (4092) app_main: successfully created APP task I (4102) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (4112) main_task: Returned from app_main() I (4112) app_main: advertise operational done I (4772) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (5002) app_task: starting combineWait for window_rev 0: 1 I (5082) wifi:state: assoc -> assoc (0) I (5162) wifi:state: assoc -> run (10) I (5182) wifi:connected with SmartProducts, aid = 5, channel 9, BW20, bssid = b0:39:56:0b:f4:a0 I (5182) wifi:security: WPA3-SAE, phy: bgn, rssi: -30 I (5182) wifi:pm start, type: 1 I (5192) chip[DL]: WIFI_EVENT_STA_CONNECTED I (5192) chip[DL]: WiFi station state change: Connecting -> Connecting_Succeeded I (5192) chip[DL]: WiFi station state change: Connecting_Succeeded -> Connected I (5202) app_main: 4 seconds after restart I (5202) wifi:AP's beacon interval = 103424 us, DTIM period = 2 I (5202) chip[DL]: WiFi station interface connected I (5222) chip[ZCL]: WiFiDiagnosticsDelegate: OnConnectionStatusChanged I (5222) app_task: waiting if inverted state will be set: 0 I (5232) chip[DL]: Done driving station state, nothing else to do... I (5242) chip[DL]: Updating advertising data I (5252) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) I (5252) chip[DL]: Device already advertising, stop active advertisement and restart I (5262) NimBLE: GAP procedure initiated: stop advertising. I (5272) NimBLE: GAP procedure initiated: advertise; I (5272) NimBLE: disc_mode=2 I (5282) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 I (5292) NimBLE: I (5442) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (5872) app_main: High Water Marks I (5872) app_main: - APP Task: 1850 I (5872) app_main: - CAN Task: 1694 I (6062) wifi:idx:0 (ifx:0, b0:39:56:0b:f4:a0), tid:0, ssn:0, winSize:64 I (6082) app_task: window 0 identification remaining time: -1 (segments: 0x0) I (6302) app_main: 5 seconds after restart I (6732) app_task: window 0 identification remaining time: -1 (segments: 0x1) I (6812) chip[DL]: IP_EVENT_GOT_IP6 I (6812) chip[DL]: IPv6 addr available. Ready on WIFI_STA_DEF interface: fe80:0000:0000:0000:ea9f:6dff:fee6:33d8 assert failed: netif_get_by_index /IDF/components/lwip/lwip/src/core/netif.c:1704 (Required to lock TCPIP core functionality!) Backtrace: 0x40081afe:0x3ffe8f10 0x400927f9:0x3ffe8f30 0x400994a1:0x3ffe8f50 0x4014edca:0x3ffe9070 0x401171d6:0x3ffe9090 0x401166e4:0x3ffe90e0 0x401167f1:0x3ffe9170 0x401132d5:0x3ffe9190 0x4011475e:0x3ffe91b0 0x4011479c:0x3ffe91d0 0x40114acc:0x3ffe91f0 0x40114b01:0x3ffe9260 0x40094fad:0x3ffe9280 ELF file SHA256: 1091b6ca08f1f289 Rebooting... I (31) boot: ESP-IDF HEAD-HASH-NOTFOUND 2nd stage bootloader I (31) boot: compile time Feb 28 2024 13:25:29 I (31) boot: Multicore bootloader I (35) boot: chip revision: v3.0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 16MB I (53) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (62) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 nvs WiFi data 01 02 00010000 0000b000 I (76) boot: 1 nvs_keys NVS keys 01 04 0001b000 00008000 I (84) boot: 2 otadata OTA data 01 00 00023000 00002000 I (91) boot: 3 phy_init RF data 01 01 00025000 00001000 I (99) boot: 4 ota_0 OTA app 00 10 00030000 00600000 I (106) boot: 5 ota_1 OTA app 00 11 00630000 00600000 I (114) boot: 6 fctry WiFi data 01 02 00d00000 00006000 I (121) boot: End of partition table I (126) esp_image: segment 0: paddr=00030020 vaddr=3f400020 size=3e918h (256280) map I (227) esp_image: segment 1: paddr=0006e940 vaddr=3ffbdb60 size=016d8h ( 5848) load I (229) esp_image: segment 2: paddr=00070020 vaddr=400d0020 size=fdcf8h (1039608) map I (608) esp_image: segment 3: paddr=0016dd20 vaddr=3ffbf238 size=04198h ( 16792) load I (615) esp_image: segment 4: paddr=00171ec0 vaddr=40080000 size=1f26ch (127596) load I (683) boot: Loaded app from partition at offset 0x30000 I (684) boot: Disabling RNG early entropy source... I (695) cpu_start: Multicore app I (696) cpu_start: Pro cpu up. I (696) cpu_start: Starting app cpu, entry point is 0x40081504 I (685) cpu_start: App cpu up. I (716) cpu_start: Pro cpu start user code I (716) cpu_start: cpu freq: 160000000 Hz I (716) cpu_start: Application information: I (721) cpu_start: Project name: smp_matter I (726) cpu_start: App version: v1.0.0 I (731) cpu_start: Compile time: Feb 28 2024 13:25:36 I (737) cpu_start: ELF file SHA256: 1091b6ca08f1f289... I (743) cpu_start: ESP-IDF: HEAD-HASH-NOTFOUND I (749) cpu_start: Min chip rev: v3.0 I (753) cpu_start: Max chip rev: v3.99 I (758) cpu_start: Chip rev: v3.0 I (763) heap_init: Initializing. RAM available for dynamic allocation: I (770) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (776) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (782) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (788) heap_init: At 3FFD4038 len 0000BFC8 (47 KiB): DRAM I (794) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (801) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (807) heap_init: At 4009F26C len 00000D94 (3 KiB): IRAM I (815) spi_flash: detected chip: generic I (818) spi_flash: flash io: dio I (825) coexist: coex firmware version: 80b0d89 I (828) app_start: Starting scheduler on CPU0 I (832) app_start: Starting scheduler on CPU1 I (832) main_task: Started on CPU0 I (842) main_task: Calling app_main() I (842) uart: queue free spaces: 10 I (842) app_main: starting firmware v1.0.0 (build: Feb 28 2024 13:25:36) from partition ota_0 E (952) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading str from NVS on key trace! I (962) app_main: last trace: E (962) nvs_controller: Error (ESP_ERR_NVS_NOT_FOUND) reading u16 from NVS on key temp_lock! W (982) nvs_controller: not loading factory defaults, because of missing partition. I (982) app_main: CAN debug: start getFullAccess I (1002) app_main: CAN debug: end getFullAccess I (1002) app_main: hardware tag: SmP, Set Master-Unterputz-Seg. I (1042) app_main: Master Firmware: V5.68 r0 / V5.5.5 I (1052) app_main: CAN key: date=352454633 I (1062) app_main: CAN key: time=3859 I (1072) app_main: CAN key: valid=255 I (1072) app_main: system time: 21.2.2025 15:19 I (1082) app_main: CAN task inited successfully I (1092) app_main: CAN debug: wrong address I (1092) can_task: done processing read sleep time (sleep_window=33555200): ESP_OK