Sierra EM7455 takes 3+ minutes to connect after suspend/resume

Bill R bill at datacat.com
Thu Nov 16 06:01:09 UTC 2017


Hello,

Having a similar, but different issue with another Sierra EM7455 in 
combination with Fedora 27 with ModemManager 1.6.10 and a thinkpad x270.

Running ModemManager in console producer the following attached output.  
Laptop is put into sleep mode at 1510810951.  After resuming it takes 
about 55 seconds on this try to appear in network manager and ready to 
connect.

 From an initial glance at this it seems that it spends a lot of time 
probing around and I see various 'serial command timed out'. Is this 
something that I can improve with various udev configurations?  Possibly 
there is a way to have MM prefer the last known working configuration so 
it doesnt have to try all the various plugins again?

Thanks for any tips.  Happy to submit whatever debug/other info or run 
whatever experiments may be helpful.

Best,

- Bill




-------------- next part --------------
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:05:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: <debug> [1510810946.599531] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'signal-state')
ModemManager[4393]: <debug> [1510810946.599627] [mm-broadband-modem-mbim.c:1865] basic_connect_notification_signal_state(): Signal state indication: 5 --> 16%
ModemManager[4393]: <debug> [1510810946.599798] [mm-iface-modem.c:1206] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (16)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:11:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: <debug> [1510810951.719119] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'signal-state')
ModemManager[4393]: <debug> [1510810951.719211] [mm-broadband-modem-mbim.c:1865] basic_connect_notification_signal_state(): Signal state indication: 17 --> 54%
ModemManager[4393]: <debug> [1510810951.719369] [mm-iface-modem.c:1206] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
ModemManager[4393]: <debug> [1510810958.986919] [mm-sleep-monitor.c:138] signal_cb(): [sleep-monitor] received PrepareForSleep signal: 1
ModemManager[4393]: <debug> [1510810958.986975] [main.c:63] sleeping_cb(): Removing devices... (sleeping)
ModemManager[4393]: <debug> [1510810958.987238] [mm-device.c:399] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' from path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 02:00:00:00:0C:00:00:00:14:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 12
<<<<<<   type        = close (0x00000002)
<<<<<<   transaction = 20
ModemManager[4393]: <debug> [1510810958.987643] [mm-sleep-monitor.c:68] drop_inhibitor(): [sleep-monitor] dropping systemd sleep inhibitor
ModemManager[4393]: <debug> [1510810958.987725] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810958.987748] [mm-port-serial.c:1369] _close_internal(): (ttyUSB2) closing serial port...
ModemManager[4393]: <debug> [1510810958.990965] [mm-port-serial.c:1417] _close_internal(): (ttyUSB2) serial port closed
ModemManager[4393]: <debug> [1510810958.991042] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB2) forced to close port
ModemManager[4393]: <debug> [1510810958.991075] [mm-port-serial.c:1353] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810958.991097] [mm-port-serial.c:1369] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[4393]: <debug> [1510810958.992868] [mm-port-serial.c:1417] _close_internal(): (ttyUSB0) serial port closed
ModemManager[4393]: <debug> [1510810958.992917] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB0) forced to close port
ModemManager[4393]: <debug> [1510810958.992949] [mm-base-modem.c:1390] finalize(): Modem (Sierra) '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' completely disposed
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 16
>>>>>>   data   = 02:00:00:80:10:00:00:00:14:00:00:00:00:00:00:00
ModemManager[4393]: <debug> [1510810972.278475] [mm-sleep-monitor.c:138] signal_cb(): [sleep-monitor] received PrepareForSleep signal: 0
ModemManager[4393]: <debug> [1510810972.278526] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor
ModemManager[4393]: <debug> [1510810972.278685] [main.c:70] resuming_cb(): Re-scanning (resuming)
ModemManager[4393]: <debug> [1510810972.278708] [mm-base-manager.c:502] mm_base_manager_start(): Starting automatic device scan...
ModemManager[4393]: <debug> [1510810972.387481] [mm-base-manager.c:541] mm_base_manager_start(): Finished device scan...
ModemManager[4393]: <debug> [1510810972.387720] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 9
ModemManager[4393]: <debug> [1510810972.388780] [mm-base-manager.c:392] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.389310] [mm-base-manager.c:392] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.389892] [mm-base-manager.c:392] device_added(): (tty/ttyS10): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.390473] [mm-base-manager.c:392] device_added(): (tty/ttyS11): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.391032] [mm-base-manager.c:392] device_added(): (tty/ttyS12): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.391695] [mm-base-manager.c:392] device_added(): (tty/ttyS13): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.392242] [mm-base-manager.c:392] device_added(): (tty/ttyS14): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.392802] [mm-base-manager.c:392] device_added(): (tty/ttyS15): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.393346] [mm-base-manager.c:392] device_added(): (tty/ttyS16): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.393913] [mm-base-manager.c:392] device_added(): (tty/ttyS17): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.394461] [mm-base-manager.c:392] device_added(): (tty/ttyS18): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.395013] [mm-base-manager.c:392] device_added(): (tty/ttyS19): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.395585] [mm-base-manager.c:392] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.396134] [mm-base-manager.c:392] device_added(): (tty/ttyS20): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.397328] [mm-base-manager.c:392] device_added(): (tty/ttyS21): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.397961] [mm-base-manager.c:392] device_added(): (tty/ttyS22): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.409909] [mm-base-manager.c:392] device_added(): (tty/ttyS23): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.410475] [mm-base-manager.c:392] device_added(): (tty/ttyS24): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.410982] [mm-base-manager.c:392] device_added(): (tty/ttyS25): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.411493] [mm-base-manager.c:392] device_added(): (tty/ttyS26): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.411986] [mm-base-manager.c:392] device_added(): (tty/ttyS27): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.412524] [mm-base-manager.c:392] device_added(): (tty/ttyS28): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.413044] [mm-base-manager.c:392] device_added(): (tty/ttyS29): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.413620] [mm-base-manager.c:392] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.414134] [mm-base-manager.c:392] device_added(): (tty/ttyS30): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.414759] [mm-base-manager.c:392] device_added(): (tty/ttyS31): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.415380] [mm-base-manager.c:392] device_added(): (tty/ttyS4): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.415896] [mm-base-manager.c:392] device_added(): (tty/ttyS5): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.416370] [mm-base-manager.c:392] device_added(): (tty/ttyS6): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.416852] [mm-base-manager.c:392] device_added(): (tty/ttyS7): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.417332] [mm-base-manager.c:392] device_added(): (tty/ttyS8): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.417821] [mm-base-manager.c:392] device_added(): (tty/ttyS9): port's parent platform driver is not whitelisted
ModemManager[4393]: <debug> [1510810972.421486] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 3: new support task for device: /sys/devices/pci0000:00/0000:00:1c.2/0000:03:00.0
ModemManager[4393]: <debug> [1510810972.421611] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 3: port grabbed: wlp3s0
ModemManager[4393]: <debug> [1510810972.421634] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 3,wlp3s0: new support task for port
ModemManager[4393]: <debug> [1510810972.421650] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 3,wlp3s0: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810972.422197] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 4: new support task for device: /sys/devices/pci0000:00/0000:00:1f.6
ModemManager[4393]: <debug> [1510810972.422301] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 4: port grabbed: enp0s31f6
ModemManager[4393]: <debug> [1510810972.422322] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 4,enp0s31f6: new support task for port
ModemManager[4393]: <debug> [1510810972.422338] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 4,enp0s31f6: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810973.922651] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 3: min wait time elapsed
ModemManager[4393]: <debug> [1510810973.923177] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlp3s0] filtered by drivers
ModemManager[4393]: <debug> [1510810973.923207] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923229] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923249] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923270] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923291] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923310] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923347] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlp3s0] filtered by udev tags
ModemManager[4393]: <debug> [1510810973.923368] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923387] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923433] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923455] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923474] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923494] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923514] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923535] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923556] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923578] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlp3s0] filtered by drivers
ModemManager[4393]: <debug> [1510810973.923600] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923635] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923656] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923677] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923700] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923722] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923743] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923764] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923787] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp3s0] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.923809] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlp3s0] filtered by drivers
ModemManager[4393]: <debug> [1510810973.923830] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlp3s0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.923852] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 3,wlp3s0: found '1' plugins to try
ModemManager[4393]: <debug> [1510810973.923871] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 3,wlp3s0: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810973.923895] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 3,wlp3s0: started
ModemManager[4393]: <debug> [1510810973.923914] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 3,wlp3s0: checking with plugin 'Generic'
ModemManager[4393]: <debug> [1510810973.923939] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlp3s0] probing deferred until result suggested
ModemManager[4393]: <debug> [1510810973.923967] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 4: min wait time elapsed
ModemManager[4393]: <debug> [1510810973.923987] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [enp0s31f6] filtered by drivers
ModemManager[4393]: <debug> [1510810973.924006] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924026] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924045] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924066] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924086] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924105] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924126] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [enp0s31f6] filtered by udev tags
ModemManager[4393]: <debug> [1510810973.924146] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924166] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924187] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924207] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924227] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924248] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924270] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924291] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924312] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924334] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [enp0s31f6] filtered by drivers
ModemManager[4393]: <debug> [1510810973.924357] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924379] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924416] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924438] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924460] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924486] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924506] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924527] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924549] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [enp0s31f6] filtered by subsystem
ModemManager[4393]: <debug> [1510810973.924571] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [enp0s31f6] filtered by drivers
ModemManager[4393]: <debug> [1510810973.924592] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810973.924613] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 4,enp0s31f6: found '1' plugins to try
ModemManager[4393]: <debug> [1510810973.924633] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 4,enp0s31f6: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810973.924657] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 4,enp0s31f6: started
ModemManager[4393]: <debug> [1510810973.924679] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 4,enp0s31f6: checking with plugin 'Generic'
ModemManager[4393]: <debug> [1510810973.924704] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [enp0s31f6] probing deferred until result suggested
ModemManager[4393]: <debug> [1510810973.924735] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 3,wlp3s0: deferring support check until result suggested
ModemManager[4393]: <debug> [1510810973.924763] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 4,enp0s31f6: deferring support check until result suggested
ModemManager[4393]: <debug> [1510810974.922829] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 3: min probing time elapsed
ModemManager[4393]: <debug> [1510810974.922943] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 3: still 1 running probes (0 active): wlp3s0
ModemManager[4393]: <debug> [1510810974.923004] [mm-plugin-manager.c:1015] device_context_continue(): [plugin manager] task 3: no active tasks to probe
ModemManager[4393]: <debug> [1510810974.923058] [mm-plugin-manager.c:340] port_context_set_suggestion(): [plugin manager] task 3,wlp3s0: deferred task completed, no suggested plugin
ModemManager[4393]: <debug> [1510810974.923113] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 3,wlp3s0: finished in '2.501479' seconds
ModemManager[4393]: <debug> [1510810974.923177] [mm-plugin-manager.c:898] device_context_set_best_plugin(): [plugin manager] task 3,wlp3s0: not supported by any plugin
ModemManager[4393]: <debug> [1510810974.923242] [mm-device.c:374] mm_device_ignore_port(): Fully ignoring port 'net/wlp3s0' from now on
ModemManager[4393]: <debug> [1510810974.923301] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 3: no more ports to probe
ModemManager[4393]: <debug> [1510810974.923358] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 3: finished in '2.501873' seconds
ModemManager[4393]: <info>  [1510810974.923486] [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:03:00.0': not supported by any plugin
ModemManager[4393]: <debug> [1510810974.923576] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 4: min probing time elapsed
ModemManager[4393]: <debug> [1510810974.923637] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 4: still 1 running probes (0 active): enp0s31f6
ModemManager[4393]: <debug> [1510810974.923692] [mm-plugin-manager.c:1015] device_context_continue(): [plugin manager] task 4: no active tasks to probe
ModemManager[4393]: <debug> [1510810974.923745] [mm-plugin-manager.c:340] port_context_set_suggestion(): [plugin manager] task 4,enp0s31f6: deferred task completed, no suggested plugin
ModemManager[4393]: <debug> [1510810974.923801] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 4,enp0s31f6: finished in '2.501480' seconds
ModemManager[4393]: <debug> [1510810974.923888] [mm-plugin-manager.c:898] device_context_set_best_plugin(): [plugin manager] task 4,enp0s31f6: not supported by any plugin
ModemManager[4393]: <debug> [1510810974.923935] [mm-device.c:374] mm_device_ignore_port(): Fully ignoring port 'net/enp0s31f6' from now on
ModemManager[4393]: <debug> [1510810974.923977] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 4: no more ports to probe
ModemManager[4393]: <debug> [1510810974.924019] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 4: finished in '2.501823' seconds
ModemManager[4393]: <info>  [1510810974.924075] [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[4393]: <debug> [1510810980.759690] [mm-plugin-manager.c:1448] mm_plugin_manager_device_support_check(): [plugin manager] task 5: new support task for device: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
ModemManager[4393]: <debug> [1510810980.759861] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 5: port grabbed: cdc-wdm0
ModemManager[4393]: <debug> [1510810980.759887] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 5,cdc-wdm0: new support task for port
ModemManager[4393]: <debug> [1510810980.759903] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 5,cdc-wdm0: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810980.777968] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 5: port grabbed: ttyUSB0
ModemManager[4393]: <debug> [1510810980.778023] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 5,ttyUSB0: new support task for port
ModemManager[4393]: <debug> [1510810980.778042] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 5,ttyUSB0: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810980.786611] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 5: port grabbed: ttyUSB1
ModemManager[4393]: <debug> [1510810980.786661] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 5,ttyUSB1: new support task for port
ModemManager[4393]: <debug> [1510810980.786679] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 5,ttyUSB1: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810980.815747] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 5: port grabbed: ttyUSB2
ModemManager[4393]: <debug> [1510810980.815803] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 5,ttyUSB2: new support task for port
ModemManager[4393]: <debug> [1510810980.815828] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 5,ttyUSB2: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810980.826476] [mm-plugin-manager.c:1173] device_context_port_grabbed(): [plugin manager] task 5: port grabbed: wwp0s20f0u3i12
ModemManager[4393]: <debug> [1510810980.826534] [mm-plugin-manager.c:1198] device_context_port_grabbed(): [plugin manager] task 5,wwp0s20f0u3i12: new support task for port
ModemManager[4393]: <debug> [1510810980.826554] [mm-plugin-manager.c:1203] device_context_port_grabbed(): [plugin manager) task 5,wwp0s20f0u3i12: deferred until min wait time elapsed
ModemManager[4393]: <debug> [1510810982.262012] [mm-plugin-manager.c:1116] device_context_min_wait_time_elapsed(): [plugin manager] task 5: min wait time elapsed
ModemManager[4393]: <debug> [1510810982.262191] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wwp0s20f0u3i12] filtered by drivers
ModemManager[4393]: <debug> [1510810982.262291] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.262442] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.262542] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.262676] [mm-plugin.c:310] apply_pre_probing_filters(): (Cinterion) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.262764] [mm-plugin.c:310] apply_pre_probing_filters(): (Samsung) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.262872] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.262960] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wwp0s20f0u3i12] filtered by udev tags
ModemManager[4393]: <debug> [1510810982.263055] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.263127] [mm-plugin.c:310] apply_pre_probing_filters(): (Pantech) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.263200] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263267] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263323] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wwp0s20f0u3i12] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.263438] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263519] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wwp0s20f0u3i12] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.263598] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263679] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263738] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wwp0s20f0u3i12] filtered by drivers
ModemManager[4393]: <debug> [1510810982.263808] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia (Icera)) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.263870] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.263939] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.264014] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel LTE) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.264074] [mm-plugin.c:310] apply_pre_probing_filters(): (SimTech) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.264140] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.264213] [mm-plugin.c:310] apply_pre_probing_filters(): (AnyDATA) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.264281] [mm-plugin.c:310] apply_pre_probing_filters(): (Altair LTE) [wwp0s20f0u3i12] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.264341] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwp0s20f0u3i12] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.264463] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 5,wwp0s20f0u3i12: found '2' plugins to try
ModemManager[4393]: <debug> [1510810982.264538] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,wwp0s20f0u3i12: will try with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.264610] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,wwp0s20f0u3i12: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810982.264692] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 5,wwp0s20f0u3i12: started
ModemManager[4393]: <debug> [1510810982.264758] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,wwp0s20f0u3i12: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.264844] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwp0s20f0u3i12] probing deferred until result suggested
ModemManager[4393]: <debug> [1510810982.264924] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [ttyUSB2] filtered by drivers
ModemManager[4393]: <debug> [1510810982.264996] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265064] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265134] [mm-plugin.c:310] apply_pre_probing_filters(): (Telit) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265206] [mm-plugin.c:310] apply_pre_probing_filters(): (Cinterion) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265273] [mm-plugin.c:310] apply_pre_probing_filters(): (Samsung) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265345] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by forbidden drivers
ModemManager[4393]: <debug> [1510810982.265458] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags
ModemManager[4393]: <debug> [1510810982.265522] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265590] [mm-plugin.c:310] apply_pre_probing_filters(): (Pantech) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265663] [mm-plugin.c:310] apply_pre_probing_filters(): (Haier) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265724] [mm-plugin.c:310] apply_pre_probing_filters(): (MTK) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265789] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [ttyUSB2] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.265848] [mm-plugin.c:310] apply_pre_probing_filters(): (Linktop) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.265915] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [ttyUSB2] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.265985] [mm-plugin.c:264] apply_pre_probing_filters(): (Option) [ttyUSB2] filtered by drivers
ModemManager[4393]: <debug> [1510810982.266055] [mm-plugin.c:310] apply_pre_probing_filters(): (Iridium) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266127] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers
ModemManager[4393]: <debug> [1510810982.266202] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266263] [mm-plugin.c:310] apply_pre_probing_filters(): (Motorola) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266330] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266445] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266523] [mm-plugin.c:310] apply_pre_probing_filters(): (SimTech) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266595] [mm-plugin.c:310] apply_pre_probing_filters(): (Thuraya) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266665] [mm-plugin.c:310] apply_pre_probing_filters(): (AnyDATA) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266724] [mm-plugin.c:310] apply_pre_probing_filters(): (Altair LTE) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266791] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.266870] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 5,ttyUSB2: found '2' plugins to try
ModemManager[4393]: <debug> [1510810982.266955] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB2: will try with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.267019] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB2: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810982.267098] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 5,ttyUSB2: started
ModemManager[4393]: <debug> [1510810982.267162] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,ttyUSB2: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.267232] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB2] probe required: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.267254] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.267284] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [ttyUSB1] filtered by drivers
ModemManager[4393]: <debug> [1510810982.267304] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267326] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267348] [mm-plugin.c:310] apply_pre_probing_filters(): (Telit) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267368] [mm-plugin.c:310] apply_pre_probing_filters(): (Cinterion) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267401] [mm-plugin.c:310] apply_pre_probing_filters(): (Samsung) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267423] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by forbidden drivers
ModemManager[4393]: <debug> [1510810982.267447] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
ModemManager[4393]: <debug> [1510810982.267468] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267490] [mm-plugin.c:310] apply_pre_probing_filters(): (Pantech) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267513] [mm-plugin.c:310] apply_pre_probing_filters(): (Haier) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267535] [mm-plugin.c:310] apply_pre_probing_filters(): (MTK) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267558] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [ttyUSB1] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.267580] [mm-plugin.c:310] apply_pre_probing_filters(): (Linktop) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267602] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [ttyUSB1] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.267624] [mm-plugin.c:264] apply_pre_probing_filters(): (Option) [ttyUSB1] filtered by drivers
ModemManager[4393]: <debug> [1510810982.267646] [mm-plugin.c:310] apply_pre_probing_filters(): (Iridium) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267667] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
ModemManager[4393]: <debug> [1510810982.267689] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267711] [mm-plugin.c:310] apply_pre_probing_filters(): (Motorola) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267733] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267757] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267779] [mm-plugin.c:310] apply_pre_probing_filters(): (SimTech) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267801] [mm-plugin.c:310] apply_pre_probing_filters(): (Thuraya) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267824] [mm-plugin.c:310] apply_pre_probing_filters(): (AnyDATA) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267846] [mm-plugin.c:310] apply_pre_probing_filters(): (Altair LTE) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267868] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.267890] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 5,ttyUSB1: found '2' plugins to try
ModemManager[4393]: <debug> [1510810982.267912] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB1: will try with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.267934] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB1: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810982.267958] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 5,ttyUSB1: started
ModemManager[4393]: <debug> [1510810982.267980] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,ttyUSB1: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.268010] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB1] probe required: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.268034] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.268061] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [ttyUSB0] filtered by drivers
ModemManager[4393]: <debug> [1510810982.268082] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268103] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268125] [mm-plugin.c:310] apply_pre_probing_filters(): (Telit) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268147] [mm-plugin.c:310] apply_pre_probing_filters(): (Cinterion) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268183] [mm-plugin.c:310] apply_pre_probing_filters(): (Samsung) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268205] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by forbidden drivers
ModemManager[4393]: <debug> [1510810982.268228] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
ModemManager[4393]: <debug> [1510810982.268249] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268269] [mm-plugin.c:310] apply_pre_probing_filters(): (Pantech) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268291] [mm-plugin.c:310] apply_pre_probing_filters(): (Haier) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268313] [mm-plugin.c:310] apply_pre_probing_filters(): (MTK) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268333] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [ttyUSB0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.268356] [mm-plugin.c:310] apply_pre_probing_filters(): (Linktop) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268378] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [ttyUSB0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.268418] [mm-plugin.c:264] apply_pre_probing_filters(): (Option) [ttyUSB0] filtered by drivers
ModemManager[4393]: <debug> [1510810982.268440] [mm-plugin.c:310] apply_pre_probing_filters(): (Iridium) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268461] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
ModemManager[4393]: <debug> [1510810982.268482] [mm-plugin.c:310] apply_pre_probing_filters(): (Nokia (Icera)) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268504] [mm-plugin.c:310] apply_pre_probing_filters(): (Motorola) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268525] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268547] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel LTE) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268568] [mm-plugin.c:310] apply_pre_probing_filters(): (SimTech) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268591] [mm-plugin.c:310] apply_pre_probing_filters(): (Thuraya) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268612] [mm-plugin.c:310] apply_pre_probing_filters(): (AnyDATA) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268633] [mm-plugin.c:310] apply_pre_probing_filters(): (Altair LTE) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268654] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268676] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 5,ttyUSB0: found '2' plugins to try
ModemManager[4393]: <debug> [1510810982.268698] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB0: will try with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.268719] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,ttyUSB0: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810982.268742] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 5,ttyUSB0: started
ModemManager[4393]: <debug> [1510810982.268762] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,ttyUSB0: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.268790] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB0] probe required: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.268815] [mm-port-probe.c:1388] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm'
ModemManager[4393]: <debug> [1510810982.268838] [mm-plugin.c:212] apply_pre_probing_filters(): (Sierra (legacy)) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268859] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268879] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268896] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268916] [mm-plugin.c:310] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.268934] [mm-plugin.c:212] apply_pre_probing_filters(): (Samsung) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268953] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.268978] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
ModemManager[4393]: <debug> [1510810982.268999] [mm-plugin.c:310] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.269021] [mm-plugin.c:310] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.269042] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269063] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269080] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.269100] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269117] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[4393]: <debug> [1510810982.269136] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269154] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269173] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269192] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269212] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269236] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.269257] [mm-plugin.c:212] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269278] [mm-plugin.c:310] apply_pre_probing_filters(): (SimTech) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.269298] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269320] [mm-plugin.c:310] apply_pre_probing_filters(): (AnyDATA) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[4393]: <debug> [1510810982.269342] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269362] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
ModemManager[4393]: <debug> [1510810982.269383] [mm-plugin-manager.c:605] port_context_run(): [plugin manager] task 5,cdc-wdm0: found '2' plugins to try
ModemManager[4393]: <debug> [1510810982.269416] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,cdc-wdm0: will try with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.269440] [mm-plugin-manager.c:628] port_context_run(): [plugin manager] task 5,cdc-wdm0: will try with plugin 'Generic'
ModemManager[4393]: <debug> [1510810982.269460] [mm-plugin-manager.c:641] port_context_run(): [plugin manager) task 5,cdc-wdm0: started
ModemManager[4393]: <debug> [1510810982.269482] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,cdc-wdm0: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810982.269510] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [cdc-wdm0] probe required: 'mbim'
ModemManager[4393]: <debug> [1510810982.269531] [mm-port-probe.c:1388] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
ModemManager[4393]: <debug> [1510810982.269563] [mm-plugin-manager.c:456] port_context_defer_until_suggested(): [plugin manager] task 5,wwp0s20f0u3i12: deferring support check until result suggested
ModemManager[4393]: <debug> [1510810982.269647] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[4393]: <warn>  [1510810982.269981] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[4393]: <debug> [1510810982.270030] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[4393]: <debug> [1510810982.270142] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB1) opening serial port...
ModemManager[4393]: <warn>  [1510810982.270312] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[4393]: <debug> [1510810982.270348] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[4393]: <debug> [1510810982.270486] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[4393]: <warn>  [1510810982.270645] [mm-port-serial.c:513] real_config_fd(): (ttyUSB0): port attributes not fully set
ModemManager[4393]: <debug> [1510810982.270681] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[4393]: <debug> [1510810982.270724] [mm-port-probe.c:551] wdm_probe_mbim(): (usbmisc/cdc-wdm0) probing MBIM...
ModemManager[4393]: opening device...
ModemManager[4393]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[4393]: [/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[4393]: [/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[4393]: <debug> [1510810982.371128] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810982.371237] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810982.371317] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810983.261708] [mm-plugin-manager.c:1064] device_context_min_probing_time_elapsed(): [plugin manager] task 5: min probing time elapsed
ModemManager[4393]: <debug> [1510810983.261872] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 5: still 5 running probes (4 active): wwp0s20f0u3i12, ttyUSB2, ttyUSB1, ttyUSB0, cdc-wdm0
ModemManager[4393]: <debug> [1510810985.528639] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810985.528806] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810985.528925] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810985.529127] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810985.529354] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810985.529593] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810988.240813] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- 'AT<CR><CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510810988.240916] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
ModemManager[4393]: <debug> [1510810988.240946] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 5,ttyUSB2: found best plugin for port (Sierra)
ModemManager[4393]: <debug> [1510810988.240968] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 5,ttyUSB2: finished in '7.425165' seconds
ModemManager[4393]: <debug> [1510810988.240993] [mm-plugin-manager.c:919] device_context_set_best_plugin(): [plugin manager] task 5,ttyUSB2: found best plugin: Sierra
ModemManager[4393]: <debug> [1510810988.241023] [mm-plugin-manager.c:329] port_context_set_suggestion(): [plugin manager] task 5,wwp0s20f0u3i12: deferred task completed, got suggested plugin (Sierra)
ModemManager[4393]: <debug> [1510810988.241048] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 5,ttyUSB1: got suggested plugin (Sierra)
ModemManager[4393]: <debug> [1510810988.241067] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 5,ttyUSB0: got suggested plugin (Sierra)
ModemManager[4393]: <debug> [1510810988.241084] [mm-plugin-manager.c:369] port_context_set_suggestion(): [plugin manager] task 5,cdc-wdm0: got suggested plugin (Sierra)
ModemManager[4393]: <debug> [1510810988.241106] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 5: still 4 running probes (4 active): wwp0s20f0u3i12, ttyUSB1, ttyUSB0, cdc-wdm0
ModemManager[4393]: <debug> [1510810988.241140] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810988.241161] [mm-port-serial.c:1369] _close_internal(): (ttyUSB2) closing serial port...
ModemManager[4393]: <debug> [1510810988.241854] [mm-port-serial.c:1417] _close_internal(): (ttyUSB2) serial port closed
ModemManager[4393]: <debug> [1510810988.241905] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB2) forced to close port
ModemManager[4393]: <debug> [1510810988.241960] [mm-plugin-manager.c:522] port_context_next(): [plugin manager] task 5,wwp0s20f0u3i12: checking with plugin 'Sierra'
ModemManager[4393]: <debug> [1510810988.241993] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwp0s20f0u3i12] probing deferred until result suggested
ModemManager[4393]: <debug> [1510810988.242026] [mm-plugin-manager.c:437] port_context_defer_until_suggested(): [plugin manager] task 5,wwp0s20f0u3i12: completed, got suggested plugin (Sierra)
ModemManager[4393]: <debug> [1510810988.242048] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 5,wwp0s20f0u3i12: finished in '7.415516' seconds
ModemManager[4393]: <debug> [1510810988.242071] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 5,wwp0s20f0u3i12: best plugin matches device reported one: Sierra
ModemManager[4393]: <debug> [1510810988.242095] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 5: still 3 running probes (3 active): ttyUSB1, ttyUSB0, cdc-wdm0
ModemManager[4393]: <debug> [1510810988.526531] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810988.526652] [mm-port-serial-at.c:460] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810991.528572] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810991.528747] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810991.528875] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
ModemManager[4393]: <debug> [1510810991.529049] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
ModemManager[4393]: <debug> [1510810991.529168] [mm-port-serial.c:1353] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810991.529305] [mm-port-serial.c:1369] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[4393]: <debug> [1510810991.531330] [mm-port-serial.c:1417] _close_internal(): (ttyUSB0) serial port closed
ModemManager[4393]: <debug> [1510810991.531571] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB0) forced to close port
ModemManager[4393]: <debug> [1510810991.531700] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[4393]: <debug> [1510810991.532236] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[4393]: <debug> [1510810991.532446] [mm-port-serial-at.c:460] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[4393]: <debug> [1510810991.532598] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
ModemManager[4393]: <debug> [1510810991.535086] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 00 4a 75 6e 20 33 30 20 32 30 31 36 31 31 3a 31 31 3a 32 31 4a 75 6e 20 31 37 20 32 30 31 36 30 36 3a 30 30 3a 30 30 43 32 32 30 30 33 30 30 3a 06 ff 64 00 02 09 22 f4 d4 7e
ModemManager[4393]: <debug> [1510810991.535229] [mm-port-probe.c:243] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
ModemManager[4393]: <debug> [1510810991.535328] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 5,ttyUSB0: found best plugin for port (Sierra)
ModemManager[4393]: <debug> [1510810991.535478] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 5,ttyUSB0: finished in '10.757456' seconds
ModemManager[4393]: <debug> [1510810991.535581] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 5,ttyUSB0: best plugin matches device reported one: Sierra
ModemManager[4393]: <debug> [1510810991.535670] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 5: still 2 running probes (2 active): ttyUSB1, cdc-wdm0
ModemManager[4393]: <debug> [1510810991.535781] [mm-port-serial.c:1353] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810991.535865] [mm-port-serial.c:1369] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[4393]: <debug> [1510810991.536699] [mm-port-serial.c:1417] _close_internal(): (ttyUSB0) serial port closed
ModemManager[4393]: <debug> [1510810991.536821] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB0) forced to close port
ModemManager[4393]: <debug> [1510810994.529508] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[4393]: <debug> [1510810994.529695] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
ModemManager[4393]: <debug> [1510810994.529859] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM...
ModemManager[4393]: <debug> [1510810994.529940] [mm-port-serial.c:1353] _close_internal(): (ttyUSB1) device open count is 0 (close)
ModemManager[4393]: <debug> [1510810994.530031] [mm-port-serial.c:1369] _close_internal(): (ttyUSB1) closing serial port...
ModemManager[4393]: <debug> [1510810994.531067] [mm-port-serial.c:1417] _close_internal(): (ttyUSB1) serial port closed
ModemManager[4393]: <debug> [1510810994.531198] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB1) forced to close port
ModemManager[4393]: <debug> [1510810994.531309] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB1) opening serial port...
ModemManager[4393]: <debug> [1510810994.531973] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[4393]: <debug> [1510810994.532152] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 2
<<<<<< Contents:
<<<<<<   max_control_transfer = 4096
ModemManager[4393]: [/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[4393]: <debug> [1510810995.371904] [mm-port-probe.c:304] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is MBIM-capable
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 02:00:00:00:0C:00:00:00:03:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 12
<<<<<<   type        = close (0x00000002)
<<<<<<   transaction = 3
ModemManager[4393]: [/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[4393]: <debug> [1510810995.372849] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 5,cdc-wdm0: found best plugin for port (Sierra)
ModemManager[4393]: <debug> [1510810995.372952] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 5,cdc-wdm0: finished in '14.613064' seconds
ModemManager[4393]: <debug> [1510810995.373038] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 5,cdc-wdm0: best plugin matches device reported one: Sierra
ModemManager[4393]: <debug> [1510810995.373108] [mm-plugin-manager.c:1011] device_context_continue(): [plugin Manager] task 5: still 1 running probes (1 active): ttyUSB1
ModemManager[4393]: <debug> [1510810997.528640] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
ModemManager[4393]: <debug> [1510811000.529240] [mm-port-probe.c:261] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is not QCDM-capable
ModemManager[4393]: <debug> [1510811000.529485] [mm-plugin-manager.c:286] port_context_supported(): [plugin manager] task 5,ttyUSB1: found best plugin for port (Sierra)
ModemManager[4393]: <debug> [1510811000.529602] [mm-plugin-manager.c:268] port_context_complete(): [plugin manager] task 5,ttyUSB1: finished in '19.742941' seconds
ModemManager[4393]: <debug> [1510811000.529752] [mm-plugin-manager.c:972] device_context_set_best_plugin(): [plugin manager] task 5,ttyUSB1: best plugin matches device reported one: Sierra
ModemManager[4393]: <debug> [1510811000.529854] [mm-plugin-manager.c:985] device_context_continue(): [plugin manager] task 5: no more ports to probe
ModemManager[4393]: <debug> [1510811000.529947] [mm-plugin-manager.c:819] device_context_complete(): [plugin manager] task 5: finished in '19.770259' seconds
ModemManager[4393]: <info>  [1510811000.530086] [mm-device.c:538] mm_device_create_modem(): Creating modem with plugin 'Sierra' and '5' ports
ModemManager[4393]: <debug> [1510811000.530212] [sierra/mm-plugin-sierra.c:66] create_modem(): MBIM-powered Sierra modem found...
ModemManager[4393]: <debug> [1510811000.530739] [mm-base-modem.c:280] mm_base_modem_grab_port(): (wwp0s20f0u3i12) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
ModemManager[4393]: <debug> [1510811000.530956] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
ModemManager[4393]: <warn>  [1510811000.531051] [mm-plugin.c:957] mm_plugin_create_modem(): Could not grab port (tty/ttyUSB1): 'Cannot add port 'tty/ttyUSB1', unhandled serial type'
ModemManager[4393]: <debug> [1510811000.531159] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB0) type 'qcdm' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
ModemManager[4393]: <debug> [1510811000.531258] [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-3
ModemManager[4393]: <debug> [1510811000.531337] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) tty/ttyUSB2 at (primary)
ModemManager[4393]: <debug> [1510811000.531454] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) net/wwp0s20f0u3i12 data (primary)
ModemManager[4393]: <debug> [1510811000.531520] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) tty/ttyUSB0 qcdm
ModemManager[4393]: <debug> [1510811000.531589] [mm-base-modem.c:875] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) usb/cdc-wdm0 mbim (primary)
ModemManager[4393]: <info>  [1510811000.532139] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' successfully created
ModemManager[4393]: <debug> [1510811000.532280] [mm-port-serial.c:1353] _close_internal(): (ttyUSB1) device open count is 0 (close)
ModemManager[4393]: <debug> [1510811000.532368] [mm-port-serial.c:1369] _close_internal(): (ttyUSB1) closing serial port...
ModemManager[4393]: <debug> [1510811000.532967] [mm-port-serial.c:1417] _close_internal(): (ttyUSB1) serial port closed
ModemManager[4393]: <debug> [1510811000.533004] [mm-port-serial.c:1479] port_serial_close_force(): (ttyUSB1) forced to close port
ModemManager[4393]: opening device...
ModemManager[4393]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 2
<<<<<< Contents:
<<<<<<   max_control_transfer = 4096
ModemManager[4393]: [/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[4393]: <debug> [1510811000.555159] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[4393]: <warn>  [1510811000.555752] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[4393]: <debug> [1510811000.555870] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[4393]: <debug> [1510811000.555911] [mm-port-serial-at.c:496] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
ModemManager[4393]: <debug> [1510811000.556006] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811000.556053] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[4393]: <debug> [1510811000.556457] [mm-broadband-modem-mbim.c:221] modem_load_current_capabilities(): loading current capabilities...
ModemManager[4393]: [/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[4393]: [/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[4393]: <debug> [1510811000.556645] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[4393]: <debug> [1510811000.562112] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- 'AT'
ModemManager[4393]: <debug> [1510811000.567780] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- 'E0<CR>'
ModemManager[4393]: <debug> [1510811000.568693] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.568856] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
ModemManager[4393]: <debug> [1510811000.575823] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.575916] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[4393]: <debug> [1510811000.589661] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.589719] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
ModemManager[4393]: <debug> [1510811000.601802] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.601916] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
ModemManager[4393]: <debug> [1510811000.614360] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.614497] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[4393]: [/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:38:00:38:00:38:00:33:00:31:00:30:00:31: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:32:00:30:00:2E:00:30:00:33:00:2E:00:30:00:30:00:45:00:4D:00:37:00:34:00:35:00:35:00
ModemManager[4393]: [/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[4393]: <debug> [1510811000.618570] [mm-broadband-modem-mbim.c:147] modem_load_current_capabilities_finish(): loaded modem capabilities: gsm-umts, lte
ModemManager[4393]: <debug> [1510811000.618594] [mm-iface-modem.c:3902] load_current_capabilities_ready(): Setting EPS network as supported
ModemManager[4393]: <debug> [1510811000.618655] [mm-iface-modem.c:4360] interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[4393]: <debug> [1510811000.618681] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[4393]: <debug> [1510811000.618781] [mm-modem-helpers.c:310] mm_create_device_identifier(): Device ID source '0000119900009079014582008883101SWI9X30C_02.20.03.00MBIM[1199:9079]Sierra'
ModemManager[4393]: <debug> [1510811000.618799] [mm-modem-helpers.c:311] mm_create_device_identifier(): Device ID '45d18b8394e0e0683a3db9404b33763f0b3d6b02'
ModemManager[4393]: [/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[4393]: [/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[4393]: <debug> [1510811000.626258] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.626314] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[4393]: <debug> [1510811000.626351] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[4393]: <debug> [1510811000.640211] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811000.640324] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: [/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:00:00:00:00
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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:28:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:39:00:30:00:39:00:33:00:36:00:32:00:37:00:34:00:37:00:34:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:00:00:38:00:39:00:30:00:31:00:34:00:31:00:30:00:33:00:32:00:37:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:31:00
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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:03:00:00:00
ModemManager[4393]: [/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[4393]: <debug> [1510811000.811693] [mm-iface-modem.c:3106] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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:28:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:39:00:30:00:39:00:33:00:36:00:32:00:37:00:34:00:37:00:34:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:00:00:38:00:39:00:30:00:31:00:34:00:31:00:30:00:33:00:32:00:37:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:31:00
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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:28:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:39:00:30:00:39:00:33:00:36:00:32:00:37:00:34:00:37:00:34:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:00:00:38:00:39:00:30:00:31:00:34:00:31:00:30:00:33:00:32:00:37:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:31:00
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 100
>>>>>>   data   = 03:00:00:80:64: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:34:00:00:00:20:00:00:00:0C:00:00:00:01:00:00:00:2C:00:00:00:08:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:41:00:54:00:26:00:54:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 100
>>>>>>   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[4393]: [/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[4393]: [/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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 100
>>>>>>   data   = 03:00:00:80:64: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:34:00:00:00:20:00:00:00:0C:00:00:00:01:00:00:00:2C:00:00:00:08:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:41:00:54:00:26:00:54:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 100
>>>>>>   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[4393]: [/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[4393]: [/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[4393]: [/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:28:00:00:00:00:00:00:00:01:00:00:00:24:00:00:00:16:00:00:00:31:00:39:00:30:00:39:00:33:00:36:00:32:00:37:00:34:00:37:00:34:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:00:00:38:00:39:00:30:00:31:00:34:00:31:00:30:00:33:00:32:00:37:00:36:00:38:00:33:00:30:00:36:00:37:00:32:00:37:00:35:00:31:00
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: <debug> [1510811001.194669] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811001.194713] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CUSD=?<CR>'
ModemManager[4393]: <debug> [1510811001.212639] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.212755] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811001.212906] [mm-broadband-modem-mbim.c:2925] messaging_check_support(): Messaging capabilities supported
ModemManager[4393]: <debug> [1510811001.212960] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported storages loaded:
ModemManager[4393]: <debug> [1510811001.212984] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'mt'
ModemManager[4393]: <debug> [1510811001.213000] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready():   mem2 (write/send) storages:       'mt'
ModemManager[4393]: <debug> [1510811001.213021] [mm-iface-modem-messaging.c:1152] load_supported_storages_ready():   mem3 (reception) storages:        'mt'
ModemManager[4393]: <debug> [1510811001.213058] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811001.213097] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
ModemManager[4393]: <debug> [1510811001.233188] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: "SM",0,30,"SM",0,30,"SR",0,30<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.233481] [mm-broadband-modem.c:5280] cpms_query_ready(): Current storages initialized:
ModemManager[4393]: <debug> [1510811001.233528] [mm-broadband-modem.c:5283] cpms_query_ready():   mem1 (list/read/delete) storages: 'sm'
ModemManager[4393]: <debug> [1510811001.233544] [mm-broadband-modem.c:5287] cpms_query_ready():   mem2 (write/send) storages:       'sm'
ModemManager[4393]: <debug> [1510811001.233565] [mm-iface-modem-messaging.c:1232] init_current_storages_ready(): Current storages initialized
ModemManager[4393]: <debug> [1510811001.233662] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811001.233731] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811001.233767] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATH<CR>'
ModemManager[4393]: <debug> [1510811001.244057] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.244163] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811001.244219] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811001.244259] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CTZU=1<CR>'
ModemManager[4393]: <debug> [1510811001.265799] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.266103] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CCLK?<CR>'
ModemManager[4393]: <debug> [1510811001.285667] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>+CCLK: "80/01/06,00:00:14-32"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.286000] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811001.286154] [mm-broadband-modem.c:9714] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
ModemManager[4393]: <debug> [1510811001.286311] [mm-broadband-modem.c:9715] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
ModemManager[4393]: <debug> [1510811001.286453] [mm-broadband-modem.c:9716] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available'
ModemManager[4393]: <info>  [1510811001.286585] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[4393]: <debug> [1510811001.286717] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 0 (close)
ModemManager[4393]: <debug> [1510811001.286768] [mm-port-serial.c:1369] _close_internal(): (ttyUSB2) closing serial port...
ModemManager[4393]: <debug> [1510811001.288739] [mm-port-serial.c:1417] _close_internal(): (ttyUSB2) serial port closed
ModemManager[4393]: <debug> [1510811001.288805] [mm-base-modem.c:844] initialize_ready(): modem properly initialized
ModemManager[4393]: <debug> [1510811001.289314] [mm-device.c:453] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' at path '/org/freedesktop/ModemManager1/Modem/1'
ModemManager[4393]: <debug> [1510811001.289351] [mm-device.c:463] export_modem(): (/org/freedesktop/ModemManager1/Modem/1): 'Sierra' modem, VID 0x1199 PID 0x9079 (usb)
ModemManager[4393]: <info>  [1510811001.293955] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling)
ModemManager[4393]: <debug> [1510811001.294130] [mm-broadband-modem.c:8617] enabling_started(): Skipping modem initialization: device hotplugged
ModemManager[4393]: <debug> [1510811001.294163] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[4393]: <warn>  [1510811001.294641] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[4393]: <debug> [1510811001.294683] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[4393]: <debug> [1510811001.294702] [mm-port-serial-at.c:496] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
ModemManager[4393]: <debug> [1510811001.294733] [mm-port-serial.c:1166] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[4393]: <debug> [1510811001.294916] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[4393]: <debug> [1510811001.294936] [mm-broadband-modem.c:8633] enabling_started(): Flashing primary AT port before enabling...
ModemManager[4393]: <debug> [1510811001.294981] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[4393]: <debug> [1510811001.304336] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.304447] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
ModemManager[4393]: <debug> [1510811001.314261] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.314554] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[4393]: <debug> [1510811001.332414] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.332506] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
ModemManager[4393]: <debug> [1510811001.342064] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.342359] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
ModemManager[4393]: <debug> [1510811001.360626] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: [/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[4393]: [/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[4393]: [/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:00:00:00:00
ModemManager[4393]: [/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[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34: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:03:00:00:00:01:00:00:00:04:00:00:00:01:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 14
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'radio-state' (0x00000003)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 56
>>>>>>   data   = 03:00:00:80:38: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:03:00:00:00:02:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 56
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 14
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'Failure' (0x00000002)
>>>>>>   service      = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid          = 'radio-state' (0x00000003)
ModemManager[4393]: <warn>  [1510811001.483527] [mm-broadband-modem-mbim.c:1227] radio_state_set_up_ready(): Failure
ModemManager[4393]: [/dev/cdc-wdm0] Opening device with flags 'proxy, mbim'...
ModemManager[4393]: [/dev/cdc-wdm0] loaded driver of cdc-wdm port: cdc_mbim
ModemManager[4393]: [/dev/cdc-wdm0] creating MBIM device...
ModemManager[4393]: [/dev/cdc-wdm0] MBIM device created
ModemManager[4393]: [/dev/cdc-wdm0] opening MBIM device...
ModemManager[4393]: opening device...
ModemManager[4393]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[4393]: [/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:0F: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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 16
<<<<<<   type        = open (0x00000001)
<<<<<<   transaction = 2
<<<<<< Contents:
<<<<<<   max_control_transfer = 4096
ModemManager[4393]: [/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[4393]: [/dev/cdc-wdm0] MBIM device open
ModemManager[4393]: [/dev/cdc-wdm0] Assuming service 'dms' is supported...
ModemManager[4393]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[4393]: [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:01:22:00:04:00:01:01:00:02
ModemManager[4393]: [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = dms
ModemManager[4393]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 64
<<<<<<   data   = 03:00:00:00:40:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:10:00:00:00:01:0F:00:00:00:00:00:01:22:00:04:00:01:01:00:02
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 64
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 3
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<<   cid     = 'msg' (0x00000001)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 03:00:00:80:48:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:18:00:00:00:01:17:00:80:00:00:01:01:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 3
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>>   cid          = 'msg' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[4393]: [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:01:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Allocation Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:03
<<<<<<   translated = [ service = 'dms' cid = '3' ]
ModemManager[4393]: [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '3'
ModemManager[4393]: [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:02:03:00:01:00:5F:55:00:00
ModemManager[4393]: [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "dms"
<<<<<<   client  = 3
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Set FCC Authentication" (0x555F)
ModemManager[4393]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 61
<<<<<<   data   = 03:00:00:00:3D:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:0D:00:00:00:01:0C:00:00:02:03:00:01:00:5F:55:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 61
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 4
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<<   cid     = 'msg' (0x00000001)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 68
>>>>>>   data   = 03:00:00:80:44:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:14:00:00:00:01:13:00:80:02:03:02:01:00:5F:55:07:00:02:04:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 68
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 4
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>>   cid          = 'msg' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[4393]: [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:80:02:03:02:01:00:5F:55:07:00:02:04:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x80
<<<<<<   service = "dms"
<<<<<<   client  = 3
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Set FCC Authentication" (0x555F)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
ModemManager[4393]: [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
ModemManager[4393]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '3'
ModemManager[4393]: [/dev/cdc-wdm0] sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:02:23:00:05:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] sent generic request (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:03
<<<<<<   translated = [ service = 'dms' cid = '3' ]
ModemManager[4393]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 65
<<<<<<   data   = 03:00:00:00:41:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:11:00:00:00:01:10:00:00:00:00:00:02:23:00:05:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 65
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 5
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<<   cid     = 'msg' (0x00000001)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 07:00:00:80:34: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:03:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'radio-state' (0x00000003)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 07:00:00:80:34: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:03:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'radio-state' (0x00000003)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 92
>>>>>>   data   = 07:00:00:80:5C: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:09:00:00:00:30:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00: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
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 92
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'register-state' (0x00000009)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 92
>>>>>>   data   = 07:00:00:80:5C: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:09:00:00:00:30:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00: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
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 92
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'register-state' (0x00000009)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 03:00:00:80:48:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:18:00:00:00:01:17:00:80:00:00:01:02:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 5
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>>   cid          = 'msg' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[4393]: [/dev/cdc-wdm0] received message...
<<<<<< RAW:
<<<<<<   length = 24
<<<<<<   data   = 01:17:00:80:00:00:01:02:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[4393]: [/dev/cdc-wdm0] received generic response (translated)...
<<<<<< QMUX:
<<<<<<   length  = 23
<<<<<<   flags   = 0x80
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "response"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Result" (0x02)
<<<<<<   length     = 4
<<<<<<   value      = 00:00:00:00
<<<<<<   translated = SUCCESS
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 02:03
<<<<<<   translated = [ service = 'dms' cid = '3' ]
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34: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:03:00:00:00:01:00:00:00:04:00:00:00:01:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 15
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'radio-state' (0x00000003)
<<<<<<   type    = 'set' (0x00000001)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 56
>>>>>>   data   = 03:00:00:80:38: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:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 56
>>>>>>   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          = 'radio-state' (0x00000003)
ModemManager[4393]: [/dev/cdc-wdm0] Releasing '(null)' client with flags 'release-cid'...
ModemManager[4393]: <debug> [1510811001.930973] [mm-iface-modem.c:3221] modem_power_up_ready(): Modem set in full-power mode...
ModemManager[4393]: <debug> [1510811001.931211] [mm-broadband-modem.c:9278] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
ModemManager[4393]: <debug> [1510811001.931270] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: <debug> [1510811001.931339] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 96
<<<<<<   data   = 03:00:00:00:60: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: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 96
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 16
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 96
>>>>>>   data   = 03:00:00:80:60: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: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 96
>>>>>>   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          = 'device-service-subscribe-list' (0x00000013)
ModemManager[4393]: <debug> [1510811001.931914] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: <debug> [1510811001.931969] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 100
<<<<<<   data   = 03:00:00:00:64:00:00:00:11: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 100
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 17
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 100
>>>>>>   data   = 03:00:00:80:64:00:00:00:11: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 100
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 17
>>>>>> 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[4393]: <debug> [1510811001.932427] [mm-broadband-modem.c:9291] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
ModemManager[4393]: <debug> [1510811001.932478] [mm-broadband-modem.c:4915] set_unsolicited_result_code_handlers(): (ttyUSB2) Setting unsolicited result code handlers
ModemManager[4393]: <debug> [1510811001.932532] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811001.932579] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CUSD=1<CR>'
ModemManager[4393]: <debug> [1510811001.945617] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811001.945942] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811001.946214] [mm-broadband-modem.c:9319] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[4393]: <debug> [1510811001.946289] [mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[4393]: <debug> [1510811001.946459] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes')
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 48
<<<<<<   data   = 03:00:00:00:30: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:09:00:00:00:00:00:00:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 48
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 18
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<<   cid     = 'register-state' (0x00000009)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[4393]: <debug> [1510811001.946905] [mm-broadband-modem.c:9332] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[4393]: <debug> [1510811001.946959] [mm-broadband-modem-mbim.c:3080] load_initial_sms_parts(): loading SMS parts...
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 60
<<<<<<   data   = 03:00:00:00:3C:00:00:00:13: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 60
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 19
<<<<<< Fragment header:
<<<<<<   total   = 1
<<<<<<   current = 0
<<<<<< Contents:
<<<<<<   service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
<<<<<<   cid     = 'read' (0x00000002)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 96
>>>>>>   data   = 03:00:00:80:60: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:09:00:00:00:00:00:00:00:30:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00: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
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 96
>>>>>>   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          = 'register-state' (0x00000009)
ModemManager[4393]: <info>  [1510811001.994997] [mm-iface-modem-3gpp.c:1186] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> searching)
ModemManager[4393]: <debug> [1510811001.995125] [mm-iface-modem.c:1602] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 56
>>>>>>   data   = 03:00:00:80:38:00:00:00:13: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 56
>>>>>>   type        = command-done (0x80000003)
>>>>>>   transaction = 19
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   status error = 'None' (0x00000000)
>>>>>>   service      = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f)
>>>>>>   cid          = 'read' (0x00000002)
ModemManager[4393]: <debug> [1510811002.058948] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: <debug> [1510811002.059034] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 136
<<<<<<   data   = 03:00:00:00:88: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: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 136
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 20
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 136
>>>>>>   data   = 03:00:00:80:88: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: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 136
>>>>>>   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          = 'device-service-subscribe-list' (0x00000013)
ModemManager[4393]: <debug> [1510811002.059716] [mm-broadband-modem.c:9345] enabling_step(): Modem has voice capabilities, enabling the Voice interface...
ModemManager[4393]: <debug> [1510811002.059825] [mm-broadband-modem.c:6541] set_voice_unsolicited_events_handlers(): (ttyUSB2) Setting voice unsolicited events handlers
ModemManager[4393]: <debug> [1510811002.059897] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811002.059956] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CLIP=1<CR>'
ModemManager[4393]: <debug> [1510811002.072927] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811002.073178] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CRC=1<CR>'
ModemManager[4393]: <debug> [1510811002.083685] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811002.083809] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811002.083887] [mm-broadband-modem.c:9358] enabling_step(): Modem has time capabilities, enabling the Time interface...
ModemManager[4393]: <info>  [1510811002.083975] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> searching)
ModemManager[4393]: <debug> [1510811002.084331] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'yes')
ModemManager[4393]: [/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:09:00:00:00:00:00:00:00:00:00:00:00
ModemManager[4393]: [/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     = 'register-state' (0x00000009)
<<<<<<   type    = 'query' (0x00000000)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 96
>>>>>>   data   = 03:00:00:80:60: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:09:00:00:00:00:00:00:00:30:00:00:00:00:00:00:00:02:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00: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
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 96
>>>>>>   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          = 'register-state' (0x00000009)
ModemManager[4393]: <debug> [1510811002.122643] [mm-broadband-modem.c:8655] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:63:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:63:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: <debug> [1510811002.892490] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'signal-state')
ModemManager[4393]: <debug> [1510811002.892610] [mm-broadband-modem-mbim.c:1865] basic_connect_notification_signal_state(): Signal state indication: 99 --> 0%
ModemManager[4393]: <debug> [1510811002.892848] [mm-iface-modem.c:1206] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 112
>>>>>>   data   = 07:00:00:80:70: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:09:00:00:00:44: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:08:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:41:00:54:00:26:00:54:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 112
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'register-state' (0x00000009)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 112
>>>>>>   data   = 07:00:00:80:70: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:09:00:00:00:44: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:08:00:00:00:00:00:00:00:00:00:00:00:03:00:00:00:33:00:31:00:30:00:34:00:31:00:30:00:41:00:54:00:26:00:54:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 112
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'register-state' (0x00000009)
ModemManager[4393]: <debug> [1510811002.954710] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'register-state')
ModemManager[4393]: <info>  [1510811002.954759] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (searching -> registering)
ModemManager[4393]: <debug> [1510811002.954822] [mm-iface-modem.c:935] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (unknown -> lte)
ModemManager[4393]: <debug> [1510811002.954910] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '310', MNC: '410', Location area code: '0', Cell ID: '0')
ModemManager[4393]: <info>  [1510811002.955148] [mm-iface-modem-3gpp.c:1096] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home)
ModemManager[4393]: <info>  [1510811002.955200] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (searching -> registered)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 07:00:00:80:48: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:0A: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'packet-service' (0x0000000a)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 07:00:00:80:48: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:0A: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'packet-service' (0x0000000a)
ModemManager[4393]: <debug> [1510811003.018918] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'packet-service')
ModemManager[4393]: <debug> [1510811003.018952] [mm-broadband-modem-mbim.c:2067] basic_connect_notification_packet_service(): Packet service state: 'attached', data class: 'lte'
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 07:00:00:80:48: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:0A: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'packet-service' (0x0000000a)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 72
>>>>>>   data   = 07:00:00:80:48: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:0A: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 72
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'packet-service' (0x0000000a)
ModemManager[4393]: <debug> [1510811003.083210] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'packet-service')
ModemManager[4393]: <debug> [1510811003.083265] [mm-broadband-modem-mbim.c:2067] basic_connect_notification_packet_service(): Packet service state: 'attached', data class: 'lte'
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:0B:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 64
>>>>>>   data   = 07:00:00:80:40: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:0B:00:00:00:14:00:00:00:0B:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 64
>>>>>>   type        = indicate-status (0x80000007)
>>>>>>   transaction = 0
>>>>>> Fragment header:
>>>>>>   total   = 1
>>>>>>   current = 0
>>>>>> Contents:
>>>>>>   service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>>   cid     = 'signal-state' (0x0000000b)
ModemManager[4393]: <debug> [1510811005.164039] [mm-broadband-modem-mbim.c:2252] device_notification_cb(): Received notification (service 'basic-connect', command 'signal-state')
ModemManager[4393]: <debug> [1510811005.164116] [mm-broadband-modem-mbim.c:1865] basic_connect_notification_signal_state(): Signal state indication: 11 --> 35%
ModemManager[4393]: <debug> [1510811005.164287] [mm-iface-modem.c:1206] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (35)
^CModemManager[4393]: <info>  [1510811006.891756] [main.c:46] quit_cb(): Caught signal, shutting down...
ModemManager[4393]: <debug> [1510811006.892667] [mm-base-manager.c:853] set_property(): Stopping connection in object manager server
ModemManager[4393]: <info>  [1510811006.893010] [mm-iface-modem.c:1433] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> disabling)
ModemManager[4393]: <debug> [1510811006.893281] [mm-iface-modem.c:1206] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0)
ModemManager[4393]: <debug> [1510811006.893379] [mm-iface-modem.c:1323] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled
ModemManager[4393]: <debug> [1510811006.893594] [mm-iface-modem.c:935] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (lte -> unknown)
ModemManager[4393]: <debug> [1510811006.893681] [mm-iface-modem.c:1049] periodic_access_technologies_check_disable(): Periodic access technology checks disabled
ModemManager[4393]: <debug> [1510811006.893768] [mm-broadband-modem.c:8942] disabling_step(): Modem has time capabilities, disabling the Time interface...
ModemManager[4393]: <debug> [1510811006.893872] [mm-iface-modem-time.c:622] update_network_timezone_ready(): Couldn't update network timezone: 'Network timezone loading cancelled'
ModemManager[4393]: <debug> [1510811006.894182] [mm-broadband-modem.c:8954] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface...
ModemManager[4393]: <debug> [1510811006.894284] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 100
<<<<<<   data   = 03:00:00:00:64: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: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 100
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 22
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 100
>>>>>>   data   = 03:00:00:80:64: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: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 100
>>>>>>   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          = 'device-service-subscribe-list' (0x00000013)
ModemManager[4393]: <debug> [1510811006.895933] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: <debug> [1510811006.896457] [mm-broadband-modem.c:8966] disabling_step(): Modem has voice capabilities, disabling the Voice interface...
ModemManager[4393]: <debug> [1510811006.896641] [mm-broadband-modem.c:6541] set_voice_unsolicited_events_handlers(): (ttyUSB2) Removing voice unsolicited events handlers
ModemManager[4393]: <debug> [1510811006.897152] [mm-broadband-modem.c:8978] disabling_step(): Modem has location capabilities, disabling the Location interface...
ModemManager[4393]: <debug> [1510811006.897267] [mm-iface-modem-location.c:768] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci'
ModemManager[4393]: <debug> [1510811006.897383] [mm-iface-modem-location.c:667] setup_gathering_step(): Disabled location '3gpp-lac-ci' gathering...
ModemManager[4393]: <debug> [1510811006.897919] [mm-broadband-modem.c:9006] disabling_step(): Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface...
ModemManager[4393]: <debug> [1510811006.898007] [mm-port-serial.c:1296] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[4393]: <debug> [1510811006.898170] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): --> 'AT+CUSD=0<CR>'
ModemManager[4393]: <debug> [1510811006.910923] [mm-port-serial-at.c:460] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[4393]: <debug> [1510811006.911208] [mm-broadband-modem.c:4915] set_unsolicited_result_code_handlers(): (ttyUSB2) Removing unsolicited result code handlers
ModemManager[4393]: <debug> [1510811006.911279] [mm-port-serial.c:1353] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[4393]: <debug> [1510811006.911761] [mm-broadband-modem.c:9018] disabling_step(): Modem has 3GPP capabilities, disabling the Modem 3GPP interface...
ModemManager[4393]: <debug> [1510811006.911826] [mm-iface-modem-3gpp.c:1327] periodic_registration_check_disable(): Periodic 3GPP registration checks disabled
ModemManager[4393]: <debug> [1510811006.911958] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 96
<<<<<<   data   = 03:00:00:00:60: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: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[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 96
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 23
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 96
>>>>>>   data   = 03:00:00:80:60: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: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[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 96
>>>>>>   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          = 'device-service-subscribe-list' (0x00000013)
ModemManager[4393]: <debug> [1510811006.913821] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes)
ModemManager[4393]: <debug> [1510811006.914022] [mm-broadband-modem-mbim.c:2281] common_setup_cleanup_unsolicited_events_sync(): Supported notifications: signal (no), registration (no), sms (no), connect (no), subscriber (no), packet (no)
ModemManager[4393]: <debug> [1510811006.914235] [mm-broadband-modem-mbim.c:2448] common_enable_disable_unsolicited_events(): Enabled notifications: signal (no), registration (no), sms (no), connect (no), subscriber (no), packet (no)
ModemManager[4393]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 52
<<<<<<   data   = 03:00:00:00:34: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:13:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<<   length      = 52
<<<<<<   type        = command (0x00000003)
<<<<<<   transaction = 24
<<<<<< 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[4393]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 52
>>>>>>   data   = 03:00:00:80:34: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:13:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00
ModemManager[4393]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>>   length      = 52
>>>>>>   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          = 'device-service-subscribe-list' (0x00000013)


More information about the ModemManager-devel mailing list