Description
I have been having issues with the cellular connection failing even in an area where there should be good signal quality.
Environment
2013 Nissan Leaf
OVMS module
Firmware 3.3.004-396-geb3d1636
OVMS v3 MQTT server to my own system
Hologram SIM card
Issue
I have been experiencing a problem ever since I received the new OVMS system a month ago. I am using a hologram SIM card and have tried to debug the issue with them, but they were not able to help. It seems that OVMS tries to establish a PPP connection but after establishing the connection 'NO CARRIER' is received and then after that a loop occurs and the connection is not reestablished.
I set 'log level debug cellular' to try to see what's going on, but I don't see anything obvious on my side to know why 'NO CARRIER' happens after it does establish the PPP session sucessfully.
Sample Logs
I am showing a session where I start up the modem (from shut down state) to see the PPP session establishing and then immediately getting a 'NO CARRIER' followed by the looping.
If there are further commands or logs that would help to you (or me) to debug the issue, please let me know.
D (55193464) cellular: State timeout PowerOffOn => PoweringOn
I (55193464) cellular: State: Enter PoweringOn state
I (55193464) cellular-modem-auto: Power Cycle 4000ms
D (55197464) cellular: tx-cmd: AT
D (55197464) cellular: tx-cmd: AT
D (55197464) cellular: tx-cmd: AT
D (55197464) cellular: tx-cmd: AT
D (55198464) cellular: tx-cmd: AT
D (55199464) cellular: tx-cmd: AT
D (55200464) cellular: tx-cmd: AT
D (55201464) cellular: tx-cmd: AT
D (55202464) cellular: tx-cmd: AT
D (55203464) cellular: tx-cmd: AT
D (55204464) cellular: tx-cmd: AT
D (55205464) cellular: tx-cmd: AT
D (55206464) cellular: tx-cmd: AT
D (55207464) cellular: tx-cmd: AT
D (55208464) cellular: tx-cmd: AT
I (55208554) cellular: State: Enter Identify state
D (55209464) cellular: tx-cmd: AT+CGMM
I (55209474) cellular: Identified cellular modem: SIM7600/Experimental support for SIMCOM SIM7600
D (55209474) cellular: Remove old 'auto' modem driver
I (55209474) cellular: Set modem driver to 'SIM7600'
I (55209474) cellular: State: Enter PoweredOn state
D (55210664) cellular: mux-rx-line #0 (2/16): OK
D (55210664) cellular: mux-rx-line #0 (12/0): +CPIN: READY
D (55211884) cellular: mux-rx-line #0 (8/0): SMS DONE
D (55219464) cellular: tx-cmd: AT+CPIN?;+CREG=1;+CGREG=1;+CEREG=1;+CTZU=1;+CTZR=1;+CLIP=1;+CMGF=1;+CNMI=1,2,0,0,0;+CSDH=1;+CMEE=2;+CSQ;+AUTOCSQ=1,1;E0;S0=0
D (55219494) cellular: mux-rx-line #0 (124/37): AT+CPIN?;+CREG=1;+CGREG=1;+CEREG=1;+CTZU=1;+CTZR=1;+CLIP=1;+CMGF=1;+CNMI=1,2,0,0,0;+CSDH=1;+CMEE=2;+CSQ;+AUTOCSQ=1,1;E0;S0=0
D (55219494) cellular: mux-rx-line #0 (12/21): +CPIN: READY
D (55219494) cellular: mux-rx-line #0 (11/6): +CSQ: 99,99
D (55219494) cellular: mux-rx-line #0 (2/0): OK
D (55221464) cellular: tx-cmd: AT+CGMR;+ICCID
D (55221484) cellular: mux-rx-line #0 (22/38): +CGMR: LE20B05SIM7600G
D (55221484) cellular: mux-rx-line #0 (28/6): +ICCID: 89464XXXXXXXXXXXXX
D (55221484) cellular: mux-rx-line #0 (2/0): OK
D (55229464) cellular: tx-cmd: AT+CMUX=0;+CATR=6
D (55229474) cellular: mux-rx-line #0 (2/0): OK
I (55229474) cellular: State: Enter MuxStart state
I (55229474) gsm-mux: Start MUX
I (55229484) gsm-mux: Channel #0 is open
I (55229494) gsm-mux: Channel #1 is open
I (55229504) gsm-mux: Channel #2 is open
I (55229514) gsm-mux: Channel #3 is open
I (55229524) gsm-mux: Channel #4 is open
D (55230464) cellular: State transition MuxStart => NetWait
I (55230464) cellular: State: Enter NetWait state
I (55230464) gsm-nmea: Startup
D (55230464) cellular: mux-tx #1: AT+CGPS=0
D (55232464) cellular: mux-tx #1: AT+CGPSNMEA=258
D (55232484) cellular: mux-tx #1: AT+CGPSINFOCFG=5,258
D (55232504) cellular: mux-tx #1: AT+CGPS=1,1
D (55232504) cellular: mux-rx-line #1 (2/0): OK
D (55232504) cellular: mux-rx-line #1 (2/0): OK
D (55232504) cellular: mux-rx-line #1 (2/0): OK
D (55232514) cellular: mux-rx-line #1 (2/0): OK
D (55240464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55240484) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
I (55240484) cellular: Network Registration status: Searching
D (55240484) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55240484) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55240484) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:51:08-20"
D (55240494) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55240494) cellular: mux-rx-line #3 (2/0): OK
D (55241464) cellular: mux-tx #3: AT+CPSI?
D (55241474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55241474) cellular: mux-rx-line #3 (2/0): OK
D (55242464) cellular: mux-tx #3: AT+COPS?
D (55242474) cellular: mux-rx-line #3 (8/6): +COPS: 0
D (55242474) cellular: mux-rx-line #3 (2/0): OK
D (55250464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55250484) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
D (55250484) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55250484) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55250484) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:51:18-20"
D (55250484) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55250484) cellular: mux-rx-line #3 (2/0): OK
D (55251464) cellular: mux-tx #3: AT+CPSI?
D (55251474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55251474) cellular: mux-rx-line #3 (2/0): OK
D (55252464) cellular: mux-tx #3: AT+COPS?
D (55252474) cellular: mux-rx-line #3 (8/6): +COPS: 0
D (55252474) cellular: mux-rx-line #3 (2/0): OK
D (55256104) cellular: mux-rx-line #3 (11/0): +CSQ: 20,99
D (55256104) cellular: Signal Quality is: 20 (-73 dBm)
D (55256124) cellular: mux-rx-line #3 (8/26): +CREG: 5
I (55256124) cellular: Network Registration status: RegisteredRoaming
D (55256124) cellular: mux-rx-line #3 (9/13): +CGREG: 5
D (55256124) cellular: mux-rx-line #3 (9/0): +CEREG: 5
D (55256124) cellular: mux-rx-line #3 (28/0): +CTZV: -20,25/03/05,15:51:24
D (55256464) cellular: State transition NetWait => NetStart
I (55256464) cellular: State: Enter NetStart state
D (55257464) cellular: Netstart AT+CGDCONT=1,"IP","hologram";+CGDATA="PPP",1
D (55257524) cellular: mux-rx-line #2 (14/0): CONNECT 115200
I (55257524) cellular: PPP Connection is ready to start
D (55258464) cellular: State transition NetStart => NetMode
I (55258464) cellular: State: Enter NetMode state
I (55258464) gsm-ppp: Initialising...
I (55258614) gsm-ppp: StatusCallBack: None
I (55258614) gsm-ppp: status_cb: Connected
I (55258614) gsm-ppp: our_ipaddr = 10.200.252.205
I (55258614) gsm-ppp: his_ipaddr = 10.64.64.64
I (55258614) gsm-ppp: netmask = 255.255.255.255
I (55258614) gsm-ppp: DNS#0 = 8.8.8.8
I (55258614) gsm-ppp: DNS#1 = 8.8.4.4
I (55258614) gsm-ppp: our6_ipaddr = ::
I (55258614) netmanager: Set DNS#1 0.0.0.0
I (55258614) netmanager: Set DNS#2 0.0.0.0
I (55258614) netmanager: MODEM up (with WIFI client up): staying with WIFI client priority
D (55264784) cellular: mux-rx-line #3 (10/0): NO CARRIER
D (55264784) cellular: mux-rx-line #3 (19/0): +PPPD: DISCONNECTED
I (55264784) cellular: PPP Connection disconnected
W (55265464) cellular: Lost network connection (+PPP disconnect in NetMode)
D (55265464) cellular: State transition NetMode => NetLoss
I (55265464) cellular: State: Enter NetLoss state
D (55265464) cellular: mux-tx #3: AT+CGATT=0
I (55265464) gsm-ppp: Shutting down (hard)...
I (55265464) gsm-ppp: PPP is shutdown
I (55265464) netmanager: Set DNS#1 0.0.0.0
I (55265474) netmanager: Set DNS#2 0.0.0.0
I (55265474) netmanager: MODEM down (with WIFI client up): staying with WIFI client priority
I (55266464) ovms-server-v3: Transmit all metrics
I (55270764) gsm-ppp: StatusCallBack: User Interrupt
D (55275464) cellular: State timeout NetLoss => NetWait
I (55275464) cellular: State: Enter NetWait state
D (55279464) cellular: State transition NetWait => NetStart
I (55279464) cellular: State: Enter NetStart state
D (55280464) cellular: Netstart AT+CGDCONT=1,"IP","hologram";+CGDATA="PPP",1
D (55281964) cellular: mux-rx-line #3 (11/0): +CSQ: 99,99
D (55281964) cellular: Signal Quality is: 99 (0 dBm)
D (55281964) cellular: mux-rx-line #3 (2/0): OK
D (55281964) cellular: mux-rx-line #3 (8/0): +CREG: 2
I (55281974) cellular: Network Registration status: Searching
D (55282004) cellular: mux-rx-line #2 (14/0): CONNECT 115200
I (55282004) cellular: PPP Connection is ready to start
D (55282464) cellular: State transition NetStart => NetMode
I (55282464) cellular: State: Enter NetMode state
I (55282464) gsm-ppp: Initialising...
D (55282584) cellular: mux-rx-line #3 (10/0): NO CARRIER
D (55282584) cellular: mux-rx-line #3 (19/0): +PPPD: DISCONNECTED
I (55282584) cellular: PPP Connection disconnected
W (55283464) cellular: Lost network connection (NetworkRegistration in NetMode)
D (55283464) cellular: State transition NetMode => NetLoss
I (55283464) cellular: State: Enter NetLoss state
D (55283464) cellular: mux-tx #3: AT+CGATT=0
I (55283464) gsm-ppp: Shutting down (hard)...
I (55283464) gsm-ppp: PPP is shutdown
I (55283474) netmanager: Set DNS#1 0.0.0.0
I (55283474) netmanager: Set DNS#2 0.0.0.0
D (55293464) cellular: State timeout NetLoss => NetWait
I (55293464) cellular: State: Enter NetWait state
I (55295464) gsm-ppp: StatusCallBack: User Interrupt
D (55303464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55303494) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
D (55303494) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55303494) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55303494) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:52:11-20"
D (55303494) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55303494) cellular: mux-rx-line #3 (2/0): OK
D (55304464) cellular: mux-tx #3: AT+CPSI?
D (55304474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55304484) cellular: mux-rx-line #3 (2/0): OK
D (55305464) cellular: mux-tx #3: AT+COPS?
D (55305474) cellular: mux-rx-line #3 (8/6): +COPS: 0
D (55305474) cellular: mux-rx-line #3 (2/0): OK
D (55313464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55313484) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
D (55313484) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55313484) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55313484) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:52:21-20"
D (55313484) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55313484) cellular: mux-rx-line #3 (2/0): OK
D (55314464) cellular: mux-tx #3: AT+CPSI?
D (55314474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55314474) cellular: mux-rx-line #3 (2/0): OK
D (55315464) cellular: mux-tx #3: AT+COPS?
D (55315474) cellular: mux-rx-line #3 (8/6): +COPS: 0
D (55315474) cellular: mux-rx-line #3 (2/0): OK
D (55323464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55323484) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
D (55323494) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55323494) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55323494) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:52:31-20"
D (55323494) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55323494) cellular: mux-rx-line #3 (2/0): OK
D (55324464) cellular: mux-tx #3: AT+CPSI?
D (55324474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55324474) cellular: mux-rx-line #3 (2/0): OK
D (55325464) cellular: mux-tx #3: AT+COPS?
D (55325474) cellular: mux-rx-line #3 (8/6): +COPS: 0
D (55325474) cellular: mux-rx-line #3 (2/0): OK
D (55333464) cellular: mux-tx #3: AT+CREG?;+CGREG?;+CEREG?;+CCLK?;+CSQ
D (55333484) cellular: mux-rx-line #3 (10/84): +CREG: 1,2
D (55333484) cellular: mux-rx-line #3 (11/69): +CGREG: 1,2
D (55333484) cellular: mux-rx-line #3 (11/54): +CEREG: 1,4
D (55333484) cellular: mux-rx-line #3 (29/21): +CCLK: "25/03/05,10:52:41-20"
D (55333484) cellular: mux-rx-line #3 (11/6): +CSQ: 99,99
D (55333484) cellular: mux-rx-line #3 (2/0): OK
D (55334464) cellular: mux-tx #3: AT+CPSI?
D (55334474) cellular: mux-rx-line #3 (24/0): +CPSI: NO SERVICE,Online
D (55334474) cellular: mux-rx-line #3 (2/0): OK
.... loop continues....