Device connected but no IP address

[CmKeen]

Hi,
I've setup a new ESP32-CAM with AI-on-the-edge. Flashing worked fine and the device connect to my network (I see it in my Unify Network). However, it doesn't receive any IP address, hence I can't connect to it to continue the setup.
I've tried with and without setting up a fixed IP and ended with the same result.

Has anyone an idea?

Here are the logs:

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=40014h (262164) map
I (225) esp_image: segment 1: paddr=0005003c vaddr=3ffb0000 size=040e8h ( 16616) load
I (232) esp_image: segment 2: paddr=0005412c vaddr=40080000 size=0beech ( 48876) load
I (253) esp_image: segment 3: paddr=00060020 vaddr=400d0020 size=1100f4h (1114356) map
I (680) esp_image: segment 4: paddr=0017011c vaddr=4008beec size=0e354h ( 58196) load
I (718) boot: Loaded app from partition at offset 0x10000
I (718) boot: Disabling RNG early entropy source...
I (730) cpu_start: Multicore app
I (730) quad_psram: This chip is ESP32-D0WD
I (730) esp_psram: Found 8MB PSRAM device
I (731) esp_psram: Speed: 40MHz
I (734) esp_psram: PSRAM initialized, cache is in low/high (2-core) mode.
W (741) esp_psram: Virtual address not enough for PSRAM, map as much as we can. 4MB is mapped
I (749) cpu_start: Pro cpu up.
I (752) cpu_start: Starting app cpu, entry point is 0x40082e0c
I (0) cpu_start: App cpu up.
I (1636) esp_psram: SPI SRAM memory test OK
I (1645) cpu_start: Pro cpu start user code
I (1645) cpu_start: cpu freq: 160000000 Hz
I (1645) cpu_start: Application information:
I (1646) cpu_start: Project name:     AI-on-the-edge
I (1650) cpu_start: App version:      v15.7.0
I (1654) cpu_start: Compile time:     Feb 17 2024 00:03:24
I (1660) cpu_start: ELF file SHA256:  71b36fda6e4066fa...
I (1665) cpu_start: ESP-IDF:          5.1.2
I (1669) cpu_start: Min chip rev:     v0.0
I (1672) cpu_start: Max chip rev:     v3.99 
I (1676) cpu_start: Chip rev:         v3.1
I (1681) heap_init: Initializing. RAM available for dynamic allocation:
I (1687) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM
I (1692) heap_init: At 3FFBC688 len 00023978 (142 KiB): DRAM
I (1697) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM
I (1703) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM
I (1708) heap_init: At 4009A240 len 00005DC0 (23 KiB): IRAM
I (1714) esp_psram: Adding pool of 4082K of PSRAM memory to heap allocator
I (1722) spi_flash: detected chip: generic
I (1724) spi_flash: flash io: dio
I (1739) app_start: Starting scheduler on CPU0
I (1739) app_start: Starting scheduler on CPU1
I (1739) main_task: Started on CPU0
I (1749) esp_psram: Reserving pool of 40K of internal memory for DMA/internal allocations
I (1749) main_task: Calling app_main()
I (1749) MAIN: 



