I (30) boot: ESP-IDF v3.1-beta1 2nd stage bootloader I (30) boot: compile time 13:45:37 I (30) boot: Enabling RNG early entropy source... I (31) boot: SPI Speed : 40MHz I (31) boot: SPI Mode : DIO I (32) boot: SPI Flash Size : 4MB I (32) boot: Partition Table: I (33) boot: ## Label Usage Type ST Offset Length I (34) boot: 0 nvs WiFi data 01 02 00009000 00004000 I (35) boot: 1 otadata OTA data 01 00 0000d000 00002000 I (35) boot: 2 phy_init RF data 01 01 0000f000 00001000 I (36) boot: 3 factory factory app 00 00 00010000 00100000 I (37) boot: 4 ota_0 OTA app 00 10 00110000 00100000 I (38) boot: 5 ota_1 OTA app 00 11 00210000 00100000 I (39) boot: End of partition table I (40) boot: Defaulting to factory image I (40) esp_image: segment 0: paddr=0x00010020 vaddr=0x3f400020 size=0x1d110 (119056) map I (83) esp_image: segment 1: paddr=0x0002d138 vaddr=0x3ffb0000 size=0x02ed8 ( 11992) load I (88) esp_image: segment 2: paddr=0x00030018 vaddr=0x400d0018 size=0x8a69c (566940) map _stext at ??:? I (287) esp_image: segment 3: paddr=0x000ba6bc vaddr=0x3ffb2ed8 size=0x00da8 ( 3496) load I (289) esp_image: segment 4: paddr=0x000bb46c vaddr=0x3ffb3c80 size=0x00000 ( 0) load I (289) esp_image: segment 5: paddr=0x000bb474 vaddr=0x40080000 size=0x00400 ( 1024) load _iram_start at /home/jim/git/clock/fw/esp-idf/components/freertos/xtensa_vectors.S:1685 I (290) esp_image: segment 6: paddr=0x000bb87c vaddr=0x40080400 size=0x11b90 ( 72592) load I (321) esp_image: segment 7: paddr=0x000cd414 vaddr=0x400c0000 size=0x00000 ( 0) load I (321) esp_image: segment 8: paddr=0x000cd41c vaddr=0x50000000 size=0x00000 ( 0) load I (333) boot: Loaded app from partition at offset 0x10000 I (333) boot: Disabling RNG early entropy source... I (333) cpu_start: Pro cpu up. I (333) cpu_start: Starting app cpu, entry point is 0x400810d0 call_start_cpu1 at /home/jim/git/clock/fw/esp-idf/components/esp32/cpu_start.c:225 I (0) cpu_start: App cpu up. I (335) heap_init: Initializing. RAM available for dynamic allocation: D (335) heap_init: New heap initialised at 0x3ffae6e0 I (336) heap_init: At 3FFAE6E0 len 00001920 (6 KiB): DRAM D (337) heap_init: New heap initialised at 0x3ffbb3f0 I (337) heap_init: At 3FFBB3F0 len 00024C10 (147 KiB): DRAM I (338) heap_init: At 3FFE0440 len 00003BC0 (14 KiB): D/IRAM I (339) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM D (341) heap_init: New heap initialised at 0x40091f90 I (341) heap_init: At 40091F90 len 0000E070 (56 KiB): IRAM I (342) cpu_start: Pro cpu start user code D (342) clk: waiting for 32k oscillator to start up E (580) clk: RTC: Not found External 32 kHz XTAL. Switching to Internal 150 kHz RC chain D (600) clk: RTC_SLOW_CLK calibration value: 3263658 D (154) intr_alloc: Connected src 46 to int 2 (cpu 0) D (157) intr_alloc: Connected src 57 to int 3 (cpu 0) D (158) stack_chk: Intialize random stack guard D (160) intr_alloc: Connected src 24 to int 9 (cpu 0) I (161) cpu_start: Starting scheduler on PRO CPU. D (0) intr_alloc: Connected src 25 to int 2 (cpu 1) I (0) cpu_start: Starting scheduler on APP CPU. D (165) heap_init: New heap initialised at 0x3ffe0440 D (165) heap_init: New heap initialised at 0x3ffe4350 D (165) intr_alloc: Connected src 16 to int 12 (cpu 0) I (185) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE D (185) intr_alloc: Connected src 50 to int 13 (cpu 0) I (195) wifi_manager: init I (195) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE I (195) wifi: wifi driver task: 3ffc6774, prio:23, stack:4096, core=0 I (195) wifi: wifi firmware version: b65dd05 I (195) wifi: config NVS flash: enabled I (195) wifi: config nano formating: disabled I (195) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE I (195) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE I (205) wifi: Init dynamic tx buffer num: 32 I (205) wifi: Init data frame dynamic rx buffer num: 32 I (215) wifi: Init management frame dynamic rx buffer num: 32 I (215) wifi: Init static rx buffer size: 1600 I (215) wifi: Init static rx buffer num: 10 I (215) wifi: Init dynamic rx buffer num: 32 I (265) phy: phy_version: 3910, c0c45a3, May 21 2018, 18:07:06, 0, 0 I (265) wifi: mode : sta (b4:e6:2d:ac:76:11) I (275) wifi_manager: Scanning for access points I (2685) wifi_manager: Connecting to SSID 'BACONPALACE' with passphrase 'xxxxx' I (3295) wifi: n:5 0, o:1 0, ap:255 255, sta:5 0, prof:1 I (3965) wifi: state: init -> auth (b0) I (3965) wifi: state: auth -> assoc (0) I (3975) wifi: state: assoc -> run (10) I (4005) wifi: connected with BACONPALACE, channel 5 I (4005) wifi: pm start, type: 1 I (5065) event: sta ip: 10.0.103.186, mask: 255.255.0.0, gw: 10.0.1.1 I (5065) wifi_manager: got new IP: 10.0.103.186 I (5065) wifi_manager: Connected! I (5075) serverconfig: Sending HTTP request to https://example.com/clock/config?mac=b4:e6:2d:ac:76:11 I (5305) timekeeper: Time jump: 1530375078.947486 sec. It is now: Sat Jun 30 12:11:24 2018 I (8095) serverconfig: HTTP response OK I (114425) wifi: state: run -> auth (7c0) I (114425) wifi: pm stop, total sleep time: 102976934 us / 110417391 us I (114425) wifi: n:5 0, o:5 0, ap:255 255, sta:5 0, prof:1 I (114425) wifi_manager: disconnected; reason 7 W (114425) wifi_manager: Lost wifi connection I (114425) wifi: state: auth -> init (0) I (114425) wifi: flush txq I (114425) wifi: stop sw txq I (114425) wifi: lmac stop hw txq I (114425) main: Not connected I (114425) timekeeper: stop I (114425) wifi_manager: disconnected; reason 202