search

jomjol / AI-on-the-edge-device

Easy to use device for connecting "old" measuring units (water, power, gas, ...) to the digital world
https://jomjol.github.io/AI-on-the-edge-device-docs/
6.1k stars 655 forks source link

System hängt sich auf #2433

Closed d3nn3s08 closed 1 year ago

d3nn3s08 commented 1 year ago

The Problem

Es scheint das sich das System aufhängt und auch keine daten mehr auf die sd carde schreibt leider weis ich nicht wie ein usb log geht da ich diesen noch gemacht habe

Version

Release: v15.2.4 (Commit: cbd14a2)

Logfile

Config
[TakeImage]
;RawImagesLocation = /log/source
WaitBeforeTakingPicture = 5
;RawImagesRetention = 15
Demo = false
Brightness = -80
Contrast = -2
Saturation = 1
LEDIntensity = 20
ImageQuality = 12
ImageSize = VGA
FixedExposure = false

[Alignment]
InitialRotate = 175
InitialMirror = false
SearchFieldX = 20
SearchFieldY = 20
AlignmentAlgo = default
FlipImageSize = false
/config/ref0.jpg 345 125
/config/ref1.jpg 121 363

[Digits]
Model = /config/dig-cont_0611_s3_q.tflite
CNNGoodThreshold = 0.5
;ROIImagesLocation = /log/digit
;ROIImagesRetention = 3
main.dig3 252 137 27 42 false
main.dig2 279 137 29 44 false
main.dig1 313 140 25 38 false

[Analog]
Model = /config/ana-cont_1105_s2_q.tflite
;ROIImagesLocation = /log/analog
;ROIImagesRetention = 3
main.ana1 382 199 73 73 false
main.ana2 346 283 75 75 false
main.ana3 261 318 75 75 false
main.ana4 171 280 75 75 false

[PostProcessing]
main.DecimalShift = 0
main.AnalogDigitalTransitionStart = 9.2
PreValueUse = true
PreValueAgeStartup = 720
main.AllowNegativeRates = false
main.MaxRateValue = 0.05
;main.MaxRateType = AbsoluteChange
main.ExtendedResolution = false
main.IgnoreLeadingNaN = false
ErrorMessage = true
CheckDigitIncreaseConsistency = true

[MQTT]
Uri = mqtt://192.168.1.191:1883
MainTopic = watermeter
;ClientID = watermeter
user = *******
password = ******
RetainMessages = false
HomeassistantDiscovery = true
MeterType = water_m3

;[InfluxDB]
;Uri = undefined
;Database = undefined
;user = undefined
;password = undefined
;main.Measurement = undefined
;main.Field = 

;[InfluxDBv2]
;Uri = undefined
;Database = undefined
;Org = undefined
;Token = undefined
;main.Measurement = undefined
main.Field = undefined

;[GPIO]
;IO0 = input disabled 10 false false 
;IO1 = input disabled 10 false false 
;IO3 = input disabled 10 false false 
;IO4 = built-in-led disabled 10 false false 
;IO12 = input-pullup disabled 10 false false 
;IO13 = input-pullup disabled 10 false false 
LEDType = WS2812
LEDNumbers = 2
LEDColor = 150 150 150

[AutoTimer]
AutoStart = true
Interval = 5

[DataLogging]
DataLogActive = true
DataFilesRetention = 3

[Debug]
LogLevel = 3
LogfilesRetention = 3

[System]
TimeZone = CET-1CEST,M3.5.0,M10.5.0/3
;TimeServer = pool.ntp.org
;Hostname = undefined
RSSIThreshold = -65
CPUFrequency = 160
SetupMode = false`

Expected Behavior

No response

Screenshots

No response

Additional Context

No response

Reinhard-M commented 1 year ago

Ich kann das Verhalten mit der gleichen Version bei mir bestätigen. Ab einem Zeitpunkt X werden keine Daten mehr gesendet oder geschrieben. Mir fehlen dann schon mal Logs von 1 oder 2 Tagen. Aus diesem Grund werde ich für MQTT auch die Einführung eines LWT in einem separaten Ticket vorschlagen. Damit kann man in solchen Fällen zumindest eine Message generieren die auf den Ausfall hinweist.

d3nn3s08 commented 1 year ago

ich hab jezt erst mall eine Automation gemacht sobald ein Wi-Fi RSSI und Problem der Status sich ändern maltet sich HA jetzt über Telegramm mit einen Alarm.

war gerade wider die LED blickt 3x und dann noch 2x

Jensilein commented 1 year ago

Bei mir dasselbe Problem. Anfangs hat alles stabil funktioniert. Nunmehr hängt sich das System nach ca. 2 Stunden auf. Es werden keine Daten mehr an den MQTT-Broker gesendet und die Webseite ist auch nicht mehr erreichbar.

caco3 commented 1 year ago

leider weis ich nicht wie ein usb log geht da ich diesen noch gemacht habe

Siehe nächster Kommentar

github-actions[bot] commented 1 year ago

You can use the Web Console to get USB log from the device. The USB log contains more information about the startup and operation of the device than the normal Web UI log When you copy the log into herm, please make sure to use Fenced code blocks by wrapping it into separate lines with ```, see https://docs.github.com/en/get-started/writing-on-github/working-with-advanced-formatting/creating-and-highlighting-code-blocks#fenced-code-blocks

