Feb 14 19:30:59 armadillo ModemManager[1995]: <info>  logging: level 'DEBUG'
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.4903] agent-manager: req[0x25d18e0, :1.39/nmcli-connect/0]: agent registered
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5044] device (ttyCommModem): Activation: starting connection 'gsm-ttyCommModem' (5181bf32-8531-477e-9cf1-8ed5041bb3b1)
Feb 14 19:31:02 armadillo NetworkManager[1938]: <error> [1550140262.5161] audit: failed to open auditd socket: Protocol not supported
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5164] audit: op="connection-activate" uuid="5181bf32-8531-477e-9cf1-8ed5041bb3b1" name="gsm-ttyCommModem" pid=7306 uid=0 result="success"
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5173] device (ttyCommModem): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5207] manager: NetworkManager state is now CONNECTING
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5244] device (ttyCommModem): state change: prepare -> need-auth (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5447] device (ttyCommModem): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Simple connect started...
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    PIN: unspecified
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    Operator ID: unspecified
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    Allowed roaming: yes
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    APN: ●●●
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    IP family: ipv4v6
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    Allowed authentication: unspecified
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    User: ●●●
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug>    Password: ●●●
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Simple connect state (4/8): Wait to get fully enabled
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Simple connect state (5/8): Register
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Already registered in network '44010', automatic registration not launched...
Feb 14 19:31:02 armadillo NetworkManager[1938]: <info>  [1550140262.5704] modem["ttyCommModem"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Simple connect state (6/8): Bearer
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Creating new bearer...
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Creating Broadband bearer Quectel EC25 in broadband modem
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Simple connect state (7/8): Connect
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:02 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Launching 3GPP connection attempt with APN '●●●'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Quectel EC25, We always use cid 1.
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QICSGP=1<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+QICSGP: 1,"●●●","●●●","●●●",2<CR><LF><CR><LF>OK<CR><LF>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> using existing cid: 1, start check operator.
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> load operator code ...
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 4 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+COPS=3,2<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+COPS?<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+COPS: 0,2,"44010",7<CR><LF><CR><LF>OK<CR><LF>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> loaded Operator Code: 44010
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QIACT?<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QIACT=1<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Activated PDP context, start dial.
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3) opening serial port...
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3): setting up baudrate: 57600
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3): no flow control explicitly requested for device
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3): port attributes not fully set
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 1 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> Connection through a plain serial AT port (ttyUSB3)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 2 (open)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 1 (close)
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyUSB3): --> 'ATD*99***1#<CR>'
Feb 14 19:31:02 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+CIEV: 7,2<CR><LF>'
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> (ttyUSB3): <-- '<CR><LF>CONNECT 150000000<CR><LF>'
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> (ttyUSB3): port now connected
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0049] modem["ttyCommModem"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> PPP is required for connection, will ignore disconnection reports
Feb 14 19:31:03 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Feb 14 19:31:03 armadillo ModemManager[1995]: <info>  Simple connect state (8/8): All done
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0139] device (ttyCommModem): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 1 (close)
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0202] device (ttyCommModem): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:03 armadillo NetworkManager[1938]: <warn>  [1550140263.0236] device (ttyCommModem): interface ttyCommModem not up for IP configuration
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0239] modem["ttyCommModem"]: using modem-specified IP timeout: 20 seconds
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0295] loaded PPP plugin /usr/lib/arm-linux-gnueabihf/NetworkManager/1.14.6/libnm-ppp-plugin.so
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0328] ppp-manager: starting PPP connection
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.0447] ppp-manager: pppd started with pid 7312
Feb 14 19:31:03 armadillo NetworkManager[1938]: Plugin /usr/lib/pppd/2.4.7/nm-pppd-plugin.so loaded.
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (plugin_init): initializing
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Feb 14 19:31:03 armadillo NetworkManager[1938]: Using interface ppp0
Feb 14 19:31:03 armadillo NetworkManager[1938]: Connect: ppp0 <--> /dev/ttyUSB3
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.1318] manager: (ppp0): new Ppp device (/org/freedesktop/NetworkManager/Devices/12)
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Feb 14 19:31:03 armadillo NetworkManager[1938]: CHAP authentication succeeded
Feb 14 19:31:03 armadillo NetworkManager[1938]: CHAP authentication succeeded
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> (net/ppp0): adding device at sysfs path: /sys/devices/virtual/net/ppp0
Feb 14 19:31:03 armadillo ModemManager[1995]: <debug> [filter] (net/ppp0) port filtered: virtual device
Feb 14 19:31:03 armadillo NetworkManager[1938]: Could not determine remote IP address: defaulting to 10.64.64.64
Feb 14 19:31:03 armadillo NetworkManager[1938]: local  IP address 10.98.218.84
Feb 14 19:31:03 armadillo NetworkManager[1938]: remote IP address 10.64.64.64
Feb 14 19:31:03 armadillo NetworkManager[1938]: primary   DNS address 203.138.71.154
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
Feb 14 19:31:03 armadillo NetworkManager[1938]: secondary DNS address 210.150.255.66
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_ip_up): ip-up event
Feb 14 19:31:03 armadillo pppd[7312]: nm-ppp-plugin: (nm_ip_up): sending IPv4 config to NetworkManager...
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.3240] ppp-manager: (IPv4 Config Get) reply received.
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.3593] device (ttyCommModem): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.3809] device (ttyCommModem): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.3862] device (ttyCommModem): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.3952] manager: NetworkManager state is now CONNECTED_LOCAL
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.4180] manager: NetworkManager state is now CONNECTED_SITE
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.4205] policy: set 'gsm-ttyCommModem' (ppp0) as default for IPv4 routing and DNS
Feb 14 19:31:03 armadillo dnsmasq[2238]: reading /etc/resolv.conf
Feb 14 19:31:03 armadillo dnsmasq[2238]: using nameserver 203.138.71.154#53
Feb 14 19:31:03 armadillo dnsmasq[2238]: using nameserver 210.150.255.66#53
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.4321] device (ttyCommModem): Activation: successful, device activated.
Feb 14 19:31:03 armadillo dbus-daemon[1911]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.3' (uid=0 pid=1938 comm="/usr/sbin/NetworkManager --no-daemon ")
Feb 14 19:31:03 armadillo dnsmasq[2238]: reading /etc/resolv.conf
Feb 14 19:31:03 armadillo dnsmasq[2238]: using nameserver 203.138.71.154#53
Feb 14 19:31:03 armadillo dnsmasq[2238]: using nameserver 210.150.255.66#53
Feb 14 19:31:03 armadillo NetworkManager[1938]: <info>  [1550140263.4518] manager: NetworkManager state is now CONNECTED_GLOBAL
Feb 14 19:31:03 armadillo systemd[1]: Starting Network Manager Script Dispatcher Service...
Feb 14 19:31:03 armadillo connection-recover: ttyCommModem reconnect success
Feb 14 19:31:03 armadillo dbus-daemon[1911]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Feb 14 19:31:03 armadillo systemd[1]: Started Network Manager Script Dispatcher Service.
Feb 14 19:31:03 armadillo nm-dispatcher: req:1 'up' [ppp0]: new request (2 scripts)
Feb 14 19:31:03 armadillo nm-dispatcher: req:1 'up' [ppp0]: start running ordered scripts...
Feb 14 19:31:03 armadillo nm-dispatcher: req:2 'connectivity-change': new request (2 scripts)
Feb 14 19:31:03 armadillo nm-dispatcher: req:2 'connectivity-change': start running ordered scripts...
Feb 14 19:31:04 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:04 armadillo update-ca-certificates[7402]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:04 armadillo update-ca-certificates[7402]: done.
Feb 14 19:31:04 armadillo update-ca-certificates[7402]: Updating certificates in /etc/ssl/certs...
Feb 14 19:31:10 armadillo NetworkManager[1938]: <info>  [1550140270.3235] keyfile: add connection /etc/NetworkManager/system-connections/gsm-ttyCommModem-6.nmconnection (e4be1aa1-cf5d-40cc-87b9-aa02a5fb4454,"gsm-ttyCommModem-6")
Feb 14 19:31:10 armadillo NetworkManager[1938]: <info>  [1550140270.3436] audit: op="connection-add" uuid="e4be1aa1-cf5d-40cc-87b9-aa02a5fb4454" name="gsm-ttyCommModem-6" pid=7636 uid=0 result="success"
Feb 14 19:31:10 armadillo systemd[1]: Reloading.
Feb 14 19:31:11 armadillo systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Feb 14 19:31:11 armadillo systemd[1]: serial-getty@ttymxc4.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Feb 14 19:31:12 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:12 armadillo update-ca-certificates[7705]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.6933] agent-manager: req[0x25d1c10, :1.45/nmcli-connect/0]: agent registered
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.7073] device (ttyCommModem): state change: activated -> deactivating (reason 'new-activation', sys-iface-state: 'managed')
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.7318] manager: NetworkManager state is now CONNECTED_LOCAL
Feb 14 19:31:12 armadillo nm-dispatcher: req:3 'connectivity-change': new request (2 scripts)
Feb 14 19:31:12 armadillo nm-dispatcher: req:3 'connectivity-change': start running ordered scripts...
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.7465] device (ttyCommModem): disconnecting for new activation request.
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.7476] audit: op="connection-activate" uuid="5181bf32-8531-477e-9cf1-8ed5041bb3b1" name="gsm-ttyCommModem" pid=7672 uid=0 result="success"
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 10 / phase 'terminate'
Feb 14 19:31:12 armadillo NetworkManager[1938]: Terminating on signal 15
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Feb 14 19:31:12 armadillo NetworkManager[1938]: Connect time 0.2 minutes.
Feb 14 19:31:12 armadillo NetworkManager[1938]: Sent 42 bytes, received 0 bytes.
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:12 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> sending PDP context deactivation in primary port...
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (open)
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QIDEACT=1<CR>'
Feb 14 19:31:12 armadillo NetworkManager[1938]: <info>  [1550140272.8004] modem["ttyCommModem"]: modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> reopening data port ttyUSB3...
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyUSB3) reopening port (1)
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 0 (close)
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyUSB3) closing serial port...
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyUSB3): port now disconnected
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyUSB3) serial port closed
Feb 14 19:31:12 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 1 (close)
Feb 14 19:31:12 armadillo dnsmasq[2238]: no servers found in /etc/resolv.conf, will retry
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Feb 14 19:31:12 armadillo NetworkManager[1938]: Connection terminated.
Feb 14 19:31:12 armadillo dnsmasq[2238]: reading /etc/resolv.conf
Feb 14 19:31:12 armadillo dnsmasq[2238]: using nameserver 203.138.71.154#53
Feb 14 19:31:12 armadillo dnsmasq[2238]: using nameserver 210.150.255.66#53
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Feb 14 19:31:12 armadillo pppd[7312]: nm-ppp-plugin: (nm_exit_notify): cleaning up
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+CIEV: 7,0<CR><LF>'
Feb 14 19:31:13 armadillo update-ca-certificates[7705]: done.
Feb 14 19:31:13 armadillo update-ca-certificates[7705]: Updating certificates in /etc/ssl/certs...
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3) opening serial port...
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3): setting up baudrate: 57600
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3): no flow control explicitly requested for device
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3): port attributes not fully set
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 1 (open)
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> flashing data port ttyUSB3...
Feb 14 19:31:13 armadillo ModemManager[1995]: <debug> (ttyUSB3): port attributes not fully set
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 0 (close)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyUSB3) closing serial port...
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyUSB3) serial port closed
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> PDP disconnection already sent
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered)
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8395] modem["ttyCommModem"]: modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8410] device (ttyCommModem): state change: deactivating -> disconnected (reason 'new-activation', sys-iface-state: 'managed')
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8524] device (ttyCommModem): Activation: starting connection 'gsm-ttyCommModem' (5181bf32-8531-477e-9cf1-8ed5041bb3b1)
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8688] device (ttyCommModem): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8745] manager: NetworkManager state is now CONNECTING
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.8776] device (ttyCommModem): state change: prepare -> need-auth (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:14 armadillo nm-dispatcher: req:4 'down' [ttyCommModem]: new request (2 scripts)
Feb 14 19:31:14 armadillo nm-dispatcher: req:4 'down' [ttyCommModem]: start running ordered scripts...
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.9054] device (ttyCommModem): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Simple connect started...
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    PIN: unspecified
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    Operator ID: unspecified
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    Allowed roaming: yes
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    APN: ●●●
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    IP family: ipv4v6
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    Allowed authentication: unspecified
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    User: ●●●
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug>    Password: ●●●
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Simple connect state (4/8): Wait to get fully enabled
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Simple connect state (5/8): Register
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Already registered in network '44010', automatic registration not launched...
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Simple connect state (6/8): Bearer
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
Feb 14 19:31:14 armadillo NetworkManager[1938]: <info>  [1550140274.9739] modem["ttyCommModem"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Simple connect state (7/8): Connect
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:14 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Launching 3GPP connection attempt with APN '●●●'
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> Quectel EC25, We always use cid 1.
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (open)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QICSGP=1<CR>'
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+QICSGP: 1,"●●●","●●●","●●●",2<CR><LF><CR><LF>OK<CR><LF>'
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> using existing cid: 1, start check operator.
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> load operator code ...
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 4 (open)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (close)
Feb 14 19:31:14 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+COPS=3,2<CR>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+COPS?<CR>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+COPS: 0,2,"44010",7<CR><LF><CR><LF>OK<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> loaded Operator Code: 44010
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QIACT?<CR>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 3 (open)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (close)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+QIACT=1<CR>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>OK<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> Activated PDP context, start dial.
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3) opening serial port...
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): setting up baudrate: 57600
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): no flow control explicitly requested for device
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): port attributes not fully set
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 1 (open)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> Connection through a plain serial AT port (ttyUSB3)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 2 (open)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 1 (close)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): --> 'ATD*99***1#<CR>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+CIEV: 7,2<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): <-- '<CR><LF>CONNECT 150000000<CR><LF>'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3): port now connected
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> PPP is required for connection, will ignore disconnection reports
Feb 14 19:31:15 armadillo ModemManager[1995]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Feb 14 19:31:15 armadillo ModemManager[1995]: <info>  Simple connect state (8/8): All done
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.2958] modem["ttyCommModem"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (ttyUSB3) device open count is 1 (close)
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.3061] device (ttyCommModem): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.3086] device (ttyCommModem): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:15 armadillo NetworkManager[1938]: <warn>  [1550140275.3100] device (ttyCommModem): interface ttyCommModem not up for IP configuration
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.3102] modem["ttyCommModem"]: using modem-specified IP timeout: 20 seconds
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.3108] ppp-manager: starting PPP connection
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.3277] ppp-manager: pppd started with pid 7945
Feb 14 19:31:15 armadillo NetworkManager[1938]: Plugin /usr/lib/pppd/2.4.7/nm-pppd-plugin.so loaded.
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (plugin_init): initializing
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Feb 14 19:31:15 armadillo NetworkManager[1938]: Using interface ppp0
Feb 14 19:31:15 armadillo NetworkManager[1938]: Connect: ppp0 <--> /dev/ttyUSB3
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.4101] manager: (ppp0): new Ppp device (/org/freedesktop/NetworkManager/Devices/13)
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Feb 14 19:31:15 armadillo NetworkManager[1938]: CHAP authentication succeeded
Feb 14 19:31:15 armadillo NetworkManager[1938]: CHAP authentication succeeded
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> (net/ppp0): adding device at sysfs path: /sys/devices/virtual/net/ppp0
Feb 14 19:31:15 armadillo ModemManager[1995]: <debug> [filter] (net/ppp0) port filtered: virtual device
Feb 14 19:31:15 armadillo NetworkManager[1938]: Could not determine remote IP address: defaulting to 10.64.64.64
Feb 14 19:31:15 armadillo NetworkManager[1938]: local  IP address 10.98.218.84
Feb 14 19:31:15 armadillo NetworkManager[1938]: remote IP address 10.64.64.64
Feb 14 19:31:15 armadillo NetworkManager[1938]: primary   DNS address 203.138.71.154
Feb 14 19:31:15 armadillo NetworkManager[1938]: secondary DNS address 210.150.255.66
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_ip_up): ip-up event
Feb 14 19:31:15 armadillo pppd[7945]: nm-ppp-plugin: (nm_ip_up): sending IPv4 config to NetworkManager...
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.5591] ppp-manager: (IPv4 Config Get) reply received.
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.5891] device (ttyCommModem): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6040] device (ttyCommModem): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6096] device (ttyCommModem): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6179] manager: NetworkManager state is now CONNECTED_LOCAL
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6355] manager: NetworkManager state is now CONNECTED_SITE
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6388] policy: set 'gsm-ttyCommModem' (ppp0) as default for IPv4 routing and DNS
Feb 14 19:31:15 armadillo dnsmasq[2238]: reading /etc/resolv.conf
Feb 14 19:31:15 armadillo dnsmasq[2238]: using nameserver 203.138.71.154#53
Feb 14 19:31:15 armadillo dnsmasq[2238]: using nameserver 210.150.255.66#53
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6520] device (ttyCommModem): Activation: successful, device activated.
Feb 14 19:31:15 armadillo NetworkManager[1938]: <info>  [1550140275.6592] manager: NetworkManager state is now CONNECTED_GLOBAL
Feb 14 19:31:15 armadillo nm-dispatcher: req:5 'up' [ppp0]: new request (2 scripts)
Feb 14 19:31:15 armadillo nm-dispatcher: req:5 'up' [ppp0]: start running ordered scripts...
Feb 14 19:31:15 armadillo dnsmasq[2238]: reading /etc/resolv.conf
Feb 14 19:31:15 armadillo nm-dispatcher: req:6 'connectivity-change': new request (2 scripts)
Feb 14 19:31:15 armadillo dnsmasq[2238]: using nameserver 203.138.71.154#53
Feb 14 19:31:15 armadillo dnsmasq[2238]: using nameserver 210.150.255.66#53
Feb 14 19:31:15 armadillo nm-dispatcher: req:6 'connectivity-change': start running ordered scripts...
Feb 14 19:31:19 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:19 armadillo update-ca-certificates[8005]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:19 armadillo update-ca-certificates[8005]: done.
Feb 14 19:31:19 armadillo update-ca-certificates[8005]: Updating certificates in /etc/ssl/certs...
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> loading signal quality...
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 2 (open)
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyCommModem): --> 'AT+CIND?<CR>'
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyCommModem): <-- '<CR><LF>+CIND: 0,3,1,0,0,0,1,2<CR><LF><CR><LF>OK<CR><LF>'
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (60)
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0) device open count is 2 (open)
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> loading access technologies via QCDM...
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyCommModem) device open count is 1 (close)
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0): --> 4b 08 01 00 66 f5 7e
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0): <-- 4b 08 01 00 08 8a 56 30 06 94 62 59 13 00 00 00 00 00 00 00 00 00 09 de ad 00 00 00 00 00 00 00 78 ee 7e
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0): --> 4b 04 0f 00 d5 ca 7e
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0): <-- 4b 04 0f 00 08 8a 56 30 06 94 62 59 13 08 49 04 01 23 72 09 28 21 09 56 b3 7e
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> QCDM operating mode: 0
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> QCDM system mode: 0
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> QCDM hybrid pref: 0
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> QCDM WCDMA open: 0
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> QCDM EVDO open: 0
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> Periodic signal quality checks scheduled in 30s
Feb 14 19:31:20 armadillo ModemManager[1995]: <debug> (ttyUSB0) device open count is 1 (close)
Feb 14 19:31:25 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:25 armadillo systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Feb 14 19:31:25 armadillo update-ca-certificates[8253]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:26 armadillo update-ca-certificates[8253]: done.
Feb 14 19:31:26 armadillo update-ca-certificates[8253]: Updating certificates in /etc/ssl/certs...
Feb 14 19:31:32 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:32 armadillo update-ca-certificates[8495]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:32 armadillo update-ca-certificates[8495]: done.
Feb 14 19:31:32 armadillo update-ca-certificates[8495]: Updating certificates in /etc/ssl/certs...
Feb 14 19:31:38 armadillo systemd-timedated[2417]: Set NTP to disabled
Feb 14 19:31:38 armadillo update-ca-certificates[8740]: Clearing symlinks in /etc/ssl/certs...
Feb 14 19:31:39 armadillo update-ca-certificates[8740]: done.
Feb 14 19:31:39 armadillo update-ca-certificates[8740]: Updating certificates in /etc/ssl/certs...