<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
</head>
<body text="#000000" bgcolor="#FFFFFF">
HI Mark,<br>
<br>
For what it's worth, I'm not seeing a lockup here. The ppp
connection does seem to bounce like clockwork every hour, making me
think it is just timing out for not being used. Wifi is in
AP+Client mode, connected to the home network for Internet access.
This has run on my desk for over a day, with no troubles. Behavior
has been this way for a few days now, if not earlier. Seems pretty
stable.<br>
<br>
Here's a snip of the log, running yesterday's bits... <br>
<br>
<div dir="ltr">I (79200417) housekeeping: Local time: Sun Mar 25
16:20:31 2018<br>
I (79339417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (79339417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,79339,0,0,0,0,13.6374,0,0,0,0,0<br>
I (79339417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,9,1,TR,T-Mobile
Hologram<br>
I (79723987) simcom: PPP Connection disconnected<br>
I (79723997) simcom: PPP Connection disconnected<br>
I (79724417) simcom: Lost network connection (+PPP disconnect in
NetMode)<br>
I (79724417) simcom: State: Enter NetLoss state<br>
I (79724417) gsm-ppp: Shutting down (hard)...<br>
I (79724417) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (79724417) netmanager: MODEM down (with WIFI client up): staying
with WIFI client priority<br>
I (79729747) gsm-ppp: StatusCallBack: User Interrupt<br>
I (79729747) gsm-ppp: PPP connection has been closed<br>
I (79729747) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (79733417) simcom: State timeout, transition to 5<br>
I (79733417) simcom: State: Enter NetWait state<br>
I (79737417) simcom: State: Enter NetStart state<br>
I (79738487) simcom: PPP Connection is ready to start<br>
I (79739417) simcom: State: Enter NetMode state<br>
I (79739417) gsm-ppp: Initialising...<br>
I (79742537) gsm-ppp: StatusCallBack: None<br>
I (79742537) gsm-ppp: status_cb: Connected<br>
I (79742537) gsm-ppp: our_ipaddr = 10.170.146.142<br>
I (79742537) gsm-ppp: his_ipaddr = 10.64.64.64<br>
I (79742537) gsm-ppp: netmask = 255.255.255.255<br>
I (79742537) gsm-ppp: our6_ipaddr = ::<br>
I (79742537) netmanager: Set DNS#0 9.9.9.9<br>
I (79742537) netmanager: Set DNS#1 8.8.8.8<br>
I (79742537) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (79742537) netmanager: MODEM up (with WIFI client up): staying
with WIFI client priority<br>
I (79800417) housekeeping: Local time: Sun Mar 25 16:30:31 2018<br>
I (79940417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (79940417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,79940,0,0,0,0,13.6319,0,0,0,0,0<br>
I (79940417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,9,1,TR,T-Mobile
Hologram<br>
I (80400417) housekeeping: Local time: Sun Mar 25 16:40:31 2018<br>
I (80541417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (80541417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,80541,0,0,0,0,13.6264,0,0,0,0,0<br>
I (81000417) housekeeping: Local time: Sun Mar 25 16:50:31 2018<br>
I (81142417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (81142417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,81142,0,0,0,0,13.5989,0,0,0,0,0<br>
I (81142417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,9,1,TR,T-Mobile
Hologram<br>
I (81600417) housekeeping: Local time: Sun Mar 25 17:00:31 2018<br>
I (81743417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (81743417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,81743,0,0,0,0,13.6484,0,0,0,0,0<br>
I (81743417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,6,1,TR,T-Mobile
Hologram<br>
I (82200417) housekeeping: Local time: Sun Mar 25 17:10:31 2018<br>
I (82344417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (82344417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,82344,0,0,0,0,13.6264,0,0,0,0,0<br>
I (82344417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,6,1,TR,T-Mobile
Hologram<br>
I (82800417) housekeeping: Local time: Sun Mar 25 17:20:31 2018<br>
I (82945417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (82945417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,82945,0,0,0,0,13.6319,0,0,0,0,0<br>
I (82945417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,9,1,TR,T-Mobile
Hologram<br>
I (83342577) simcom: PPP Connection disconnected<br>
I (83342577) simcom: PPP Connection disconnected<br>
I (83343417) simcom: Lost network connection (+PPP disconnect in
NetMode)<br>
I (83343417) simcom: State: Enter NetLoss state<br>
I (83343417) gsm-ppp: Shutting down (hard)...<br>
I (83343417) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (83343417) netmanager: MODEM down (with WIFI client up): staying
with WIFI client priority<br>
I (83348317) gsm-ppp: StatusCallBack: User Interrupt<br>
I (83348317) gsm-ppp: PPP connection has been closed<br>
I (83348317) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (83352417) simcom: State timeout, transition to 5<br>
I (83352417) simcom: State: Enter NetWait state<br>
I (83356417) simcom: State: Enter NetStart state<br>
I (83357497) simcom: PPP Connection is ready to start<br>
I (83358417) simcom: State: Enter NetMode state<br>
I (83358417) gsm-ppp: Initialising...<br>
I (83361847) gsm-ppp: StatusCallBack: None<br>
I (83361847) gsm-ppp: status_cb: Connected<br>
I (83361847) gsm-ppp: our_ipaddr = 10.170.146.142<br>
I (83361847) gsm-ppp: his_ipaddr = 10.64.64.64<br>
I (83361847) gsm-ppp: netmask = 255.255.255.255<br>
I (83361847) gsm-ppp: our6_ipaddr = ::<br>
I (83361857) netmanager: Set DNS#0 9.9.9.9<br>
I (83361857) netmanager: Set DNS#1 8.8.8.8<br>
I (83361857) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (83361857) netmanager: MODEM up (with WIFI client up): staying
with WIFI client priority<br>
I (83400417) housekeeping: Local time: Sun Mar 25 17:30:31 2018<br>
I (83546417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (83546417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,83546,0,0,0,0,13.7692,0,0,0,0,0<br>
I (83546417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,7,1,TR,T-Mobile
Hologram<br>
I (84000417) housekeeping: Local time: Sun Mar 25 17:40:31 2018<br>
I (84147417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (84147417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,84147,0,0,0,0,13.7473,0,0,0,0,0<br>
I (84147417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,7,1,TR,T-Mobile
Hologram<br>
I (84600417) housekeeping: Local time: Sun Mar 25 17:50:31 2018<br>
I (84748417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (84748417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,84748,0,0,0,0,13.7253,0,0,0,0,0<br>
I (85200417) housekeeping: Local time: Sun Mar 25 18:00:31 2018<br>
I (85349417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (85349417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,85349,0,0,0,0,13.7637,0,0,0,0,0<br>
I (85349417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,10,1,TR,T-Mobile
Hologram<br>
I (85800417) housekeeping: Local time: Sun Mar 25 18:10:31 2018<br>
I (85950417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (85950417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,85950,0,0,0,0,13.7418,0,0,0,0,0<br>
I (85950417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,7,1,TR,T-Mobile
Hologram<br>
I (86400417) housekeeping: Local time: Sun Mar 25 18:20:31 2018<br>
I (86551417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (86551417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,86551,0,0,0,0,13.7198,0,0,0,0,0<br>
I (86963687) simcom: PPP Connection disconnected<br>
I (86963687) simcom: PPP Connection disconnected<br>
I (86964417) simcom: Lost network connection (+PPP disconnect in
NetMode)<br>
I (86964417) simcom: State: Enter NetLoss state<br>
I (86964417) gsm-ppp: Shutting down (hard)...<br>
I (86964417) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (86964417) netmanager: MODEM down (with WIFI client up): staying
with WIFI client priority<br>
I (86969427) gsm-ppp: StatusCallBack: User Interrupt<br>
I (86969427) gsm-ppp: PPP connection has been closed<br>
I (86969427) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (86973417) simcom: State timeout, transition to 5<br>
I (86973417) simcom: State: Enter NetWait state<br>
I (86977417) simcom: State: Enter NetStart state<br>
I (86978507) simcom: PPP Connection is ready to start<br>
I (86979417) simcom: State: Enter NetMode state<br>
I (86979417) gsm-ppp: Initialising...<br>
I (86982877) gsm-ppp: StatusCallBack: None<br>
I (86982877) gsm-ppp: status_cb: Connected<br>
I (86982877) gsm-ppp: our_ipaddr = 10.170.146.142<br>
I (86982877) gsm-ppp: his_ipaddr = 10.64.64.64<br>
I (86982877) gsm-ppp: netmask = 255.255.255.255<br>
I (86982877) gsm-ppp: our6_ipaddr = ::<br>
I (86982877) netmanager: Set DNS#0 9.9.9.9<br>
I (86982877) netmanager: Set DNS#1 8.8.8.8<br>
I (86982877) netmanager: Interface priority is st1 (<a
href="http://10.30.1.148/255.255.255.0">10.30.1.148/255.255.255.0</a>
gateway 10.30.1.1)<br>
I (86982877) netmanager: MODEM up (with WIFI client up): staying
with WIFI client priority<br>
I (87000417) housekeeping: Local time: Sun Mar 25 18:30:31 2018<br>
I (87009447) simcom: CREG Network Registration: Searching<br>
I (87009457) simcom: CREG Network Registration: RegisteredRoaming<br>
I (87152417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (87152417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,87152,0,0,0,0,13.7088,0,0,0,0,0<br>
I (87600417) housekeeping: Local time: Sun Mar 25 18:40:31 2018<br>
I (87753417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (87753417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,87753,0,0,0,0,13.6978,0,0,0,0,0<br>
I (88200417) housekeeping: Local time: Sun Mar 25 18:50:31 2018<br>
I (88354417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (88354417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,88354,0,0,0,0,13.7473,0,0,0,0,0<br>
I (88354417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,7,1,TR,T-Mobile
Hologram<br>
I (88800417) housekeeping: Local time: Sun Mar 25 19:00:31 2018<br>
I (88955417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (88955417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,88955,0,0,0,0,13.7033,0,0,0,0,0<br>
I (89400417) housekeeping: Local time: Sun Mar 25 19:10:31 2018<br>
I (89556417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (89556417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,89556,0,0,0,0,13.7253,0,0,0,0,0<br>
I (89556417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,7,1,TR,T-Mobile
Hologram<br>
I (90000417) housekeeping: Local time: Sun Mar 25 19:20:31 2018<br>
I (90157417) ovms-server-v2: Send MP-0
S38.0,K,0,0,stopped,standard,0,0,0,0,0,0,0,21,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0<br>
I (90157417) ovms-server-v2: Send MP-0
D0,0,5,0,0,0,0,0,0,90157,0,0,0,0,13.6868,0,0,0,0,0<br>
I (90157417) ovms-server-v2: Send MP-0
F3.1.000-16-g9b8ec80/factory/main (build idf
v3.1-dev-429-g7f6161cd Mar 24 2018 11:19:27),,6,1,TR,T-Mobile
Hologram<br>
OVMS> <br>
<br>
</div>
<br>
<br>
<div class="moz-cite-prefix">Mark Webb-Johnson wrote:<br>
</div>
<blockquote type="cite"
cite="mid:D55E6F1A-CB3F-43A0-8473-A24CDA961732@webb-johnson.net">
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
<div class=""><br class="">
</div>
<div class="">My car managed to repeat the issue with the task
lockup I have seen occasionally. The symptom is that network
connectivity fails and the async console becomes unresponsive
when you issue a command to bring up/down wifi, simcom, etc.</div>
<div class=""><br class="">
</div>
<div class="">The new housekeeping 10 minute ticker worked well
for this, and stopped in the middle of the night:</div>
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">…</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (134401020)
housekeeping: Local time: Sat Mar 24 20:15:45 2018</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (135001020)
housekeeping: Local time: Sat Mar 24 20:25:45 2018</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (135601020)
housekeeping: Local time: Sat Mar 24 20:35:45 2018</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (136201020)
housekeeping: Local time: Sat Mar 24 20:45:45 2018</span></font></div>
</div>
</blockquote>
<div class=""><br class="">
</div>
<div class="">Looking at the logs after Sat Mar 24 20:45:45, I
see:</div>
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136543060) simcom: rx
line ch=4 len=19 : +PPPD: DISCONNECTED<br class="">
I (136543070) simcom: PPP Connection disconnected<br
class="">
I (136544020) simcom: Lost network connection (+PPP
disconnect in NetMode)<br class="">
I (136544020) simcom: State: Enter NetLoss state</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (136544030) gsm-ppp:
Shutting down (hard)...<br class="">
I (136544040) events: Signal(system.modem.down)<br
class="">
I (136544040) netmanager: Interface priority is st1
(x.y.z.212/255.255.248.0 gateway x.y.z.64)<br class="">
I (136544050) events: Signal(network.modem.down)<br
class="">
I (136544050) netmanager: MODEM down (with WIFI client
up): staying with WIFI client priority<br class="">
I (136544060) events: Signal(network.interface.change)</span></font></div>
</blockquote>
<div class=""><br class="">
</div>
<div class="">OK, so ppp went down (lost network connectivity),
and WIFI client was up so we kept with that.</div>
<div class=""><br class="">
</div>
<div class="">The GSM signal is still up (CREG), so we try to
reconnect PPP:</div>
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">I (136558160) simcom:
PPP Connection is ready to start<br class="">
I (136559020) simcom: State: Enter NetMode state<br
class="">
I (136559020) gsm-ppp: Initialising..</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136589180) simcom: rx
line ch=4 len=8 : +CREG: 2<br class="">
I (136589190) simcom: CREG Network Registration: Searching</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136589250) simcom: rx
line ch=4 len=8 : +CREG: 5^[[0m<br class="">
^[[0;32mI (136589250) simcom: CREG Network Registration:
RegisteredRoaming</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136589320) simcom: rx
line ch=4 len=8 : +CREG: 2^[[0m<br class="">
^[[0;32mI (136589320) simcom: CREG Network Registration:
Searching</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136589380) simcom: rx
line ch=4 len=8 : +CREG: 5^[[0m<br class="">
^[[0;32mI (136589390) simcom: CREG Network Registration:
RegisteredRoaming</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136601690) simcom: rx
line ch=3 len=8 : +CREG: 2^[[0m<br class="">
^[[0;32mI (136601690) simcom: CREG Network Registration:
Searching</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">D (136601780) simcom: rx
line ch=3 len=8 : +CREG: 5^[[0m<br class="">
^[[0;32mI (136601780) simcom: CREG Network Registration:
RegisteredRoaming</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">V (136617440) gsm-ppp:
tx: 7e ff 7d 23 c0 21 7d 25 7d 2c 7d 20 20 4e 6f 20 |
~.}#.!}%},} No <br class="">
V (136617440) gsm-ppp: tx: 6e 65 74 77 6f 72 6b 20 70 72
6f 74 6f 63 6f 6c | network protocol<br class="">
V (136617450) gsm-ppp: tx: 73 20 72 75 6e 6e 69 6e 67 4b
8f 7e | s runningK.~ <br class="">
V (136617450) simcom: tx: f9 09 ff 59 7e ff 7d 23 c0 21 7d
25 7d 2c 7d 20 | ...Y~.}#.!}%},} <br class="">
V (136617450) simcom: tx: 20 4e 6f 20 6e 65 74 77 6f 72 6b
20 70 72 6f 74 | No network prot<br class="">
V (136617460) simcom: tx: 6f 63 6f 6c 73 20 72 75 6e 6e 69
6e 67 4b 8f 7e | ocols runningK.~<br class="">
V (136617460) simcom: tx: a6 f9
| .. <br class="">
V (136617490) simcom: rx: f9 09 ff 25 7e ff 7d 23 c0 21 7d
26 7d 2c 7d 20 | ...%~.}#.!}&},} <br class="">
V (136617500) simcom: rx: 7d 24 8f 7d 3d 7e fb f9
| }$.}=~.. <br class="">
V (136617510) gsm-mux: ProcessFrame(CHAN=2, ADDR=09,
CTRL=ff, FCS=fb, LEN=24)<br class="">
V (136617520) gsm-mux: ChanProcessFrame(CHAN=2, ADDR=09,
CTRL=ff, LEN=21, IFP=3)<br class="">
V (136617520) gsm-ppp: rx: 7e ff 7d 23 c0 21 7d 26 7d 2c
7d 20 7d 24 8f 7d | ~.}#.!}&},} }$.}<br class="">
V (136617530) gsm-ppp: rx: 3d 7e
| =~ <br class="">
I (136617540) gsm-ppp: StatusCallBack: Connection Lost<br
class="">
E (136617540) gsm-ppp: status_cb: Connection lost<br
class="">
I (136617550) gsm-ppp: Shutdown (via status callback)<br
class="">
I (136617550) events: Signal(system.modem.down)<br
class="">
I (136617560) netmanager: Interface priority is st1
(x.y.z.212/255.255.248.0 gateway z.y.z.64)<br class="">
I (136617560) gsm-ppp: Attempting PPP reconnecting in 30
seconds...</span></font></div>
</blockquote>
<div class=""><br class="">
</div>
<div class="">So, while initialising PPP we’ve been told that the
cellular network is down (CREG: 2). Then, it flaps up and down
like a yoyo, until the PPP connect finally fails.</div>
<div class=""><br class="">
</div>
<div class="">At this point, things go screwy. The simcom tells us
the PPP is down, and then we lose the ability to transmit to the
modem (or at least log those transmits):</div>
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">V (136618650) simcom:
rx: 50 50 50 44 3a 20 44 49 53 43 4f 4e 4e 45 43 54 |
PPPD: DISCONNECT<br class="">
V (136618660) simcom: rx: 45 44 0d 0a d4 f9 f9 11 ff 2f 0d
0a 2b 50 50 50 | ED......./..+PPP<br class="">
V (136618660) gsm-mux: ProcessFrame(CHAN=3, ADDR=0d,
CTRL=ff, FCS=d4, LEN=29)<br class="">
V (136618670) gsm-mux: ChanProcessFrame(CHAN=3, ADDR=0d,
CTRL=ff, LEN=26, IFP=3)<br class="">
D (136618680) simcom: rx line ch=3 len=19 : +PPPD:
DISCONNECTED<br class="">
I (136618690) simcom: PPP Connection disconnected<br
class="">
V (136618690) simcom: rx: 44 3a 20 44 49 53 43 4f 4e 4e 45
43 54 45 44 0d | D: DISCONNECTED.<br class="">
V (136618700) simcom: rx: 0a d9 f9
| ... <br class="">
V (136618710) gsm-mux: ProcessFrame(CHAN=4, ADDR=11,
CTRL=ff, FCS=d9, LEN=29)<br class="">
V (136618710) gsm-mux: ChanProcessFrame(CHAN=4, ADDR=11,
CTRL=ff, LEN=26, IFP=3)<br class="">
D (136618720) simcom: rx line ch=4 len=19 : +PPPD:
DISCONNECTED<br class="">
I (136618730) simcom: PPP Connection disconnected<br
class="">
I (136619020) simcom: Lost network connection (+PPP
disconnect in NetMode)<br class="">
I (136619020) simcom: State: Enter NetLoss state<br
class="">
V (136619030) simcom: tx: f9 0d ff 19 41 54 2b 43 47 41 54
54 3d 30 0d 0a | ....AT+CGATT=0..<br class="">
V (136619030) simcom: tx: 14 f9
| .. <br class="">
I (136619030) gsm-ppp: Shutting down (hard)...^[[0m<br
class="">
I (136619040) events: Signal(system.modem.down)^[[0m<br
class="">
I (136619040) netmanager: Interface priority is st1
(x.y.z.212/255.255.248.0 gateway x.y.z.64)<br class="">
V (136632920) simcom: rx: f9 0d ff 0d 0d 0a 4f 4b 0d 0a 0f
f9 | ......OK.... <br class="">
V (153076210) simcom: rx: f9 09 ff 1d 0d 0a 2b 43 53 51 3a
20 31 2c 39 39 | ......+CSQ: 1,99<br class="">
V (153076220) simcom: rx: 0d 0a d1 f9 f9 0d ff 1d 0d 0a 2b
43 53 51 3a 20 | ..........+CSQ: <br class="">
V (153076220) simcom: rx: 31 2c 39 39 0d 0a 13 f9 f9 11 ff
1d 0d 0a 2b 43 | 1,99..........+C<br class="">
V (153076230) simcom: rx: 53 51 3a 20 31 2c 39 39 0d 0a 1e
f9 | SQ: 1,99.... <br class="">
V (153202470) simcom: rx: f9 09 ff 1d 0d 0a 2b 43 53 51 3a
20 34 2c 39 39 | ......+CSQ: 4,99<br class="">
V (153202480) simcom: rx: 0d 0a d1 f9 f9 0d ff 1d 0d 0a 2b
43 53 51 3a 20 | ..........+CSQ: <br class="">
V (153202490) simcom: rx: 34 2c 39 39 0d 0a 13 f9 f9 11 ff
1d 0d 0a 2b 43 | 4,99..........+C<br class="">
V (153202500) simcom: rx: 53 51 3a 20 34 2c 39 39 0d 0a 1e
f9 | SQ: 4,99.... <br class="">
V (179212720) simcom: rx: f9 09 ff 1d 0d 0a 2b 43 53 51 3a
20 30 2c 39 39 | ......+CSQ: 0,99<br class="">
V (179212730) simcom: rx: 0d 0a d1 f9 f9 0d ff 1d 0d 0a 2b
43 53 51 3a 20 | ..........+CSQ: <br class="">
V (179212730) simcom: rx: 30 2c 39 39 0d 0a 13 f9 f9 11 ff
1d 0d 0a 2b 43 | 0,99..........+C<br class="">
V (179212740) simcom: rx: 53 51 3a 20 30 2c 39 39 0d 0a 1e
f9 | SQ: 0,99.... <br class="">
V (179242750) simcom: rx: f9 09 ff 1d 0d 0a 2b 43 53 51 3a
20 30 2c 39 39 | ......+CSQ: 0,99<br class="">
V (179242760) simcom: rx: 0d 0a d1 f9 f9 0d ff 1d 0d 0a 2b
43 53 51 3a 20 | ..........+CSQ: <br class="">
V (179242770) simcom: rx: 30 2c 39 39 0d 0a 13 f9 f9 11 ff
1d 0d 0a 2b 43 | 0,99..........+C<br class="">
V (179242770) simcom: rx: 53 51 3a 20 30 2c 39 39 0d 0a 1e
f9 | SQ: 0,99.... <br class="">
V (179246100) simcom: rx: f9 09 ff 19 0d 0a 2b 43 52 45 47
3a 20 32 0d 0a | ......+CREG: 2..<br class="">
V (179246110) simcom: rx: d6 f9 f9 0d ff 19 0d 0a 2b 43 52
45 47 3a 20 32 | ........+CREG: 2<br class="">
V (179246110) simcom: rx: 0d 0a 14 f9 f9 11 ff 19 0d 0a 2b
43 52 45 47 3a | ..........+CREG:<br class="">
V (179246120) simcom: rx: 20 32 0d 0a 19 f9
| 2.... <br class="">
V (179251580) simcom: rx: f9 09 ff 1d 0d 0a 2b 43 53 51 3a
20 34 2c 39 39 | ......+CSQ: 4,99<br class="">
V (179251590) simcom: rx: 0d 0a d1 f9 f9 0d ff 1d 0d 0a 2b
43 53 51 3a 20 | ..........+CSQ: <br class="">
V (179251600) simcom: rx: 34 2c 39 39 0d 0a 13 f9 f9 11 ff
1d 0d 0a 2b 43 | 4,99..........+C<br class="">
V (179251600) simcom: rx: 53 51 3a 20 34 2c 39 39 0d 0a 1e
f9 | SQ: 4,99.... <br class="">
I (179290100) wifi: bcn_timout,ap_probe_send_start<br
class="">
I (179292610) wifi: ap_probe_send over, resett wifi status
to disassoc<br class="">
I (179292610) wifi: state: run -> init (1)<br class="">
I (179292620) wifi: pm stop, total sleep time: 0/138543477<br
class="">
I (179292620) wifi: n:13 0, o:13 0, ap:255 255, sta:13 0,
prof:1</span></font></div>
</blockquote>
<div class=""><br class="">
</div>
<div class="">The simcom itself seems to be up, and is giving us
unsolicited notifications. Finally, the wifi stack falls over
(bcn_timeout.ap_probe_sent).</div>
<div class=""><br class="">
</div>
<div class="">This is very nasty, and I suspect will take some
time to resolve. We can probably make it more stable by
introducing a check for network loss, and requiring the network
to be up and stable for N seconds before even attempting a
reconnection; but I don’t think that addresses the core issue
(which is most likely in the lwip ppp stack).</div>
<div class=""><br class="">
</div>
<div class="">Code-wise, the simcom driver entering ‘NetLoss’
state calls m_ppp.Shutdown(true) (which issues the shutting down
(hard) message). Our pppos driver issues the system.modem.down
event (which causes netmanager to do it’s thing), and then
calls ppapi_close(m_ppp, nocarrier). I suspect that never
returns (but don’t know for sure).</div>
<div class=""><br class="">
</div>
<div class="">The other workaround is to enable watchdog timers.
I’m 99% certain this sort of lockup would mess up the idle tasks
and those would pickup and fire a watchdog timer. So, I’ve gone
ahead and committed code to enable the watchdog timers (2
minutes), and make that available as an OTA update:</div>
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">OVMS# ota status</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">Firmware:
3.1.001/ota_0/main (build idf v3.1-dev-453-g0f978bcb
Mar 25 2018 14:45:59)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-size: 14px;" class="">Server Available:
3.1.001</span></font></div>
</div>
</blockquote>
<div class=""><br class="">
</div>
<div class="">The module in my car is now running OTA, so I can
verify the OTA update process is smooth (it seems so).</div>
<div class=""><br class="">
</div>
<div class="">Regards, Mark.</div>
<div class=""><br class="">
</div>
<div class=""><br class="">
</div>
<br>
<fieldset class="mimeAttachmentHeader"></fieldset>
<br>
<pre wrap="">_______________________________________________
OvmsDev mailing list
<a class="moz-txt-link-abbreviated" href="mailto:OvmsDev@lists.teslaclub.hk">OvmsDev@lists.teslaclub.hk</a>
<a class="moz-txt-link-freetext" href="http://lists.teslaclub.hk/mailman/listinfo/ovmsdev">http://lists.teslaclub.hk/mailman/listinfo/ovmsdev</a>
</pre>
</blockquote>
<br>
</body>
</html>