caco3 commented 1 year ago

Ein paar Fragen:

Jensilein commented 1 year ago

Ein paar Fragen:

  • Hattet ihr schon ältere Versionen am laufen? Wie liefen die?
  • Die aktuelle version läuft bei mir ohne Probleme und Crashes über Tage!
  • Formatiert die SD-Karte mal neue oder nehmt eine andere.

Dumme Frage: Muss ich dann das ganze Setup nochmal komplett neu durchführen oder kann ich dafür dann auch ein backup nutzen/einspielen?

friedpa commented 1 year ago

Ein Backup kann für diesen Fall benutzt werden.

d3nn3s08 commented 1 year ago

@caco3

sobald es wider Crasht werde ich das USB log ihr rein setzten

Jensilein commented 1 year ago

Ich habe das Ganze nochmals auf einer komplett neuen SD-Karte installiert und dann anschließend das Backup von der config eingespielt. Das System lief dann eine Zeit lang stabil, aber jetzt tritt das Phänomen erneut auf, d.h. es werden keine Daten mehr an den MQTT-Broker gesendet und auch die Webseite vom "watermeter" nicht mehr erreichbar. Ich habe unten mal das Ergebnis vom USB log eingefügt. Lässt sich daraus irgend etwas ablesen, was weiterhilft?

ets Jul 29 2019 12:21:46

rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:7664
load:0x40078000,len:15360
load:0x40080400,len:3744
entry 0x4008067c
I (27) boot: ESP-IDF 4.3.2 2nd stage bootloader
I (27) boot: compile time 20:02:47
I (27) boot: chip revision: 3
I (30) boot_comm: chip revision: 3, min. bootloader chip revision: 0
I (37) boot.esp32: SPI Speed      : 40MHz
I (42) boot.esp32: SPI Mode       : DIO
I (46) boot.esp32: SPI Flash Size : 4MB
I (51) boot: Enabling RNG early entropy source...
I (56) boot: Partition Table:
I (60) boot: ## Label            Usage          Type ST Offset   Length
I (67) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (75) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (82) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (89) boot:  3 ota_0            OTA app          00 10 00010000 001db000
I (97) boot:  4 ota_1            OTA app          00 11 001f0000 001db000
I (104) boot: End of partition table
I (109) boot_comm: chip revision: 3, min. application chip revision: 0
I (116) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=4634ch (287564) map
I (235) esp_image: segment 1: paddr=00056374 vaddr=3ffb0000 size=03ce0h ( 15584) load
I (241) esp_image: segment 2: paddr=0005a05c vaddr=40080000 size=05fbch ( 24508) load
I (252) esp_image: segment 3: paddr=00060020 vaddr=400d0020 size=1558e0h (1399008) map
I (787) esp_image: segment 4: paddr=001b5908 vaddr=40085fbc size=14494h ( 83092) load
I (837) boot: Loaded app from partition at offset 0x10000
I (837) boot: Disabling RNG early entropy source...
I (848) quad_psram: This chip is ESP32-D0WD
I (849) esp_psram: Found 8MB PSRAM device
I (849) esp_psram: Speed: 40MHz
I (849) esp_psram: PSRAM initialized, cache is in low/high (2-core) mode.
W (856) esp_psram: Virtual address not enough for PSRAM, map as much as we can. 4MB is mapped
I (864) cpu_start: Pro cpu up.
I (867) cpu_start: Starting app cpu, entry point is 0x40082dbc
I (0) cpu_start: App cpu up.
I (1761) esp_psram: SPI SRAM memory test OK
I (1770) cpu_start: Pro cpu start user code
I (1770) cpu_start: cpu freq: 160000000 Hz
I (1770) cpu_start: Application information:
I (1771) cpu_start: Project name:     AI-on-the-edge
I (1775) cpu_start: App version:      v15.2.4
I (1780) cpu_start: Compile time:     May  2 2023 05:56:12
I (1785) cpu_start: ELF file SHA256:  3bf7289bb70a59d5...
I (1790) cpu_start: ESP-IDF:          5.0.1
I (1794) cpu_start: Min chip rev:     v0.0
I (1798) cpu_start: Max chip rev:     v3.99 
I (1802) cpu_start: Chip rev:         v3.1
I (1806) heap_init: Initializing. RAM available for dynamic allocation:
I (1812) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
I (1817) heap_init: At 3FFBD888 len 00022778 (137 KiB): DRAM
I (1822) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1828) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (1833) heap_init: At 4009A450 len 00005BB0 (22 KiB): IRAM
I (1839) esp_psram: Adding pool of 4084K of PSRAM memory to heap allocator
I (1847) spi_flash: detected chip: generic
I (1849) spi_flash: flash io: dio
I (1864) cpu_start: Starting scheduler on PRO CPU.
I (0) cpu_start: Starting scheduler on APP CPU.
I (1865) esp_psram: Reserving pool of 40K of internal memory for DMA/internal allocations
I (1865) MAIN: 

