Aleksander asked me to post the debug output from this almost-working
mbim session.
The backstory is that it is connecting, getting ip/dns info and
bringing the wwan0 interface up, but no packets are flowing:
https://gist.github.com/anonymous/ee1bf424aad61d82130c2581ad1301a5
The full --debug log is attached of the current
libmbim/libqmi/modemmanager qmi-over-mbim branches.
Hopefully it is not me doing something idiotic here. Let me know what
else I can do to help.
-Rasmus
ModemManager[15695]: spawning new mbim-proxy (try 1)...
ModemManager[15695]: <debug> [1465836139.238397] [mm-port-serial-at.c:459]
debug_log(): (ttyS0): --> 'AT<CR>'
ModemManager[15695]: [/dev/cdc-wdm0] Read max control message size from
descriptors file: 4096
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data =
03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 1
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
<<<<<< cid = 'configuration' (0x00000001)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: <debug> [1465836140.122541] [mm-plugin-manager.c:1058]
device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing
time elapsed
ModemManager[15695]: <debug> [1465836140.122574] [mm-plugin-manager.c:1005]
device_context_continue(): [plugin Manager] task 0: still 1 running probes (1
active): ttyS0
ModemManager[15695]: <debug> [1465836140.122586] [mm-plugin-manager.c:1058]
device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing
time elapsed
ModemManager[15695]: <debug> [1465836140.122594] [mm-plugin-manager.c:1005]
device_context_continue(): [plugin Manager] task 1: still 2 running probes (1
active): cdc-wdm0, wwan0
ModemManager[15695]: <debug> [1465836140.122607] [mm-plugin-manager.c:1058]
device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing
time elapsed
ModemManager[15695]: <debug> [1465836140.122623] [mm-plugin-manager.c:1005]
device_context_continue(): [plugin Manager] task 2: still 1 running probes (0
active): wlan0
ModemManager[15695]: <debug> [1465836140.122655] [mm-plugin-manager.c:1009]
device_context_continue(): [plugin manager] task 2: no active tasks to probe
ModemManager[15695]: <debug> [1465836140.122671] [mm-plugin-manager.c:337]
port_context_set_suggestion(): [plugin manager] task 2,wlan0: deferred task
completed, no suggested plugin
ModemManager[15695]: <debug> [1465836140.122686] [mm-plugin-manager.c:265]
port_context_complete(): [plugin manager] task 2,wlan0: finished in '2.500597'
seconds
ModemManager[15695]: <debug> [1465836140.122749] [mm-plugin-manager.c:892]
device_context_set_best_plugin(): [plugin manager] task 2,wlan0: not supported
by any plugin
ModemManager[15695]: <debug> [1465836140.122768] [mm-device.c:373]
mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
ModemManager[15695]: <debug> [1465836140.122783] [mm-plugin-manager.c:979]
device_context_continue(): [plugin manager] task 2: no more ports to probe
ModemManager[15695]: <debug> [1465836140.122792] [mm-plugin-manager.c:813]
device_context_complete(): [plugin manager] task 2: finished in '2.500745'
seconds
ModemManager[15695]: <info> [1465836140.122941] [mm-base-manager.c:155]
device_support_check_ready(): Couldn't check support for device at
'/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0': not supported by any plugin
ModemManager[15695]: <debug> [1465836140.123143] [mm-plugin-manager.c:1058]
device_context_min_probing_time_elapsed(): [plugin manager] task 3: min probing
time elapsed
ModemManager[15695]: <debug> [1465836140.123314] [mm-plugin-manager.c:1005]
device_context_continue(): [plugin Manager] task 3: still 1 running probes (0
active): eth0
ModemManager[15695]: <debug> [1465836140.123485] [mm-plugin-manager.c:1009]
device_context_continue(): [plugin manager] task 3: no active tasks to probe
ModemManager[15695]: <debug> [1465836140.123603] [mm-plugin-manager.c:337]
port_context_set_suggestion(): [plugin manager] task 3,eth0: deferred task
completed, no suggested plugin
ModemManager[15695]: <debug> [1465836140.123760] [mm-plugin-manager.c:265]
port_context_complete(): [plugin manager] task 3,eth0: finished in '2.501520'
seconds
ModemManager[15695]: <debug> [1465836140.123994] [mm-plugin-manager.c:892]
device_context_set_best_plugin(): [plugin manager] task 3,eth0: not supported
by any plugin
ModemManager[15695]: <debug> [1465836140.124173] [mm-device.c:373]
mm_device_ignore_port(): Fully ignoring port 'net/eth0' from now on
ModemManager[15695]: <debug> [1465836140.124283] [mm-plugin-manager.c:979]
device_context_continue(): [plugin manager] task 3: no more ports to probe
ModemManager[15695]: <debug> [1465836140.124392] [mm-plugin-manager.c:813]
device_context_complete(): [plugin manager] task 3: finished in '2.502200'
seconds
ModemManager[15695]: <info> [1465836140.124509] [mm-base-manager.c:155]
device_support_check_ready(): Couldn't check support for device at
'/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data =
>>>>>> 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:10:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
ModemManager[15695]: <debug> [1465836142.310162] [mm-port-probe.c:304]
mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is MBIM-capable
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 02:00:00:00:0C:00:00:00:03:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 12
<<<<<< type = close (0x00000002)
<<<<<< transaction = 3
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 02:00:00:80:10:00:00:00:03:00:00:00:00:00:00:00
ModemManager[15695]: <debug> [1465836142.310392] [mm-plugin-manager.c:283]
port_context_supported(): [plugin manager] task 1,cdc-wdm0: found best plugin
for port (Sierra)
ModemManager[15695]: <debug> [1465836142.310403] [mm-plugin-manager.c:265]
port_context_complete(): [plugin manager] task 1,cdc-wdm0: finished in
'4.687581' seconds
ModemManager[15695]: <debug> [1465836142.310418] [mm-plugin-manager.c:913]
device_context_set_best_plugin(): [plugin manager] task 1,cdc-wdm0: found best
plugin: Sierra
ModemManager[15695]: <debug> [1465836142.310426] [mm-plugin-manager.c:326]
port_context_set_suggestion(): [plugin manager] task 1,wwan0: deferred task
completed, got suggested plugin (Sierra)
ModemManager[15695]: <debug> [1465836142.310434] [mm-plugin-manager.c:1005]
device_context_continue(): [plugin Manager] task 1: still 1 running probes (1
active): wwan0
ModemManager[15695]: <debug> [1465836142.310454] [mm-plugin-manager.c:519]
port_context_next(): [plugin manager] task 1,wwan0: checking with plugin
'Sierra'
ModemManager[15695]: <debug> [1465836142.310539] [mm-plugin.c:730]
mm_plugin_supports_port(): (Sierra) [wwan0] probing deferred until result
suggested
ModemManager[15695]: <debug> [1465836142.310662] [mm-plugin-manager.c:434]
port_context_defer_until_suggested(): [plugin manager] task 1,wwan0: completed,
got suggested plugin (Sierra)
ModemManager[15695]: <debug> [1465836142.310781] [mm-plugin-manager.c:265]
port_context_complete(): [plugin manager] task 1,wwan0: finished in '4.688840'
seconds
ModemManager[15695]: <debug> [1465836142.310904] [mm-plugin-manager.c:966]
device_context_set_best_plugin(): [plugin manager] task 1,wwan0: best plugin
matches device reported one: Sierra
ModemManager[15695]: <debug> [1465836142.311061] [mm-plugin-manager.c:979]
device_context_continue(): [plugin manager] task 1: no more ports to probe
ModemManager[15695]: <debug> [1465836142.311172] [mm-plugin-manager.c:813]
device_context_complete(): [plugin manager] task 1: finished in '4.689290'
seconds
ModemManager[15695]: <info> [1465836142.311273] [mm-device.c:525]
mm_device_create_modem(): Creating modem with plugin 'Sierra' and '2' ports
ModemManager[15695]: <debug> [1465836142.311387] [sierra/mm-plugin-sierra.c:66]
create_modem(): MBIM-powered Sierra modem found...
ModemManager[15695]: <debug> [1465836142.312107] [mm-base-modem.c:280]
mm_base_modem_grab_port(): (cdc-wdm0) type 'mbim' claimed by
/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
ModemManager[15695]: <debug> [1465836142.312131] [mm-base-modem.c:280]
mm_base_modem_grab_port(): (wwan0) type 'net' claimed by
/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
ModemManager[15695]: <debug> [1465836142.312147] [mm-base-modem.c:864]
log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) net/wwan0 data
(primary)
ModemManager[15695]: <debug> [1465836142.312157] [mm-base-modem.c:864]
log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) usb/cdc-wdm0 mbim
(primary)
ModemManager[15695]: <info> [1465836142.312285] [mm-base-manager.c:175]
device_support_check_ready(): Modem for device at
'/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2' successfully created
ModemManager[15695]: opening device...
ModemManager[15695]: [/dev/cdc-wdm0] Read max control message size from
descriptors file: 4096
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data =
03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 88
<<<<<< type = command (0x00000003)
<<<<<< transaction = 1
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
<<<<<< cid = 'configuration' (0x00000001)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data =
>>>>>> 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:10:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
ModemManager[15695]: <debug> [1465836142.372720]
[mm-broadband-modem-mbim.c:1578] parent_initialization_started_ready():
Couldn't start parent initialization: Couldn't open ports during modem
initialization: Couldn't get primary port
ModemManager[15695]: <debug> [1465836142.373155]
[mm-broadband-modem-mbim.c:218] modem_load_current_capabilities(): loading
current capabilities...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:03: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:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 3
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-caps' (0x00000001)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 196
>>>>>> data =
>>>>>> 03:00:00:80:C4:00:00:00:03: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:01:00:00:00:00:00:00:00:94:00:00:00:03:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:00:03:00:00:00:01:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:40:00:00:00:1E:00:00:00:60:00:00:00:28:00:00:00:88:00:00:00:0C:00:00:00:30:00:31:00:34:00:35:00:38:00:32:00:30:00:30:00:30:00:35:00:32:00:36:00:34:00:37:00:36:00:00:00:53:00:57:00:49:00:39:00:58:00:33:00:30:00:43:00:5F:00:30:00:32:00:2E:00:30:00:38:00:2E:00:30:00:32:00:2E:00:30:00:30:00:45:00:4D:00:37:00:34:00:35:00:35:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 196
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 3
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-caps' (0x00000001)
ModemManager[15695]: <debug> [1465836142.436612]
[mm-broadband-modem-mbim.c:144] modem_load_current_capabilities_finish():
loaded modem capabilities: gsm-umts, lte
ModemManager[15695]: <debug> [1465836142.436672] [mm-iface-modem.c:4345]
interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[15695]: <debug> [1465836142.436683] [mm-bearer-list.c:270]
mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[15695]: <debug> [1465836142.436777] [mm-modem-helpers.c:211]
mm_create_device_identifier(): Device ID source
'0000119900009079014582000526476SWI9X30C_02.08.02.00MBIM[1199:9079]Sierra'
ModemManager[15695]: <debug> [1465836142.436788] [mm-modem-helpers.c:212]
mm_create_device_identifier(): Device ID
'9e169eacf81d32d78cb812e12eccffd3dc58b9a8'
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:04: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:03:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 4
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data =
>>>>>> 03:00:00:80:38:00:00:00:04: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:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 4
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:05: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:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 5
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 180
>>>>>> data =
>>>>>> 03:00:00:80:B4:00:00:00:05: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:02:00:00:00:00:00:00:00:84:00:00:00:01:00:00:00:3C:00:00:00:1E:00:00:00:5C:00:00:00:26:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:36:00:35:00:30:00:33:00:30:00:37:00:30:00:37:00:37:00:34:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:39:00:37:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:00:00:38:00:39:00:30:00:31:00:32:00:36:00:30:00:39:00:37:00:31:00:31:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 180
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 5
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:06: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:04:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 6
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data =
>>>>>> 03:00:00:80:3C:00:00:00:06: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:04:00:00:00:00:00:00:00:0C:00:00:00:03:00:00:00:01:00:00:00:0A:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 6
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
ModemManager[15695]: <debug> [1465836142.628702] [mm-iface-modem.c:3110]
update_lock_info_context_step(): SIM is ready, and no need for the after SIM
unlock step...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:07: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:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 7
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 180
>>>>>> data =
>>>>>> 03:00:00:80:B4:00:00:00:07: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:02:00:00:00:00:00:00:00:84:00:00:00:01:00:00:00:3C:00:00:00:1E:00:00:00:5C:00:00:00:26:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:36:00:35:00:30:00:33:00:30:00:37:00:30:00:37:00:37:00:34:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:39:00:37:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:00:00:38:00:39:00:30:00:31:00:32:00:36:00:30:00:39:00:37:00:31:00:31:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 180
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 7
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:08: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:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 8
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 180
>>>>>> data =
>>>>>> 03:00:00:80:B4:00:00:00:08: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:02:00:00:00:00:00:00:00:84:00:00:00:01:00:00:00:3C:00:00:00:1E:00:00:00:5C:00:00:00:26:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:36:00:35:00:30:00:33:00:30:00:37:00:30:00:37:00:37:00:34:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:39:00:37:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:00:00:38:00:39:00:30:00:31:00:32:00:36:00:30:00:39:00:37:00:31:00:31:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 180
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 8
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:09: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:06:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 9
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 112
>>>>>> data =
>>>>>> 03:00:00:80:70:00:00:00:09: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:06:00:00:00:00:00:00:00:40:00:00:00:20:00:00:00:0C:00:00:00:01:00:00:00:2C:00:00:00:14:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:50:00:72:00:6F:00:6A:00:65:00:63:00:74:00:20:00:46:00:69:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 112
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 9
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:0A: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:06:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 10
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 112
>>>>>> data =
>>>>>> 03:00:00:80:70:00:00:00:0A: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:06:00:00:00:00:00:00:00:40:00:00:00:20:00:00:00:0C:00:00:00:01:00:00:00:2C:00:00:00:14:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:50:00:72:00:6F:00:6A:00:65:00:63:00:74:00:20:00:46:00:69:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 112
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 10
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:0B: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:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 11
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 180
>>>>>> data =
>>>>>> 03:00:00:80:B4:00:00:00:0B: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:02:00:00:00:00:00:00:00:84:00:00:00:01:00:00:00:3C:00:00:00:1E:00:00:00:5C:00:00:00:26:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:36:00:35:00:30:00:33:00:30:00:37:00:30:00:37:00:37:00:34:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:39:00:37:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:00:00:38:00:39:00:30:00:31:00:32:00:36:00:30:00:39:00:37:00:31:00:31:00:30:00:36:00:34:00:33:00:36:00:38:00:39:00:30:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 180
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 11
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:0C: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:05:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 12
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin-list' (0x00000005)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 208
>>>>>> data =
>>>>>> 03:00:00:80:D0:00:00:00:0C: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:05:00:00:00:00:00:00:00:A0:00:00:00:02:00:00:00:01:00:00:00:04:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00:04:00:00:00:08: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: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:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 208
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 12
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin-list' (0x00000005)
ModemManager[15695]: <debug> [1465836143.012756]
[mm-iface-modem-3gpp-ussd.c:786] check_support_ready(): USSD support check
failed: 'No AT port available to run command'
ModemManager[15695]: <debug> [1465836143.012777] [mm-broadband-modem.c:9627]
iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD
not supported'
ModemManager[15695]: <debug> [1465836143.013014]
[mm-broadband-modem-mbim.c:2881] messaging_check_support(): Messaging
capabilities supported
ModemManager[15695]: <debug> [1465836143.013040]
[mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported
storages loaded:
ModemManager[15695]: <debug> [1465836143.013047]
[mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem1
(list/read/delete) storages: 'mt'
ModemManager[15695]: <debug> [1465836143.013052]
[mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem2
(write/send) storages: 'mt'
ModemManager[15695]: <debug> [1465836143.013056]
[mm-iface-modem-messaging.c:1152] load_supported_storages_ready(): mem3
(reception) storages: 'mt'
ModemManager[15695]: <debug> [1465836143.013077]
[mm-iface-modem-messaging.c:1229] init_current_storages_ready(): Couldn't
initialize current storages: 'No AT port available to run command'
ModemManager[15695]: <debug> [1465836143.013143] [mm-iface-modem-voice.c:916]
check_support_ready(): Voice support check failed: 'No AT port available to run
command'
ModemManager[15695]: <debug> [1465836143.013160] [mm-broadband-modem.c:9631]
iface_modem_voice_initialize_ready(): Couldn't initialize interface: 'Voice not
supported'
ModemManager[15695]: <debug> [1465836143.013204] [mm-iface-modem-time.c:831]
check_support_ready(): Time support check failed: 'No AT port available to run
command'
ModemManager[15695]: <debug> [1465836143.013218] [mm-broadband-modem.c:9632]
iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not
supported'
ModemManager[15695]: <debug> [1465836143.013267] [mm-broadband-modem.c:9633]
iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended
Signal information not supported'
ModemManager[15695]: <debug> [1465836143.014998] [mm-broadband-modem.c:9634]
iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not
supported'
ModemManager[15695]: <debug> [1465836143.015056] [mm-broadband-modem.c:9635]
iface_modem_firmware_initialize_ready(): Couldn't initialize interface:
'Firmware not supported'
ModemManager[15695]: <info> [1465836143.015154] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem: state changed (unknown ->
disabled)
ModemManager[15695]: <debug> [1465836143.015265] [mm-base-modem.c:833]
initialize_ready(): modem properly initialized
ModemManager[15695]: <debug> [1465836143.015814] [mm-device.c:452]
export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2'
at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[15695]: <debug> [1465836143.015840] [mm-device.c:462]
export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Sierra' modem, VID
0x1199 PID 0x9079 (usb)
ModemManager[15695]: <info> [1465836143.023994] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[15695]: <debug> [1465836143.024763] [mm-broadband-modem.c:8539]
enabling_started(): Skipping modem initialization: not required
ModemManager[15695]: <debug> [1465836143.026572]
[mm-broadband-modem-mbim.c:1505] parent_enabling_started_ready(): Couldn't
start parent enabling: Couldn't open ports during modem enabling: Couldn't get
primary port
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:0D: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:03:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 13
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data =
>>>>>> 03:00:00:80:38:00:00:00:0D: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:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 13
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
ModemManager[15695]: <debug> [1465836143.076600] [mm-iface-modem.c:3293]
set_power_state(): No need to change power state: already in 'on' power state
ModemManager[15695]: <debug> [1465836143.076626] [mm-broadband-modem.c:9197]
enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP
interface...
ModemManager[15695]: <debug> [1465836143.076645]
[mm-broadband-modem-mbim.c:2265] common_setup_cleanup_unsolicited_events():
Supported notifications: signal (yes), registration (no), sms (no), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: <debug> [1465836143.076677]
[mm-broadband-modem-mbim.c:2404] common_enable_disable_unsolicited_events():
Enabled notifications: signal (yes), registration (no), sms (no), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 96
<<<<<< data =
03:00:00:00:60:00:00:00:0E: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:13:00:00:00:01:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 96
<<<<<< type = command (0x00000003)
<<<<<< transaction = 14
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 96
>>>>>> data =
>>>>>> 03:00:00:80:60:00:00:00:0E: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:13:00:00:00:00:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 96
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 14
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
ModemManager[15695]: <debug> [1465836143.076924]
[mm-broadband-modem-mbim.c:2265] common_setup_cleanup_unsolicited_events():
Supported notifications: signal (yes), registration (yes), sms (no), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: <debug> [1465836143.076947]
[mm-broadband-modem-mbim.c:2404] common_enable_disable_unsolicited_events():
Enabled notifications: signal (yes), registration (yes), sms (no), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 100
<<<<<< data =
03:00:00:00:64:00:00:00:0F: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:13:00:00:00:01:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 100
<<<<<< type = command (0x00000003)
<<<<<< transaction = 15
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 100
>>>>>> data =
>>>>>> 03:00:00:80:64:00:00:00:0F: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:13:00:00:00:00:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 100
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 15
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
ModemManager[15695]: <debug> [1465836143.077340] [mm-broadband-modem.c:9238]
enabling_step(): Modem has location capabilities, enabling the Location
interface...
ModemManager[15695]: <debug> [1465836143.077464]
[mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following
location sources: '3gpp-lac-ci'
ModemManager[15695]: <debug> [1465836143.077602] [mm-iface-modem-3gpp.c:761]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS:
'yes', PS: 'yes', EPS: 'no')
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:10: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:09:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 16
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'register-state' (0x00000009)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: <debug> [1465836143.078021] [mm-broadband-modem.c:9251]
enabling_step(): Modem has messaging capabilities, enabling the Messaging
interface...
ModemManager[15695]: <debug> [1465836143.078115]
[mm-broadband-modem-mbim.c:3036] load_initial_sms_parts(): loading SMS parts...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 60
<<<<<< data =
03:00:00:00:3C:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 60
<<<<<< type = command (0x00000003)
<<<<<< transaction = 17
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
<<<<<< cid = 'read' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: <debug> [1465836143.115484] [mm-port-probe-at.c:43]
mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed
out'
ModemManager[15695]: <debug> [1465836143.115650] [mm-port-serial-at.c:459]
debug_log(): (ttyS0): --> 'AT<CR>'
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data =
>>>>>> 03:00:00:80:38:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:00:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 17
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
>>>>>> cid = 'read' (0x00000002)
ModemManager[15695]: <debug> [1465836143.140592]
[mm-broadband-modem-mbim.c:2265] common_setup_cleanup_unsolicited_events():
Supported notifications: signal (yes), registration (yes), sms (yes), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: <debug> [1465836143.140611]
[mm-broadband-modem-mbim.c:2404] common_enable_disable_unsolicited_events():
Enabled notifications: signal (yes), registration (yes), sms (yes), connect
(yes), subscriber (yes), packet (yes)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 136
<<<<<< data =
03:00:00:00:88:00:00:00:12: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:13:00:00:00:01:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 136
<<<<<< type = command (0x00000003)
<<<<<< transaction = 18
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-service-subscribe-list' (0x00000013)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 136
>>>>>> data =
>>>>>> 03:00:00:80:88:00:00:00:12: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:13:00:00:00:00:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 136
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 18
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-service-subscribe-list' (0x00000013)
ModemManager[15695]: <info> [1465836143.141784] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
ModemManager[15695]: <debug> [1465836143.141966] [mm-iface-modem-3gpp.c:761]
mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS:
'yes', PS: 'yes', EPS: 'no')
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:13: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:09:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 19
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'register-state' (0x00000009)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> data =
>>>>>> 03:00:00:80:80:00:00:00:10: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:09:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0C:00:00:00:3C:00:00:00:14:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:50:00:72:00:6F:00:6A:00:65:00:63:00:74:00:20:00:46:00:69:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 128
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 16
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
ModemManager[15695]: <info> [1465836143.204634] [mm-iface-modem-3gpp.c:1171]
update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP
Registration state changed (unknown -> registering)
ModemManager[15695]: <debug> [1465836143.204667] [mm-iface-modem.c:933]
mm_iface_modem_update_access_technologies(): Modem
/org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown ->
lte)
ModemManager[15695]: <debug> [1465836143.204697]
[mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '310', MNC:
'260', Location area code: '0', Cell ID: '0')
ModemManager[15695]: <info> [1465836143.204842] [mm-iface-modem-3gpp.c:1096]
update_registration_reload_current_registration_info_ready(): Modem
/org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed
(registering -> home)
ModemManager[15695]: <debug> [1465836143.204867] [mm-iface-modem.c:1600]
get_updated_consolidated_state(): Will start keeping track of state for
subsystem '3gpp'
ModemManager[15695]: <info> [1465836143.204877] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)
ModemManager[15695]: <debug> [1465836143.204963] [mm-iface-modem.c:1075]
periodic_access_technologies_check_enable(): Periodic access technology checks
enabled
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> data =
>>>>>> 03:00:00:80:80:00:00:00:13: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:09:00:00:00:00:00:00:00:50:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0C:00:00:00:3C:00:00:00:14:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:50:00:72:00:6F:00:6A:00:65:00:63:00:74:00:20:00:46:00:69:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 128
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 19
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'register-state' (0x00000009)
ModemManager[15695]: <debug> [1465836143.268610] [mm-broadband-modem.c:8574]
modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks
finished
ModemManager[15695]: <debug> [1465836146.114813] [mm-port-probe-at.c:43]
mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed
out'
ModemManager[15695]: <debug> [1465836146.114935] [mm-port-serial-at.c:459]
debug_log(): (ttyS0): --> 'AT<CR>'
ModemManager[15695]: <debug> [1465836149.115782] [mm-port-probe-at.c:43]
mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed
out'
ModemManager[15695]: <debug> [1465836149.115857] [mm-port-probe.c:165]
mm_port_probe_set_result_at(): (tty/ttyS0) port is not AT-capable
ModemManager[15695]: <debug> [1465836149.115904] [mm-plugin.c:504]
apply_post_probing_filters(): (Iridium) [ttyS0] filtered as no vendor/product
strings given
ModemManager[15695]: <debug> [1465836149.115947] [mm-plugin-manager.c:519]
port_context_next(): [plugin manager] task 0,ttyS0: checking with plugin
'Cinterion'
ModemManager[15695]: <debug> [1465836149.116033] [mm-plugin.c:804]
mm_plugin_supports_port(): (Cinterion) [ttyS0] probe required: 'at, at-vendor'
ModemManager[15695]: <debug> [1465836149.116105] [mm-port-probe.c:1367]
mm_port_probe_run(): (tty/ttyS0) port probing finished: no more probings needed
ModemManager[15695]: <debug> [1465836149.116191] [mm-port-serial.c:1345]
_close_internal(): (ttyS0) device open count is 0 (close)
ModemManager[15695]: <debug> [1465836149.116229] [mm-port-serial.c:1361]
_close_internal(): (ttyS0) closing serial port...
ModemManager[15695]: <debug> [1465836149.116386] [mm-port-serial.c:1409]
_close_internal(): (ttyS0) serial port closed
ModemManager[15695]: <debug> [1465836149.116460] [mm-port-serial.c:1471]
port_serial_close_force(): (ttyS0) forced to close port
ModemManager[15695]: <debug> [1465836149.116532] [mm-plugin.c:479]
apply_post_probing_filters(): (Cinterion) [ttyS0] filtered by vendor strings
ModemManager[15695]: <debug> [1465836149.116570] [mm-plugin-manager.c:519]
port_context_next(): [plugin manager] task 0,ttyS0: checking with plugin 'Nokia'
ModemManager[15695]: <debug> [1465836149.116632] [mm-plugin.c:804]
mm_plugin_supports_port(): (Nokia) [ttyS0] probe required: 'at, at-vendor,
at-icera'
ModemManager[15695]: <debug> [1465836149.116765] [mm-port-probe.c:1367]
mm_port_probe_run(): (tty/ttyS0) port probing finished: no more probings needed
ModemManager[15695]: <debug> [1465836149.117307] [mm-plugin.c:479]
apply_post_probing_filters(): (Nokia) [ttyS0] filtered by vendor strings
ModemManager[15695]: <debug> [1465836149.117699] [mm-plugin-manager.c:519]
port_context_next(): [plugin manager] task 0,ttyS0: checking with plugin 'Via
CBP7'
ModemManager[15695]: <debug> [1465836149.118124] [mm-plugin.c:804]
mm_plugin_supports_port(): (Via CBP7) [ttyS0] probe required: 'at, at-vendor,
at-product'
ModemManager[15695]: <debug> [1465836149.118530] [mm-port-probe.c:1367]
mm_port_probe_run(): (tty/ttyS0) port probing finished: no more probings needed
ModemManager[15695]: <debug> [1465836149.118963] [mm-plugin.c:504]
apply_post_probing_filters(): (Via CBP7) [ttyS0] filtered as no vendor/product
strings given
ModemManager[15695]: <debug> [1465836149.119359] [mm-plugin-manager.c:519]
port_context_next(): [plugin manager] task 0,ttyS0: checking with plugin
'Generic'
ModemManager[15695]: <debug> [1465836149.119813] [mm-plugin.c:804]
mm_plugin_supports_port(): (Generic) [ttyS0] probe required: 'at, qcdm'
ModemManager[15695]: <debug> [1465836149.120212] [mm-port-probe.c:1377]
mm_port_probe_run(): (tty/ttyS0) launching port probing: 'qcdm'
ModemManager[15695]: <debug> [1465836149.120632] [mm-port-probe.c:691]
serial_probe_qcdm(): (tty/ttyS0) probing QCDM...
ModemManager[15695]: <debug> [1465836149.121078] [mm-port-serial.c:1158]
mm_port_serial_open(): (ttyS0) opening serial port...
ModemManager[15695]: <debug> [1465836149.121623] [mm-port-serial.c:1288]
mm_port_serial_open(): (ttyS0) device open count is 1 (open)
ModemManager[15695]: <debug> [1465836149.121898] [mm-port-serial-qcdm.c:199]
debug_log(): (ttyS0): --> 7e 00 78 f0 7e
ModemManager[15695]: <debug> [1465836152.115926] [mm-port-serial-qcdm.c:199]
debug_log(): (ttyS0): --> 7e 00 78 f0 7e
ModemManager[15695]: <debug> [1465836155.115466] [mm-port-probe.c:261]
mm_port_probe_set_result_qcdm(): (tty/ttyS0) port is not QCDM-capable
ModemManager[15695]: <debug> [1465836155.115517] [mm-plugin-manager.c:283]
port_context_supported(): [plugin manager] task 0,ttyS0: found best plugin for
port (Generic)
ModemManager[15695]: <debug> [1465836155.115539] [mm-plugin-manager.c:265]
port_context_complete(): [plugin manager] task 0,ttyS0: finished in '17.494387'
seconds
ModemManager[15695]: <debug> [1465836155.115571] [mm-plugin-manager.c:979]
device_context_continue(): [plugin manager] task 0: no more ports to probe
ModemManager[15695]: <debug> [1465836155.115587] [mm-plugin-manager.c:813]
device_context_complete(): [plugin manager] task 0: finished in '17.494539'
seconds
ModemManager[15695]: <info> [1465836155.115630] [mm-device.c:525]
mm_device_create_modem(): Creating modem with plugin 'Generic' and '1' ports
ModemManager[15695]: <warn> [1465836155.116004] [mm-plugin.c:957]
mm_plugin_create_modem(): Could not grab port (tty/ttyS0): 'Cannot add port
'tty/ttyS0', unhandled serial type'
ModemManager[15695]: <debug> [1465836155.116068] [mm-base-modem.c:1379]
finalize(): Modem (Generic) '/sys/devices/pci0000:00/0000:00:16.3' completely
disposed
ModemManager[15695]: <warn> [1465836155.116091] [mm-base-manager.c:167]
device_support_check_ready(): Couldn't create modem for device at
'/sys/devices/pci0000:00/0000:00:16.3': Failed to find primary AT port
ModemManager[15695]: <debug> [1465836155.116136] [mm-port-serial.c:1345]
_close_internal(): (ttyS0) device open count is 0 (close)
ModemManager[15695]: <debug> [1465836155.116162] [mm-port-serial.c:1361]
_close_internal(): (ttyS0) closing serial port...
ModemManager[15695]: <debug> [1465836155.116265] [mm-port-serial.c:1409]
_close_internal(): (ttyS0) serial port closed
ModemManager[15695]: <debug> [1465836155.116302] [mm-port-serial.c:1471]
port_serial_close_force(): (ttyS0) forced to close port
ModemManager[15695]: <info> [1465836169.423340] [mm-iface-modem-simple.c:641]
connect_auth_ready(): Simple connect started...
ModemManager[15695]: <debug> [1465836169.423367] [mm-iface-modem-simple.c:651]
connect_auth_ready(): PIN: unspecified
ModemManager[15695]: <debug> [1465836169.423376] [mm-iface-modem-simple.c:653]
connect_auth_ready(): Operator ID: unspecified
ModemManager[15695]: <debug> [1465836169.423384] [mm-iface-modem-simple.c:655]
connect_auth_ready(): Allowed roaming: yes
ModemManager[15695]: <debug> [1465836169.423391] [mm-iface-modem-simple.c:657]
connect_auth_ready(): APN: h2g2
ModemManager[15695]: <debug> [1465836169.423398] [mm-iface-modem-simple.c:665]
connect_auth_ready(): IP family: unspecified
ModemManager[15695]: <debug> [1465836169.423409] [mm-iface-modem-simple.c:670]
connect_auth_ready(): Allowed authentication: none, pap, chap, mschap,
mschapv2, eap
ModemManager[15695]: <debug> [1465836169.423417] [mm-iface-modem-simple.c:675]
connect_auth_ready(): User: unspecified
ModemManager[15695]: <debug> [1465836169.423424] [mm-iface-modem-simple.c:677]
connect_auth_ready(): Password: unspecified
ModemManager[15695]: <debug> [1465836169.423431] [mm-iface-modem-simple.c:679]
connect_auth_ready(): Number: *99#
ModemManager[15695]: <info> [1465836169.423437] [mm-iface-modem-simple.c:469]
connection_step(): Simple connect state (4/8): Wait to get fully enabled
ModemManager[15695]: <info> [1465836169.423452] [mm-iface-modem-simple.c:478]
connection_step(): Simple connect state (5/8): Register
ModemManager[15695]: <debug> [1465836169.423552] [mm-iface-modem-3gpp.c:400]
mm_iface_modem_3gpp_register_in_network(): Already registered in network
'310260', automatic registration not launched...
ModemManager[15695]: <info> [1465836169.423904] [mm-iface-modem-simple.c:501]
connection_step(): Simple connect state (6/8): Bearer
ModemManager[15695]: <debug> [1465836169.423922] [mm-iface-modem-simple.c:521]
connection_step(): Creating new bearer...
ModemManager[15695]: <debug> [1465836169.424017]
[mm-broadband-modem-mbim.c:1448] modem_create_bearer(): Creating MBIM bearer in
MBIM modem
ModemManager[15695]: <debug> [1465836169.424356]
[mm-broadband-modem-mbim.c:1362] modem_create_bearer_finish(): New bearer
created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[15695]: <info> [1465836169.424474] [mm-iface-modem-simple.c:583]
connection_step(): Simple connect state (7/8): Connect
ModemManager[15695]: <debug> [1465836169.424489] [mm-base-bearer.c:703]
mm_base_bearer_connect(): Connecting bearer
'/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[15695]: <info> [1465836169.424750] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[15695]: <debug> [1465836169.424929] [mm-bearer-mbim.c:1024]
_connect(): Launching connection with data port (net/wwan0)
ModemManager[15695]: <debug> [1465836169.424943] [mm-bearer-mbim.c:782]
connect_context_step(): Activating packet service...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 52
<<<<<< data =
03:00:00:00:34:00:00:00:14: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[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 52
<<<<<< type = command (0x00000003)
<<<<<< transaction = 20
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'packet-service' (0x0000000a)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 76
>>>>>> data =
>>>>>> 03:00:00:80:4C:00:00:00:14: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:A3:E1:11:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 76
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 20
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-service' (0x0000000a)
ModemManager[15695]: <debug> [1465836169.444253] [mm-bearer-mbim.c:721]
packet_service_set_ready(): Packet service update:
ModemManager[15695]: <debug> [1465836169.444263] [mm-bearer-mbim.c:722]
packet_service_set_ready(): state: 'attached'
ModemManager[15695]: <debug> [1465836169.444269] [mm-bearer-mbim.c:723]
packet_service_set_ready(): data class: 'lte'
ModemManager[15695]: <debug> [1465836169.444276] [mm-bearer-mbim.c:724]
packet_service_set_ready(): uplink: '50000000' bps
ModemManager[15695]: <debug> [1465836169.444282] [mm-bearer-mbim.c:725]
packet_service_set_ready(): downlink: '300000000' bps
ModemManager[15695]: <debug> [1465836169.444289] [mm-bearer-mbim.c:803]
connect_context_step(): Listing provisioned contexts...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:15: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[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 21
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'provisioned-contexts' (0x0000000d)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 120
>>>>>> data =
>>>>>> 03:00:00:80:78:00:00:00:15: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:48:00:00:00:01:00:00:00:0C:00:00:00:3C:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:34:00:00:00:08: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:68:00:32:00:67:00:32:00
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 120
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 21
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'provisioned-contexts' (0x0000000d)
ModemManager[15695]: <debug> [1465836169.508375] [mm-bearer-mbim.c:656]
provisioned_contexts_query_ready(): Provisioned contexts found (1):
ModemManager[15695]: <debug> [1465836169.508392] [mm-bearer-mbim.c:662]
provisioned_contexts_query_ready(): [1] context type: internet
ModemManager[15695]: <debug> [1465836169.508401] [mm-bearer-mbim.c:663]
provisioned_contexts_query_ready(): uuid:
7e5e2a7e-4e6f-7272-736b-656e7e5e2a7e
ModemManager[15695]: <debug> [1465836169.508409] [mm-bearer-mbim.c:664]
provisioned_contexts_query_ready(): access string: h2g2
ModemManager[15695]: <debug> [1465836169.508417] [mm-bearer-mbim.c:665]
provisioned_contexts_query_ready(): username:
ModemManager[15695]: <debug> [1465836169.508425] [mm-bearer-mbim.c:666]
provisioned_contexts_query_ready(): password:
ModemManager[15695]: <debug> [1465836169.508435] [mm-bearer-mbim.c:667]
provisioned_contexts_query_ready(): compression: none
ModemManager[15695]: <debug> [1465836169.508443] [mm-bearer-mbim.c:668]
provisioned_contexts_query_ready(): auth: none
ModemManager[15695]: <debug> [1465836169.508470] [mm-bearer-mbim.c:868]
connect_context_step(): No specific IP family requested, defaulting to ipv4
ModemManager[15695]: <debug> [1465836169.508483] [mm-bearer-mbim.c:899]
connect_context_step(): Launching connection with APN 'h2g2'...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 116
<<<<<< data =
03:00:00:00:74:00:00:00:16: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:44:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:08: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:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:68:00:32:00:67:00:32:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 116
<<<<<< type = command (0x00000003)
<<<<<< transaction = 22
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'connect' (0x0000000c)
<<<<<< type = 'set' (0x00000001)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 80
>>>>>> data =
>>>>>> 07:00:00:80:50:00:00:00:00: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:24:00:00:00:00:00:00:00:02: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[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 80
>>>>>> type = indicate-status (0x80000007)
>>>>>> transaction = 0
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'connect' (0x0000000c)
ModemManager[15695]: <debug> [1465836169.572370]
[mm-broadband-modem-mbim.c:2219] device_notification_cb(): Received
notification (service 'basic-connect', command 'connect')
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 84
>>>>>> data =
>>>>>> 03:00:00:80:54:00:00:00:16: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[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 84
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 22
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'connect' (0x0000000c)
ModemManager[15695]: <debug> [1465836169.925088] [mm-bearer-mbim.c:611]
connect_set_ready(): Session ID '0': activated (IP type: ipv4)
ModemManager[15695]: <debug> [1465836169.925103] [mm-bearer-mbim.c:930]
connect_context_step(): Querying IP configuration...
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 108
<<<<<< data =
03:00:00:00:6C:00:00:00:17: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[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 108
<<<<<< type = command (0x00000003)
<<<<<< transaction = 23
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'ip-configuration' (0x0000000f)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> data =
>>>>>> 03:00:00:80:80:00:00:00:17: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:0F: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:02:00:00:00:48:00:00:00:00:00:00:00:00:00:00:00:DC:05:00:00:00:00:00:00:1C:00:00:00:1A:C3:D7:27:1A:C3:D7:28:0A:B1:00:22:0A:B1:00:D2
ModemManager[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 128
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 23
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'ip-configuration' (0x0000000f)
ModemManager[15695]: <debug> [1465836169.988458] [mm-bearer-mbim.c:319]
ip_configuration_query_ready(): IPv4 configuration available: 'address,
gateway, dns, mtu'
ModemManager[15695]: <debug> [1465836169.988468] [mm-bearer-mbim.c:325]
ip_configuration_query_ready(): IP addresses (1)
ModemManager[15695]: <debug> [1465836169.988539] [mm-bearer-mbim.c:329]
ip_configuration_query_ready(): IP [0]: '26.195.215.39/28'
ModemManager[15695]: <debug> [1465836169.988552] [mm-bearer-mbim.c:338]
ip_configuration_query_ready(): Gateway: '26.195.215.40'
ModemManager[15695]: <debug> [1465836169.988560] [mm-bearer-mbim.c:346]
ip_configuration_query_ready(): DNS addresses (2)
ModemManager[15695]: <debug> [1465836169.988571] [mm-bearer-mbim.c:351]
ip_configuration_query_ready(): DNS [0]: '10.177.0.34'
ModemManager[15695]: <debug> [1465836169.988581] [mm-bearer-mbim.c:351]
ip_configuration_query_ready(): DNS [1]: '10.177.0.210'
ModemManager[15695]: <debug> [1465836169.988588] [mm-bearer-mbim.c:359]
ip_configuration_query_ready(): MTU: '1500'
ModemManager[15695]: <debug> [1465836169.988594] [mm-bearer-mbim.c:365]
ip_configuration_query_ready(): IPv6 configuration available: 'none'
ModemManager[15695]: <debug> [1465836169.988633] [mm-port.c:95]
mm_port_set_connected(): (wwan0): port now connected
ModemManager[15695]: <debug> [1465836169.988649] [mm-base-bearer.c:601]
connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:18: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:14:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 24
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'packet-statistics' (0x00000014)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: <info> [1465836169.988935] [mm-iface-modem.c:1431]
__iface_modem_update_state_internal(): Modem
/org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
ModemManager[15695]: <info> [1465836169.989161] [mm-iface-modem-simple.c:602]
connection_step(): Simple connect state (8/8): All done
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 96
>>>>>> data =
>>>>>> 03:00:00:80:60:00:00:00:18: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:14:00:00:00:00:00:00:00:30: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[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 96
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 24
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-statistics' (0x00000014)
ModemManager[15695]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data =
03:00:00:00:30:00:00:00:19: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:14:00:00:00:00:00:00:00:00:00:00:00
ModemManager[15695]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 25
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'packet-statistics' (0x00000014)
<<<<<< type = 'query' (0x00000000)
ModemManager[15695]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 96
>>>>>> data =
>>>>>> 03:00:00:80:60:00:00:00:19: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:14:00:00:00:00:00:00:00:30: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[15695]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 96
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 25
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'packet-statistics' (0x00000014)
_______________________________________________
ModemManager-devel mailing list
ModemManager-devel@lists.freedesktop.org
https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
