[Ovmsdev] Instability on GSM

Sun Dec 17 09:59:09 HKT 2017

Hi,

I let my ovms run on GSM for a while and it's not very stable. I'm not 
sure if wifi is better or worse but here's a log starting from a reboot 
with the button, connecting, disconnecting and crashing. My esp-idf 
looks up to date:

commit 88ab5d4830e2eeb24fbae1d5f7d66a2e33bdbde0
Author: Stephen Casner <casner at acm.org>
Date:   Sun Oct 22 00:25:10 2017 -0700

Welcome to the Open Vehicle Monitoring System (OVMS) - Async Console
I (605) simcom: State: Enter PoweringOn state
I (605) simcom: Power Cycle
D (1485) SIMCOM tx: 41 54 0d 0a                                     AT..
D (1495) SIMCOM rx: 41 54 0d                                        AT.
I (1495) simcom: State: Enter PoweredOn state
D (1495) SIMCOM rx: 0d 0a 4f 4b 0d 0a                               ..OK..
D (1505) SIMCOM line: 4f 4b                                           OK
Launching OVMS Server V2 connection (oscv2)
Logging level for * set to debug
Power mode of simcom is now on
OVMS > level info
Logging level for * set to info
I (11485) simcom: State timeout, transition to 2
I (11485) simcom: State: Enter PoweringOn state
I (11485) simcom: Power Cycle
I (17475) simcom: State: Enter PoweredOn state
I (36485) simcom: State: Enter MuxStart state
I (36485) gsm-mux: Start MUX
I (36495) gsm-mux: Channel #0 is open
I (36505) gsm-mux: Channel #1 is open
I (36505) gsm-mux: Channel #2 is open
I (36515) gsm-mux: Channel #3 is open
I (36515) gsm-mux: Channel #4 is open
I (37485) simcom: State: Enter NetWait state
I (47525) simcom: CREG Network Registration: RegisteredRoaming
I (48485) simcom: State: Enter NetStart state
I (49555) simcom: PPP Connection is ready to start
I (50485) simcom: State: Enter NetMode state
I (50485) gsm-ppp: Initialising...
I (77405) gsm-ppp: StatusCallBack: None
I (77405) gsm-ppp: status_cb: Connected
I (77405) gsm-ppp:    our_ipaddr  = 10.170.199.15
I (77405) gsm-ppp:    his_ipaddr  = 10.64.64.64
I (77405) gsm-ppp:    netmask     = 255.255.255.255
I (77405) gsm-ppp:    our6_ipaddr = ::
I (80505) ovms-server-v2: Status: Network connectivity established
I (80505) ovms-server-v2: Connection is redacted
I (81905) ovms-server-v2: Connected to OVMS Server V2 at ovms.dexters-web.de
I (81905) ovms-server-v2: Status: Connected to server
I (81905) ovms-server-v2: Status: Logging in...
I (81905) ovms-server-v2: Sending server login: MP-C 0 redacted
I (101915) ovms-server-v2: Server response is incomplete (0 bytes)
E (101915) ovms-server-v2: Status: Error: Server response is incomplete
I (107445) simcom: CREG Network Registration: Searching
I (107485) simcom: Lost network connection (NetworkRegistration in NetMode)
I (107485) simcom: State: Enter NetLoss staGuru Meditation Error of type 
LoadProhibited occurred on core  0. Exception was unhandled.
Register dump:
PC      : 0x40180151  PS      : 0x00060830  A0      : 0x80180192 A1      
: 0x3ffcf080
0x40180151: ip4_route at 
/home/ubuntu/esp/esp-idf/components/lwip/core/ipv4/ip4.c:250 
(discriminator 2)

A2      : 0x0000f01d  A3      : 0x00000000  A4      : 0x3ffeae94 A5      
: 0x3f429c7c
A6      : 0x3ffd18c0  A7      : 0x00000000  A8      : 0x80180145 A9      
: 0x3ffcf060
A10     : 0x00000000  A11     : 0x00000000  A12     : 0x3ffdcd3c A13     
: 0x3ffc09a0
A14     : 0x00000003  A15     : 0x00060023  SAR     : 0x00000010 
EXCCAUSE: 0x0000001c
EXCVADDR: 0x0000f0d2  LBEG    : 0x4000c2e0  LEND    : 0x4000c2f6 LCOUNT  
: 0xffffffff

