IPv4 config for QMI modem

Thu Nov 8 14:40:28 UTC 2018

Hi,

I am trying to connect a QMI modem (u-blox SARA-R412M) using ModemManager 1.8.2 and libqmi 1.20.0. The modem transitions into the connected state which makes NetworkManager start the DHCP procedure. That, however, never succeeds. In the ModemManager logs, I can see that QMI reports IPv4 settings that seem correct (IP range checks out for my operator).

Do I need to configure this sort of setup in a special way to tell ModemManager/NetworkManager to use the QMI IP config?

Thanks,
Sven

The info-level logs of NetworkManager and ModemManager follow (I can also provide the debug-level ones):

[    9.264447] systemd[1]: Starting Network Manager...
[    9.546962] NetworkManager[739]: <info>  [1541687256.2166] NetworkManager (version 1.12.2) is starting... (for the first time)
[    9.548598] NetworkManager[739]: <info>  [1541687256.2201] Read config: /etc/NetworkManager/NetworkManager.conf
[    9.595141] systemd[1]: Started Network Manager.
[    9.603148] NetworkManager[739]: <info>  [1541687256.2745] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
[    9.624445] NetworkManager[739]: <info>  [1541687256.2959] manager[0x56294030]: monitoring kernel firmware directory '/lib/firmware'.
[   10.193655] NetworkManager[739]: <info>  [1541687256.8650] hostname: hostname: using hostnamed
[   10.194256] NetworkManager[739]: <info>  [1541687256.8653] hostname: hostname changed from (none) to "0d92acf"
[   10.195000] NetworkManager[739]: <info>  [1541687256.8665] dns-mgr[0x56289000]: init: dns=default, rc-manager=resolvconf
[   10.459734] NetworkManager[739]: <info>  [1541687257.1312] settings: Loaded settings plugin: NMSKeyfilePlugin (internal)
[   10.468110] NetworkManager[739]: <info>  [1541687257.1395] keyfile: new connection /etc/NetworkManager/system-connections/gprs (14e02637-0ffb-3a28-806f-ad60904afe7f,"gprsconnection")
[   10.478683] NetworkManager[739]: <info>  [1541687257.1478] manager: rfkill: WiFi enabled by radio killswitch; enabled by state file
[   10.483116] NetworkManager[739]: <info>  [1541687257.1539] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
[   10.486086] NetworkManager[739]: <info>  [1541687257.1576] manager: Networking is enabled by state file
[   10.489089] NetworkManager[739]: <info>  [1541687257.1606] dhcp-init: Using DHCP client 'internal'
[   10.499784] NetworkManager[739]: <info>  [1541687257.1712] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/1.12.2/libnm-device-plugin-wifi.so)
[   10.565221] NetworkManager[739]: <info>  [1541687257.2366] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/1.12.2/libnm-device-plugin-bluetooth.so)
[   10.568262] NetworkManager[739]: <info>  [1541687257.2397] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/1.12.2/libnm-device-plugin-wwan.so)
[   10.571322] NetworkManager[739]: <info>  [1541687257.2428] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/1.12.2/libnm-device-plugin-adsl.so)
[   10.576625] NetworkManager[739]: <info>  [1541687257.2480] device (lo): carrier: link connected
[   10.579578] NetworkManager[739]: <info>  [1541687257.2499] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
[   10.587938] NetworkManager[739]: <info>  [1541687257.2581] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
[   10.597294] NetworkManager[739]: <info>  [1541687257.2687] keyfile: add connection in-memory (98f59fb7-21ae-33be-8f85-f4a77b8f1eb6,"Wired connection 1")
[   10.608476] NetworkManager[739]: <info>  [1541687257.2795] settings: (eth0): created default wired connection 'Wired connection 1'
[   10.619876] NetworkManager[739]: <info>  [1541687257.2913] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
[   10.770121] NetworkManager[739]: <info>  [1541687257.4414] manager: (resin-dns): new Bridge device (/org/freedesktop/NetworkManager/Devices/3)
[   11.804623] NetworkManager[739]: <info>  [1541687258.4760] keyfile: add connection in-memory (cf7e0951-aed5-458d-9f75-9e05f7bb5cc3,"resin-dns")
[   11.812558] NetworkManager[739]: <info>  [1541687258.4840] device (resin-dns): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
[   11.828609] NetworkManager[739]: <info>  [1541687258.5001] device (resin-dns): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
[   11.848185] NetworkManager[739]: <info>  [1541687258.5196] device (resin-dns): Activation: starting connection 'resin-dns' (cf7e0951-aed5-458d-9f75-9e05f7bb5cc3)
[   11.852532] NetworkManager[739]: <info>  [1541687258.5240] manager: (sit0): new IPTunnel device (/org/freedesktop/NetworkManager/Devices/4)
[   11.905717] NetworkManager[739]: <info>  [1541687258.5716] device (resin-dns): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
[   11.925804] NetworkManager[739]: <info>  [1541687258.5973] device (resin-dns): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
[   11.931760] NetworkManager[739]: <info>  [1541687258.6032] device (resin-dns): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
[   11.942712] NetworkManager[739]: <info>  [1541687258.6141] device (resin-dns): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
[   11.956276] NetworkManager[739]: <info>  [1541687258.6277] device (resin-dns): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
[   11.960852] NetworkManager[739]: <info>  [1541687258.6323] device (resin-dns): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
[   11.966920] NetworkManager[739]: <info>  [1541687258.6384] manager: NetworkManager state is now CONNECTED_LOCAL
[   12.003457] NetworkManager[739]: <info>  [1541687258.6749] device (resin-dns): Activation: successful, device activated.
[   13.740849] systemd[1]: Starting Modem Manager...
[   14.049659] ModemManager[841]: ModemManager (version 1.8.2) starting in system bus...
[   14.166600] systemd[1]: Started Modem Manager.
[   14.168922] NetworkManager[739]: <info>  [1541687260.8392] modem-manager: ModemManager not available
[   14.322823] NetworkManager[739]: <info>  [1541687260.9941] modem-manager: ModemManager now available
[   14.705270] NetworkManager[739]: <info>  [1541687261.3766] device (eth0): carrier: link connected
[   14.707656] NetworkManager[739]: <info>  [1541687261.3790] device (eth0): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed')
[   14.714292] NetworkManager[739]: <info>  [1541687261.3850] policy: auto-activating connection 'Wired connection 1' (98f59fb7-21ae-33be-8f85-f4a77b8f1eb6)
[   14.719563] NetworkManager[739]: <info>  [1541687261.3906] device (eth0): Activation: starting connection 'Wired connection 1' (98f59fb7-21ae-33be-8f85-f4a77b8f1eb6)
[   14.720972] NetworkManager[739]: <info>  [1541687261.3916] device (eth0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
[   14.724669] NetworkManager[739]: <info>  [1541687261.3949] manager: NetworkManager state is now CONNECTING
[   14.726652] NetworkManager[739]: <info>  [1541687261.3969] device (eth0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
[   14.729027] NetworkManager[739]: <info>  [1541687261.4003] device (eth0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
[   14.731955] NetworkManager[739]: <info>  [1541687261.4034] dhcp4 (eth0): activation: beginning transaction (no timeout)
[   14.780911] NetworkManager[739]: <info>  [1541687261.4523] dhcp4 (eth0):   address a.b.c.d
[   14.781373] NetworkManager[739]: <info>  [1541687261.4526] dhcp4 (eth0):   plen 24
[   14.781632] NetworkManager[739]: <info>  [1541687261.4527] dhcp4 (eth0):   expires in 85490 seconds
[   14.782122] NetworkManager[739]: <info>  [1541687261.4528] dhcp4 (eth0):   nameserver 'a.b.c.d'
[   14.782437] NetworkManager[739]: <info>  [1541687261.4529] dhcp4 (eth0):   nameserver 'a.b.c.d'
[   14.782668] NetworkManager[739]: <info>  [1541687261.4530] dhcp4 (eth0):   domain name 'domain.com'
[   14.782894] NetworkManager[739]: <info>  [1541687261.4531] dhcp4 (eth0):   gateway a.b.c.d
[   14.804401] NetworkManager[739]: <info>  [1541687261.4755] dhcp4 (eth0): state changed unknown -> bound
[   14.826658] NetworkManager[739]: <info>  [1541687261.4981] device (eth0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
[   14.846121] NetworkManager[739]: <info>  [1541687261.5176] device (eth0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
[   14.853052] NetworkManager[739]: <info>  [1541687261.5245] device (eth0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
[   14.861285] NetworkManager[739]: <info>  [1541687261.5328] manager: NetworkManager state is now CONNECTED_LOCAL
[   14.935356] NetworkManager[739]: <info>  [1541687261.6065] manager: NetworkManager state is now CONNECTED_SITE
[   14.937194] NetworkManager[739]: <info>  [1541687261.6085] policy: set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS
[   14.939958] NetworkManager[739]: <info>  [1541687261.6114] dns-mgr: Writing DNS information to /sbin/resolvconf
[   15.088794] NetworkManager[739]: <info>  [1541687261.7602] device (eth0): Activation: successful, device activated.
[   15.103756] NetworkManager[739]: <info>  [1541687261.7752] manager: NetworkManager state is now CONNECTED_GLOBAL
[   15.123486] NetworkManager[739]: <info>  [1541687261.7949] manager: startup complete
[   15.795681] ModemManager[841]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
[   15.796793] ModemManager[841]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
[   15.797370] ModemManager[841]: cannot connect to proxy: Could not connect: Connection refused
[   15.797648] ModemManager[841]: spawning new qmi-proxy (try 1)...
[   15.925874] NetworkManager[739]: <info>  [1541687262.5973] manager: (balena0): new Bridge device (/org/freedesktop/NetworkManager/Devices/5)
[   15.969311] ModemManager[841]: [/dev/cdc-wdm0] Checking version info (10 retries)...
[   15.982362] ModemManager[841]: [/dev/cdc-wdm0] QMI Device supports 20 services:
[   15.984107] ModemManager[841]: [/dev/cdc-wdm0]    ctl (1.5)
[   15.991830] ModemManager[841]: [/dev/cdc-wdm0]    wds (1.67)
[   15.993934] ModemManager[841]: [/dev/cdc-wdm0]    dms (1.0)
[   15.994411] ModemManager[841]: [/dev/cdc-wdm0]    nas (1.25)
[   15.994777] ModemManager[841]: [/dev/cdc-wdm0]    wms (1.10)
[   16.002519] ModemManager[841]: [/dev/cdc-wdm0]    at (1.4)
[   16.002832] ModemManager[841]: [/dev/cdc-wdm0]    uim (1.46)
[   16.003082] ModemManager[841]: [/dev/cdc-wdm0]    test (1.0)
[   16.003303] ModemManager[841]: [/dev/cdc-wdm0]    sar (1.0)
[   16.003534] ModemManager[841]: [/dev/cdc-wdm0]    csd (1.0)
[   16.003743] ModemManager[841]: [/dev/cdc-wdm0]    ts (1.0)
[   16.003936] ModemManager[841]: [/dev/cdc-wdm0]    tmd (1.0)
[   16.004152] ModemManager[841]: [/dev/cdc-wdm0]    wda (1.16)
[   16.004362] ModemManager[841]: [/dev/cdc-wdm0]    pdc (1.0)
[   16.004572] ModemManager[841]: [/dev/cdc-wdm0]    rfrpe (1.0)
[   16.004782] ModemManager[841]: [/dev/cdc-wdm0]    dsd (1.0)
[   16.005290] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
[   16.005489] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
[   16.005687] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
[   16.005874] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x48] (1.0)
[   16.817076] ModemManager[841]: Couldn't check support for device '/sys/devices/platform/soc/30800000.aips-bus/30be0000.ethernet': not supported by any plugin
[   17.007527] NetworkManager[739]: <info>  [1541687263.6789] dhcp6 (eth0): activation: beginning transaction (timeout in 45 seconds)
[   17.017051] NetworkManager[739]: <info>  [1541687263.6885] dns-mgr: Writing DNS information to /sbin/resolvconf
[   17.594708] NetworkManager[739]: <info>  [1541687264.2611] manager: (br-278f284c8dd0): new Bridge device (/org/freedesktop/NetworkManager/Devices/6)
[   17.998275] NetworkManager[739]: <info>  [1541687264.6657] manager: (resin-vpn): new Tun device (/org/freedesktop/NetworkManager/Devices/7)
[   18.204019] NetworkManager[739]: <info>  [1541687264.8755] manager: (supervisor0): new Bridge device (/org/freedesktop/NetworkManager/Devices/8)
[   18.540530] NetworkManager[739]: <info>  [1541687265.2097] device (supervisor0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
[   18.566244] NetworkManager[739]: <info>  [1541687265.2376] keyfile: add connection in-memory (e59eb4c2-1e6b-4378-bcd5-c0d8e7307f42,"supervisor0")
[   18.574585] NetworkManager[739]: <info>  [1541687265.2460] device (supervisor0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
[   18.585168] NetworkManager[739]: <info>  [1541687265.2566] device (supervisor0): Activation: starting connection 'supervisor0' (e59eb4c2-1e6b-4378-bcd5-c0d8e7307f42)
[   18.590108] NetworkManager[739]: <info>  [1541687265.2591] device (supervisor0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
[   18.595494] NetworkManager[739]: <info>  [1541687265.2669] device (supervisor0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
[   18.601745] NetworkManager[739]: <info>  [1541687265.2732] device (supervisor0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
[   18.607173] NetworkManager[739]: <info>  [1541687265.2786] device (supervisor0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
[   18.620941] NetworkManager[739]: <info>  [1541687265.2924] device (supervisor0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
[   18.625318] NetworkManager[739]: <info>  [1541687265.2968] device (supervisor0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
[   18.665605] NetworkManager[739]: <info>  [1541687265.3370] device (supervisor0): Activation: successful, device activated.
[   25.788990] ModemManager[841]: Couldn't check support for device '/sys/devices/platform/soc/30800000.aips-bus/30be0000.ethernet': not supported by any plugin
[   29.008348] ModemManager[841]: [device /sys/devices/platform/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1] creating modem with plugin 'Generic' and '4' ports
[   29.047392] ModemManager[841]: Modem for device '/sys/devices/platform/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1' successfully created
[   29.054719] ModemManager[841]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
[   29.055385] ModemManager[841]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
[   29.070063] ModemManager[841]: [/dev/cdc-wdm0] Checking version info (10 retries)...
[   29.085583] ModemManager[841]: [/dev/cdc-wdm0] QMI Device supports 20 services:
[   29.085974] ModemManager[841]: [/dev/cdc-wdm0]    ctl (1.5)
[   29.086175] ModemManager[841]: [/dev/cdc-wdm0]    wds (1.67)
[   29.086356] ModemManager[841]: [/dev/cdc-wdm0]    dms (1.0)
[   29.086583] ModemManager[841]: [/dev/cdc-wdm0]    nas (1.25)
[   29.086805] ModemManager[841]: [/dev/cdc-wdm0]    wms (1.10)
[   29.087063] ModemManager[841]: [/dev/cdc-wdm0]    at (1.4)
[   29.087278] ModemManager[841]: [/dev/cdc-wdm0]    uim (1.46)
[   29.087523] ModemManager[841]: [/dev/cdc-wdm0]    test (1.0)
[   29.087747] ModemManager[841]: [/dev/cdc-wdm0]    sar (1.0)
[   29.087948] ModemManager[841]: [/dev/cdc-wdm0]    csd (1.0)
[   29.088157] ModemManager[841]: [/dev/cdc-wdm0]    ts (1.0)
[   29.088359] ModemManager[841]: [/dev/cdc-wdm0]    tmd (1.0)
[   29.088551] ModemManager[841]: [/dev/cdc-wdm0]    wda (1.16)
[   29.088775] ModemManager[841]: [/dev/cdc-wdm0]    pdc (1.0)
[   29.089019] ModemManager[841]: [/dev/cdc-wdm0]    rfrpe (1.0)
[   29.089224] ModemManager[841]: [/dev/cdc-wdm0]    dsd (1.0)
[   29.089406] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
[   29.089590] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
[   29.089792] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
[   29.090117] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x48] (1.0)
[   29.090368] ModemManager[841]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwan0/qmi/raw_ip
[   29.090624] ModemManager[841]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
[   29.090854] ModemManager[841]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: qmi_wwan
[   29.093861] ModemManager[841]: [/dev/cdc-wdm0] Checking version info (10 retries)...
[   29.100826] ModemManager[841]: [/dev/cdc-wdm0] QMI Device supports 20 services:
[   29.101246] ModemManager[841]: [/dev/cdc-wdm0]    ctl (1.5)
[   29.101456] ModemManager[841]: [/dev/cdc-wdm0]    wds (1.67)
[   29.101686] ModemManager[841]: [/dev/cdc-wdm0]    dms (1.0)
[   29.101888] ModemManager[841]: [/dev/cdc-wdm0]    nas (1.25)
[   29.102101] ModemManager[841]: [/dev/cdc-wdm0]    wms (1.10)
[   29.102319] ModemManager[841]: [/dev/cdc-wdm0]    at (1.4)
[   29.102535] ModemManager[841]: [/dev/cdc-wdm0]    uim (1.46)
[   29.102761] ModemManager[841]: [/dev/cdc-wdm0]    test (1.0)
[   29.103007] ModemManager[841]: [/dev/cdc-wdm0]    sar (1.0)
[   29.103235] ModemManager[841]: [/dev/cdc-wdm0]    csd (1.0)
[   29.103439] ModemManager[841]: [/dev/cdc-wdm0]    ts (1.0)
[   29.103629] ModemManager[841]: [/dev/cdc-wdm0]    tmd (1.0)
[   29.103825] ModemManager[841]: [/dev/cdc-wdm0]    wda (1.16)
[   29.104012] ModemManager[841]: [/dev/cdc-wdm0]    pdc (1.0)
[   29.104215] ModemManager[841]: [/dev/cdc-wdm0]    rfrpe (1.0)
[   29.104407] ModemManager[841]: [/dev/cdc-wdm0]    dsd (1.0)
[   29.104611] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
[   29.104812] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
[   29.104996] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
[   29.105168] ModemManager[841]: [/dev/cdc-wdm0]    unknown [0x48] (1.0)
[   29.105340] ModemManager[841]: [/dev/cdc-wdm0] Setting network port data format...
[   29.109190] ModemManager[841]: [/dev/cdc-wdm0] Network port data format operation finished
[   29.109937] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   29.116971] ModemManager[841]: [/dev/cdc-wdm0] Registered 'dms' (version 1.0) client with ID '1'
[   29.122076] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   29.129243] ModemManager[841]: [/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '2'
[   29.133134] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   29.141103] ModemManager[841]: [/dev/cdc-wdm0] Registered 'wms' (version 1.10) client with ID '2'
[   29.144165] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   29.152757] ModemManager[841]: [/dev/cdc-wdm0] Registered 'uim' (version 1.46) client with ID '1'
[   29.283730] ModemManager[841]: couldn't load SIM identifier: 'Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported''
[   29.287661] ModemManager[841]: couldn't load IMSI: 'Couldn't get UIM IMSI: QMI protocol error (94): 'NotSupported''
[   29.302016] ModemManager[841]: couldn't load Operator identifier: 'Couldn't get home network: QMI protocol error (16): 'NotProvisioned''
[   29.309069] ModemManager[841]: couldn't load Operator name: 'Couldn't get home network: QMI protocol error (16): 'NotProvisioned''
[   29.323978] ModemManager[841]: couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
[   33.060095] ModemManager[841]: Modem: state changed (unknown -> disabled)
[   33.091942] NetworkManager[739]: <info>  [1541687279.7630] modem["cdc-wdm0"]: modem state changed, 'disabled' --> 'enabling' (reason: user preference)
[   33.095020] NetworkManager[739]: <info>  [1541687279.7660] manager: (cdc-wdm0): new Broadband device (/org/freedesktop/NetworkManager/Devices/9)
[   33.119886] NetworkManager[739]: <info>  [1541687279.7913] device (cdc-wdm0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
[   33.138627] NetworkManager[739]: <info>  [1541687279.8101] device (cdc-wdm0): modem state 'enabling'
[   33.163172] NetworkManager[739]: <info>  [1541687279.8344] device (cdc-wdm0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
[   33.181671] NetworkManager[739]: <info>  [1541687279.8531] policy: auto-activating connection 'gprsconnection' (14e02637-0ffb-3a28-806f-ad60904afe7f)
[   33.189273] NetworkManager[739]: <info>  [1541687279.8607] device (cdc-wdm0): Activation: starting connection 'gprsconnection' (14e02637-0ffb-3a28-806f-ad60904afe7f)
[   33.209102] NetworkManager[739]: <info>  [1541687279.8805] device (cdc-wdm0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
[   33.246368] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
[   33.265389] ModemManager[841]: Simple connect started...
[   33.265841] ModemManager[841]: Simple connect state (4/8): Wait to get fully enabled
[   33.364708] ModemManager[841]: could not load SIM identifier: Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported' (3 retries left)
[   35.009012] ModemManager[841]: could not load SIM identifier: Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported' (2 retries left)
[   36.008526] ModemManager[841]: could not load SIM identifier: Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported' (1 retries left)
[   37.008866] ModemManager[841]: could not load SIM identifier: Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported'
[   37.009976] ModemManager[841]: Error checking if SIM was swapped: 'Couldn't get UIM ICCID: QMI protocol error (94): 'NotSupported''
[   37.035727] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> idle)
[   37.153836] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
[   37.154317] ModemManager[841]: Simple connect state (5/8): Register
[   37.157534] NetworkManager[739]: <info>  [1541687283.8288] modem["cdc-wdm0"]: modem state changed, 'enabling' --> 'enabled' (reason: user-requested)
[   53.010764] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> searching)
[   53.012266] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> searching)
[   53.017707] NetworkManager[739]: <info>  [1541687300.8724] modem["cdc-wdm0"]: modem state changed, 'enabled' --> 'searching' (reason: unknown)
[   62.012920] NetworkManager[739]: <warn>  [1541687309.8650] dhcp6 (eth0): request timed out
[   62.014356] NetworkManager[739]: <info>  [1541687309.8653] dhcp6 (eth0): state changed unknown -> timeout
[   62.014725] NetworkManager[739]: <info>  [1541687309.8656] dhcp6 (eth0): canceled DHCP transaction
[   62.015027] NetworkManager[739]: <info>  [1541687309.8657] dhcp6 (eth0): state changed timeout -> done
[   72.118372] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (searching -> idle)
[   75.038659] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> registering)
[   75.043901] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
[   75.044423] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (searching -> registered)
[   75.049228] NetworkManager[739]: <info>  [1541687322.9040] modem["cdc-wdm0"]: modem state changed, 'searching' --> 'registered' (reason: unknown)
[   76.012998] ModemManager[841]: Simple connect state (6/8): Bearer
[   76.015129] ModemManager[841]: Simple connect state (7/8): Connect
[   76.015857] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
[   76.021750] NetworkManager[739]: <info>  [1541687323.8756] modem["cdc-wdm0"]: modem state changed, 'registered' --> 'connecting' (reason: user-requested)
[   76.026784] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   76.034600] ModemManager[841]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '2'
[   77.123676] ModemManager[841]: QMI IPv4 Settings:
[   77.125930] ModemManager[841]:     Address: a.b.c.d/29
[   77.126327] ModemManager[841]:     Gateway: a.b.c.d
[   77.126594] ModemManager[841]:     DNS #1: a.b.c.d
[   77.126845] ModemManager[841]:     DNS #2: a.b.c.d
[   77.127088] ModemManager[841]:        MTU: 68
[   77.127338] ModemManager[841]: [/dev/cdc-wdm0] Allocating new client ID...
[   77.134705] ModemManager[841]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '3'
[   77.163151] ModemManager[841]: error: couldn't start network: QMI protocol error (14): 'CallFailed'
[   77.163733] ModemManager[841]: call end reason (1): 'generic-unspecified'
[   77.164138] ModemManager[841]: verbose call end reason (2,210): [internal] pdn-ipv6-call-disallowed
[   77.165411] ModemManager[841]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
[   77.167966] ModemManager[841]: Simple connect state (8/8): All done
[   77.170573] NetworkManager[739]: <info>  [1541687325.0254] modem["cdc-wdm0"]: modem state changed, 'connecting' --> 'connected' (reason: user-requested)
[   77.191357] NetworkManager[739]: <info>  [1541687325.0462] device (cdc-wdm0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
[   77.196246] NetworkManager[739]: <info>  [1541687325.0512] device (cdc-wdm0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
[   77.200582] NetworkManager[739]: <info>  [1541687325.0555] dhcp4 (wwan0): activation: beginning transaction (no timeout)
[   77.201303] NetworkManager[739]: <info>  [1541687325.0562] modem["cdc-wdm0"]: IPv6 configuration disabled