Balena Fin v1.1 Cellular Connection Issues

donfmorrison · May 5, 2020, 7:05am

I’m having trouble getting a Balena Fin v1.1 device to connect to the internet using mPCIe cellular modem EC25-A with a Twilio SIM card. If I leave ethernet disconnected, then the device never establishes a connection to the Balena dashboard. I found another topic very similar to my issue where the advice was to create a new blank Application and poke at some ModemManager and NetworkManager things. So here goes:

My Balena Fin is running a new blank application with the default “Factory build” - no services. I inserted the Twilio SIM and booted up the device. Nothing happened, the device did not show up on the dashboard - Okay, so that’s probably expected since I haven’t told balena to use the connection, so I plugged in the ethernet cable and bam, new device detected, etc. Fine.

The modem should be registered and connected if all is well so far I think, so next I start a terminal session to the host:

mmcli -L

root@localhost:~# mmcli -L
    /org/freedesktop/ModemManager1/Modem/0 [QUALCOMM INCORPORATED] QUECTEL Mobile Broadband Module

mmcli -m 0

root@localhost:~# mmcli -m 0
  -----------------------------------
  General  |               dbus path: /org/freedesktop/ModemManager1/Modem/0
           |               device id: 2eaa2d42ff00984d7b4f1d73f1d27d1395914a37
  -----------------------------------
  Hardware |            manufacturer: QUALCOMM INCORPORATED
           |                   model: QUECTEL Mobile Broadband Module
           |       firmware revision: EC25AFAR05A04M4G
           |          carrier config: Commercial-TMO
           | carrier config revision: 05010524
           |            h/w revision: 10000
           |               supported: gsm-umts, lte
           |                 current: gsm-umts, lte
           |            equipment id: 861641041894105
  -----------------------------------
  System   |                  device: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2
           |                 drivers: option1, qmi_wwan
           |                  plugin: Quectel
           |            primary port: cdc-wdm0
           |                   ports: ttyUSB0 (qcdm), ttyUSB2 (at), cdc-wdm0 (qmi), wwan0 (net), 
           |                          ttyUSB3 (at)
  -----------------------------------
  Numbers  |                     own: 19044226780
  -----------------------------------
  Status   |                    lock: sim-pin2
           |          unlock retries: sim-pin (3), sim-pin2 (10), sim-puk (10), sim-puk2 (10)
           |                   state: registered
           |             power state: on
           |             access tech: lte
           |          signal quality: 62% (recent)
  -----------------------------------
  Modes    |               supported: allowed: 3g; preferred: none
           |                          allowed: 4g; preferred: none
           |                          allowed: 3g, 4g; preferred: 3g
           |                          allowed: 3g, 4g; preferred: 4g
           |                 current: allowed: 3g, 4g; preferred: 4g
  -----------------------------------
  Bands    |               supported: utran-4, utran-5, utran-2, eutran-2, eutran-4, eutran-12
           |                 current: utran-4, utran-5, utran-2, eutran-2, eutran-4, eutran-12
  -----------------------------------
  IP       |               supported: ipv4, ipv6, ipv4v6
  -----------------------------------
  3GPP     |                    imei: 861641041894105
           |             operator id: 310260
           |            registration: home
  -----------------------------------
  3GPP EPS |    ue mode of operation: csps-1
  -----------------------------------
  SIM      |               dbus path: /org/freedesktop/ModemManager1/SIM/0

Modem appears to be registered. Everything seems ok?

journalctl -a -u ModemManager --no-pager

