Kein Systemstart wenn kein Computer verbunden ist #3680
Description
The Problem
Ich habe das Problem das der ESP nicht zuverlässig bootet wenn kein Computer nit Serial Monitor verbunden ist.
Bedeutet wenn eich ein USB Netzteil (habe unterschiedliche getestet) als Stromquelle nutze startet der ESP nicht. Er verbindet sich nicht mit dem WLAN. Da wenn ich ihn zum Debugging an den Computer anschließe alles geht, kann ich leider auch keine Boot Logs auswerten.
Ein Boot Log von der Konsole (da sieht alles gut aus und ich komme auch auf das Webinterface drauf) habe ich angehängt.
Hat jemand hier eine Idee ?
Version
Release: v16.0.0 (Commit: f542d84)
Logfile
14:07:23.870 -> �z�LB�=]�I=9}I�M�Q�,boot:0x13 (SPI_FAST_FLASH_BOOT)
14:07:23.870 -> configsip: 0, SPIWP:0xee
14:07:23.870 -> clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
14:07:23.870 -> mode:DIO, clock div:2
14:07:23.870 -> load:0x3fff0030,len:3540
14:07:23.870 -> load:0x40078000,len:14712
14:07:23.895 -> load:0x40080400,len:4
14:07:23.895 -> load:0x40080404,len:3096
14:07:23.895 -> entry 0x40080584
14:07:24.442 -> I (583) quad_psram: This chip is ESP32-D0WD
14:07:24.442 -> I (583) esp_psram: Found 8MB PSRAM device
14:07:24.442 -> I (583) esp_psram: Speed: 40MHz
14:07:24.442 -> I (583) esp_psram: PSRAM initialized, cache is in low/high (2-core) mode.
14:07:24.473 -> W (588) esp_psram: Virtual address not enough for PSRAM, map as much as we can. 4MB is mapped
14:07:24.473 -> I (597) cpu_start: Multicore app
14:07:25.340 -> I (1477) esp_psram: SPI SRAM memory test OK
14:07:25.340 -> I (1486) cpu_start: Pro cpu start user code
14:07:25.340 -> I (1486) cpu_start: cpu freq: 160000000 Hz
14:07:25.340 -> I (1486) app_init: Application information:
14:07:25.373 -> I (1487) app_init: Project name: AI-on-the-edge
14:07:25.373 -> I (1491) app_init: App version: v16.0.0
14:07:25.373 -> I (1495) app_init: Compile time: Mar 15 2025 13:31:36
14:07:25.373 -> I (1500) app_init: ELF file SHA256: 2a3f258f4...
14:07:25.373 -> I (1505) app_init: ESP-IDF: 5.3.1
14:07:25.373 -> I (1509) efuse_init: Min chip rev: v0.0
14:07:25.373 -> I (1512) efuse_init: Max chip rev: v3.99
14:07:25.373 -> I (1517) efuse_init: Chip rev: v1.0
14:07:25.405 -> I (1521) heap_init: Initializing. RAM available for dynamic allocation:
14:07:25.405 -> I (1527) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
14:07:25.405 -> I (1532) heap_init: At 3FFBC580 len 00023A80 (142 KiB): DRAM
14:07:25.405 -> I (1537) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
14:07:25.405 -> I (1543) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
14:07:25.405 -> I (1548) heap_init: At 4009B4C8 len 00004B38 (18 KiB): IRAM
14:07:25.436 -> I (1554) esp_psram: Adding pool of 4081K of PSRAM memory to heap allocator
14:07:25.436 -> I (1562) spi_flash: detected chip: generic
14:07:25.436 -> I (1564) spi_flash: flash io: dio
14:07:25.436 -> W (1569) i2c: This driver is an old driver, please migrate your application code to adapt `driver/i2c_master.h`
14:07:25.436 -> I (1589) main_task: Started on CPU0
14:07:25.469 -> I (1599) esp_psram: Reserving pool of 40K of internal memory for DMA/internal allocations
14:07:25.469 -> I (1599) main_task: Calling app_main()
14:07:25.469 -> I (1599) MAIN:
14:07:25.469 ->
14:07:25.469 ->
14:07:25.469 ->
14:07:25.469 -> ================ Start app_main =================
14:07:25.758 -> I (1889) MAIN: =================================================
14:07:25.758 -> I (1899) MAIN: ==================== Start ======================
14:07:25.790 -> I (1909) MAIN: =================================================
14:07:25.790 -> I (1919) SDCARD: Basic R/W check started...
14:07:25.790 -> I (1939) SDCARD: Basic R/W check successful
14:07:25.821 -> I (1949) OTA: Start CheckOTAUpdateCheck...
14:07:25.821 -> I (1959) OTA: SHA-256 for the partition table: : 9d4a2809ae90fb20709a8ca3dd3ac3462e1e73286bcd31b689060c91de06fa3a
14:07:25.854 -> I (1979) OTA: SHA-256 for bootloader: : 521c0d8591adb4faf6cf5eb915d85144e7bed079d5012bc9e7c8a173ceec0416
14:07:26.239 -> I (2379) OTA: SHA-256 for current firmware: : 6b7b421ffc456d7032c99d64763181a1b80020d93b597ad72b73495dd4e56f86
14:07:26.271 -> I (2409) MAIN: PSRAM size: 8388608 byte (8MB / 64MBit)
14:07:26.271 -> I (2419) MAIN: Total heap: 4378411 byte
14:07:26.400 -> I (2529) gpio: GPIO[25]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:2
14:07:26.400 -> I (2529) cam_hal: cam init ok
14:07:26.400 -> I (2529) sccb: pin_sda 26 pin_scl 27
14:07:26.400 -> I (2529) sccb: sccb_i2c_port=1
14:07:26.400 -> I (2529) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0
14:07:26.432 -> I (2569) camera: Detected camera at address=0x30
14:07:26.432 -> I (2569) camera: Detected OV2640 camera
14:07:26.432 -> I (2569) camera: Camera PID=0x26 VER=0x42 MIDL=0x7f MIDH=0xa2
14:07:26.529 -> I (2659) cam_hal: buffer_size: 32768, half_buffer_size: 4096, node_buffer_size: 2048, node_cnt: 16, total_cnt: 15
14:07:26.529 -> I (2659) cam_hal: Allocating 61440 Byte frame buffer in PSRAM
14:07:26.529 -> I (2659) cam_hal: cam config ok
14:07:26.529 -> I (2659) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
14:07:26.688 -> I (2839) CAM: OV2640 camera module detected
14:07:28.715 -> I (4839) MAIN: Camera info: PID: 0x26, VER: 0x42, MIDL: 0x7f, MIDH: 0xa2
14:07:28.780 -> I (4919) SNTP: TimeServer not defined, using default: pool.ntp.org
14:07:28.780 -> I (4919) SNTP: Configuring NTP Client...
14:07:28.780 -> I (4929) SNTP: Time zone set to CET-1CEST,M3.5.0,M10.5.0/3
14:07:28.811 -> I (4939) SNTP: time zone: +0100 Delta to UTC: 3600 seconds
14:07:28.811 -> I (4949) SNTP: The local time is unknown, starting with 1970-01-01 01:00:03
14:07:28.811 -> I (4949) SNTP: Once the NTP server provides a time, we will switch to that one
14:07:28.843 -> I (4969) MAIN: CPU frequency: 160 MHz
14:07:28.843 -> I (4979) SDCARD: Folder/file presence check started...
14:07:28.876 -> I (5029) SDCARD: Folder/file presence check successful
14:07:28.908 -> I (5039) MAIN: Tag: 'v16.0.0', Release: v16.0.0 (Commit: f542d84), Date/Time: 2025-03-15 13:28, Web UI: Release: v16.0.0 (Commit: f542d84)
14:07:28.908 -> I (5049) MAIN: Reset reason: Power-on event (or reset button)
14:07:28.940 -> I (5059) WLANINI: SSID: gizmo
14:07:28.940 -> I (5069) WLANINI: Password: XXXXXXXX
14:07:28.940 -> I (5069) WLANINI: Hostname: watermeter
14:07:28.940 -> I (5079) WLANINI: RSSIThreshold: -75
14:07:28.940 -> I (5089) MAIN: WLAN config loaded, init WIFI...
14:07:28.973 -> I (5099) WIFI: Automatic interface config --> Use DHCP service
14:07:28.973 -> I (5119) wifi:wifi driver task: 3ffcfaf0, prio:23, stack:6144, core=0
14:07:29.004 -> I (5129) wifi:wifi firmware version: ccaebfa
14:07:29.004 -> I (5129) wifi:wifi certification version: v7.0
14:07:29.004 -> I (5129) wifi:config NVS flash: enabled
14:07:29.004 -> I (5129) wifi:config nano formating: enabled
14:07:29.004 -> I (5129) wifi:Init data frame dynamic rx buffer num: 16
14:07:29.004 -> I (5139) wifi:Init static rx mgmt buffer num: 5
14:07:29.004 -> I (5139) wifi:Init management short buffer num: 32
14:07:29.004 -> I (5149) wifi:Init static tx buffer num: 16
14:07:29.036 -> I (5149) wifi:Init tx cache buffer num: 16
14:07:29.036 -> I (5149) wifi:Init static rx buffer size: 1600
14:07:29.036 -> I (5159) wifi:Init static rx buffer num: 16
14:07:29.036 -> I (5159) wifi:Init dynamic rx buffer num: 16
14:07:29.036 -> I (5169) wifi_init: rx ba win: 16
14:07:29.036 -> I (5169) wifi_init: accept mbox: 6
14:07:29.036 -> I (5169) wifi_init: tcpip mbox: 32
14:07:29.036 -> I (5179) wifi_init: udp mbox: 6
14:07:29.036 -> I (5179) wifi_init: tcp mbox: 6
14:07:29.068 -> I (5179) wifi_init: tcp tx win: 5760
14:07:29.068 -> I (5179) wifi_init: tcp rx win: 5760
14:07:29.068 -> I (5189) wifi_init: tcp mss: 1440
14:07:29.068 -> I (5189) wifi_init: WiFi/LWIP prefer SPIRAM
14:07:29.068 -> I (5189) wifi_init: WiFi IRAM OP enabled
14:07:29.068 -> I (5199) wifi_init: WiFi RX IRAM OP enabled
14:07:29.068 -> W (5199) wifi:Password length matches WPA2 standards, authmode threshold changes from OPEN to WPA2
14:07:29.068 -> I (5209) phy_init: phy_version 4830,54550f7,Jun 20 2024,14:22:08
14:07:29.165 -> I (5289) wifi:mode : sta (xx:xx:fb:2f:66:f8)
14:07:29.165 -> I (5289) wifi:enable tsf
14:07:29.165 -> I (5289) WIFI: Set hostname to: watermeter
14:07:29.165 -> I (5309) WIFI: Init successful
14:07:31.587 -> I (7709) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1, snd_ch_cfg:0x0
14:07:31.587 -> I (7709) wifi:state: init -> auth (0xb0)
14:07:31.587 -> I (7719) wifi:state: auth -> assoc (0x0)
14:07:31.587 -> I (7729) wifi:state: assoc -> run (0x10)
14:07:31.618 -> I (7749) wifi:connected with xxxx, aid = 12, channel 6, BW20, bssid = xx:21:xx:a3:4f:xx
14:07:31.618 -> I (7759) wifi:security: WPA2-PSK, phy: bgn, rssi: -72
14:07:31.618 -> I (7759) wifi:pm start, type: 1
14:07:31.618 ->
14:07:31.618 -> I (7759) wifi:dp: 1, bi: 102400, li: 3, scale listen interval from 307200 us to 307200 us
14:07:31.651 -> I (7769) WIFI: Connected to: gizmo, RSSI: -70
14:07:31.651 -> I (7799) wifi:<ba-add>idx:0 (ifx:0, xx:21:xx:a3:4f:xx), tid:6, ssn:0, winSize:64
14:07:31.682 -> I (7829) wifi:AP's beacon interval = 102400 us, DTIM period = 1
14:07:32.133 -> I (8259) wifi:<ba-add>idx:1 (ifx:0, xx:21:xx:a3:4f:xx), tid:0, ssn:1, winSize:64
14:07:32.617 -> I (8769) esp_netif_handlers: sta ip: 192.168.10.236, mask: 255.255.255.0, gw: 192.168.10.1
14:07:32.649 -> I (8769) WIFI: Assigned IP: 192.168.10.236
14:07:33.262 -> I (9409) SNTP: =================================================
14:07:33.293 -> I (9419) SNTP: ==================== Start ======================
14:07:33.293 -> I (9429) SNTP: == Logs before time sync -> log_1970-01-01.txt ==
14:07:33.293 -> I (9429) SNTP: Time is synced with NTP Server pool.ntp.org: 2025-03-30 14:07:33
14:07:35.159 -> I (11309) MAIN: Device info: CPU cores: 2, Chip revision: 100
14:07:35.191 -> I (11319) MAIN: SD card info: Name: SK32G, Capacity: 30436MB, Free: 30412MB
14:07:37.163 -> I (13319) MAIN SERVER: Starting server on port: '80'
14:07:37.195 -> I (13319) MAIN SERVER: Registering URI handlers
14:07:37.195 -> I (13319) MAINCTRL: server_main_flow_task - Registering URI handlers
14:07:37.195 -> I (13319) OTA: Registering URI handlers
14:07:37.195 -> I (13319) GPIO: start GpioHandler
14:07:37.195 -> I (13329) GPIO: register GPIO Uri
14:07:37.195 -> I (13329) GPIO: server_GPIO - Registering URI handlers
14:07:37.195 -> I (13329) MAIN: Initialization completed successfully
14:07:37.227 -> I (13349) main_task: Returned from app_main()
14:07:37.227 -> E (13349) FLOWCTRL: Start ReadParameter ([TakeImage]
14:07:37.227 -> )
14:07:37.227 -> I (13369) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
14:07:37.421 -> I (13569) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
14:07:39.006 -> E (15139) FLOWCTRL: Start ReadParameter ([Alignment])
14:07:39.006 -> E (15149) FLOWCTRL: Start ReadParameter ([Digits])
14:07:39.425 -> I (15569) TFLITE: Trying to load the model. If it crashes here, it ist most likely due to a corrupted model!
14:07:39.457 -> E (15589) FLOWCTRL: Start ReadParameter ([Analog])
14:07:39.713 -> I (15839) TFLITE: Trying to load the model. If it crashes here, it ist most likely due to a corrupted model!
14:07:39.713 -> E (15849) FLOWCTRL: Start ReadParameter ([PostProcessing])
14:07:39.745 -> E (15869) FLOWCTRL: Start ReadParameter ([MQTT])
14:07:39.745 -> E (15889) FLOWCTRL: Start ReadParameter ([AutoTimer]
14:07:39.745 -> )
14:07:39.745 -> E (15889) FLOWCTRL: Start ReadParameter ([DataLogging])
14:07:39.777 -> E (15899) FLOWCTRL: Start ReadParameter ([Debug])
14:07:39.777 -> I (15899) LOGFILE: Set log level to ERROR
14:07:39.777 -> E (15909) FLOWCTRL: Start ReadParameter ([System])
14:07:39.777 -> I (15909) GPIO: read GPIO config and init GPIO
14:07:39.777 -> I (15929) GPIO: GPIO init completed, handler is disabled
14:07:39.810 -> I (15929) MQTT IF: Init
14:07:39.810 -> I (15939) MQTT IF: Client started, waiting for established connection...
14:07:39.810 -> I (15939) MAINCTRL: Starting Flow...
14:07:39.810 -> I (15949) MAINCTRL: Round #1 started
14:07:41.392 -> I (17509) MQTT IF: Connected to broker
14:08:00.417 -> I (36539) TFLITE: Trying to load the model. If it crashes here, it ist most likely due to a corrupted model!
14:08:00.578 -> I (36729) OTA FILE: uri: /log
14:08:00.611 -> I (36729) OTA FILE: File contains 1153 bytes
14:08:01.927 -> W (38069) httpd_txrx: httpd_sock_err: error in recv : 104
14:08:03.089 -> W (39239) CNN: Value Rejected due to Threshold (Fit: 0.367188, Threshold: 0.500000)
14:08:05.771 -> W (41919) CNN: Value Rejected due to Threshold (Fit: 0.367188, Threshold: 0.500000)
14:08:08.426 -> W (44569) CNN: Value Rejected due to Threshold (Fit: 0.367188, Threshold: 0.500000)
14:08:08.812 -> I (44949) TFLITE: Trying to load the model. If it crashes here, it ist most likely due to a corrupted model!
14:08:11.549 -> I (47679) POSTPROC: main: Raw: NNN.4253, Value: NNN.4253, Status:
14:08:11.838 -> I (47989) MAINCTRL: Round #1 completed (32 seconds)
14:08:30.398 -> I (66519) OTA FILE: uri: /log
14:08:30.398 -> I (66529) OTA FILE: File contains 1153 bytesExpected Behavior
No response
Screenshots
No response
Additional Context
No response