================ Start app_main =================
I (1985) MAIN: =================================================
I (1985) MAIN: ==================== Start ======================
I (2005) MAIN: =================================================
I (2015) MAIN: PSRAM size: 8388608 byte (8MB / 64MBit)
I (2025) MAIN: Total heap: 4377083 byte
I (2035) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (4035) gpio: GPIO[25]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:2 
I (4035) cam_hal: cam init ok
I (4035) sccb: pin_sda 26 pin_scl 27
I (4035) sccb: sccb_i2c_port=1

I (4035) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (4075) camera: Detected camera at address=0x30
I (4075) camera: Detected OV2640 camera
I (4075) camera: Camera PID=0x26 VER=0x42 MIDL=0x7f MIDH=0xa2
I (4165) cam_hal: buffer_size: 32768, half_buffer_size: 4096, node_buffer_size: 2048, node_cnt: 16, total_cnt: 15
I (4165) cam_hal: Allocating 61440 Byte frame buffer in PSRAM
I (4165) cam_hal: cam config ok
I (4165) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
I (6245) MAIN: Camera info: PID: 0x26, VER: 0x42, MIDL: 0x7f, MIDH: 0xa2
I (6255) SDCARD: Basic R/W check started...
I (6295) SDCARD: Basic R/W check successful
I (6345) SNTP: TimeServer not defined, using default: pool.ntp.org
I (6355) SNTP: Configuring NTP Client...
I (6365) SNTP: Time zone set to CET-1CEST,M3.5.0,M10.5.0/3
I (6375) SNTP: The local time is unknown, starting with 1970-01-01 01:00:04
I (6385) SNTP: Once the NTP server provides a time, we will switch to that one
I (6405) MAIN: CPU frequency: 160 MHz
I (6415) OTA: Start CheckOTAUpdateCheck...
I (6425) OTA: SHA-256 for the partition table: : 9d4a2809ae90fb20709a8ca3dd3ac3462e1e73286bcd31b689060c91de06fa3a
I (6445) OTA: SHA-256 for bootloader: : 4a9573dafac5d5c79c43e5a356568332da3a99dd2e220da6ceeadc96d32708a2
I (6925) OTA: SHA-256 for current firmware: : b466e493336ff9d3c9569adde1350c625cd15e53b5a82ea56fa857d3ba3d3945
I (6965) SDCARD: Folder/file presence check started...
I (7015) SDCARD: Folder/file presence check successful
I (7035) MAIN: Tag: 'v15.2.4', Release: v15.2.4 (Commit: cbd14a2), Date/Time: 2023-05-02 05:56, Web UI: Release: v15.2.4 (Commit: cbd14a2)
I (7065) MAIN: Reset reason: Power-on event (or reset button)
I (7085) WLANINI: SSID: FRITZ!Box7490_24
I (7085) WLANINI: Password: XXXXXXXX
I (7105) WLANINI: RSSIThreshold: 0
I (7105) MAIN: WLAN config loaded, init WIFI...
I (7115) WIFI: Automatic interface config --> Use DHCP service
I (7135) wifi:wifi driver task: 3ffd10d0, prio:23, stack:6144, core=0
I (7135) system_api: Base MAC address is not set
I (7135) system_api: read default base MAC address from EFUSE
I (7145) wifi:wifi firmware version: 17afb16
I (7145) wifi:wifi certification version: v7.0
I (7145) wifi:config NVS flash: enabled
I (7145) wifi:config nano formating: enabled
I (7155) wifi:Init data frame dynamic rx buffer num: 16
I (7155) wifi:Init management frame dynamic rx buffer num: 16
I (7165) wifi:Init management short buffer num: 32
I (7165) wifi:Init static tx buffer num: 16
I (7175) wifi:Init tx cache buffer num: 16
I (7175) wifi:Init static rx buffer size: 1600
I (7185) wifi:Init static rx buffer num: 16
I (7185) wifi:Init dynamic rx buffer num: 16
I (7185) wifi_init: rx ba win: 16
I (7195) wifi_init: tcpip mbox: 32
I (7195) wifi_init: udp mbox: 6
I (7195) wifi_init: tcp mbox: 6
I (7195) wifi_init: tcp tx win: 5744
I (7205) wifi_init: tcp rx win: 5744
I (7205) wifi_init: tcp mss: 1440
I (7205) wifi_init: WiFi/LWIP prefer SPIRAM
I (7215) wifi_init: WiFi IRAM OP enabled
I (7215) wifi_init: WiFi RX IRAM OP enabled
I (7235) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07
I (7335) wifi:mode : sta (a0:b7:65:50:67:a0)
I (7335) wifi:enable tsf
I (7345) WIFI: Set hostname to: watermeter
I (7355) WIFI: Init successful
W (9755) WIFI: Disconnected (201, No AP)
I (12185) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1
I (12185) wifi:state: init -> auth (b0)
I (17255) wifi:state: auth -> init (200)
I (17255) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
I (17255) wifi:new:<1,0>, old:<6,0>, ap:<255,255>, sta:<1,0>, prof:1
I (17265) wifi:state: init -> auth (b0)
I (22335) wifi:state: auth -> init (200)
I (22335) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
W (22335) WIFI: Disconnected (2, Auth fail)
W (24765) WIFI: Disconnected (201, No AP)
I (27185) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (27185) wifi:state: init -> auth (b0)
I (28335) MAIN: Device info: CPU cores: 2, Chip revision: 301
I (28345) MAIN: SD card info: Name: SC16G, Capacity: 15193MB, Free: 15167MB
I (29485) WIFI: Connected to: FRITZ!Box7490_24, RSSI: -82
I (30345) MAIN SERVER: Starting server on port: '80'
I (30345) MAIN SERVER: Registering URI handlers
I (30345) MAINCTRL: server_main_flow_task - Registering URI handlers
I (30345) OTA: Registering URI handlers
I (30345) GPIO: start GpioHandler
I (30355) GPIO: register GPIO Uri
I (30355) GPIO: server_GPIO - Registering URI handlers
I (30355) MAIN: Initialization completed successfully
I (30395) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
I (32535) LOGFILE: Set log level to ERROR
I (32545) GPIO: read GPIO config and init GPIO
I (32555) GPIO: GPIO init completed, handler is disabled
I (32565) MAINCTRL: Starting Flow...
I (32565) MAINCTRL: Round #1 started
E (84635) POSTPROC: main: Raw: 77777.65444, Value: , Status: Rate too high - Read: 77777.65444 - Pre: 133.07215 - Rate: 77644.58229
W (84675) MQTT SERVER: Unable to send Static Topics, we are not connected to the MQTT broker!
W (84675) MQTT SERVER: One or more MQTT topics failed to be published, will try sending them in the next round!
W (84685) MQTT SERVER: Unable to send System Topics, we are not connected to the MQTT broker!
W (84695) MQTT: One or more MQTT topics failed to be published!
I (84705) MAINCTRL: Round #1 completed (52 seconds)
W (84705) MAINCTRL: Time server is configured, but time is not yet set!
I (332555) MAINCTRL: Round #2 started
E (384635) POSTPROC: main: Raw: 77777.43448, Value: , Status: Rate too high - Read: 77777.43448 - Pre: 133.07215 - Rate: 77644.36233
W (384675) MQTT SERVER: Unable to send Static Topics, we are not connected to the MQTT broker!
W (384675) MQTT SERVER: One or more MQTT topics failed to be published, will try sending them in the next round!
W (384675) MQTT SERVER: Unable to send System Topics, we are not connected to the MQTT broker!
W (384685) MQTT: One or more MQTT topics failed to be published!
I (384705) MAINCTRL: Round #2 completed (52 seconds)
W (384705) MAINCTRL: Time server is configured, but time is not yet set!
ets Jul 29 2019 12:21:46