root@localhost:~# journalctl -a -u ModemManager --no-pager
-- Logs begin at Fri 2020-01-31 10:38:40 UTC, end at Tue 2020-05-05 06:06:16 UTC. --
May 05 05:37:30 localhost ModemManager[820]: ModemManager (version 1.12.4) starting in system bus...
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0] created endpoint
May 05 05:37:32 localhost ModemManager[820]: cannot connect to proxy: Could not connect: Connection refused
May 05 05:37:32 localhost ModemManager[820]: spawning new qmi-proxy (try 1)...
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0] Checking version info (20 retries)...
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0] QMI Device supports 29 services:
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    ctl (1.5)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    wds (1.67)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    dms (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    nas (1.25)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    qos (1.12)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    wms (1.10)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    auth (1.3)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    at (1.2)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    voice (2.1)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    cat2 (2.24)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    uim (1.46)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    pbm (1.4)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    test (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    loc (2.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    sar (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    ims (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    ts (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    tmd (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    wda (1.16)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    csvt (1.1)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    imsp (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    imsa (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    coex (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    pdc (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    rfrpe (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    dsd (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
May 05 05:37:32 localhost ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
May 05 05:37:33 259a3d1 ModemManager[820]: Couldn't check support for device '/sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc2/mmc2:0001/mmc2:0001:1': not supported by any plugin
May 05 05:37:33 259a3d1 ModemManager[820]: Couldn't check support for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1': not supported by any plugin
May 05 05:37:41 259a3d1 ModemManager[820]: (tty/ttyUSB0) failed to parse QCDM version info command result: -7
May 05 05:37:41 259a3d1 ModemManager[820]: [device /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2] creating modem with plugin 'Quectel' and '6' ports
May 05 05:37:41 259a3d1 ModemManager[820]: Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
May 05 05:37:41 259a3d1 ModemManager[820]: Modem for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2' successfully created
May 05 05:37:41 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
May 05 05:37:41 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
May 05 05:37:41 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] created endpoint
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Checking version info (20 retries)...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] QMI Device supports 29 services:
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    ctl (1.5)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    wds (1.67)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    dms (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    nas (1.25)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    qos (1.12)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    wms (1.10)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    auth (1.3)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    at (1.2)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    voice (2.1)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    cat2 (2.24)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    uim (1.46)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    pbm (1.4)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    test (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    loc (2.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    sar (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    ims (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    ts (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    tmd (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    wda (1.16)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    csvt (1.1)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    imsp (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    imsa (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    coex (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    pdc (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    rfrpe (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    dsd (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwan0/qmi/raw_ip
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'wda' (version 1.16) client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Writing expected data format to: /sys/class/net/wwan0/qmi/raw_ip
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwan0/qmi/raw_ip
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Releasing 'wda' client with flags 'release-cid'...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Unregistered 'wda' client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'dms' (version 1.0) client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '3'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'wms' (version 1.10) client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'uim' (version 1.46) client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'loc' (version 2.0) client with ID '1'
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 05:37:42 259a3d1 ModemManager[820]: [/dev/cdc-wdm0] Registered 'pdc' (version 1.0) client with ID '1'
May 05 05:37:43 259a3d1 ModemManager[820]: Unhandled QMI radio interface (9)
May 05 05:37:43 259a3d1 ModemManager[820]: Unhandled QMI radio interface (9)
May 05 05:37:43 259a3d1 ModemManager[820]: Unhandled QMI radio interface (9)
May 05 05:37:44 259a3d1 ModemManager[820]: Modem: state changed (unknown -> disabled)
May 05 05:37:44 259a3d1 ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
May 05 05:37:45 259a3d1 ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
May 05 05:37:45 259a3d1 ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
May 05 05:37:45 259a3d1 ModemManager[820]: Couldn't load Operator Name: 'Current operator description is still unknown'
May 05 05:37:45 259a3d1 ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
May 05 05:37:45 259a3d1 ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)
May 05 06:01:55 localhost.localdomain ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> idle)
May 05 06:01:55 localhost.localdomain ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> enabled)
May 05 06:01:55 localhost.localdomain ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> registering)
May 05 06:01:55 localhost.localdomain ModemManager[820]: Couldn't load Operator Name: 'Current operator description is still unknown'
May 05 06:01:55 localhost.localdomain ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
May 05 06:01:55 localhost.localdomain ModemManager[820]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)

I haven’t really told NetworkManager to do anything with this modem yet, but let’s have a poke at NM anyway.

nmcli c

root@localhost:~# nmcli c
NAME                UUID                                  TYPE      DEVICE      
Wired connection 1  7f8ca23b-379f-3b80-9c60-db733363fb61  ethernet  eth0        
supervisor0         a991eef0-6cd5-42e8-9b1e-d489d72e6639  bridge    supervisor0

Kind of expected, I think. I haven’t added any system-connections files so far. Time to add a config for the modem to tell BalenaOS to use it.

donfmorrison · May 5, 2020, 7:05am

I power down the device, connect the programming cable again and add a file to system-connections.

[connection]
id=cellular
type=gsm
autoconnect=true

[gsm]
apn=wireless.twilio.com

[serial]
baud=115200

[ipv4]
method=auto

[ipv6]
addr-gen-mode=stable-privacy
method=auto

I can see that the device is connected to the T-Mobile network from the Twilio dashboard. But the device is still not showing in the dashboard as online.

So, I connect the ethernet again and ModemManager seems to be reporting it sees and can use the modem. It appears to be connected. I have 2 IP showing on the device dashboard - a LAN address that is certainly from the ethernet interface and another that I would presume to be from the cellular modem.

root@259a3d1:~# nmcli c
NAME                UUID                                  TYPE      DEVICE      
Wired connection 1  7f8ca23b-379f-3b80-9c60-db733363fb61  ethernet  eth0        
cellular            d811a35a-70fc-3716-84e1-a3578d10cc1e  gsm       cdc-wdm0    
supervisor0         cba34e29-fa19-46ba-aa5c-bedb4e4679ae  bridge    supervisor0

Now for some logs:

journalctl -a -u ModemManager --no-pager

root@259a3d1:~# journalctl -a -u ModemManager --no-pager
-- Logs begin at Fri 2020-01-31 10:38:40 UTC, end at Tue 2020-05-05 06:50:47 UTC. --
May 05 06:44:23 localhost ModemManager[716]: ModemManager (version 1.12.4) starting in system bus...
May 05 06:44:25 localhost ModemManager[716]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
May 05 06:44:25 localhost ModemManager[716]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
May 05 06:44:25 localhost ModemManager[716]: [/dev/cdc-wdm0] created endpoint
May 05 06:44:25 localhost ModemManager[716]: cannot connect to proxy: Could not connect: Connection refused
May 05 06:44:25 localhost ModemManager[716]: spawning new qmi-proxy (try 1)...
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Checking version info (20 retries)...
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] QMI Device supports 29 services:
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ctl (1.5)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wds (1.67)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    dms (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    nas (1.25)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    qos (1.12)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wms (1.10)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    auth (1.3)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    at (1.2)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    voice (2.1)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    cat2 (2.24)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    uim (1.46)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    pbm (1.4)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    test (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    loc (2.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    sar (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ims (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ts (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    tmd (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wda (1.16)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    csvt (1.1)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    imsp (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    imsa (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    coex (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    pdc (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    rfrpe (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    dsd (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
May 05 06:44:25 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
May 05 06:44:26 259a3d1 ModemManager[716]: Couldn't check support for device '/sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc2/mmc2:0001/mmc2:0001:1': not supported by any plugin
May 05 06:44:26 259a3d1 ModemManager[716]: Couldn't check support for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1': not supported by any plugin
May 05 06:44:34 259a3d1 ModemManager[716]: (tty/ttyUSB0) failed to parse QCDM version info command result: -7
May 05 06:44:34 259a3d1 ModemManager[716]: [device /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2] creating modem with plugin 'Quectel' and '6' ports
May 05 06:44:34 259a3d1 ModemManager[716]: Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
May 05 06:44:34 259a3d1 ModemManager[716]: Modem for device '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2' successfully created
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] created endpoint
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Checking version info (20 retries)...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] QMI Device supports 29 services:
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ctl (1.5)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wds (1.67)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    dms (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    nas (1.25)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    qos (1.12)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wms (1.10)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    auth (1.3)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    at (1.2)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    voice (2.1)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    cat2 (2.24)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    uim (1.46)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    pbm (1.4)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    test (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    loc (2.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    sar (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ims (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    ts (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    tmd (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    wda (1.16)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    csvt (1.1)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    imsp (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    imsa (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    coex (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    pdc (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    rfrpe (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    dsd (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwan0/qmi/raw_ip
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'wda' (version 1.16) client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Writing expected data format to: /sys/class/net/wwan0/qmi/raw_ip
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwan0/qmi/raw_ip
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Releasing 'wda' client with flags 'release-cid'...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Unregistered 'wda' client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'dms' (version 1.0) client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '3'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'wms' (version 1.10) client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'uim' (version 1.46) client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'loc' (version 2.0) client with ID '1'
May 05 06:44:34 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:35 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'pdc' (version 1.0) client with ID '1'
May 05 06:44:35 259a3d1 ModemManager[716]: Unhandled QMI radio interface (9)
May 05 06:44:35 259a3d1 ModemManager[716]: Unhandled QMI radio interface (9)
May 05 06:44:35 259a3d1 ModemManager[716]: Unhandled QMI radio interface (9)
May 05 06:44:36 259a3d1 ModemManager[716]: Modem: state changed (unknown -> disabled)
May 05 06:44:36 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
May 05 06:44:36 259a3d1 ModemManager[716]: Simple connect started...
May 05 06:44:36 259a3d1 ModemManager[716]: Simple connect state (4/8): Wait to get fully enabled
May 05 06:44:36 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
May 05 06:44:36 259a3d1 ModemManager[716]: Simple connect state (5/8): Register
May 05 06:44:37 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
May 05 06:44:37 259a3d1 ModemManager[716]: Couldn't load Operator Name: 'Current operator description is still unknown'
May 05 06:44:37 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
May 05 06:44:37 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)
May 05 06:44:37 259a3d1 ModemManager[716]: Simple connect state (6/8): Bearer
May 05 06:44:37 259a3d1 ModemManager[716]: Simple connect state (7/8): Connect
May 05 06:44:37 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
May 05 06:44:37 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:37 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '19'
May 05 06:44:37 259a3d1 ModemManager[716]: QMI IPv4 Settings:
May 05 06:44:37 259a3d1 ModemManager[716]:     Address: 26.17.XXX.XXX/27
May 05 06:44:37 259a3d1 ModemManager[716]:     Gateway: 26.17.176.241
May 05 06:44:37 259a3d1 ModemManager[716]:     DNS #1: 10.177.0.34
May 05 06:44:37 259a3d1 ModemManager[716]:     DNS #2: 10.177.0.210
May 05 06:44:37 259a3d1 ModemManager[716]:        MTU: 1500
May 05 06:44:37 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Allocating new client ID...
May 05 06:44:37 259a3d1 ModemManager[716]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '20'
May 05 06:44:37 259a3d1 ModemManager[716]: QMI IPv6 Settings:
May 05 06:44:37 259a3d1 ModemManager[716]:     Address: 2607:fb90:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx/64
May 05 06:44:37 259a3d1 ModemManager[716]:     Gateway: 2607:fb90:20d3:b0cc:9d99:3983:423e:4b26/64
May 05 06:44:37 259a3d1 ModemManager[716]:     DNS #1: fd00:976a::9
May 05 06:44:37 259a3d1 ModemManager[716]:     DNS #2: fd00:976a::10
May 05 06:44:37 259a3d1 ModemManager[716]:        MTU: 1500
May 05 06:44:37 259a3d1 ModemManager[716]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
May 05 06:44:37 259a3d1 ModemManager[716]: Simple connect state (8/8): All done

journalctl -u NetworkManager --no-pager

root@259a3d1:~# journalctl -u NetworkManager --no-pager
-- Logs begin at Fri 2020-01-31 10:38:40 UTC, end at Tue 2020-05-05 06:50:47 UTC. --
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.3408] NetworkManager (version 1.20.2) is starting... (for the first time)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.3410] Read config: /etc/NetworkManager/NetworkManager.conf (etc: os-networkmanager.conf)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.3493] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4190] manager[0x97c030]: monitoring kernel firmware directory '/lib/firmware'.
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4390] hostname: hostname: using hostnamed
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4393] hostname: hostname changed from (none) to "259a3d1"
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4439] dns-mgr[0x97d0a8]: init: dns=default,systemd-resolved rc-manager=resolvconf
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4478] rfkill1: found Wi-Fi radio killswitch (at /sys/devices/platform/soc/3f300000.mmcnr/mmc_host/mmc2/mmc2:0001/mmc2:0001:1/ieee80211/mwiphy0/rfkill1) (driver wlan_sdio)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4505] manager[0x97c030]: rfkill: Wi-Fi hardware radio set enabled
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.4507] manager[0x97c030]: rfkill: WWAN hardware radio set enabled
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.5456] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/1.20.2/libnm-device-plugin-wifi.so)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6253] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/1.20.2/libnm-device-plugin-bluetooth.so)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6580] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/1.20.2/libnm-device-plugin-adsl.so)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6792] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/1.20.2/libnm-device-plugin-wwan.so)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6798] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6803] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6806] manager: Networking is enabled by state file
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6810] dhcp-init: Using DHCP client 'internal'
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.6864] settings: Loaded settings plugin: keyfile (internal)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.7287] device (lo): carrier: link connected
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.7299] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.7345] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.7526] settings: (eth0): created default wired connection 'Wired connection 1'
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.7550] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8710] device (eth0): carrier: link connected
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8818] manager: (resin-dns): new Bridge device (/org/freedesktop/NetworkManager/Devices/3)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8870] device (uap0): driver supports Access Point (AP) mode
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8891] manager: (uap0): new 802.11 Wi-Fi device (/org/freedesktop/NetworkManager/Devices/4)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8920] device (wlan0): driver supports Access Point (AP) mode
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8943] manager: (wlan0): new 802.11 Wi-Fi device (/org/freedesktop/NetworkManager/Devices/5)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.8986] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9112] device (wlan0): set-hw-addr: set MAC address to 8E:FE:B7:6F:96:86 (scanning)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9500] bluez: use BlueZ version 5
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9656] modem-manager: ModemManager available
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9683] device (eth0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9741] policy: auto-activating connection 'Wired connection 1' (7f8ca23b-379f-3b80-9c60-db733363fb61)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9812] device (eth0): Activation: starting connection 'Wired connection 1' (7f8ca23b-379f-3b80-9c60-db733363fb61)
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9820] device (eth0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9839] manager: NetworkManager state is now CONNECTING
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9855] bluez5: NAP: added interface AC:3F:A4:84:C9:EB
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9858] device (eth0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9920] device (eth0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 05 06:44:26 259a3d1 NetworkManager[789]: <info>  [1588661066.9936] dhcp4 (eth0): activation: beginning transaction (no timeout)
May 05 06:44:27 259a3d1 NetworkManager[789]: <info>  [1588661067.0343] supplicant: wpa_supplicant running
May 05 06:44:27 259a3d1 NetworkManager[789]: <info>  [1588661067.0345] device (wlan0): supplicant interface state: init -> starting
May 05 06:44:27 259a3d1 NetworkManager[789]: <info>  [1588661067.1469] sup-iface[0x97d2a0,wlan0]: supports 5 scan SSIDs
May 05 06:44:27 259a3d1 NetworkManager[789]: <info>  [1588661067.1491] device (wlan0): supplicant interface state: starting -> ready
May 05 06:44:27 259a3d1 NetworkManager[789]: <info>  [1588661067.1493] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
May 05 06:44:28 259a3d1 NetworkManager[789]: <info>  [1588661068.2734] device (eth0): carrier: link connected
May 05 06:44:29 259a3d1 NetworkManager[789]: <info>  [1588661069.7846] manager: (supervisor0): new Bridge device (/org/freedesktop/NetworkManager/Devices/6)
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0017] device (supervisor0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0094] manager: (balena0): new Bridge device (/org/freedesktop/NetworkManager/Devices/7)
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0297] device (supervisor0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0335] device (supervisor0): Activation: starting connection 'supervisor0' (cba34e29-fa19-46ba-aa5c-bedb4e4679ae)
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0351] device (supervisor0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0497] device (supervisor0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0522] device (supervisor0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0548] device (supervisor0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0708] device (supervisor0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0726] device (supervisor0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0880] policy: set-hostname: set hostname to '259a3d1' (from system configuration)
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.0887] device (supervisor0): Activation: successful, device activated.
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.1912] manager: (br-d34be56ec388): new Bridge device (/org/freedesktop/NetworkManager/Devices/8)
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.5977] dhcp4 (eth0): state changed unknown -> bound
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6027] device (eth0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6277] device (eth0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6293] device (eth0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6323] manager: NetworkManager state is now CONNECTED_LOCAL
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6515] manager: NetworkManager state is now CONNECTED_SITE
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6524] policy: set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.6549] dns-mgr: Writing DNS information to /sbin/resolvconf
May 05 06:44:30 259a3d1 NetworkManager[789]: <info>  [1588661070.7260] device (eth0): Activation: successful, device activated.
May 05 06:44:31 259a3d1 NetworkManager[789]: <info>  [1588661071.0780] manager: NetworkManager state is now CONNECTED_GLOBAL
May 05 06:44:31 259a3d1 NetworkManager[789]: <info>  [1588661071.2868] manager: startup complete
May 05 06:44:34 259a3d1 NetworkManager[789]: <info>  [1588661074.1818] manager: (resin-vpn): new Tun device (/org/freedesktop/NetworkManager/Devices/9)
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3044] modem["cdc-wdm0"]: modem state changed, 'disabled' --> 'enabling' (reason: user preference)
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3055] manager: (cdc-wdm0): new Broadband device (/org/freedesktop/NetworkManager/Devices/10)
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3075] device (cdc-wdm0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3092] device (cdc-wdm0): modem state 'enabling'
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3126] device (cdc-wdm0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3149] policy: auto-activating connection 'cellular' (d811a35a-70fc-3716-84e1-a3578d10cc1e)
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3173] device (cdc-wdm0): Activation: starting connection 'cellular' (d811a35a-70fc-3716-84e1-a3578d10cc1e)
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.3182] device (cdc-wdm0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 05 06:44:36 259a3d1 NetworkManager[789]: <info>  [1588661076.9628] modem["cdc-wdm0"]: modem state changed, 'enabling' --> 'enabled' (reason: user-requested)
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.0102] modem["cdc-wdm0"]: modem state changed, 'enabled' --> 'registered' (reason: unknown)
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.0990] modem["cdc-wdm0"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8598] modem["cdc-wdm0"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8692] device (cdc-wdm0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8717] device (cdc-wdm0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8728] modem-broadband[cdc-wdm0]: IPv4 static configuration:
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8730] modem-broadband[cdc-wdm0]:   address 26.17.XXX.XXX/27
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8731] modem-broadband[cdc-wdm0]:   gateway 26.17.176.241
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8732] modem-broadband[cdc-wdm0]:   DNS 10.177.0.34
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8732] modem-broadband[cdc-wdm0]:   DNS 10.177.0.210
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8754] modem-broadband[cdc-wdm0]: IPv6 base configuration:
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8756] modem-broadband[cdc-wdm0]:   address 2607:fb90:xxxx:xxxx:xxxx:xxxx:xxxx:xxxx/64
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8757] modem-broadband[cdc-wdm0]:   gateway 2607:fb90:20d3:b0cc:9d99:3983:423e:4b26
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8758] modem-broadband[cdc-wdm0]:   DNS fd00:976a::9
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8758] modem-broadband[cdc-wdm0]:   DNS fd00:976a::10
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8797] device (cdc-wdm0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8904] device (cdc-wdm0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8915] device (cdc-wdm0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8967] policy: set 'cellular' (wwan0) as default for IPv6 routing and DNS
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.8992] dns-mgr: Writing DNS information to /sbin/resolvconf
May 05 06:44:37 259a3d1 NetworkManager[789]: <info>  [1588661077.9393] device (cdc-wdm0): Activation: successful, device activated.
May 05 06:49:29 259a3d1 NetworkManager[789]: <info>  [1588661369.5721] dhcp4 (eth0): state changed bound -> bound
May 05 06:50:12 259a3d1 NetworkManager[789]: <info>  [1588661412.6494] device (wlan0): set-hw-addr: set MAC address to 52:FE:9D:B5:C7:5E (scanning)
May 05 06:50:12 259a3d1 NetworkManager[789]: <info>  [1588661412.7704] device (wlan0): supplicant interface state: ready -> disconnected
May 05 06:50:12 259a3d1 NetworkManager[789]: <info>  [1588661412.7746] device (wlan0): supplicant interface state: disconnected -> inactive