================ Start app_main =================
I (1889) MAIN: =================================================
I (1899) MAIN: ==================== Start ======================
I (1909) MAIN: =================================================
I (1919) MAIN: PSRAM size: 8388608 byte (8MB / 64MBit)
I (1929) MAIN: Total heap: 4380199 byte
I (1939) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (3939) gpio: GPIO[25]| InputEn: 1| OutputEn: 0| OpenDrain: 0| Pullup: 1| Pulldown: 0| Intr:2 
I (3939) cam_hal: cam init ok
I (3939) sccb: pin_sda 26 pin_scl 27
I (3939) sccb: sccb_i2c_port=1
I (3939) gpio: GPIO[32]| InputEn: 0| OutputEn: 1| OpenDrain: 0| Pullup: 0| Pulldown: 0| Intr:0 
I (3979) camera: Detected camera at address=0x30
I (3979) camera: Detected OV2640 camera
I (3979) camera: Camera PID=0x26 VER=0x42 MIDL=0x7f MIDH=0xa2
I (4059) cam_hal: buffer_size: 32768, half_buffer_size: 4096, node_buffer_size: 2048, node_cnt: 16, total_cnt: 15
I (4059) cam_hal: Allocating 61440 Byte frame buffer in PSRAM
I (4069) cam_hal: cam config ok
I (4069) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
I (6149) MAIN: Camera info: PID: 0x26, VER: 0x42, MIDL: 0x7f, MIDH: 0xa2
I (6159) SDCARD: Basic R/W check started...
I (6199) SDCARD: Basic R/W check successful
I (6249) SNTP: TimeServer not defined, using default: pool.ntp.org
I (6259) SNTP: Configuring NTP Client...
I (6269) SNTP: Time zone set to CET-1CEST,M3.5.0,M10.5.0/3
I (6279) SNTP: time zone: +0100 Delta to UTC: 3600 seconds
I (6289) SNTP: The local time is unknown, starting with 1970-01-01 01:00:04
I (6299) SNTP: Once the NTP server provides a time, we will switch to that one
I (6329) MAIN: CPU frequency: 160 MHz
I (6349) OTA: Start CheckOTAUpdateCheck...
I (6359) OTA: SHA-256 for the partition table: : 9d4a2809ae90fb20709a8ca3dd3ac3462e1e73286bcd31b689060c91de06fa3a
I (6379) OTA: SHA-256 for bootloader: : 4a9573dafac5d5c79c43e5a356568332da3a99dd2e220da6ceeadc96d32708a2
I (6759) OTA: SHA-256 for current firmware: : 9a05b58889422bcde87ba674b3146abea61153f01a9043d02ab6712d64cd4a6f
I (6799) SDCARD: Folder/file presence check started...
I (6839) SDCARD: Folder/file presence check successful
I (6859) MAIN: Tag: 'v15.7.0', Release: v15.7.0 (Commit: 0d0b018+), Date/Time: 2024-02-17 00:15, Web UI: Release: v16.0.0-RC5 (Commit: 7836323+)
W (6889) MAIN: Web UI version (7836323) does not match firmware version (0d0b018+)
W (6899) MAIN: Recommendation: Repeat installation using AI-on-the-edge-device__update__*.zip
I (6909) MAIN: Reset reason: Power-on event (or reset button)
I (6929) WLANINI: SSID: ObuHome_IoT
I (6939) WLANINI: Password: XXXXXXXX
I (6949) WLANINI: RSSIThreshold: -75
I (6959) MAIN: WLAN config loaded, init WIFI...
I (6969) WIFI: Automatic interface config --> Use DHCP service
I (6999) wifi:wifi driver task: 3ffcfe6c, prio:23, stack:6144, core=0
I (7009) wifi:wifi firmware version: 91b9630
I (7009) wifi:wifi certification version: v7.0
I (7009) wifi:config NVS flash: enabled
I (7019) wifi:config nano formating: enabled
I (7019) wifi:Init data frame dynamic rx buffer num: 16
I (7019) wifi:Init static rx mgmt buffer num: 5
I (7029) wifi:Init management short buffer num: 32
I (7029) wifi:Init static tx buffer num: 16
I (7039) wifi:Init tx cache buffer num: 16
I (7039) wifi:Init static rx buffer size: 1600
I (7039) wifi:Init static rx buffer num: 16
I (7049) wifi:Init dynamic rx buffer num: 16
I (7049) wifi_init: rx ba win: 16
I (7059) wifi_init: tcpip mbox: 32
I (7059) wifi_init: udp mbox: 6
I (7059) wifi_init: tcp mbox: 6
I (7059) wifi_init: tcp tx win: 5744
I (7069) wifi_init: tcp rx win: 5744
I (7069) wifi_init: tcp mss: 1440
I (7069) wifi_init: WiFi/LWIP prefer SPIRAM
I (7079) wifi_init: WiFi IRAM OP enabled
I (7079) wifi_init: WiFi RX IRAM OP enabled
I (7089) phy_init: phy_version 4780,16b31a7,Sep 22 2023,20:42:16
I (7169) wifi:mode : sta (30:c9:22:e2:97:60)
I (7169) wifi:enable tsf
I (7169) WIFI: Set hostname to: watermeter
I (7189) WIFI: Init successful
I (9589) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (9589) wifi:state: init -> auth (b0)
I (9589) wifi:state: auth -> assoc (0)
I (9599) wifi:state: assoc -> run (10)
I (12599) wifi:state: run -> init (fc0)
I (12609) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (12609) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1
I (12609) wifi:state: init -> auth (b0)
I (12649) wifi:state: auth -> assoc (0)
I (12659) wifi:state: assoc -> run (10)
I (15689) wifi:state: run -> init (fc0)
I (15689) wifi:new:<6,0>, old:<6,0>, ap:<255,255>, sta:<6,0>, prof:1
W (15689) WIFI: Disconnected (15, Auth fail)
I (18119) wifi:new:<1,0>, old:<6,0>, ap:<255,255>, sta:<1,0>, prof:1
I (18119) wifi:state: init -> auth (b0)
I (18129) wifi:state: auth -> assoc (0)
I (19129) wifi:state: assoc -> init (2700)
I (19139) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
W (19139) WIFI: Disconnected (39)
W (21559) WIFI: Disconnected (205)
I (23989) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (23989) wifi:state: init -> auth (b0)
I (23989) wifi:state: auth -> assoc (0)
I (23999) wifi:state: assoc -> run (10)
I (27009) wifi:state: run -> init (fc0)
I (27009) wifi:new:<1,0>, old:<1,0>, ap:<255,255>, sta:<1,0>, prof:1
I (27009) wifi:new:<6,0>, old:<1,0>, ap:<255,255>, sta:<6,0>, prof:1
I (27009) wifi:state: init -> auth (b0)
I (27059) wifi:state: auth -> assoc (0)
I (27099) wifi:state: assoc -> run (10)
I (27199) MAIN: Device info: CPU cores: 2, Chip revision: 301
I (27209) MAIN: SD card info: Name: SU04G, Capacity: 3781MB, Free: 3754MB
I (29219) MAIN SERVER: Starting server on port: '80'
I (29219) MAIN SERVER: Registering URI handlers
I (29219) MAINCTRL: server_main_flow_task - Registering URI handlers
I (29219) OTA: Registering URI handlers
I (29219) GPIO: start GpioHandler
I (29229) GPIO: register GPIO Uri
I (29229) GPIO: server_GPIO - Registering URI handlers
I (29229) MAIN: Initialization completed successfully
I (29249) main_task: Returned from app_main()
I (29289) ov2640: Set PLL: clk_2x: 0, clk_div: 0, pclk_auto: 0, pclk_div: 8
W (31159) WIFI: Disconnected (39)
I (31739) LOGFILE: Set log level to ERROR
I (31779) GPIO: read GPIO config and init GPIO
I (31799) GPIO: GPIO init completed, handler is disabled
I (31799) MAINCTRL: We are in Setup Mode -> Not starting Auto Flow!
W (33789) WIFI: Disconnected (205)
I (38339) MAINCTRL: Autostart is not enabled -> Not starting Flow
W (42299) WIFI: Disconnected (15, Auth fail)
W (45719) WIFI: Disconnected (2, Auth fail)
W (48139) WIFI: Disconnected (205)
W (57649) WIFI: Disconnected (2, Auth fail)
W (62199) WIFI: Disconnected (15, Auth fail)
E (62209) WIFI: Disconnected, multiple reconnect attempts failed (15), still retrying...
W (64619) WIFI: Disconnected (205)
Terminal disconnected: NetworkError: The device has been lost.

[oh5lep]

Wrong AP password in ini file perhaps?

[TopGoalVl]

W (15689) WIFI: Disconnected (15, Auth fail)
Correct Access Point password?

[CmKeen]

Checked it 5x, did copy/paste, etc ... So, I'm pretty sure it's correct. But also, I see it in my network, just not with an IP, so I guess it does connect somehow.

[CmKeen]

I honestly don't know what I changed but redid the whole thing from the start (formatting the SD card and all) and it's now working perfectly fine.
One really strange thing was that, when setting the password of my SSID through the web interface, and checking it after on the wlan.ini file, the password was replaced by "k.
I've noticed this in the past and changed it without success, but somehow, it works better now.
I've also removed the rssi threshold option. I don't know if that was it or not.

Thanks for the support.
Now, phase 2, setting this up correctly.