Connect script failed

daniel.bruchez · April 14, 2021, 5:43pm

root@raspberrypi4:/home/pi/go/bin/Sixfab/Sixfab_RPi_CellularIoT_Library# systemctl status ppp_connection_manager.service
● ppp_connection_manager.service - PPP Connection Manager
Loaded: loaded (/etc/systemd/system/ppp_connection_manager.service; enabled; vendor preset: enabled)
Active: active (running) since Mon 2021-04-12 13:06:33 EDT; 2 days ago
Main PID: 492 (bash)
Tasks: 4 (limit: 4915)
CGroup: /system.slice/ppp_connection_manager.service
├─ 492 /bin/bash ppp_connection_manager.sh
├─ 631 bash ppp_reconnect.sh
├─28555 sudo pon
└─28556 /usr/sbin/pppd call provider

Apr 12 13:20:36 raspberrypi4 chat[28565]: timeout set to 30 seconds
Apr 12 13:20:36 raspberrypi4 chat[28565]: send (AT^M)
Apr 12 13:20:36 raspberrypi4 chat[28565]: expect (OK)
Apr 12 13:20:36 raspberrypi4 bash[492]: expect (OK)
Apr 12 13:21:06 raspberrypi4 bash[492]: alarm
Apr 12 13:21:06 raspberrypi4 bash[492]: Failed
Apr 12 13:21:06 raspberrypi4 bash[492]: Script chat -s -v -f /etc/chatscripts/chat-connect -T super finished (pid 28564), status = 0x3
Apr 12 13:21:06 raspberrypi4 bash[492]: Connect script failed
Apr 12 13:21:06 raspberrypi4 pppd[28556]: Script chat -s -v -f /etc/chatscripts/chat-connect -T super finished (pid 28564), status = 0x3
Apr 12 13:21:06 raspberrypi4 pppd[28556]: Connect script failed

daniel.bruchez · April 14, 2021, 8:21pm

I rebooted my system and found this in the syslog →
Apr 14 16:04:36 raspberrypi4 bash[523]: CONNECT
Apr 14 16:04:36 raspberrypi4 bash[523]: – got it
Apr 14 16:04:36 raspberrypi4 chat[15412]: CONNECT
Apr 14 16:04:36 raspberrypi4 bash[523]: Script chat -s -v -f /etc/chatscripts/chat-connect -T super finished (pid 15411), status = 0x0
Apr 14 16:04:36 raspberrypi4 bash[523]: Serial connection established.
Apr 14 16:04:36 raspberrypi4 chat[15412]: – got it
Apr 14 16:04:36 raspberrypi4 pppd[15403]: Script chat -s -v -f /etc/chatscripts/chat-connect -T super finished (pid 15411), status = 0x0
Apr 14 16:04:36 raspberrypi4 pppd[15403]: Serial connection established.
Apr 14 16:04:36 raspberrypi4 pppd[15403]: using channel 1
Apr 14 16:04:36 raspberrypi4 bash[523]: using channel 1
Apr 14 16:04:36 raspberrypi4 bash[523]: Using interface ppp0
Apr 14 16:04:36 raspberrypi4 pppd[15403]: Using interface ppp0
Apr 14 16:04:36 raspberrypi4 bash[523]: Connect: ppp0 <–> /dev/ttyUSB3
Apr 14 16:04:36 raspberrypi4 pppd[15403]: Connect: ppp0 <–> /dev/ttyUSB3
Apr 14 16:04:37 raspberrypi4 kernel: [ 531.522129] Voltage normalised (0x00000000)
Apr 14 16:04:37 raspberrypi4 pppd[15403]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x1f47597e> ]
Apr 14 16:04:37 raspberrypi4 bash[523]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x1f47597e> ]
Apr 14 16:04:37 raspberrypi4 pppd[15403]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <magic 0x1187f9b5> ]
Apr 14 16:04:37 raspberrypi4 bash[523]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <magic 0x1187f9b5> ]
Apr 14 16:04:37 raspberrypi4 bash[523]: No auth is possible

