Unable to get IPv4 over LTE

Vincent Bernat bernat at luffy.cx
Tue Jan 19 23:20:29 PST 2016


 ❦ 19 janvier 2016 23:59 +0100, Aleksander Morgado <aleksander at aleksander.es> :

> Would be interesting to see what ModemManager says in each of those
> tries (--debug); and check which are the IP details given by the modem
> for each case, before we even try to run the DHCP client. Could you
> gather logs for both situations?

Well, luckily, it failed again this morning.

When failing:

[mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
[mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: 0000
[mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
[mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
[mm-iface-modem-simple.c:657] connect_auth_ready():    APN: internet
[mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4v6
[mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified
[mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
[mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
[mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
[mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
[mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
[mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '22802', automatic registration not launched...
[mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
[mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
[mm-broadband-modem-mbim.c:1163] modem_create_bearer(): Creating MBIM bearer in MBIM modem
[mm-broadband-modem-mbim.c:1077] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
[mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
[mm-bearer-mbim.c:897] _connect(): Launching connection with data port (net/wwan0)
[mm-bearer-mbim.c:655] connect_context_step(): Activating packet service...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 27
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'packet-service' (0x0000000a)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 76
>>>>>>   data   = 03:00:00:80:4C:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 76
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 27
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'packet-service' (0x0000000a)
[mm-bearer-mbim.c:594] packet_service_set_ready(): Packet service update:
[mm-bearer-mbim.c:595] packet_service_set_ready():          state: 'attached'
[mm-bearer-mbim.c:596] packet_service_set_ready():     data class: 'lte'
[mm-bearer-mbim.c:597] packet_service_set_ready():         uplink: '50000000' bps
[mm-bearer-mbim.c:598] packet_service_set_ready():       downlink: '100000000' bps
[mm-bearer-mbim.c:676] connect_context_step(): Listing provisioned contexts...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 28
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'provisioned-contexts' (0x0000000d)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 03:00:00:80:34:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 28
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'provisioned-contexts' (0x0000000d)
[mm-bearer-mbim.c:529] provisioned_contexts_query_ready(): Provisioned contexts found (0):
[mm-bearer-mbim.c:772] connect_context_step(): Launching connection with APN 'internet'...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 124
<<<<<<   data   = 03:00:00:00:7C:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:4C:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:10:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:69:00:6E:00:74:00:65:00:72:00:6E:00:65:00:74:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 124
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 29
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'connect' (0x0000000c)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 84
>>>>>>   data   = 03:00:00:80:54:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:00:00:00:00:24:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 84
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 29
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'connect' (0x0000000c)
[mm-bearer-mbim.c:484] connect_set_ready(): Session ID '0': activated (IP type: ipv4)
[mm-bearer-mbim.c:803] connect_context_step(): Querying IP configuration...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 108
<<<<<<   data   = 03:00:00:00:6C:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 108
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 30
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'ip-configuration' (0x0000000f)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 120
>>>>>>   data   = 03:00:00:80:78:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:48:00:00:00:00:00:00:00:03:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:44:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:18:00:00:00:0A:82:4E:44:0A:82:4E:01
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 120
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 30
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'ip-configuration' (0x0000000f)
[mm-bearer-mbim.c:212] ip_configuration_query_ready(): IPv4 configuration available: 'address, gateway'
[mm-bearer-mbim.c:218] ip_configuration_query_ready():   IP addresses (1)
[mm-bearer-mbim.c:222] ip_configuration_query_ready():     IP [0]: '10.130.78.68/24'
[mm-bearer-mbim.c:231] ip_configuration_query_ready():   Gateway: '10.130.78.1'
[mm-bearer-mbim.c:258] ip_configuration_query_ready(): IPv6 configuration available: 'none'
[mm-port.c:95] mm_port_set_connected(): (wwan0): port now connected
[mm-base-bearer.c:488] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
[mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done

So, it gets an IP address!

When succeeding:

[mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
[mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: unspecified
[mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
[mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
[mm-iface-modem-simple.c:657] connect_auth_ready():    APN: internet
[mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4v6
[mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified
[mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
[mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
[mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
[mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
[mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
[mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '22802', automatic registration not launched...
[mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
[mm-iface-modem-simple.c:574] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
[mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
[mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
[mm-bearer-mbim.c:897] _connect(): Launching connection with data port (net/wwan0)
[mm-bearer-mbim.c:655] connect_context_step(): Activating packet service...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34:00:00:00:39:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 57
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'packet-service' (0x0000000a)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 76
>>>>>>   data   = 03:00:00:80:4C:00:00:00:39:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:18:00:00:00:A0:F9:56:00:00:00:00:00:00:97:49:01:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 76
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 57
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'packet-service' (0x0000000a)
[mm-bearer-mbim.c:594] packet_service_set_ready(): Packet service update:
[mm-bearer-mbim.c:595] packet_service_set_ready():          state: 'attached'
[mm-bearer-mbim.c:596] packet_service_set_ready():     data class: 'hsdpa, hsupa'
[mm-bearer-mbim.c:597] packet_service_set_ready():         uplink: '5700000' bps
[mm-bearer-mbim.c:598] packet_service_set_ready():       downlink: '21600000' bps
[mm-bearer-mbim.c:676] connect_context_step(): Listing provisioned contexts...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30:00:00:00:3A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 58
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'provisioned-contexts' (0x0000000d)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 03:00:00:80:34:00:00:00:3A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 58
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'provisioned-contexts' (0x0000000d)
[mm-bearer-mbim.c:529] provisioned_contexts_query_ready(): Provisioned contexts found (0):
[mm-bearer-mbim.c:772] connect_context_step(): Launching connection with APN 'internet'...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 124
<<<<<<   data   = 03:00:00:00:7C:00:00:00:3B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:4C:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:10:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:69:00:6E:00:74:00:65:00:72:00:6E:00:65:00:74:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 124
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 59
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'connect' (0x0000000c)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 84
>>>>>>   data   = 03:00:00:80:54:00:00:00:3B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:00:00:00:00:24:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 84
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 59
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'connect' (0x0000000c)
[mm-bearer-mbim.c:484] connect_set_ready(): Session ID '0': activated (IP type: ipv4)
[mm-bearer-mbim.c:803] connect_context_step(): Querying IP configuration...
ModemManager[12437]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 108
<<<<<<   data   = 03:00:00:00:6C:00:00:00:3C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
ModemManager[12437]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 108
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 60
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'ip-configuration' (0x0000000f)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[12437]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 128
>>>>>>   data   = 03:00:00:80:80:00:00:00:3C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:07:00:00:00:00:00:00:00:01:00:00:00:44:00:00:00:00:00:00:00:00:00:00:00:4C:00:00:00:00:00:00:00:02:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:0A:C8:66:F4:0A:C8:66:F3:18:00:00:00:0A:93:74:D1:0A:93:74:01
ModemManager[12437]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 128
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 60
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'ip-configuration' (0x0000000f)
[mm-bearer-mbim.c:212] ip_configuration_query_ready(): IPv4 configuration available: 'address, gateway, dns'
[mm-bearer-mbim.c:218] ip_configuration_query_ready():   IP addresses (1)
[mm-bearer-mbim.c:222] ip_configuration_query_ready():     IP [0]: '10.147.116.209/24'
[mm-bearer-mbim.c:231] ip_configuration_query_ready():   Gateway: '10.147.116.1'
[mm-bearer-mbim.c:239] ip_configuration_query_ready():   DNS addresses (2)
[mm-bearer-mbim.c:244] ip_configuration_query_ready():     DNS [0]: '10.200.102.244'
[mm-bearer-mbim.c:244] ip_configuration_query_ready():     DNS [1]: '10.200.102.243'
[mm-bearer-mbim.c:258] ip_configuration_query_ready(): IPv6 configuration available: 'none'
[mm-port.c:95] mm_port_set_connected(): (wwan0): port now connected
[mm-base-bearer.c:488] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
[mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done

So, it also gets a DNS. Now, I put the computer to sleep and back, I
restart Modem Manager, unload and reload modules and reset the USB modem
(I always do this as otherwise, I get in a "sim-failed" state). I am now
over LTE again and I try to connect and it works:

[mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
[mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: unspecified
[mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
[mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
[mm-iface-modem-simple.c:657] connect_auth_ready():    APN: internet
[mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4v6
[mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified
[mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
[mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
[mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
[mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
[mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
[mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '22802', automatic registration not launched...
[mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
[mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
[mm-broadband-modem-mbim.c:1163] modem_create_bearer(): Creating MBIM bearer in MBIM modem
[mm-broadband-modem-mbim.c:1077] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
[mm-base-bearer.c:590] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting)
[mm-bearer-mbim.c:897] _connect(): Launching connection with data port (net/wwan0)
[mm-bearer-mbim.c:655] connect_context_step(): Activating packet service...
ModemManager[14494]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 29
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'packet-service' (0x0000000a)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[14494]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 76
>>>>>>   data   = 03:00:00:80:4C:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 76
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 29
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'packet-service' (0x0000000a)
[mm-bearer-mbim.c:594] packet_service_set_ready(): Packet service update:
[mm-bearer-mbim.c:595] packet_service_set_ready():          state: 'attached'
[mm-bearer-mbim.c:596] packet_service_set_ready():     data class: 'lte'
[mm-bearer-mbim.c:597] packet_service_set_ready():         uplink: '50000000' bps
[mm-bearer-mbim.c:598] packet_service_set_ready():       downlink: '100000000' bps
[mm-bearer-mbim.c:676] connect_context_step(): Listing provisioned contexts...
ModemManager[14494]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 30
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'provisioned-contexts' (0x0000000d)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[14494]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 03:00:00:80:34:00:00:00:1E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 30
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'provisioned-contexts' (0x0000000d)
[mm-bearer-mbim.c:529] provisioned_contexts_query_ready(): Provisioned contexts found (0):
[mm-bearer-mbim.c:772] connect_context_step(): Launching connection with APN 'internet'...
ModemManager[14494]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 124
<<<<<<   data   = 03:00:00:00:7C:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:4C:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:10:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:69:00:6E:00:74:00:65:00:72:00:6E:00:65:00:74:00
ModemManager[14494]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 124
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 31
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'connect' (0x0000000c)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[14494]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 84
>>>>>>   data   = 03:00:00:80:54:00:00:00:1F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:00:00:00:00:24:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 84
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 31
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'connect' (0x0000000c)
[mm-bearer-mbim.c:484] connect_set_ready(): Session ID '0': activated (IP type: ipv4)
[mm-bearer-mbim.c:803] connect_context_step(): Querying IP configuration...
ModemManager[14494]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 108
<<<<<<   data   = 03:00:00:00:6C:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
ModemManager[14494]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 108
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 32
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'ip-configuration' (0x0000000f)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[14494]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 128
>>>>>>   data   = 03:00:00:80:80:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0F:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:07:00:00:00:00:00:00:00:01:00:00:00:44:00:00:00:00:00:00:00:00:00:00:00:4C:00:00:00:00:00:00:00:02:00:00:00:3C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:0A:C8:66:F4:0A:C8:66:F3:18:00:00:00:0A:93:96:6F:0A:93:96:01
ModemManager[14494]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 128
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 32
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'ip-configuration' (0x0000000f)
[mm-bearer-mbim.c:212] ip_configuration_query_ready(): IPv4 configuration available: 'address, gateway, dns'
[mm-bearer-mbim.c:218] ip_configuration_query_ready():   IP addresses (1)
[mm-bearer-mbim.c:222] ip_configuration_query_ready():     IP [0]: '10.147.150.111/24'
[mm-bearer-mbim.c:231] ip_configuration_query_ready():   Gateway: '10.147.150.1'
[mm-bearer-mbim.c:239] ip_configuration_query_ready():   DNS addresses (2)
[mm-bearer-mbim.c:244] ip_configuration_query_ready():     DNS [0]: '10.200.102.244'
[mm-bearer-mbim.c:244] ip_configuration_query_ready():     DNS [1]: '10.200.102.243'
[mm-bearer-mbim.c:258] ip_configuration_query_ready(): IPv6 configuration available: 'none'
[mm-port.c:95] mm_port_set_connected(): (wwan0): port now connected
[mm-base-bearer.c:488] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
[mm-iface-modem.c:1392] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> connected)
[mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done

As I said, I have the same behavior with different carriers. Maybe they
all use the same software though (carriers are Salt (CH), Sunrise (CH)
and Orange (FR)).

I have never updated the firmware of the modem (I think this is only
possible from Windows and no Windows). The modem is quite buggy and I
often use a "reset-all" script to get things in working order. When I
say I "reset" the modem, this includes a USB reset (ioctl
USBDEVFS_RESET).

I could totally live with 8.8.8.8 and 8.8.4.4 as DNS if this was
possible (who decides that IP configuration is incomplete?).
-- 
Test input for validity and plausibility.
            - The Elements of Programming Style (Kernighan & Plauger)


More information about the ModemManager-devel mailing list