rst:0x1 (POWERON_RESET),boot:0x12 (SPI_FAST_FLASH_BOOT)
configsip: 0, SPIWP:0xee
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0030,len:7664
load:0x40078000,len:15360
load:0x40080400,len:3744
entry 0x4008067c
I (27) boot: ESP-IDF 4.3.2 2nd stage bootloader
I (27) boot: compile time 20:02:47
I (27) boot: chip revision: 3
I (30) boot_comm: chip revision: 3, min. bootloader chip revision: 0
I (37) boot.esp32: SPI Speed      : 40MHz
I (42) boot.esp32: SPI Mode       : DIO
I (46) boot.esp32: SPI Flash Size : 4MB
I (51) boot: Enabling RNG early entropy source...
I (56) boot: Partition Table:
I (60) boot: ## Label            Usage          Type ST Offset   Length
I (67) boot:  0 nvs              WiFi data        01 02 00009000 00004000
I (75) boot:  1 otadata          OTA data         01 00 0000d000 00002000
I (82) boot:  2 phy_init         RF data          01 01 0000f000 00001000
I (89) boot:  3 ota_0            OTA app          00 10 00010000 001db000
I (97) boot:  4 ota_1            OTA app          00 11 001f0000 001db000
I (104) boot: End of partition table
I (109) boot_comm: chip revision: 3, min. application chip revision: 0
I (116) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=4634ch (287564) map
I (235) esp_image: segment 1: paddr=00056374 vaddr=3ffb0000 size=03ce0h ( 15584) load
I (241) esp_image: segment 2: paddr=0005a05c vaddr=40080000 size=05fbch ( 24508) load
I (252) esp_image: segment 3: paddr=00060020 vaddr=400d0020 size=1558e0h (1399008) map
I (787) esp_image: segment 4: paddr=001b5908 vaddr=40085fbc size=14494h ( 83092) load
I (837) boot: Loaded app from partition at offset 0x10000
I (837) boot: Disabling RNG early entropy source...
I (848) quad_psram: This chip is ESP32-D0WD
I (849) esp_psram: Found 8MB PSRAM device
I (849) esp_psram: Speed: 40MHz
I (849) esp_psram: PSRAM initialized, cache is in low/high (2-core) mode.
W (856) esp_psram: Virtual address not enough for PSRAM, map as much as we can. 4MB is mapped
I (864) cpu_start: Pro cpu up.
I (867) cpu_start: Starting app cpu, entry point is 0x40082dbc
I (0) cpu_start: App cpu up.
I (1761) esp_psram: SPI SRAM memory test OK
I (1770) cpu_start: Pro cpu start user code
I (1770) cpu_start: cpu freq: 160000000 Hz
I (1770) cpu_start: Application information:
I (1771) cpu_start: Project name:     AI-on-the-edge
I (1775) cpu_start: App version:      v15.2.4
I (1780) cpu_start: Compile time:     May  2 2023 05:56:12
I (1785) cpu_start: ELF file SHA256:  3bf7289bb70a59d5...
I (1790) cpu_start: ESP-IDF:          5.0.1
I (1794) cpu_start: Min chip rev:     v0.0
I (1798) cpu_start: Max chip rev:     v3.99 
I (1802) cpu_start: Chip rev:         v3.1
I (1806) heap_init: Initializing. RAM available for dynamic allocation:
I (1812) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
I (1817) heap_init: At 3FFBD888 len 00022778 (137 KiB): DRAM
I (1822) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1828) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (1833) heap_init: At 4009A450 len 00005BB0 (22 KiB): IRAM
I (1839) esp_psram: Adding pool of 4084K of PSRAM memory to heap allocator
I (1847) spi_flash: detected chip: generic
I (1849) spi_flash: flash io: dio
I (1864) cpu_start: Starting scheduler on PRO CPU.
I (0) cpu_start: Starting scheduler on APP CPU.
I (1865) esp_psram: Reserving pool of 40K of internal memory for DMA/internal allocations
I (1865) MAIN: 

