pppd timed out or didn't initialize our dbus module

Fri Jan 11 09:24:16 UTC 2019

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.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20190111/5078958a/attachment.html>