Backtrace: 0x40180151:0x3ffcf080 0x4018018f:0x3ffcf0a0 
0x4017e37e:0x3ffcf0c0 0x401789f3:0x3ffcf100 0x40178a15:0x3ffcf130 
0x40178ab6:0x3ffcf150 0x40178f14:0x3ffcf170 0x4017c64e:0x3ffcf190 
0x4017c68f:0x3ffcf1b0 0x401850b9:0x3ffcf1d0 0x4018547d:0x3ffcf1f0 
0x401854c4:0x3ffcf210 0x401898d5:0x3ffcf230 0x401853b4:0x3ffcf250 
0x4018cdaa:0x3ffcf270 0x4018cde6:0x3ffcf290 0x401884b9:0x3ffcf2b0 
0x401898d5:0x3ffcf2d0 0x401893fc:0x3ffcf2f0 0x40184fa1:0x3ffcf310 
0x401a2aac:0x3ffcf330 0x40173589:0x3ffcf350
0x40180151: ip4_route at 
/home/ubuntu/esp/esp-idf/components/lwip/core/ipv4/ip4.c:250 
(discriminator 2)

0x4018018f: ip4_route_src at 
/home/ubuntu/esp/esp-idf/components/lwip/core/ipv4/ip4.c:159

0x4017e37e: tcp_rst at 
/home/ubuntu/esp/esp-idf/components/lwip/core/tcp_out.c:1336 
(discriminator 4)

0x401789f3: tcp_abandon at 
/home/ubuntu/esp/esp-idf/components/lwip/core/tcp.c:1630

0x40178a15: tcp_abort at 
/home/ubuntu/esp/esp-idf/components/lwip/core/tcp.c:1630

0x40178ab6: tcp_netif_ipv4_addr_changed_pcblist at 
/home/ubuntu/esp/esp-idf/components/lwip/core/tcp.c:1630

0x40178f14: tcp_netif_ipv4_addr_changed at 
/home/ubuntu/esp/esp-idf/components/lwip/core/tcp.c:1947

0x4017c64e: netif_set_ipaddr at 
/home/ubuntu/esp/esp-idf/components/lwip/core/netif.c:452

0x4017c68f: netif_set_addr at 
/home/ubuntu/esp/esp-idf/components/lwip/core/netif.c:330

0x401850b9: cifaddr at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/ppp.c:704

0x4018547d: ipcp_clear_addrs at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/ipcp.c:2192

0x401854c4: ipcp_down at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/ipcp.c:2156

0x401898d5: fsm_lowerdown at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/fsm.c:146

0x401853b4: ipcp_lowerdown at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/ipcp.c:687

0x4018cdaa: upper_layers_down at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/auth.c:715

0x4018cde6: link_down at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/auth.c:701

0x401884b9: lcp_down at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/lcp.c:2341

0x401898d5: fsm_lowerdown at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/fsm.c:146

0x401893fc: lcp_lowerdown at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/lcp.c:485

0x40184fa1: ppp_close at 
/home/ubuntu/esp/esp-idf/components/lwip/netif/ppp/ppp.c:704

0x401a2aac: pppapi_do_ppp_close at 
/home/ubuntu/esp/esp-idf/components/lwip/api/pppapi.c:355

0x40173589: tcpip_thread at 
/home/ubuntu/esp/esp-idf/components/lwip/api/tcpip.c:474

Rebooting...
ets Jun  8 2016 00:22:57

rst:0xc (SW_CPU_RESET),boot:0x1f (SPI_FAST_FLASH_BOOT)
configsip: 156795334, SPIWP:0x00
clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00
mode:DIO, clock div:2
load:0x3fff0008,len:8
load:0x3fff0010,len:4392
load:0x40078000,len:11080
load:0x40080000,len:252
0x40080000: _WindowOverflow4 at ??:?

entry 0x40080034
0x40080034: _WindowOverflow4 at ??:?

I (46) boot: ESP-IDF v2.1-20-g88ab5d4 2nd stage bootloader
I (46) boot: compile time 11:13:21
I (79) boot: Enabling RNG early entropy source...