================ Start app_main =================
I (1985) MAIN: =================================================
I (1995) MAIN: ==================== Start ======================
I (1995) MAIN: =================================================
I (2005) MAIN: PSRAM size: 8388608 byte (8MB / 64MBit)
I (2015) MAIN: Total heap: 4377083 byte
I (2025) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (4025) gpio: GPIO[25]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:2 
I (4025) cam_hal: cam init ok
I (4025) sccb: pin_sda 26 pin_scl 27
I (4025) sccb: sccb_i2c_port=1

I (4025) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (4065) camera: Detected camera at address=0x30
I (4065) camera: Detected OV2640 camera
I (4065) camera: Camera PID=0x26 VER=0x42 MIDL=0x7f MIDH=0xa2
I (4155) cam_hal: buffer_size: 32768, half_buffer_size: 4096, node_buffer_size: 2048, node_cnt: 16, total_cnt: 15
I (4155) cam_hal: Allocating 61440 Byte frame buffer in PSRAM
I (4155) cam_hal: cam config ok
I (4155) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
I (6235) MAIN: Camera info: PID: 0x26, VER: 0x42, MIDL: 0x7f, MIDH: 0xa2
I (6245) SDCARD: Basic R/W check started...
I (6285) SDCARD: Basic R/W check successful
I (6335) SNTP: TimeServer not defined, using default: pool.ntp.org
I (6345) SNTP: Configuring NTP Client...
I (6355) SNTP: Time zone set to CET-1CEST,M3.5.0,M10.5.0/3
I (6365) SNTP: The local time is unknown, starting with 1970-01-01 01:00:04
I (6375) SNTP: Once the NTP server provides a time, we will switch to that one
I (6395) MAIN: CPU frequency: 160 MHz
I (6405) OTA: Start CheckOTAUpdateCheck...
I (6415) OTA: SHA-256 for the partition table: : 9d4a2809ae90fb20709a8ca3dd3ac3462e1e73286bcd31b689060c91de06fa3a
I (6435) OTA: SHA-256 for bootloader: : 4a9573dafac5d5c79c43e5a356568332da3a99dd2e220da6ceeadc96d32708a2
I (6915) OTA: SHA-256 for current firmware: : b466e493336ff9d3c9569adde1350c625cd15e53b5a82ea56fa857d3ba3d3945
I (6955) SDCARD: Folder/file presence check started...
I (7005) SDCARD: Folder/file presence check successful
I (7025) MAIN: Tag: 'v15.2.4', Release: v15.2.4 (Commit: cbd14a2), Date/Time: 2023-05-02 05:56, Web UI: Release: v15.2.4 (Commit: cbd14a2)
I (7055) MAIN: Reset reason: Power-on event (or reset button)
I (7075) WLANINI: SSID: FRITZ!Box7490_24
I (7075) WLANINI: Password: XXXXXXXX
I (7095) WLANINI: RSSIThreshold: 0
I (7095) MAIN: WLAN config loaded, init WIFI...
I (7105) WIFI: Automatic interface config --> Use DHCP service
I (7125) wifi:wifi driver task: 3ffd10d0, prio:23, stack:6144, core=0
I (7125) system_api: Base MAC address is not set
I (7125) system_api: read default base MAC address from EFUSE
I (7135) wifi:wifi firmware version: 17afb16
I (7135) wifi:wifi certification version: v7.0
I (7135) wifi:config NVS flash: enabled
I (7135) wifi:config nano formating: enabled
I (7145) wifi:Init data frame dynamic rx buffer num: 16
I (7145) wifi:Init management frame dynamic rx buffer num: 16
I (7155) wifi:Init management short buffer num: 32
I (7155) wifi:Init static tx buffer num: 16
I (7165) wifi:Init tx cache buffer num: 16
I (7165) wifi:Init static rx buffer size: 1600
I (7175) wifi:Init static rx buffer num: 16
I (7175) wifi:Init dynamic rx buffer num: 16
I (7175) wifi_init: rx ba win: 16
I (7185) wifi_init: tcpip mbox: 32
I (7185) wifi_init: udp mbox: 6
I (7185) wifi_init: tcp mbox: 6
I (7185) wifi_init: tcp tx win: 5744
I (7195) wifi_init: tcp rx win: 5744
I (7195) wifi_init: tcp mss: 1440
I (7195) wifi_init: WiFi/LWIP prefer SPIRAM
I (7205) wifi_init: WiFi IRAM OP enabled
I (7205) wifi_init: WiFi RX IRAM OP enabled
I (7225) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07
I (7325) wifi:mode : sta (a0:b7:65:50:67:a0)
I (7325) wifi:enable tsf
I (7335) WIFI: Set hostname to: watermeter
I (7345) WIFI: Init successful
I (9745) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (9745) wifi:state: init -> auth (b0)
I (14815) wifi:state: auth -> init (200)
I (14815) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (14825) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1
I (14825) wifi:state: init -> auth (b0)
I (17015) wifi:state: auth -> assoc (0)
I (17085) wifi:state: assoc -> run (10)
I (17545) wifi:connected with FRITZ!Box7490_24, aid = 9, channel 6, BW20, bssid = cc:ce:1e:21:cf:e5
I (17545) wifi:security: WPA3-SAE, phy: bgn, rssi: -86
I (17615) wifi:pm start, type: 1