Wonder why the systemctl status ppp_connection_manager.service call fails???

ensar · April 14, 2021, 8:27pm

Hi @daniel.bruchez ,

Could you please elaborate a bit more or point to more detailed information?
Please also share the following command outputs:

uname -sr
lsusb
lsusb -t
ls /dev/ttyUSB*
dmesg | grep tty
sudo cat /sys/kernel/debug/usb/devices

AT Commands List

AT
ATE1
ATI
AT+QGMR
AT+CPIN?
AT+CPAS
AT+CFUN?
AT+COPS?
AT+QCFG=”usbnet”
AT+QCFG=“band”
AT+CREG?
AT+CGDCONT?
AT+CSQ
AT+CGATT?
AT+QCFG=“nwscanseq”
AT+QCFG=“nwscanmode”
AT+QCFG=“iotopmode”
AT+QCSQ
AT+QNWINFO

Thanks.

daniel.bruchez · April 15, 2021, 1:46pm

pi@raspberrypi4:~ $ uname -sr
Linux 5.10.17-v7l+
pi@raspberrypi4:~ $ lsusb
Bus 002 Device 002: ID 090c:1000 Silicon Motion, Inc. - Taiwan (formerly Feiya Technology Corp.) Flash Drive
Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 005: ID 2c7c:0296 Quectel Wireless Solutions Co., Ltd. BG96 CAT-M1/NB-IoT modem
Bus 001 Device 002: ID 2109:3431 VIA Labs, Inc. Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
pi@raspberrypi4:~ $ lsusb -t
/: Bus 02.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/4p, 5000M
|__ Port 1: Dev 2, If 0, Class=Mass Storage, Driver=usb-storage, 5000M
/: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=xhci_hcd/1p, 480M
|__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/4p, 480M
|__ Port 2: Dev 5, If 0, Class=Vendor Specific Class, Driver=option, 480M
|__ Port 2: Dev 5, If 1, Class=Vendor Specific Class, Driver=option, 480M
|__ Port 2: Dev 5, If 2, Class=Vendor Specific Class, Driver=option, 480M
|__ Port 2: Dev 5, If 3, Class=Vendor Specific Class, Driver=option, 480M
|__ Port 2: Dev 5, If 4, Class=Vendor Specific Class, Driver=qmi_wwan, 480M
pi@raspberrypi4:~ $ ls /dev/ttyU*
/dev/ttyUSB0 /dev/ttyUSB1 /dev/ttyUSB2 /dev/ttyUSB3
pi@raspberrypi4:~ $ dmsg|grep tty
bash: dmsg: command not found
pi@raspberrypi4:~ $ dmesg|grep tty
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:BB:3D:DB vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 console=tty1 root=PARTUUID=97709164-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[ 0.001100] printk: console [tty1] enabled
[ 1.491245] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[ 1.498500] fe215040.serial: ttyS0 at MMIO 0xfe215040 (irq = 38, base_baud = 62500000) is a 16550
[ 7.278646] usb 1-1…2: GSM modem (1-port) converter now attached to ttyUSB0
[ 7.279458] usb 1-1…2: GSM modem (1-port) converter now attached to ttyUSB1
[ 7.280155] usb 1-1…2: GSM modem (1-port) converter now attached to ttyUSB2
[ 7.280839] usb 1-1…2: GSM modem (1-port) converter now attached to ttyUSB3
[ 168.660565] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 168.665707] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 168.668339] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 168.668960] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 667.158729] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
[ 667.160001] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
[ 667.161419] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
[ 667.166003] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB3
[ 825.396209] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
825.401810] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 832.191570] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
[ 832.193696] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
[ 832.195731] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
[ 832.196529] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB3
pi@raspberrypi4:~ $ sudo cat /sys/kernel/debug/usb/devices
sudo: unable to resolve host raspberrypi4: Name or service not known

