[Ovmsdev] Repeated WDT crashes after upgrade to 3.3 with 4G

[Stephen Casner]

On Fri, 4 Mar 2022, Mark Webb-Johnson wrote:

> @Steve can you try to get a debug log for the 'gsm-ppp' tag? That is
> the most complex part (with interactions with lwip tasks for
> tcp/ip), and most likely culprit.

Included below, but there were no messages at all with tag gsm-ppp.
Should it be something else?

                                                        -- Steve

2022-03-03 23:03:15.217 PST I (3154617) cellular: Set modem driver to 'auto'
2022-03-03 23:03:15.217 PST I (3154617) cellular: State: Enter PowerOffOn state
2022-03-03 23:03:15.217 PST I (3154617) cellular-modem-auto: Power Cycle 2000ms
2022-03-03 23:03:17.937 PST D (3157337) cellular: State timeout PowerOffOn => PoweringOn
2022-03-03 23:03:17.937 PST I (3157337) cellular: State: Enter PoweringOn state
2022-03-03 23:03:17.937 PST I (3157337) cellular-modem-auto: Power Cycle 4000ms
2022-03-03 23:03:21.937 PST D (3161337) cellular: tx-cmd: AT
2022-03-03 23:03:21.937 PST D (3161337) cellular: tx-cmd: AT
2022-03-03 23:03:21.937 PST D (3161337) cellular: tx-cmd: AT
2022-03-03 23:03:21.937 PST D (3161337) cellular: tx-cmd: AT
2022-03-03 23:03:22.937 PST D (3162337) cellular: tx-cmd: AT
2022-03-03 23:03:23.937 PST D (3163337) cellular: tx-cmd: AT
2022-03-03 23:03:24.937 PST D (3164337) cellular: tx-cmd: AT
2022-03-03 23:03:25.937 PST D (3165337) cellular: tx-cmd: AT
2022-03-03 23:03:26.937 PST D (3166337) cellular: tx-cmd: AT
2022-03-03 23:03:27.937 PST D (3167337) cellular: tx-cmd: AT
2022-03-03 23:03:27.937 PST I (3167337) ovms-server-v2: Send MP-0 S78.0,M,65535,127.00,done,standard,164,138,17,0,0,140,7,4,0,0,0,0,0.00,0,0,0,0,-1,0,0,0,0,0,0,0,0.00,0.00,0,0.00,0.00,1.00,0.0
2022-03-03 23:03:27.937 PST I (3167337) ovms-server-v2: Send MP-0 D64,0,5,19,17,17,512,625508,0,117153,19,0,0,0,11.5,0,12.6,0,19,0,0
2022-03-03 23:03:27.947 PST I (3167347) ovms-server-v2: Send MP-0 L37.346313,-122.037300,208,49,1,1,0.0,512,0,0.000,0,0,0,0,,0,0,0
2022-03-03 23:03:27.947 PST I (3167347) ovms-server-v2: Send MP-0 F3.3.001/ota_0/edge (build idf v3.3.4-848-g1ff5e24b1 Nov 22 2021 09:58:29),5YJRE11B181000033,29,1,TR1N,Revelstoke,-1,-1
2022-03-03 23:03:28.937 PST D (3168337) cellular: tx-cmd: AT
2022-03-03 23:03:29.937 PST D (3169337) cellular: tx-cmd: AT
2022-03-03 23:03:30.937 PST D (3170337) cellular: tx-cmd: AT
2022-03-03 23:03:31.937 PST D (3171337) cellular: tx-cmd: AT
2022-03-03 23:03:32.937 PST D (3172337) cellular: tx-cmd: AT
2022-03-03 23:03:32.977 PST I (3172377) cellular: State: Enter Identify state
2022-03-03 23:03:33.937 PST D (3173337) cellular: tx-cmd: AT+CGMM
2022-03-03 23:03:33.947 PST I (3173347) cellular: Identified cellular modem: SIM7600/Experimental support for SIMCOM SIM7600
2022-03-03 23:03:33.947 PST D (3173347) cellular: Remove old 'auto' modem driver
2022-03-03 23:03:33.947 PST I (3173347) cellular: Set modem driver to 'SIM7600'
2022-03-03 23:03:33.947 PST I (3173347) cellular: State: Enter PoweredOn state
2022-03-03 23:03:43.937 PST D (3183337) cellular: tx-cmd: AT+CPIN?;+CREG=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
2022-03-03 23:03:43.967 PST D (3183367) cellular: mux-rx-line #0: AT+CPIN?;+CREG=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
2022-03-03 23:03:43.967 PST D (3183367) cellular: mux-rx-line #0: +CPIN: READY
2022-03-03 23:03:43.967 PST D (3183367) cellular: mux-rx-line #0: +CSQ: 12,99
2022-03-03 23:03:43.967 PST I (3183367) cellular: Signal Quality is: 12 (-89 dBm)
2022-03-03 23:03:45.937 PST D (3185337) cellular: tx-cmd: AT+CGMR;+ICCID
2022-03-03 23:03:45.957 PST D (3185357) cellular: mux-rx-line #0: +CGMR: LE20B03SIM7600M21-A
2022-03-03 23:03:45.957 PST D (3185357) cellular: mux-rx-line #0: +ICCID: 8944501805175416178
2022-03-03 23:03:53.937 PST D (3193337) cellular: tx-cmd: AT+CMUX=0
2022-03-03 23:03:53.947 PST I (3193347) cellular: State: Enter MuxStart state
2022-03-03 23:03:53.947 PST I (3193347) gsm-mux: Start MUX
2022-03-03 23:03:53.957 PST I (3193357) gsm-mux: Channel #0 is open
2022-03-03 23:03:53.967 PST I (3193367) gsm-mux: Channel #1 is open
2022-03-03 23:03:53.977 PST I (3193377) gsm-mux: Channel #2 is open
2022-03-03 23:03:53.987 PST I (3193387) gsm-mux: Channel #3 is open
2022-03-03 23:03:53.997 PST I (3193397) gsm-mux: Channel #4 is open
2022-03-03 23:03:54.937 PST D (3194337) cellular: State transition MuxStart => NetWait
2022-03-03 23:03:54.937 PST I (3194337) cellular: State: Enter NetWait state
2022-03-03 23:04:04.937 PST D (3204337) cellular: mux-tx #3: AT+CREG?;+CCLK?;+CSQ;+COPS?
2022-03-03 23:04:04.967 PST D (3204367) cellular: mux-rx-line #3: +CREG: 1,5
2022-03-03 23:04:04.967 PST I (3204367) cellular: Network Registration status: RegisteredRoaming
2022-03-03 23:04:04.967 PST D (3204367) cellular: mux-rx-line #3: +CCLK: "22/03/03,23:04:02-32"
2022-03-03 23:04:04.967 PST D (3204367) cellular: mux-rx-line #3: +CSQ: 12,99
2022-03-03 23:04:04.967 PST D (3204367) cellular: mux-rx-line #3: +COPS: 0,0,"AT&T Hologram",2
2022-03-03 23:04:04.967 PST I (3204367) cellular: Network Provider is: AT&T Hologram
2022-03-03 23:05:15.687 PST I (8637) command: OpenLogfile: now logging to file '/sd/crash.log'