I (17625) WIFI: Connected to: FRITZ!Box7490_24, RSSI: -86
I (23095) wifi:bcn_timeout,ap_probe_send_start
I (27895) MAIN: Device info: CPU cores: 2, Chip revision: 301
I (27905) MAIN: SD card info: Name: SC16G, Capacity: 15193MB, Free: 15167MB
I (29905) MAIN SERVER: Starting server on port: '80'
I (29905) MAIN SERVER: Registering URI handlers
I (29905) MAINCTRL: server_main_flow_task - Registering URI handlers
I (29905) OTA: Registering URI handlers
I (29905) GPIO: start GpioHandler
I (29915) GPIO: register GPIO Uri
I (29915) GPIO: server_GPIO - Registering URI handlers
I (29915) MAIN: Initialization completed successfully
I (29955) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
I (32075) LOGFILE: Set log level to ERROR
I (32085) GPIO: read GPIO config and init GPIO
I (32095) GPIO: GPIO init completed, handler is disabled
I (32105) MAINCTRL: Starting Flow...
I (32105) MAINCTRL: Round #1 started
E (84165) POSTPROC: main: Raw: 77777.54455, Value: , Status: Rate too high - Read: 77777.54455 - Pre: 133.07215 - Rate: 77644.47240
W (84195) MQTT SERVER: Unable to send Static Topics, we are not connected to the MQTT broker!
W (84195) MQTT SERVER: One or more MQTT topics failed to be published, will try sending them in the next round!
W (84195) MQTT SERVER: Unable to send System Topics, we are not connected to the MQTT broker!
W (84205) MQTT: One or more MQTT topics failed to be published!
I (84225) MAINCTRL: Round #1 completed (52 seconds)
W (84225) MAINCTRL: Time server is configured, but time is not yet set!
>
friedpa commented 1 year ago