T: Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=480 MxCh= 1
B: Alloc= 0/800 us ( 0%), #Int= 0, #Iso= 0
D: Ver= 2.00 Cls=09(hub ) Sub=00 Prot=01 MxPS=64 #Cfgs= 1
P: Vendor=1d6b ProdID=0002 Rev= 5.10
S: Manufacturer=Linux 5.10.17-v7l+ xhci-hcd
S: Product=xHCI Host Controller
S: SerialNumber=0000:01:00.0
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr= 0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
E: Ad=81(I) Atr=03(Int.) MxPS= 4 Ivl=256ms

T: Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#= 2 Spd=480 MxCh= 4
D: Ver= 2.10 Cls=09(hub ) Sub=00 Prot=01 MxPS=64 #Cfgs= 1
P: Vendor=2109 ProdID=3431 Rev= 4.21
S: Product=USB2.0 Hub
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=100mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
E: Ad=81(I) Atr=03(Int.) MxPS= 1 Ivl=256ms

T: Bus=01 Lev=02 Prnt=02 Port=01 Cnt=01 Dev#= 5 Spd=480 MxCh= 0
D: Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs= 1
P: Vendor=2c7c ProdID=0296 Rev= 0.00
S: Manufacturer=Qualcomm, Incorporated
S: Product=Qualcomm CDMA Technologies MSM
S: SerialNumber=9177fc23
C:* #Ifs= 5 Cfg#= 1 Atr=e0 MxPwr=500mA
I:* If#= 0 Alt= 0 #EPs= 2 Cls=ff(vend.) Sub=ff Prot=ff Driver=option
E: Ad=81(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E: Ad=01(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms
I:* If#= 1 Alt= 0 #EPs= 2 Cls=ff(vend.) Sub=ff Prot=ff Driver=option
E: Ad=82(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
I:* If#= 1 Alt= 0 #EPs= 2 Cls=ff(vend.) Sub=ff Prot=ff Driver=option
E: Ad=82(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E: Ad=02(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms
I:* If#= 2 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=ff Prot=ff Driver=option
E: Ad=83(I) Atr=03(Int.) MxPS= 64 Ivl=2ms
E: Ad=84(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E: Ad=03(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms
I:* If#= 3 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=fe Prot=ff Driver=option
E: Ad=85(I) Atr=03(Int.) MxPS= 64 Ivl=2ms
E: Ad=86(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E: Ad=04(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms
I:* If#= 4 Alt= 0 #EPs= 3 Cls=ff(vend.) Sub=ff Prot=ff Driver=qmi_wwan
E: Ad=87(I) Atr=03(Int.) MxPS= 64 Ivl=2ms
E: Ad=88(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E: Ad=05(O) Atr=02(Bulk) MxPS= 512 Ivl=0ms

T: Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=5000 MxCh= 4
B: Alloc= 0/800 us ( 0%), #Int= 0, #Iso= 0
D: Ver= 3.00 Cls=09(hub ) Sub=00 Prot=03 MxPS= 9 #Cfgs= 1
P: Vendor=1d6b ProdID=0003 Rev= 5.10
S: Manufacturer=Linux 5.10.17-v7l+ xhci-hcd
S: Product=xHCI Host Controller
S: SerialNumber=0000:01:00.0
C:* #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr= 0mA
I:* If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
E: Ad=81(I) Atr=03(Int.) MxPS= 4 Ivl=256ms

T: Bus=02 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#= 2 Spd=5000 MxCh= 0
D: Ver= 3.10 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 9 #Cfgs= 1
P: Vendor=090c ProdID=1000 Rev=11.00
S: Manufacturer=Samsung
S: Product=Flash Drive FIT
S: SerialNumber=0373520070005336
S: Product=Flash Drive FIT
S: SerialNumber=0373520070005336
C:* #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=304mA
I:* If#= 0 A [remainder of message body omitted; too large]
Reply