What do I try next? I seem to be connecting to the carrier, but the device isn’t using (or isn’t able to use) that connection to route traffic.

richbayliss · May 5, 2020, 10:11am

Hi,

Looking at the logs I can see what looks like the device getting a /27 IP address, gateway and DNS so it would seem that a connection is made. Personally I have had issues with cellular providers putting a walled-garden in place that requires some HTTP interaction. Could this be the case?

If you could use journalctl to look at the logs for resin-supervisor that would be helpful, as this is the agent responsible for provisioning the device into the application and managing the running code/containers. I expect it will be failing to connect to our backend.

donfmorrison · May 6, 2020, 2:26am

I think you are correct about the walled garden. I changed the data plan associated to the SIM card today and tonight it is working as expected. If nothing else I’ve learned a bit more about how ModemManager and NetworkMananger work.

Topic		Replies	Views
modem found but no internet connection balenaOS network	6	1035	October 20, 2020
Odd cellular behavior on Balena balenaOS	32	2666	July 10, 2022
Cellular not connecting on boot balenaFin network	7	3241	May 28, 2019
Can't (easily) connect to the Internet using Quectel EC20 on my balenaFin V1.1 balenaFin support , network	16	7100	May 30, 2019
What does "error: couldn't start network: QMI protocol error (14): 'CallFailed' Product support	26	17916	August 27, 2021

Balena Fin v1.1 Cellular Connection Issues

Related topics