Das Problem liegt einmal in der WIFI Authentification, anscheinend fällt der ESP ab und zu aus dem Netzwerk (und kann sich nachher nicht mehr verbinden) und ist daher auch nicht mehr erreichbar:

W (22335) WIFI: Disconnected (2, Auth fail) W (24765) WIFI: Disconnected (201, No AP)

Beim MQTT gibt es das Problem, dass der ESP den MQTT Server nicht erreicht. Ist denn 192.168.1.191 erreichbar (mit ping einmal ausprobieren)?

W (84195) MQTT SERVER: Unable to send Static Topics, we are not connected to the MQTT broker! W (84195) MQTT SERVER: One or more MQTT topics failed to be published, will try sending them in the next round! W (84195) MQTT SERVER: Unable to send System Topics, we are not connected to the MQTT broker! W (84205) MQTT: One or more MQTT topics failed to be published!

Also einmal Deine Config posten (ohne WIFI Password), oder ist die gleich wie oben?

Jensilein commented 1 year ago

Das Problem liegt einmal in der WIFI Authentification, anscheinend fällt der ESP ab und zu aus dem Netzwerk (und kann sich nachher nicht mehr verbinden) und ist daher auch nicht mehr erreichbar:

W (22335) WIFI: Disconnected (2, Auth fail) W (24765) WIFI: Disconnected (201, No AP)

Beim MQTT gibt es das Problem, dass der ESP den MQTT Server nicht erreicht. Ist denn 192.168.1.191 erreichbar (mit ping einmal ausprobieren)?

W (84195) MQTT SERVER: Unable to send Static Topics, we are not connected to the MQTT broker! W (84195) MQTT SERVER: One or more MQTT topics failed to be published, will try sending them in the next round! W (84195) MQTT SERVER: Unable to send System Topics, we are not connected to the MQTT broker! W (84205) MQTT: One or more MQTT topics failed to be published!

Also einmal Deine Config posten (ohne WIFI Password), oder ist die gleich wie oben?

Hm, Danke. Wobei er sich ja zu Beginn aber immer erfolgreich mit dem WLAN verbindet und dann auch eine relativ lange Zeit stabil läuft. Wie lässt sich das erklären? Die erwähnte IP-Addresse vom MQTT-Broker ist nicht die von meinem MQTT-Broker, sonderrn die vom user d3nn3s08.

Viele Grüße

Reinhard-M commented 1 year ago

Ich kann die von Jensilein gemachten Beobachtungen voll bestätigen. Mein WIFI Pegel liegt aber in der Region um die -50 dBm, also "Excellent". Leider schaffe ich es aktuell nicht weiter zu debuggen. Sporadisch kompletter Verbindungsverlust. Dann werden außerdem keine weiteren Daten geschrieben (Log ist leer und ich zeichne jede Minute auf). Zunächst lange Zeit stabil. Jetzt in immer kürzeren Abständen (5 Tage, 3 Tage, 1 Tag) Absturz ohne wieder auf die Beine zu kommen. Gestern Abend war nicht einmal ein Restart möglich. Über Nacht abgeschaltet, seit ich heute morgen wieder aktiviert habe läuft er problemlos. Errormeldung gestern Abend "0x00000010" (hoffe die Nullen vorne stimmen). Laut Doku Kartenfehler. Trat davor und danach nicht auf. 32G Karte.

friedpa commented 1 year ago

Warum das WIFI wegbricht lässt sich schwer von extern beurteilen, aber der ESP ist sehr schwach was Empfang/Senden angeht. Es hilft eine externe Antenne, oder aber einfach ein Repeater in der Nähe des ESP platzieren um das WIFI stabil zu bekommen. Hat das "pingen" einen Erfolg beim Erreichen des MQTT Servers gezeigt?

