Cellular IoT Hat dropping immediately after connecting via ppp

End-of-Life Product Cellular IoT HAT
1

I have completed my Verizon business setup and I am trying to get ppp working. Here are two cycles of the log. I will call Verizon support tomorrow to see what’s up but the accounts and cell numbers show up on my account. I am testing with one of my two lines. The only thing I see is the CSQ 99,99, which I researched. I have the individual antennas now but have not soldered the SJX yet, my board is actually different than the layout in the FAQ. I don’t want to guess on which one to solder. Have a look at the log and see if any errors jump out.

…
Jul 15 21:52:33 localhost chat[1447]: CONNECT
Jul 15 21:52:33 localhost chat[1447]: – got it
Jul 15 21:52:33 localhost pppd[1445]: Serial connection established.
Jul 15 21:52:33 localhost pppd[1445]: Using interface ppp0
Jul 15 21:52:33 localhost pppd[1445]: Connect: ppp0 <–> /dev/ttyS0
Jul 15 21:52:34 localhost pppd[1445]: PAP authentication succeeded
Jul 15 21:53:04 localhost pppd[1445]: IPCP: timeout sending Config-Requests
Jul 15 21:53:10 localhost pppd[1445]: Connection terminated.
Jul 15 21:53:10 localhost chat[1456]: abort on (ERROR)
Jul 15 21:53:10 localhost chat[1456]: abort on (NO DIALTONE)
Jul 15 21:53:10 localhost chat[1456]: send (+++^M)
Jul 15 21:53:10 localhost chat[1456]: send (+++^M)
Jul 15 21:53:10 localhost chat[1456]: send (+++^M)
Jul 15 21:53:10 localhost pppd[1445]: Serial link disconnected.
Jul 15 21:53:11 localhost pppd[1445]: Modem hangup
Jul 15 21:53:11 localhost pppd[1445]: Exit.
Jul 15 21:53:21 localhost pppd[1467]: pppd options in effect:
Jul 15 21:53:21 localhost pppd[1467]: debug#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: nodetach#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: dump#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: noauth#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: /dev/ttyS0#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: 115200#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: lock#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: connect chat -s -v -f /etc/chatscripts/chat-connect -T vzwinternet#011#011# (from /etc/ppp/p
eers/provider)
Jul 15 21:53:21 localhost pppd[1467]: disconnect chat -s -v -f /etc/chatscripts/chat-disconnect#011#011# (from /etc/ppp/peers/prov
ider)
Jul 15 21:53:21 localhost pppd[1467]: crtscts#011#011# (from /etc/ppp/options)
Jul 15 21:53:21 localhost pppd[1467]: modem#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: asyncmap 0#011#011# (from /etc/ppp/options)
Jul 15 21:53:21 localhost pppd[1467]: lcp-echo-failure 4#011#011# (from /etc/ppp/options)
Jul 15 21:53:21 localhost pppd[1467]: lcp-echo-interval 30#011#011# (from /etc/ppp/options)
Jul 15 21:53:21 localhost pppd[1467]: hide-password#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: ipcp-accept-local#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: ipcp-accept-remote#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: noipdefault#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: ipcp-max-failure 30#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: defaultroute#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: replacedefaultroute#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: usepeerdns#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:53:21 localhost pppd[1467]: noipx#011#011# (from /etc/ppp/options)
Jul 15 21:53:21 localhost pppd[1467]: pppd 2.4.7 started by root, uid 0
Jul 15 21:53:22 localhost chat[1469]: abort on (BUSY)
Jul 15 21:53:22 localhost chat[1469]: abort on (NO CARRIER)
Jul 15 21:53:22 localhost chat[1469]: abort on (NO DIALTONE)
Jul 15 21:53:22 localhost chat[1469]: abort on (ERROR)
Jul 15 21:53:22 localhost chat[1469]: abort on (NO ANSWER)
Jul 15 21:53:22 localhost chat[1469]: timeout set to 30 seconds
Jul 15 21:53:22 localhost chat[1469]: send (AT^M)
Jul 15 21:53:22 localhost chat[1469]: expect (OK)
Jul 15 21:53:22 localhost chat[1469]: ^M
Jul 15 21:53:22 localhost chat[1469]: OK
Jul 15 21:53:22 localhost chat[1469]: – got it
Jul 15 21:53:22 localhost chat[1469]: send (ATE0^M)
Jul 15 21:53:22 localhost chat[1469]: expect (OK)
Jul 15 21:53:22 localhost chat[1469]: ^M
Jul 15 21:53:22 localhost chat[1469]: ^M
Jul 15 21:53:22 localhost chat[1469]: OK
Jul 15 21:53:23 localhost chat[1469]: – got it
Jul 15 21:53:23 localhost chat[1469]: send (ATI;+CSUB;+CSQ;+COPS?;+CGREG?;&D2^M)
Jul 15 21:53:23 localhost chat[1469]: expect (OK)
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: Quectel^M
Jul 15 21:53:23 localhost chat[1469]: BG96^M
Jul 15 21:53:23 localhost chat[1469]: Revision: BG96MAR04A03M1G^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: SubEdition: V07^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: +CSQ: 99,99^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: +COPS: 0^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: +CGREG: 0,0^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: OK
Jul 15 21:53:23 localhost chat[1469]: – got it
Jul 15 21:53:23 localhost chat[1469]: send (AT+CGDCONT=1,“IP”,“vzwinternet”,0,0^M)
Jul 15 21:53:23 localhost chat[1469]: expect (OK)
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: OK
Jul 15 21:53:23 localhost chat[1469]: – got it
Jul 15 21:53:23 localhost chat[1469]: send (ATD99#^M)
Jul 15 21:53:23 localhost chat[1469]: expect (CONNECT)
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: ^M
Jul 15 21:53:23 localhost chat[1469]: CONNECT
Jul 15 21:53:23 localhost chat[1469]: – got it
Jul 15 21:53:23 localhost pppd[1467]: Serial connection established.
Jul 15 21:53:23 localhost pppd[1467]: Using interface ppp0
Jul 15 21:53:23 localhost pppd[1467]: Connect: ppp0 <–> /dev/ttyS0
Jul 15 21:53:24 localhost pppd[1467]: PAP authentication succeeded
Jul 15 21:53:54 localhost pppd[1467]: IPCP: timeout sending Config-Requests
Jul 15 21:54:00 localhost pppd[1467]: Connection terminated.
Jul 15 21:54:00 localhost chat[1478]: abort on (ERROR)
Jul 15 21:54:00 localhost chat[1478]: abort on (NO DIALTONE)
Jul 15 21:54:00 localhost chat[1478]: send (+++^M)
Jul 15 21:54:00 localhost chat[1478]: send (+++^M)
Jul 15 21:54:01 localhost chat[1478]: send (+++^M)
Jul 15 21:54:01 localhost pppd[1467]: Serial link disconnected.
Jul 15 21:54:02 localhost pppd[1467]: Modem hangup
Jul 15 21:54:02 localhost pppd[1467]: Exit.
Jul 15 21:54:12 localhost pppd[1489]: pppd options in effect:
Jul 15 21:54:12 localhost pppd[1489]: debug#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: nodetach#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: dump#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: noauth#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: /dev/ttyS0#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: 115200#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: lock#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: connect chat -s -v -f /etc/chatscripts/chat-connect -T vzwinternet#011#011# (from /etc/ppp/p
eers/provider)
Jul 15 21:54:12 localhost pppd[1489]: disconnect chat -s -v -f /etc/chatscripts/chat-disconnect#011#011# (from /etc/ppp/peers/prov
ider)
Jul 15 21:54:12 localhost pppd[1489]: crtscts#011#011# (from /etc/ppp/options)
Jul 15 21:54:12 localhost pppd[1489]: modem#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: asyncmap 0#011#011# (from /etc/ppp/options)
Jul 15 21:54:12 localhost pppd[1489]: lcp-echo-failure 4#011#011# (from /etc/ppp/options)
Jul 15 21:54:12 localhost pppd[1489]: lcp-echo-interval 30#011#011# (from /etc/ppp/options)
Jul 15 21:54:12 localhost pppd[1489]: hide-password#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: ipcp-accept-local#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: ipcp-accept-remote#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: noipdefault#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: ipcp-max-failure 30#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: defaultroute#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: replacedefaultroute#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: usepeerdns#011#011# (from /etc/ppp/peers/provider)
Jul 15 21:54:12 localhost pppd[1489]: noipx#011#011# (from /etc/ppp/options)
Jul 15 21:54:12 localhost pppd[1489]: pppd 2.4.7 started by root, uid 0
Jul 15 21:54:13 localhost chat[1491]: abort on (BUSY)
Jul 15 21:54:13 localhost chat[1491]: abort on (NO CARRIER)
Jul 15 21:54:13 localhost chat[1491]: abort on (NO DIALTONE)
Jul 15 21:54:13 localhost chat[1491]: abort on (ERROR)
Jul 15 21:54:13 localhost chat[1491]: abort on (NO ANSWER)
Jul 15 21:54:13 localhost chat[1491]: timeout set to 30 seconds
Jul 15 21:54:13 localhost chat[1491]: send (AT^M)
Jul 15 21:54:13 localhost chat[1491]: expect (OK)
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: OK
Jul 15 21:54:13 localhost chat[1491]: – got it
Jul 15 21:54:13 localhost chat[1491]: send (ATE0^M)
Jul 15 21:54:13 localhost chat[1491]: expect (OK)
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: OK
Jul 15 21:54:13 localhost chat[1491]: – got it
Jul 15 21:54:13 localhost chat[1491]: send (ATI;+CSUB;+CSQ;+COPS?;+CGREG?;&D2^M)
Jul 15 21:54:13 localhost chat[1491]: expect (OK)
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: Quectel^M
Jul 15 21:54:13 localhost chat[1491]: BG96^M
Jul 15 21:54:13 localhost chat[1491]: Revision: BG96MAR04A03M1G^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: SubEdition: V07^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: +CSQ: 99,99^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: +COPS: 0^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: +CGREG: 0,0^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: OK
Jul 15 21:54:13 localhost chat[1491]: – got it
Jul 15 21:54:13 localhost chat[1491]: send (AT+CGDCONT=1,“IP”,“vzwinternet”,0,0^M)
Jul 15 21:54:13 localhost chat[1491]: expect (OK)
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:13 localhost chat[1491]: ^M
Jul 15 21:54:14 localhost chat[1491]: OK
Jul 15 21:54:14 localhost chat[1491]: – got it
Jul 15 21:54:14 localhost chat[1491]: send (ATD99#^M)
Jul 15 21:54:14 localhost chat[1491]: expect (CONNECT)
Jul 15 21:54:14 localhost chat[1491]: ^M
Jul 15 21:54:14 localhost chat[1491]: ^M
Jul 15 21:54:14 localhost chat[1491]: CONNECT
Jul 15 21:54:14 localhost chat[1491]: – got it
…

2

Here is the pic of my board. I believe the solder posts are above the J of SJX, but those above the X give me pause.

sjx-pic
sjx-pic1125×2436 695 KB

3

This may be applicable:
The difference seems to be that in the failed case, the compression protocol negotation never succeeds. ppp has various options to select the compression protocol and to set characters that are valid over the modem connection. Play around with them, esp. with the valid characters (start very conservatively), and see if it fixes the problem. The modem firmware might want to interpret some characters in a special way. – dirkt May 14 '19 at 7:27

4

This was indeed an issue with Verizon. Tough in the covid to get through to support but got a really good support person and we found the issue. He had to change my plan to 5G (monthly throughput capacity not bandwidth) and then back again to 2.5G in order to get it to accept the device. It was not compression protocol and was not an issue of configuration. I still have the issue with the active antenna, i will repost that issue separately. Cheers, Tim

5

Another key factor in my case is poor cell reception at my house. I had to move my whole work area out to the front porch and we started getting a signal. Stucco exterior walls due to coastal fog and situated behind a hill is not conducive to good reception, even with Verizon.