pppd timed out or didn't initialize our dbus module

Thu Feb 14 19:05:01 UTC 2019

Hello!

Could you help us on this strange issue?

W dniu pt., 11.01.2019 o 10:24 Przemyslaw Galazka <pmg at sense.com.pl>
napisał(a):

> Hello,
>
> we have a standard generic modem (Quactel M66, one uart working in
> transparent mode).
> MM is working just fine - registering and connecting to the home network.
> When NetworkManager pickup to establish PPP connection we get the strange
> problem - seems like sth is blocking the serial port (MM?)
>
> MM version is 1.8.2
>
> *Logs from NM*
> Jan 04 17:28:36 f8c790d NetworkManager[690]: <info>  [1546622916.5428]
> ppp-manager: starting PPP connection
> Jan 04 17:28:36 f8c790d NetworkManager[690]: <debug> [1546622916.5443]
> ppp-manager: command line: /usr/sbin/pppd nodetach lock nodefaultroute ipv6
> , debug ttyUSB0 noipdefault 115200 noauth usepeerdns mru 128 mtu 128
> lcp-echo-failure 0 lcp-echo-interval 0 idle 0 ipparam
> /org/freedesktop/NetworkManager/PPP/14 plugin
> /usr/lib/pppd/2.4.5/nm-pppd-plugin.so
> Jan 04 17:28:36 f8c790d NetworkManager[690]: <info>  [1546622916.5679]
> ppp-manager: pppd started with pid 2596
> Jan 04 17:28:36 f8c790d NetworkManager[690]: <info>  [1546622916.5712]
> modem["ttyUSB0"]: IPv6 configuration disabled
> Jan 04 17:28:36 f8c790d NetworkManager[690]: <debug> [1546622916.5728]
> device[0x16da2d8] (ttyUSB0): activation-stage: complete
> activate_stage3_ip_config_start,v4 (id 1657)
> Jan 04 17:28:36 f8c790d NetworkManager[690]: Plugin
> /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
> Jan 04 17:28:36 f8c790d pppd[2596]: nm-ppp-plugin: (plugin_init):
> initializing
> *Jan 04 17:28:36 f8c790d pppd[2596]: nm-ppp-plugin: (nm_phasechange):
> status 3 / phase 'serial connection'*
>
>
> Jan 04 17:28:57 f8c790d NetworkManager[690]: <warn>  [1546622937.1715]
> ppp-manager: *pppd timed out or didn't initialize our dbus module*
> Jan 04 17:28:57 f8c790d pppd[2596]: nm-ppp-plugin: (nm_phasechange):
> status 1 / phase 'dead'
>
>
> *And when we use ppp demon manually (without MM and NM) all just works so
> hardware is just fine.*
>
> *root at f8c790d:~#  pppd call gprs*
> pppd options in effect:
> debug # (from /etc/ppp/peers/gprs)
> nodetach # (from /etc/ppp/peers/gprs)
> dump # (from /etc/ppp/peers/gprs)
> noauth # (from /etc/ppp/peers/gprs)
> user internet # (from /etc/ppp/peers/gprs)
> remotename 3gppp # (from /etc/ppp/peers/gprs)
> /dev/ttyUSB0 # (from /etc/ppp/peers/gprs)
> 115200 # (from /etc/ppp/peers/gprs)
> lock # (from /etc/ppp/peers/gprs)
> connect chat -s -v -f /etc/chatscripts/quectel-chat-connect -T playmetric #
> (from /etc/ppp/peers/gprs)
> disconnect chat -s -v -f /etc/chatscripts/quectel-chat-disconnect # (from
> /etc/ppp/peers/gprs)
> nocrtscts # (from /etc/ppp/peers/gprs)
> modem # (from /etc/ppp/peers/gprs)
> asyncmap 0 # (from /etc/ppp/options)
> mru 296 # (from /etc/ppp/peers/gprs)
> mtu 296 # (from /etc/ppp/peers/gprs)
> lcp-echo-failure 4 # (from /etc/ppp/options)
> lcp-echo-interval 30 # (from /etc/ppp/options)
> show-password # (from /etc/ppp/options)
> novj # (from /etc/ppp/peers/gprs)
> novjccomp # (from /etc/ppp/peers/gprs)
> ipcp-accept-local # (from /etc/ppp/peers/gprs)
> ipcp-accept-remote # (from /etc/ppp/peers/gprs)
> ipparam 3gppp # (from /etc/ppp/peers/gprs)
> noipdefault # (from /etc/ppp/peers/gprs)
> ipcp-max-failure 30 # (from /etc/ppp/peers/gprs)
> defaultroute # (from /etc/ppp/peers/gprs)
> usepeerdns # (from /etc/ppp/peers/gprs)
> noccp # (from /etc/ppp/peers/gprs)
> noipx # (from /etc/ppp/options)
> abort on (BUSY)
> abort on (NO CARRIER)
> abort on (NO DIALTONE)
> abort on (ERROR)
> abort on (NO ANSWER)
> timeout set to 30 seconds
> send (AT^M)
> expect (OK)
> ^M
> OK
>  -- got it
>
> send (ATE0^M)
> expect (OK)
> ^M
> ^M
> OK
>  -- got it
>
> send (ATI;+CSUB;+CSQ;+CPIN?;+COPS?;+CGREG?;&D2^M)
> expect (OK)
> ^M
> ^M
> Quectel_Ltd^M
> Quectel_M66^M
> Revision: M66FAR01A08BT^M
> ^M
> SubEdition:V03^M
> ^M
> +CSQ: 29,0^M
> ^M
> +CPIN: READY^M
> ^M
> +COPS: 0,0,"T-Mobile.pl"^M
> ^M
> +CGREG: 0,5^M
> ^M
> OK
>  -- got it
>
> send (AT+CGDCONT=1,"IP","playmetric",,0,0^M)
> expect (OK)
> ^M
> ^M
> OK
>  -- got it
>
> send (ATD*99#^M)
> expect (CONNECT)
> ^M
> ^M
> *CONNECT*
>  -- got it
>
> Script chat -s -v -f /etc/chatscripts/quectel-chat-connect -T playmetric
> finished (pid 29953), status = 0x0
> Serial connection established.
> using channel 13
> Using interface ppp0
> Connect: ppp0 <--> /dev/ttyUSB0
> sent [LCP ConfReq id=0x1 <mru 296> <asyncmap 0x0> <magic 0x604edc61>
> <pcomp> <accomp>]
> rcvd [LCP ConfReq id=0x1 <asyncmap 0xa0000> <auth pap> <pcomp> <accomp>]
> sent [LCP ConfAck id=0x1 <asyncmap 0xa0000> <auth pap> <pcomp> <accomp>]
> rcvd [LCP ConfNak id=0x1 <asyncmap 0xa0000>]
> sent [LCP ConfReq id=0x2 <mru 296> <asyncmap 0xa0000> <magic 0x604edc61>
> <pcomp> <accomp>]
> rcvd [LCP ConfAck id=0x2 <mru 296> <asyncmap 0xa0000> <magic 0x604edc61>
> <pcomp> <accomp>]
> sent [LCP EchoReq id=0x0 magic=0x604edc61]
> sent [PAP AuthReq id=0x1 user="internet" password="internet"]
> rcvd [LCP EchoRep id=0x0 magic=0x0]
> rcvd [PAP AuthAck id=0x1 ""]
> *PAP authentication succeeded*
> sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2
> 0.0.0.0>]
> rcvd [IPCP ConfReq id=0x1 <addr 192.168.254.254>]
> sent [IPCP ConfAck id=0x1 <addr 192.168.254.254>]
> rcvd [IPCP ConfNak id=0x1 <addr 10.195.226.215> <ms-dns1 89.108.195.20>
> <ms-dns2 89.108.202.21>]
> sent [IPCP ConfReq id=0x2 <addr 10.195.226.215> <ms-dns1 89.108.195.20>
> <ms-dns2 89.108.202.21>]
> rcvd [IPCP ConfAck id=0x2 <addr 10.195.226.215> <ms-dns1 89.108.195.20>
> <ms-dns2 89.108.202.21>]
> local  IP address 10.195.226.215
> remote IP address 192.168.254.254
> primary   DNS address 89.108.195.20
> secondary DNS address 89.108.202.21
> Script /etc/ppp/ip-up started (pid 29971)
> Script /etc/ppp/ip-up finished (pid 29971), status = 0x0
>
>
> *And connection works...*
>
> *root at f8c790d:~# ping 8.8.8.8 -Ippp0*
> PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data.
> 64 bytes from 8.8.8.8: icmp_seq=1 ttl=120 time=802 ms
> 64 bytes from 8.8.8.8: icmp_seq=2 ttl=120 time=511 ms
> 64 bytes from 8.8.8.8: icmp_seq=3 ttl=120 time=164 ms
> 64 bytes from 8.8.8.8: icmp_seq=4 ttl=120 time=167 ms
> 64 bytes from 8.8.8.8: icmp_seq=5 ttl=120 time=282 ms
>
> *Could you help us on this?*
>
>
> *ps. Our NM connection profile*
>
> *root at f8c790d:/etc/NetworkManager/system-connections# cat
> orange.nmconnection *
> *[connection]*
> *id=orange*
> *uuid=da963f44-8d1e-4338-872f-9d9163de0832*
> *type=gsm*
> *interface-name=ttyUSB0*
> *permissions=*
> *#gateway-ping-timeout=100*
>
> *[gsm]*
> *apn=playmetric*
> *#username=internet*
> *#password=internet*
> *#home-only=true*
> *number=*99#*
> *#pin=7744*
>
> *[ipv4]*
> *dns-search=*
> *method=auto*
> *dhcp-timeout=100*
>
> *[ipv6]*
> *addr-gen-mode=stable-privacy*
> *dns-search=*
> *method=auto*
> *dhcp-timeout=100*
>
> *[ppp]*
> *baud=115200*
> *mru=128*
> *mtu=128*
> *crtscts=false*
>
> *[serial]*
> *baud=115200*
> *root at f8c790d:/etc/NetworkManager/system-connections# *
> --
> best regards / z poważaniem
>
> Przemysław Gałązka, CEO
> +48 889 348 628
>
> linkedin <http://www.linkedin.com/in/przemekgalazka>
>
> przemek at sense.com.pl
> https://www.sense-monitoring.com
> <https://www.sense-monitoring.com/?utm_source=stopka&utm_medium=mail&utm_campaign=Przemek>
>
> [image:
> sense-logo_monitoring-poziom-s2-add135aef733ff520b43a7bf69e9cd05.png
> (281×100)]
>
> Sense Monitoring sp. z o.o
> Nalkowskiej 11
> 38-500 Sanok, Poland
> KRS:0000676863, NIP: 5213780421
> BDO 000062957
>
> Please note that all information in this message or attachmentes thereof
> shall be construed as business secrets and are shared only with the
> intended recepients. // Otrzymana przez Panią/Pana wiadomość oraz
> ewentualne załączone do niej pliki stanowią tajemnicę przedsiębiorstwa i są
> przeznaczone tylko dla wymienionych adresatów.
>
-- 
best regards / z poważaniem

Przemysław Gałązka, CEO
+48 889 348 628

linkedin <http://www.linkedin.com/in/przemekgalazka>

przemek at sense.com.pl
https://www.sense-monitoring.com
<https://www.sense-monitoring.com/?utm_source=stopka&utm_medium=mail&utm_campaign=Przemek>

[image:
sense-logo_monitoring-poziom-s2-add135aef733ff520b43a7bf69e9cd05.png
(281×100)]
Sense Monitoring sp. z o.o
Nalkowskiej 11
38-500 Sanok, Poland
KRS:0000676863, NIP: 5213780421
BDO 000062957

Please note that all information in this message or attachmentes thereof
shall be construed as business secrets and are shared only with the
intended recepients. // Otrzymana przez Panią/Pana wiadomość oraz
ewentualne załączone do niej pliki stanowią tajemnicę przedsiębiorstwa i są
przeznaczone tylko dla wymienionych adresatów.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20190214/2e7314b2/attachment-0001.html>