Reinhard-M commented 1 year ago

Wie erwähnt habe ich wirklich ein gutes Signal. Ich verwende eine externe Antenne am ESP und der Repeater ist höchstens 6m entfernt. Und der ESP ist auf diesem Repeater eingebucht. Abgesehen davon, selbst wenn WIFI komplett wegbricht müsste der ESP ja weiter auf die Karte schreiben. Das bleibt aber ebenfalls aus. Ich hatte z. B. 2 Tage keine einzige Datenzeile. Wenn ich mich recht erinnere bleibt dann auch die Aufnahme des Zählerstandes aus. Mache ich am Ausbleiben der Beleuchtung fest.

caco3 commented 1 year ago

@Jensilein

I (17545) wifi:security: WPA3-SAE, phy: bgn, rssi: -86

Deine Verbindung ist sehr schlecht, deshalb die Disconnects und Folgefehler. Das RSSI sollte besser als ca. -60 sein!

caco3 commented 1 year ago

@Reinhard-M

Zunächst lange Zeit stabil. Jetzt in immer kürzeren Abständen (5 Tage, 3 Tage, 1 Tag) Absturz ohne wieder auf die Beine zu kommen.

Das tönt nach Temperaturprobleme oder schlechter Hardware. Kannst Du es mal mit einem anderen ESP versuchen? Und einer anderen SD Karte.

caco3 commented 1 year ago

selbst wenn WIFI komplett wegbricht müsste der ESP ja weiter auf die Karte schreiben.

Das wäre auch meine Erwartung. Aber es könnte sein, dass er irgendo hängenbleibt.

Das USB Log könnte da mehr Infos liefern.

Errormeldung gestern Abend "0x00000010" (hoffe die Nullen vorne stimmen). Laut Doku Kartenfehler.

Der ESP ist etwas zickig, versuchs mal mit einer anderen Karte

Jensilein commented 1 year ago

@Jensilein

I (17545) wifi:security: WPA3-SAE, phy: bgn, rssi: -86

Deine Verbindung ist sehr schlecht, deshalb die Disconnects und Folgefehler. Das RSSI sollte besser als ca. -60 sein!

Ich werde nun als nächstes mal versuchen, mit einem WLAN-Repeater die Verbindung zu verbessern. Mal sehen, was das bringt.

d3nn3s08 commented 1 year ago

update: folgendes habe ich gestern geändert

State: Flow finished (20:37:39) Last Page Refresh:20:40:22 CPU Temperature: 50°C WIFI Signal: Good (-66dBm) Uptime: 1d 05h 08m 44s (Round: 350) Uptime: 1d 05h 11m 29s

caco3 commented 1 year ago

@d3nn3s08 Das sieht doch schon viel besser aus. Und bestätigt leider, dass nicht alle SD-Karten/Formatierungen problemlos laufen.

[1d05h06m16s] 2023-05-25T20:37:52 [OTA FILE] Failed to read file: /sdcard/log/message/log_2023-05-25.txt!

Diese Fehlermeldung sagt, dass das WebUI diese Datei angefordert hat, der Webserver sie nicht gefunden hat. Das kann z.B. direkt nach Mitternacht passieren, wenn noch keine Runde gelaufen ist. Aber dass Du sie um 20:00 immer noch siehst, ist komisch.

Ev. war der Webserver auch einfach kurz überlastet und meldet sich dann so. Wir verwenden da die vom ESP Framework bereitgestellten Funktionen.

d3nn3s08 commented 1 year ago

@caco3 jop daten log ist noch aktiv und steht auf debug und der fehler ist immer noch da

https://de.share-your-photo.com/707d0c703c der schreibt irgent wie kein log mehr :/ 112B groß

was ich für die Zukunft mit rein nehmen würde währe die System Belastung

caco3 commented 1 year ago

@d3nn3s08 Ich vermute ein Problem mit deiner SD-Karte. Schau dir mal den USB-Log an.

Jensilein commented 1 year ago

@Jensilein

I (17545) wifi:security: WPA3-SAE, phy: bgn, rssi: -86

Deine Verbindung ist sehr schlecht, deshalb die Disconnects und Folgefehler. Das RSSI sollte besser als ca. -60 sein!

Ich werde nun als nächstes mal versuchen, mit einem WLAN-Repeater die Verbindung zu verbessern. Mal sehen, was das bringt.

Habe jetzt seit ein paar Tagen einen WLAN-Repeater unmittelbar in der Nähe des ESP platziert. Empfangsqualität wird mit Excellent angegeben. System scheint oder besser gesagt schien seitdem weitgehend stabil zu laufen. Heute ist der ESP aber wieder unvermittelt abgestürzt und nicht mehr erreichbar. Es ist zum Verzweifeln.