Preventing MM from probing specific serial ports on supported modems
Bjørn Mork
bjorn at mork.no
Wed Sep 21 12:16:13 UTC 2016
Aleksander Morgado <aleksander at aleksander.es> writes:
> On Wed, Sep 21, 2016 at 8:55 AM, Bjørn Mork <bjorn at mork.no> wrote:
>> Note that it is possible to have a very generic GPS port rule for all
>> Sierra modems, since they always use the same USB interface number. So
>> if there only was a way to blacklist individual USB interfaces on
>> otherwise managed modems...
>
> ENV{ID_MM_PORT_IGNORE}="1" should work in udev rules; this can be set
> to single ports on otherwise managed devices.
That's what I've tried, but I cannot make it work. I have this rule for
the GPS port:
SUBSYSTEM=="tty",ACTION=="add",ENV{ID_VENDOR_ID}=="1199",ATTRS{bInterfaceNumber}=="02",SYMLINK="gps%n",ENV{ID_MM_PORT_IGNORE}="1",RUN+="/usr/sbin/gpsdctl add $devnode"
I guess I am setting it on the wrong node or something? The udev rule
is matching and applied to the ttyUSB1 device:
root at miraculix:/tmp# udevadm test /sys/class/tty/ttyUSB1
calling: test
version 231
This program is for debugging only, it does not run any program
specified by a RUN key. It may show incorrect results, because
some values may be different, or not available at a simulation run.
=== trie on-disk ===
tool version: 231
file size: 7276705 bytes
header size 80 bytes
strings 1814625 bytes
nodes 5462000 bytes
Load module index
Found container virtualization none
timestamp of '/etc/systemd/network' changed
timestamp of '/lib/systemd/network' changed
Parsed configuration file /lib/systemd/network/99-default.link
Created link configuration context.
timestamp of '/etc/udev/rules.d' changed
timestamp of '/lib/udev/rules.d' changed
Skipping overridden file: /lib/udev/rules.d/80-net-setup-link.rules.
Reading rules file: /lib/udev/rules.d/39-usbmuxd.rules
..
Reading rules file: /etc/udev/rules.d/99_miraculix-late-local.rules
rules contain 393216 bytes tokens (32768 * 12 bytes), 35671 bytes strings
29025 strings (238241 bytes), 25306 de-duplicated (206290 bytes), 3720 trie nodes used
PROGRAM 'usb_modeswitch --symlink-name /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/ttyUSB1/tty/ttyUSB1 1199 9079 ' /lib/udev/rules.d/40-usb_modeswitch.rules:9
starting 'usb_modeswitch --symlink-name /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/ttyUSB1/tty/ttyUSB1 1199 9079 '
Process 'usb_modeswitch --symlink-name /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/ttyUSB1/tty/ttyUSB1 1199 9079 ' succeeded.
GROUP 20 /lib/udev/rules.d/50-udev-default.rules:26
IMPORT builtin 'hwdb' /lib/udev/rules.d/60-serial.rules:7
IMPORT builtin 'usb_id' /lib/udev/rules.d/60-serial.rules:8
/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2: if_class 255 protocol 0
IMPORT builtin 'hwdb' /lib/udev/rules.d/60-serial.rules:8
IMPORT builtin 'path_id' /lib/udev/rules.d/60-serial.rules:15
LINK 'serial/by-path/pci-0000:00:14.0-usb-0:2:1.2-port0' /lib/udev/rules.d/60-serial.rules:17
IMPORT builtin skip 'usb_id' /lib/udev/rules.d/60-serial.rules:19
LINK 'serial/by-id/usb-Sierra_Wireless__Incorporated_Sierra_Wireless_EM7455_Qualcomm_Snapdragon_X7_LTE-A_LF61512611041013-if02-port0' /lib/udev/rules.d/60-serial.rules:24
LINK 'gps1' /etc/udev/rules.d/60_miraculix-local.rules:14
RUN '/usr/sbin/gpsdctl add $devnode' /etc/udev/rules.d/60_miraculix-local.rules:14
handling device node '/dev/ttyUSB1', devnum=c188:1, mode=0660, uid=0, gid=20
preserve permissions /dev/ttyUSB1, 020660, uid=0, gid=20
preserve already existing symlink '/dev/char/188:1' to '../ttyUSB1'
found 'c188:1' claiming '/run/udev/links/\x2fgps1'
creating link '/dev/gps1' to '/dev/ttyUSB1'
preserve already existing symlink '/dev/gps1' to 'ttyUSB1'
created db file '/run/udev/data/c188:1' for '/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/ttyUSB1/tty/ttyUSB1'
.ID_PORT=0
.MM_USBIFNUM=02
ACTION=add
DEVLINKS=/dev/gps1
DEVNAME=/dev/ttyUSB1
DEVPATH=/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2:1.2/ttyUSB1/tty/ttyUSB1
ID_BUS=usb
ID_MM_CANDIDATE=1
ID_MM_PORT_IGNORE=1
ID_MODEL=Sierra_Wireless_EM7455_Qualcomm_Snapdragon_X7_LTE-A
ID_MODEL_ENC=Sierra\x20Wireless\x20EM7455\x20Qualcomm\x20Snapdragon\x20X7\x20LTE-A
ID_MODEL_FROM_DATABASE=Sunrise Point-LP USB 3.0 xHCI Controller
ID_MODEL_ID=9079
ID_PATH=pci-0000:00:14.0-usb-0:2:1.2
ID_PATH_TAG=pci-0000_00_14_0-usb-0_2_1_2
ID_PCI_CLASS_FROM_DATABASE=Serial bus controller
ID_PCI_INTERFACE_FROM_DATABASE=XHCI
ID_PCI_SUBCLASS_FROM_DATABASE=USB controller
ID_REVISION=0006
ID_SERIAL=Sierra_Wireless__Incorporated_Sierra_Wireless_EM7455_Qualcomm_Snapdragon_X7_LTE-A_LF61512611041013
ID_SERIAL_SHORT=LF61512611041013
ID_TYPE=generic
ID_USB_DRIVER=qcserial
ID_USB_INTERFACES=:ffffff:ff4201:ff0000:020e00:0a0002:
ID_USB_INTERFACE_NUM=02
ID_VENDOR=Sierra_Wireless__Incorporated
ID_VENDOR_ENC=Sierra\x20Wireless\x2c\x20Incorporated
ID_VENDOR_FROM_DATABASE=Sierra Wireless, Inc.
ID_VENDOR_ID=1199
MAJOR=188
MINOR=1
SUBSYSTEM=tty
TAGS=:systemd:
USEC_INITIALIZED=2318374350
run: '/usr/sbin/gpsdctl add /dev/ttyUSB1'
Unload module index
Unloaded link configuration context.
But ModemManager does not seem to care. It does both AT and QCDM
probing on all three serial ports. I do see a
mm_plugin_create_modem(): (tty/ttyUSB1): port is blacklisted
message in there, but that's way after the probing is finished. So it
looks like the blacklisting is applied, but doesn't affect the probing.
Which makes it a bit pointless..
ModemManager[6194]: <info> [1474459913.435923] [main.c:158] main(): ModemManager (version 1.6.2) starting in system bus...
ModemManager[6194]: <debug> [1474459913.436951] [mm-sleep-monitor.c:245] mm_sleep_monitor_get(): create MMSleepMonitor singleton (0x1dce090)
ModemManager[6194]: <debug> [1474459913.440771] [main.c:83] bus_acquired_cb(): Bus acquired, creating manager...
ModemManager[6194]: <debug> [1474459913.444398] [mm-plugin-manager.c:1559] load_plugins(): [plugin manager] looking for plugins in '/usr/lib/x86_64-linux-gnu/ModemManager'
ModemManager[6194]: <debug> [1474459913.445093] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Longcheer'
ModemManager[6194]: <debug> [1474459913.446197] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Ericsson MBM'
ModemManager[6194]: <debug> [1474459913.446661] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Wavecom'
ModemManager[6194]: <debug> [1474459913.447010] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option High-Speed'
ModemManager[6194]: <debug> [1474459913.447318] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'ZTE'
ModemManager[6194]: <debug> [1474459913.447627] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'X22X'
ModemManager[6194]: <debug> [1474459913.447848] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Via CBP7'
ModemManager[6194]: <debug> [1474459913.448074] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra'
ModemManager[6194]: <debug> [1474459913.448319] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option'
ModemManager[6194]: <debug> [1474459913.448609] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Telit'
ModemManager[6194]: <debug> [1474459913.448819] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Generic'
ModemManager[6194]: <debug> [1474459913.449062] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'MTK'
ModemManager[6194]: <debug> [1474459913.449306] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'SimTech'
ModemManager[6194]: <debug> [1474459913.449628] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Motorola'
ModemManager[6194]: <debug> [1474459913.450003] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra (legacy)'
ModemManager[6194]: <debug> [1474459913.450361] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel'
ModemManager[6194]: <debug> [1474459913.450757] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Thuraya'
ModemManager[6194]: <debug> [1474459913.450997] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'AnyDATA'
ModemManager[6194]: <debug> [1474459913.451240] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Samsung'
ModemManager[6194]: <debug> [1474459913.451462] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Haier'
ModemManager[6194]: <debug> [1474459913.451710] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Iridium'
ModemManager[6194]: <debug> [1474459913.451926] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia'
ModemManager[6194]: <debug> [1474459913.452195] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia (Icera)'
ModemManager[6194]: <debug> [1474459913.452626] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Cinterion'
ModemManager[6194]: <debug> [1474459913.452996] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel LTE'
ModemManager[6194]: <debug> [1474459913.453709] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Altair LTE'
ModemManager[6194]: <debug> [1474459913.454257] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Linktop'
ModemManager[6194]: <debug> [1474459913.454637] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Pantech'
ModemManager[6194]: <debug> [1474459913.456128] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Huawei'
ModemManager[6194]: <debug> [1474459913.457415] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Dell'
ModemManager[6194]: <debug> [1474459913.457586] [mm-plugin-manager.c:1609] load_plugins(): [plugin manager] successfully loaded 30 plugins
ModemManager[6194]: <debug> [1474459913.460681] [main.c:105] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
ModemManager[6194]: <debug> [1474459913.460752] [mm-base-manager.c:498] mm_base_manager_start(): Starting automatic device scan...
ModemManager[6194]: <debug> [1474459913.488669] [mm-base-manager.c:537] mm_base_manager_start(): Finished device scan...
ModemManager[6194]: <debug> [1474459913.488766] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor
ModemManager[6194]: <debug> [1474459913.491594] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 0: new support task for device: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
ModemManager[6194]: <debug> [1474459913.491928] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB0
ModemManager[6194]: <debug> [1474459913.492000] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB0: new support task for port
ModemManager[6194]: <debug> [1474459913.492016] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB0: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.493773] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB1
ModemManager[6194]: <debug> [1474459913.493839] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB1: new support task for port
ModemManager[6194]: <debug> [1474459913.493852] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB1: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.495297] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: ttyUSB2
ModemManager[6194]: <debug> [1474459913.495381] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,ttyUSB2: new support task for port
ModemManager[6194]: <debug> [1474459913.495398] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,ttyUSB2: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.498837] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: wwan0
ModemManager[6194]: <debug> [1474459913.498899] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,wwan0: new support task for port
ModemManager[6194]: <debug> [1474459913.498915] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,wwan0: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.499637] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 1: new support task for device: /sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0
ModemManager[6194]: <debug> [1474459913.499807] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 1: port grabbed: wlan0
ModemManager[6194]: <debug> [1474459913.499838] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 1,wlan0: new support task for port
ModemManager[6194]: <debug> [1474459913.499853] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 1,wlan0: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.500934] [mm-plugin-manager.c:1442] mm_plugin_manager_device_support_check(): [plugin manager] task 2: new support task for device: /sys/devices/pci0000:00/0000:00:1f.6
ModemManager[6194]: <debug> [1474459913.501101] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: eth0
ModemManager[6194]: <debug> [1474459913.501224] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,eth0: new support task for port
ModemManager[6194]: <debug> [1474459913.501242] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,eth0: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.502220] [mm-base-manager.c:364] device_added(): (net/tap0): could not get port's parent device
ModemManager[6194]: <debug> [1474459913.502801] [mm-base-manager.c:364] device_added(): (net/tap0.42): could not get port's parent device
ModemManager[6194]: <debug> [1474459913.504359] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: cdc-wdm0
ModemManager[6194]: <debug> [1474459913.504435] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,cdc-wdm0: new support task for port
ModemManager[6194]: <debug> [1474459913.504451] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,cdc-wdm0: deferred until min wait time elapsed
ModemManager[6194]: <debug> [1474459913.504545] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 10
ModemManager[6194]: <debug> [1474459914.993876] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 0: min wait time elapsed
ModemManager[6194]: <debug> [1474459914.994130] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.994223] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [cdc-wdm0] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.994291] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [cdc-wdm0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.994355] [mm-plugin.c:212] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.994420] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [cdc-wdm0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459914.994683] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.994806] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.994988] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,cdc-wdm0: found '2' plugins to try
ModemManager[6194]: <debug> [1474459914.995083] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.995431] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459914.995641] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,cdc-wdm0: started
ModemManager[6194]: <debug> [1474459914.995733] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,cdc-wdm0: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.995973] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [cdc-wdm0] probe required: 'mbim'
ModemManager[6194]: <debug> [1474459914.996085] [mm-port-probe.c:1377] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
ModemManager[6194]: <debug> [1474459914.996225] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wwan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.996317] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wwan0] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.996395] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wwan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.996475] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wwan0] filtered by drivers
ModemManager[6194]: <debug> [1474459914.996629] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wwan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459914.996712] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [wwan0] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.996789] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wwan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.996880] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,wwan0: found '2' plugins to try
ModemManager[6194]: <debug> [1474459914.996959] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,wwan0: will try with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.997032] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,wwan0: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459914.997125] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,wwan0: started
ModemManager[6194]: <debug> [1474459914.997210] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,wwan0: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.997324] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwan0] probing deferred until result suggested
ModemManager[6194]: <debug> [1474459914.997446] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.997612] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB2] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.997686] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB2] filtered by forbidden drivers
ModemManager[6194]: <debug> [1474459914.997767] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB2] filtered by drivers
ModemManager[6194]: <debug> [1474459914.997888] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [ttyUSB2] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459914.998004] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.998248] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB2] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.998330] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,ttyUSB2: found '2' plugins to try
ModemManager[6194]: <debug> [1474459914.998393] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB2: will try with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.998454] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB2: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459914.998592] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,ttyUSB2: started
ModemManager[6194]: <debug> [1474459914.998704] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,ttyUSB2: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.998844] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB2] probe required: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.998956] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB2) launching port probing: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.999069] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999148] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB1] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.999220] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB1] filtered by forbidden drivers
ModemManager[6194]: <debug> [1474459914.999303] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB1] filtered by drivers
ModemManager[6194]: <debug> [1474459914.999323] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [ttyUSB1] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459914.999344] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999370] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB1] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999392] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,ttyUSB1: found '2' plugins to try
ModemManager[6194]: <debug> [1474459914.999413] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB1: will try with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.999433] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB1: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459914.999455] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,ttyUSB1: started
ModemManager[6194]: <debug> [1474459914.999476] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,ttyUSB1: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.999529] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB1] probe required: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.999556] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB1) launching port probing: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.999585] [mm-plugin.c:310] apply_pre_probing_filters(): (Longcheer) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999607] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [ttyUSB0] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.999626] [mm-plugin.c:279] apply_pre_probing_filters(): (Wavecom) [ttyUSB0] filtered by forbidden drivers
ModemManager[6194]: <debug> [1474459914.999645] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [ttyUSB0] filtered by drivers
ModemManager[6194]: <debug> [1474459914.999664] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [ttyUSB0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459914.999684] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999703] [mm-plugin.c:310] apply_pre_probing_filters(): (Via CBP7) [ttyUSB0] filtered by implicit MBIM driver
ModemManager[6194]: <debug> [1474459914.999723] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,ttyUSB0: found '2' plugins to try
ModemManager[6194]: <debug> [1474459914.999743] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB0: will try with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.999769] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,ttyUSB0: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459914.999791] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,ttyUSB0: started
ModemManager[6194]: <debug> [1474459914.999810] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,ttyUSB0: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459914.999840] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [ttyUSB0] probe required: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.999864] [mm-port-probe.c:1377] mm_port_probe_run(): (tty/ttyUSB0) launching port probing: 'at, qcdm'
ModemManager[6194]: <debug> [1474459914.999917] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 1: min wait time elapsed
ModemManager[6194]: <debug> [1474459914.999940] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459914.999961] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlan0] filtered by udev tags
ModemManager[6194]: <debug> [1474459914.999981] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000001] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlan0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.000020] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000040] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000060] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000080] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlan0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.000099] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000119] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000139] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000160] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000179] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000199] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlan0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.000219] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000239] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000258] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000279] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000299] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000318] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000338] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000357] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000378] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000399] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000435] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000455] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlan0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.000477] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000518] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000709] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlan0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.000734] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 1,wlan0: found '1' plugins to try
ModemManager[6194]: <debug> [1474459915.000754] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 1,wlan0: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459915.000778] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 1,wlan0: started
ModemManager[6194]: <debug> [1474459915.000797] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 1,wlan0: checking with plugin 'Generic'
ModemManager[6194]: <debug> [1474459915.000822] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlan0] probing deferred until result suggested
ModemManager[6194]: <debug> [1474459915.000856] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 0,wwan0: deferring support check until result suggested
ModemManager[6194]: <debug> [1474459915.000894] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 1,wlan0: deferring support check until result suggested
ModemManager[6194]: <debug> [1474459915.000956] [mm-port-probe.c:551] wdm_probe_mbim(): (usbmisc/cdc-wdm0) probing MBIM...
ModemManager[6194]: <debug> [1474459915.002300] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[6194]: <warn> [1474459915.002941] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[6194]: <debug> [1474459915.002999] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459915.003140] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port...
ModemManager[6194]: <warn> [1474459915.003330] [mm-port-serial.c:513] real_config_fd(): (ttyUSB1): port attributes not fully set
ModemManager[6194]: <debug> [1474459915.003366] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459915.003516] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[6194]: <warn> [1474459915.003687] [mm-port-serial.c:513] real_config_fd(): (ttyUSB0): port attributes not fully set
ModemManager[6194]: <debug> [1474459915.003719] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459915.003784] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 2: min wait time elapsed
ModemManager[6194]: <debug> [1474459915.003808] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.003832] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [eth0] filtered by udev tags
ModemManager[6194]: <debug> [1474459915.003850] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.003870] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [eth0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.003889] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.003908] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.003934] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.003953] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [eth0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.003972] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.003990] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004009] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004028] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004051] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004083] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [eth0] filtered by drivers
ModemManager[6194]: <debug> [1474459915.004100] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004117] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004134] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004152] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004169] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004186] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004203] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004221] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004240] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004257] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004276] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004294] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [eth0] filtered by subsystem
ModemManager[6194]: <debug> [1474459915.004312] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004329] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004346] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [eth0] filtered by vendor/product IDs
ModemManager[6194]: <debug> [1474459915.004365] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,eth0: found '1' plugins to try
ModemManager[6194]: <debug> [1474459915.004382] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,eth0: will try with plugin 'Generic'
ModemManager[6194]: <debug> [1474459915.004404] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,eth0: started
ModemManager[6194]: <debug> [1474459915.004421] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,eth0: checking with plugin 'Generic'
ModemManager[6194]: <debug> [1474459915.004444] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [eth0] probing deferred until result suggested
ModemManager[6194]: <debug> [1474459915.004549] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 2,eth0: deferring support check until result suggested
ModemManager[6194]: opening device...
ModemManager[6194]: cannot connect to proxy: Could not connect: Connection refused
ModemManager[6194]: spawning new mbim-proxy (try 1)...
ModemManager[6194]: <debug> [1474459915.105164] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459915.105714] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459915.105956] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[6194]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[6194]: [/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[6194]: [/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[6194]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 128
>>>>>> 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:10:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF: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:16:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[6194]: [/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[6194]: [/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:16:00:00:00:63:00:00:00:05:00:00:00:02:00:00:00:FF:FF:FF:FF
ModemManager[6194]: [/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[6194]: <debug> [1474459915.310962] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459915.311088] [mm-port-probe.c:155] mm_port_probe_set_result_at(): (tty/ttyUSB2) port is AT-capable
ModemManager[6194]: <debug> [1474459915.311145] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 0,ttyUSB2: found best plugin for port (Sierra)
ModemManager[6194]: <debug> [1474459915.311189] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,ttyUSB2: finished in '1.815809' seconds
ModemManager[6194]: <debug> [1474459915.311234] [mm-plugin-manager.c:913] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB2: found best plugin: Sierra
ModemManager[6194]: <debug> [1474459915.311289] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 0,cdc-wdm0: got suggested plugin (Sierra)
ModemManager[6194]: <debug> [1474459915.311316] [mm-plugin-manager.c:326] port_context_set_suggestion(): [plugin manager] task 0,wwan0: deferred task completed, got suggested plugin (Sierra)
ModemManager[6194]: <debug> [1474459915.311350] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 0,ttyUSB1: got suggested plugin (Sierra)
ModemManager[6194]: <debug> [1474459915.311378] [mm-plugin-manager.c:366] port_context_set_suggestion(): [plugin manager] task 0,ttyUSB0: got suggested plugin (Sierra)
ModemManager[6194]: <debug> [1474459915.311414] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 4 running probes (4 active): cdc-wdm0, wwan0, ttyUSB1, ttyUSB0
ModemManager[6194]: <debug> [1474459915.311467] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459915.311511] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
ModemManager[6194]: <debug> [1474459915.312058] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
ModemManager[6194]: <debug> [1474459915.312101] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB2) forced to close port
ModemManager[6194]: <debug> [1474459915.312132] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,wwan0: checking with plugin 'Sierra'
ModemManager[6194]: <debug> [1474459915.312173] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwan0] probing deferred until result suggested
ModemManager[6194]: <debug> [1474459915.312195] [mm-plugin-manager.c:434] port_context_defer_until_suggested(): [plugin manager] task 0,wwan0: completed, got suggested plugin (Sierra)
ModemManager[6194]: <debug> [1474459915.312208] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,wwan0: finished in '1.813309' seconds
ModemManager[6194]: <debug> [1474459915.315790] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 0,wwan0: best plugin matches device reported one: Sierra
ModemManager[6194]: <debug> [1474459915.315862] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 3 running probes (3 active): cdc-wdm0, ttyUSB1, ttyUSB0
ModemManager[6194]: <debug> [1474459915.992678] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing time elapsed
ModemManager[6194]: <debug> [1474459915.992746] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 3 running probes (3 active): cdc-wdm0, ttyUSB1, ttyUSB0
ModemManager[6194]: <debug> [1474459915.999869] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing time elapsed
ModemManager[6194]: <debug> [1474459915.999926] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 1: still 1 running probes (0 active): wlan0
ModemManager[6194]: <debug> [1474459915.999941] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 1: no active tasks to probe
ModemManager[6194]: <debug> [1474459915.999954] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 1,wlan0: deferred task completed, no suggested plugin
ModemManager[6194]: <debug> [1474459915.999988] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 1,wlan0: finished in '2.500152' seconds
ModemManager[6194]: <debug> [1474459916.000079] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 1,wlan0: not supported by any plugin
ModemManager[6194]: <debug> [1474459916.000103] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/wlan0' from now on
ModemManager[6194]: <debug> [1474459916.000119] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 1: no more ports to probe
ModemManager[6194]: <debug> [1474459916.000130] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 1: finished in '2.500499' seconds
ModemManager[6194]: <info> [1474459916.000153] [mm-base-manager.c:155] device_support_check_ready(): Couldn't check support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0': not supported by any plugin
ModemManager[6194]: <debug> [1474459916.001278] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing time elapsed
ModemManager[6194]: <debug> [1474459916.001326] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 1 running probes (0 active): eth0
ModemManager[6194]: <debug> [1474459916.001341] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 2: no active tasks to probe
ModemManager[6194]: <debug> [1474459916.001352] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 2,eth0: deferred task completed, no suggested plugin
ModemManager[6194]: <debug> [1474459916.001363] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,eth0: finished in '2.500140' seconds
ModemManager[6194]: <debug> [1474459916.001387] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 2,eth0: not supported by any plugin
ModemManager[6194]: <debug> [1474459916.001408] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/eth0' from now on
ModemManager[6194]: <debug> [1474459916.001423] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 2: no more ports to probe
ModemManager[6194]: <debug> [1474459916.001433] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 2: finished in '2.500503' seconds
ModemManager[6194]: <info> [1474459916.001453] [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[6194]: <debug> [1474459918.472132] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459918.472190] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459918.472245] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459918.472320] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459921.471823] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459921.471886] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459921.471930] [mm-port-serial-at.c:459] debug_log(): (ttyUSB1): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459921.471996] [mm-port-serial-at.c:459] debug_log(): (ttyUSB0): --> 'AT<CR>'
ModemManager[6194]: <debug> [1474459924.472542] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459924.472628] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB1) port is not AT-capable
ModemManager[6194]: <debug> [1474459924.472656] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[6194]: <debug> [1474459924.472693] [mm-port-probe.c:165] mm_port_probe_set_result_at(): (tty/ttyUSB0) port is not AT-capable
ModemManager[6194]: <debug> [1474459924.472735] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB1) probing QCDM...
ModemManager[6194]: <debug> [1474459924.472767] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459924.472798] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port...
ModemManager[6194]: <debug> [1474459924.474265] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed
ModemManager[6194]: <debug> [1474459924.474313] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB1) forced to close port
ModemManager[6194]: <debug> [1474459924.474381] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB1) opening serial port...
ModemManager[6194]: <debug> [1474459924.474611] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB1) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459924.474690] [mm-port-probe.c:691] serial_probe_qcdm(): (tty/ttyUSB0) probing QCDM...
ModemManager[6194]: <debug> [1474459924.474715] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459924.474728] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[6194]: <debug> [1474459924.478717] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
ModemManager[6194]: <debug> [1474459924.479744] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port
ModemManager[6194]: <debug> [1474459924.479894] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[6194]: <debug> [1474459924.480145] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459924.480257] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
ModemManager[6194]: <debug> [1474459924.480352] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 7e 00 78 f0 7e
ModemManager[6194]: <debug> [1474459924.482812] [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[6194]: <debug> [1474459924.482926] [mm-port-probe.c:243] mm_port_probe_set_result_qcdm(): (tty/ttyUSB0) port is QCDM-capable
ModemManager[6194]: <debug> [1474459924.482956] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 0,ttyUSB0: found best plugin for port (Sierra)
ModemManager[6194]: <debug> [1474459924.482971] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,ttyUSB0: finished in '10.990973' seconds
ModemManager[6194]: <debug> [1474459924.483101] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB0: best plugin matches device reported one: Sierra
ModemManager[6194]: <debug> [1474459924.483131] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 2 running probes (2 active): cdc-wdm0, ttyUSB1
ModemManager[6194]: <debug> [1474459924.483176] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459924.483195] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[6194]: <debug> [1474459924.483686] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
ModemManager[6194]: <debug> [1474459924.483884] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB0) forced to close port
ModemManager[6194]: <debug> [1474459927.471084] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB1): --> 7e 00 78 f0 7e
ModemManager[6194]: <debug> [1474459930.472244] [mm-port-probe.c:261] mm_port_probe_set_result_qcdm(): (tty/ttyUSB1) port is not QCDM-capable
ModemManager[6194]: <debug> [1474459930.472308] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 0,ttyUSB1: found best plugin for port (Sierra)
ModemManager[6194]: <debug> [1474459930.472329] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,ttyUSB1: finished in '16.978485' seconds
ModemManager[6194]: <debug> [1474459930.472373] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 0,ttyUSB1: best plugin matches device reported one: Sierra
ModemManager[6194]: <debug> [1474459930.472409] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 1 running probes (1 active): cdc-wdm0
ModemManager[6194]: <debug> [1474459930.472458] [mm-port-serial.c:1345] _close_internal(): (ttyUSB1) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459930.472517] [mm-port-serial.c:1361] _close_internal(): (ttyUSB1) closing serial port...
ModemManager[6194]: <debug> [1474459930.474307] [mm-port-serial.c:1409] _close_internal(): (ttyUSB1) serial port closed
ModemManager[6194]: <debug> [1474459930.474364] [mm-port-serial.c:1471] port_serial_close_force(): (ttyUSB1) forced to close port
ModemManager[6194]: [/dev/cdc-wdm0] unexpected port hangup!
ModemManager[6194]: proxy configuration failed: closed
ModemManager[6194]: <debug> [1474459945.128680] [mm-port-probe.c:526] mbim_port_open_ready(): (usbmisc/cdc-wdm0) error checking MBIM support: 'Transaction timed out'
ModemManager[6194]: <debug> [1474459945.128717] [mm-port-probe.c:321] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is not MBIM-capable
ModemManager[6194]: <debug> [1474459945.128767] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 0,cdc-wdm0: found best plugin for port (Sierra)
ModemManager[6194]: <debug> [1474459945.128802] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,cdc-wdm0: finished in '31.624369' seconds
ModemManager[6194]: <debug> [1474459945.128839] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 0,cdc-wdm0: best plugin matches device reported one: Sierra
ModemManager[6194]: <debug> [1474459945.128877] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 0: no more ports to probe
ModemManager[6194]: <debug> [1474459945.128905] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 0: finished in '31.637317' seconds
ModemManager[6194]: <info> [1474459945.128950] [mm-device.c:525] mm_device_create_modem(): Creating modem with plugin 'Sierra' and '5' ports
ModemManager[6194]: <warn> [1474459945.129933] [mm-plugin.c:957] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported'
ModemManager[6194]: <debug> [1474459945.130114] [mm-base-modem.c:280] mm_base_modem_grab_port(): (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
ModemManager[6194]: <debug> [1474459945.130206] [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-2
ModemManager[6194]: <debug> [1474459945.130252] [mm-plugin.c:905] mm_plugin_create_modem(): (tty/ttyUSB1): port is blacklisted
ModemManager[6194]: <debug> [1474459945.130297] [mm-base-modem.c:280] mm_base_modem_grab_port(): (ttyUSB1) type 'ignored' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2
ModemManager[6194]: <debug> [1474459945.130349] [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-2
ModemManager[6194]: <debug> [1474459945.130391] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) tty/ttyUSB2 at (primary)
ModemManager[6194]: <debug> [1474459945.130427] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) net/wwan0 data (primary)
ModemManager[6194]: <debug> [1474459945.130462] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2) tty/ttyUSB0 qcdm
ModemManager[6194]: <debug> [1474459945.130667] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[6194]: <warn> [1474459945.131080] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[6194]: <debug> [1474459945.131153] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459945.131187] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
ModemManager[6194]: <debug> [1474459945.131239] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.131282] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <info> [1474459945.131324] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2' successfully created
ModemManager[6194]: <debug> [1474459945.131880] [mm-broadband-modem.c:710] modem_load_current_capabilities(): loading current capabilities...
ModemManager[6194]: <debug> [1474459945.131938] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[6194]: <debug> [1474459945.132097] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459945.132155] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[6194]: <debug> [1474459945.132243] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 26 0a 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 b2 37 7e
ModemManager[6194]: <debug> [1474459945.133395] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 26 0a 00 00 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 4f 52 7e
ModemManager[6194]: <debug> [1474459945.133454] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
ModemManager[6194]: <debug> [1474459945.138462] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.138596] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
ModemManager[6194]: <debug> [1474459945.146636] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.146731] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[6194]: <debug> [1474459945.158630] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.158890] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
ModemManager[6194]: <debug> [1474459945.167770] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.168024] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
ModemManager[6194]: <debug> [1474459945.180636] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.180728] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[6194]: <debug> [1474459945.188508] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.188584] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
ModemManager[6194]: <debug> [1474459945.188617] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[6194]: <debug> [1474459945.202147] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.202221] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.202259] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+GCAP<CR>'
ModemManager[6194]: <debug> [1474459945.213329] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+GCAP: +CGSM<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.213418] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.213442] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.213479] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+WS46=?<CR>'
ModemManager[6194]: <debug> [1474459945.228937] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+WS46: (12,22,25,28,29)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.229019] [mm-broadband-modem.c:364] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts, lte
ModemManager[6194]: <debug> [1474459945.229114] [mm-iface-modem.c:4345] interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[6194]: <debug> [1474459945.229136] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[6194]: <debug> [1474459945.229185] [mm-broadband-modem.c:770] modem_load_manufacturer(): loading manufacturer...
ModemManager[6194]: <debug> [1474459945.229208] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.229233] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459945.229251] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[6194]: <debug> [1474459945.229517] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
ModemManager[6194]: <debug> [1474459945.229545] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.229574] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMI<CR>'
ModemManager[6194]: <debug> [1474459945.241657] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>Sierra Wireless, Incorporated<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.241741] [mm-broadband-modem.c:754] modem_load_manufacturer_finish(): loaded manufacturer: Sierra Wireless, Incorporated
ModemManager[6194]: <debug> [1474459945.241775] [mm-broadband-modem.c:810] modem_load_model(): loading model...
ModemManager[6194]: <debug> [1474459945.241794] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.241823] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.241852] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMM<CR>'
ModemManager[6194]: <debug> [1474459945.254116] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>EM7455<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.254198] [mm-broadband-modem.c:794] modem_load_model_finish(): loaded model: EM7455
ModemManager[6194]: <debug> [1474459945.254238] [mm-broadband-modem.c:850] modem_load_revision(): loading revision...
ModemManager[6194]: <debug> [1474459945.254290] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.254319] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.254348] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGMR<CR>'
ModemManager[6194]: <debug> [1474459945.267194] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>SWI9X30C_02.20.03.00 r6691 CARMD-EV-FRMWR2 2016/06/30 10:54:05<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.267276] [mm-broadband-modem.c:834] modem_load_revision_finish(): loaded revision: SWI9X30C_02.20.03.00 r6691 CARMD-EV-FRMWR2 2016/06/30 10:54:05
ModemManager[6194]: <debug> [1474459945.267336] [mm-broadband-modem.c:911] modem_load_equipment_identifier(): loading equipment identifier...
ModemManager[6194]: <debug> [1474459945.267362] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.267391] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.267418] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGSN<CR>'
ModemManager[6194]: <debug> [1474459945.280630] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>014582000786815<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.280716] [mm-broadband-modem.c:893] modem_load_equipment_identifier_finish(): loaded equipment identifier: 014582000786815
ModemManager[6194]: <debug> [1474459945.280759] [mm-broadband-modem.c:999] modem_load_device_identifier(): loading device identifier...
ModemManager[6194]: <debug> [1474459945.280781] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.280808] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.280835] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATI<CR>'
ModemManager[6194]: <debug> [1474459945.293551] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>Manufacturer: Sierra Wireless, Incorporated<CR><LF>Model: EM7455<CR><LF>Revision: SWI9X30C_02.20.03.00 r6691 CARMD-EV-FRMWR2 2016/06/30 10:54:05<CR><LF>MEID: 01458200078681<CR><LF>IMEI: 014582000786815<CR><LF>IMEI SV: 6<CR><LF>FSN: LF615126110410<CR><LF>+GCAP: +CGSM<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.293678] [mm-modem-helpers.c:211] mm_create_device_identifier(): Device ID source '0000119900009079Manufacturer:SierraWireless,IncorporatedModel:EM7455Revision:SWI9X30C_02.20.03.00r6691CARMD-EV-FRMWR22016/06/3010:54:05MEID:01458200078681IMEI:014582000786815IMEISV:6FSN:LF615126110410+GCAP:+CGSM014582000786815SWI9X30C_02.20.03.00r6691CARMD-EV-FRMWR22016/06/3010:54:05EM7455SierraWireless,Incorporated'
ModemManager[6194]: <debug> [1474459945.293702] [mm-modem-helpers.c:212] mm_create_device_identifier(): Device ID '9c547b525befbfbe1bcce18df3954e105073bbf5'
ModemManager[6194]: <debug> [1474459945.293716] [mm-broadband-modem.c:962] modem_load_device_identifier_finish(): loaded device identifier: 9c547b525befbfbe1bcce18df3954e105073bbf5
ModemManager[6194]: <debug> [1474459945.293759] [mm-broadband-modem.c:1613] modem_load_supported_modes(): loading supported modes...
ModemManager[6194]: <debug> [1474459945.293798] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.293833] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.293867] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT*CNTI=2<CR>'
ModemManager[6194]: <debug> [1474459945.307896] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>*CNTI: 2,UMTS,HSDPA/HSUPA,HSPA+,LTE<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.307975] [mm-broadband-modem.c:1530] supported_modes_cnti_ready(): Device allows (3GPP) 3G networks
ModemManager[6194]: <debug> [1474459945.307991] [mm-broadband-modem.c:1535] supported_modes_cnti_ready(): Device allows (3GPP) 4G networks
ModemManager[6194]: <debug> [1474459945.308016] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.308054] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.308095] [mm-broadband-modem.c:1450] supported_modes_ws46_test_ready(): Device allows (3GPP) 2G-only network mode
ModemManager[6194]: <debug> [1474459945.308113] [mm-broadband-modem.c:1455] supported_modes_ws46_test_ready(): Device allows (3GPP) 3G-only network mode
ModemManager[6194]: <debug> [1474459945.308126] [mm-broadband-modem.c:1460] supported_modes_ws46_test_ready(): Device allows (3GPP) 4G-only network mode
ModemManager[6194]: <debug> [1474459945.308139] [mm-broadband-modem.c:1465] supported_modes_ws46_test_ready(): Device allows (3GPP) 2G/3G network mode
ModemManager[6194]: <debug> [1474459945.308159] [mm-broadband-modem.c:1481] supported_modes_ws46_test_ready(): Device allows every supported 3GPP network mode (2G/3G/4G)
ModemManager[6194]: <debug> [1474459945.308229] [mm-broadband-modem.c:1687] modem_load_supported_ip_families(): loading supported IP families...
ModemManager[6194]: <debug> [1474459945.308292] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.308316] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.308342] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGDCONT=?<CR>'
ModemManager[6194]: <debug> [1474459945.327706] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGDCONT: (1-24),"IP",,,(0-2),(0-4),(0-1),(0-1)<CR><LF>+CGDCONT: (1-24),"PPP",,,(0-2),(0-4),(0-1),(0-1)<CR><LF>+CGDCONT: (1-24),"IPV6",,,(0-2),(0-4),(0-1),(0-1)<CR><LF>+CGDCONT: (1-24),"IPV4V6",,,(0-2),(0-4),(0-1),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.328166] [mm-modem-helpers.c:895] mm_3gpp_parse_cgdcont_test_response(): Unhandled PDP type in CGDCONT=? reply: 'PPP'
ModemManager[6194]: <debug> [1474459945.328285] [mm-broadband-modem.c:3144] load_power_state(): loading power state...
ModemManager[6194]: <debug> [1474459945.328445] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.328519] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.328555] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
ModemManager[6194]: <debug> [1474459945.340060] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.340468] [mm-broadband-modem.c:1302] modem_load_unlock_required(): checking if unlock required...
ModemManager[6194]: <debug> [1474459945.340578] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.340637] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.340692] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPIN?<CR>'
ModemManager[6194]: <debug> [1474459945.355066] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.355165] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459945.355205] [mm-iface-modem.c:3110] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[6194]: <debug> [1474459945.355392] [mm-base-sim.c:1004] load_sim_identifier(): loading SIM identifier...
ModemManager[6194]: <debug> [1474459945.355419] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.355453] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,12258,0,0,10<CR>'
ModemManager[6194]: <debug> [1474459945.421457] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 144,0,"98743050210111001859"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.421809] [mm-base-sim.c:993] load_sim_identifier_finish(): loaded SIM identifier: 89470305121011008195
ModemManager[6194]: <debug> [1474459945.421932] [mm-base-sim.c:1074] load_imsi(): loading IMSI...
ModemManager[6194]: <debug> [1474459945.422005] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.422091] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.422173] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIMI<CR>'
ModemManager[6194]: <debug> [1474459945.433988] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>242013050138190<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.434339] [mm-base-sim.c:1063] load_imsi_finish(): loaded IMSI: 242013050138190
ModemManager[6194]: <debug> [1474459945.434652] [mm-base-sim.c:1194] load_operator_identifier(): loading Operator ID...
ModemManager[6194]: <debug> [1474459945.434793] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.434966] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.435129] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,28589,0,0,4<CR>'
ModemManager[6194]: <debug> [1474459945.516162] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 144,0,"00000002"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.516583] [mm-base-sim.c:1289] load_operator_name(): loading Operator Name...
ModemManager[6194]: <debug> [1474459945.516683] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.516775] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.516880] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRSM=176,28486,0,0,17<CR>'
ModemManager[6194]: <debug> [1474459945.603187] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CRSM: 144,0,"0054454C454E4F52FFFFFFFFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.603320] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459945.603403] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[6194]: <debug> [1474459945.603591] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459945.603614] [mm-broadband-modem.c:1180] modem_load_own_numbers(): loading own numbers...
ModemManager[6194]: <debug> [1474459945.603635] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.603672] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNUM<CR>'
ModemManager[6194]: <debug> [1474459945.619192] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.619375] [mm-port-serial.c:1345] _close_internal(): (ttyUSB0) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459945.619401] [mm-port-serial.c:1361] _close_internal(): (ttyUSB0) closing serial port...
ModemManager[6194]: <debug> [1474459945.619888] [mm-port-serial.c:1409] _close_internal(): (ttyUSB0) serial port closed
ModemManager[6194]: <debug> [1474459945.619919] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459945.620302] [mm-broadband-modem.c:3247] modem_3gpp_load_imei(): loading IMEI...
ModemManager[6194]: <debug> [1474459945.620440] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.620669] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGSN<CR>'
ModemManager[6194]: <debug> [1474459945.636012] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>014582000786815<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.636088] [mm-broadband-modem.c:3238] modem_3gpp_load_imei_finish(): loaded IMEI: 014582000786815
ModemManager[6194]: <debug> [1474459945.636127] [mm-broadband-modem.c:3406] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
ModemManager[6194]: <debug> [1474459945.636153] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.636176] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.636204] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK=?<CR>'
ModemManager[6194]: <debug> [1474459945.651644] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: ("AB","AC","AG","AI","AO","IR","OI","OX","SC","FD","PN","PU","PP","PC","PF")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.651818] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.651856] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.651883] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="SC",2<CR>'
ModemManager[6194]: <debug> [1474459945.676370] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.676562] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.676620] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.676672] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="FD",2<CR>'
ModemManager[6194]: <debug> [1474459945.703141] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.703302] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.703364] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.703424] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PF",2<CR>'
ModemManager[6194]: <debug> [1474459945.754376] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.754748] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.754862] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.754984] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PN",2<CR>'
ModemManager[6194]: <debug> [1474459945.807598] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.807890] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.807995] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.808082] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PU",2<CR>'
ModemManager[6194]: <debug> [1474459945.860851] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.861117] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.861222] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.861311] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PP",2<CR>'
ModemManager[6194]: <debug> [1474459945.896945] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.897237] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.897349] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.897575] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLCK="PC",2<CR>'
ModemManager[6194]: <debug> [1474459945.935256] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.936007] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459945.936807] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.936947] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CUSD=?<CR>'
ModemManager[6194]: <debug> [1474459945.953690] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.954262] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459945.955379] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459945.955707] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=?<CR>'
ModemManager[6194]: <debug> [1474459945.970296] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CNMI: (0,1,2),(0,1,2,3),(0,2),(0,1,2),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.970583] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.970675] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.970767] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS=?<CR>'
ModemManager[6194]: <debug> [1474459945.985859] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: ("ME","MT","SM","SR"),("ME","MT","SM","SR"),("ME","MT","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459945.986220] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported storages loaded:
ModemManager[6194]: <debug> [1474459945.986293] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'me, mt, sm, sr'
ModemManager[6194]: <debug> [1474459945.986339] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem2 (write/send) storages: 'me, mt, sm, sr'
ModemManager[6194]: <debug> [1474459945.986378] [mm-iface-modem-messaging.c:1152] load_supported_storages_ready(): mem3 (reception) storages: 'me, mt, sm, sr'
ModemManager[6194]: <debug> [1474459945.986549] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459945.986637] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459945.986729] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS?<CR>'
ModemManager[6194]: <debug> [1474459946.000121] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: "SR",0,0,"MT",0,255,"MT",0,255<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.000426] [mm-broadband-modem.c:5283] cpms_query_ready(): Current storages initialized:
ModemManager[6194]: <debug> [1474459946.000537] [mm-broadband-modem.c:5285] cpms_query_ready(): mem1 (list/read/delete) storages: 'sr'
ModemManager[6194]: <debug> [1474459946.000588] [mm-broadband-modem.c:5287] cpms_query_ready(): mem2 (write/send) storages: 'mt'
ModemManager[6194]: <debug> [1474459946.000645] [mm-iface-modem-messaging.c:1232] init_current_storages_ready(): Current storages initialized
ModemManager[6194]: <debug> [1474459946.000806] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.001075] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.001204] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATH<CR>'
ModemManager[6194]: <debug> [1474459946.008329] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.008884] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.009344] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.009637] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CTZU=1<CR>'
ModemManager[6194]: <debug> [1474459946.027128] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.027448] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CCLK?<CR>'
ModemManager[6194]: <debug> [1474459946.042817] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CCLK: "80/01/06,03:39:26+08"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.043147] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.043615] [mm-broadband-modem.c:9633] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
ModemManager[6194]: <debug> [1474459946.044085] [mm-broadband-modem.c:9634] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
ModemManager[6194]: <debug> [1474459946.044314] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available'
ModemManager[6194]: <info> [1474459946.044629] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[6194]: <debug> [1474459946.044836] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 0 (close)
ModemManager[6194]: <debug> [1474459946.044897] [mm-port-serial.c:1361] _close_internal(): (ttyUSB2) closing serial port...
ModemManager[6194]: <debug> [1474459946.046260] [mm-port-serial.c:1409] _close_internal(): (ttyUSB2) serial port closed
ModemManager[6194]: <debug> [1474459946.046399] [mm-base-modem.c:833] initialize_ready(): modem properly initialized
ModemManager[6194]: <debug> [1474459946.049995] [mm-device.c:452] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2' at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[6194]: <debug> [1474459946.050161] [mm-device.c:462] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Sierra' modem, VID 0x1199 PID 0x9079 (usb)
ModemManager[6194]: <info> [1474459946.071347] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[6194]: <debug> [1474459946.072321] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB2) opening serial port...
ModemManager[6194]: <warn> [1474459946.073872] [mm-port-serial.c:513] real_config_fd(): (ttyUSB2): port attributes not fully set
ModemManager[6194]: <debug> [1474459946.074106] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459946.074226] [mm-port-serial.c:1158] mm_port_serial_open(): (ttyUSB0) opening serial port...
ModemManager[6194]: <debug> [1474459946.074856] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
ModemManager[6194]: <debug> [1474459946.075025] [mm-broadband-modem.c:8552] enabling_started(): Flashing primary AT port before enabling...
ModemManager[6194]: <debug> [1474459946.176678] [mm-broadband-modem.c:8441] enabling_flash_done(): Running modem initialization sequence...
ModemManager[6194]: <debug> [1474459946.176849] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.177047] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATZ<CR>'
ModemManager[6194]: <debug> [1474459946.188477] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.188786] [mm-broadband-modem.c:8422] enabling_modem_init_ready(): Giving some time to settle the modem...
ModemManager[6194]: <debug> [1474459946.188892] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.690115] [mm-port-serial-at.c:495] mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
ModemManager[6194]: <debug> [1474459946.690439] [mm-broadband-modem.c:3144] load_power_state(): loading power state...
ModemManager[6194]: <debug> [1474459946.690660] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.690847] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATE0<CR>'
ModemManager[6194]: <debug> [1474459946.696065] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'AT'
ModemManager[6194]: <debug> [1474459946.699332] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- 'E0<CR><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.699878] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATV1<CR>'
ModemManager[6194]: <debug> [1474459946.708976] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.710356] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMEE=1<CR>'
ModemManager[6194]: <debug> [1474459946.727296] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.727742] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'ATX4<CR>'
ModemManager[6194]: <debug> [1474459946.737580] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.738387] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT&C1<CR>'
ModemManager[6194]: <debug> [1474459946.746860] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.748005] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CFUN?<CR>'
ModemManager[6194]: <debug> [1474459946.762342] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.763290] [mm-iface-modem.c:3293] set_power_state(): No need to change power state: already in 'on' power state
ModemManager[6194]: <debug> [1474459946.763430] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.764370] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.764723] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.764886] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+IFC=1,1<CR>'
ModemManager[6194]: <debug> [1474459946.785348] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.785753] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459946.785967] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS=?<CR>'
ModemManager[6194]: <debug> [1474459946.801006] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSCS: ("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.801419] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.801610] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459946.801734] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS="UCS2"<CR>'
ModemManager[6194]: <debug> [1474459946.824785] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.824998] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.825077] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459946.825173] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CSCS?<CR>'
ModemManager[6194]: <debug> [1474459946.838837] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.839054] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.839137] [mm-broadband-modem.c:9197] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
ModemManager[6194]: <debug> [1474459946.839210] [mm-broadband-modem.c:2606] modem_3gpp_setup_unsolicited_events(): Checking indicator support...
ModemManager[6194]: <debug> [1474459946.839270] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.839377] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIND=?<CR>'
ModemManager[6194]: <debug> [1474459946.853142] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.853461] [mm-broadband-modem.c:2559] cind_format_check_ready(): Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5)
ModemManager[6194]: <debug> [1474459946.853601] [mm-broadband-modem.c:2568] cind_format_check_ready(): Modem supports roaming indications via CIND at index '5'
ModemManager[6194]: <debug> [1474459946.853652] [mm-broadband-modem.c:2577] cind_format_check_ready(): Modem supports service indications via CIND at index '3'
ModemManager[6194]: <debug> [1474459946.853731] [mm-broadband-modem.c:2510] set_unsolicited_events_handlers(): (ttyUSB2) Setting 3GPP unsolicited events handlers
ModemManager[6194]: <debug> [1474459946.853902] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.853998] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459946.854097] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMER=3,0,0,1<CR>'
ModemManager[6194]: <debug> [1474459946.877976] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.878785] [mm-broadband-modem.c:3606] modem_3gpp_setup_unsolicited_registration_events(): (ttyUSB2) setting up 3GPP unsolicited registration messages handlers
ModemManager[6194]: <debug> [1474459946.878937] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.879111] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.879253] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG=2<CR>'
ModemManager[6194]: <debug> [1474459946.893188] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.893737] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.893868] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459946.893981] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG=2<CR>'
ModemManager[6194]: <debug> [1474459946.912191] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.912442] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.912779] [mm-broadband-modem.c:9210] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
ModemManager[6194]: <debug> [1474459946.912952] [mm-broadband-modem.c:4920] set_unsolicited_result_code_handlers(): (ttyUSB2) Setting unsolicited result code handlers
ModemManager[6194]: <debug> [1474459946.913170] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.913304] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CUSD=1<CR>'
ModemManager[6194]: <debug> [1474459946.930593] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.930922] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459946.931896] [mm-broadband-modem.c:9238] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[6194]: <debug> [1474459946.932037] [mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[6194]: <debug> [1474459946.932204] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[6194]: <debug> [1474459946.932296] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459946.932808] [mm-broadband-modem.c:9251] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[6194]: <debug> [1474459946.932996] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459946.933116] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG?<CR>'
ModemManager[6194]: <debug> [1474459946.950337] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,1,"FFFE","105880A",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <info> [1474459946.950609] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
ModemManager[6194]: <debug> [1474459946.950684] [mm-broadband-modem.c:3442] modem_3gpp_load_operator_code(): loading Operator Code...
ModemManager[6194]: <debug> [1474459946.950761] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
ModemManager[6194]: <debug> [1474459946.950925] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> lte)
ModemManager[6194]: <debug> [1474459946.951029] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: 'FFFE', Cell ID: '105880A')
ModemManager[6194]: <debug> [1474459946.951176] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
ModemManager[6194]: <debug> [1474459946.951259] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
ModemManager[6194]: <debug> [1474459946.954059] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGF=?<CR>'
ModemManager[6194]: <debug> [1474459946.968028] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.968398] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
ModemManager[6194]: <debug> [1474459946.968617] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
ModemManager[6194]: <debug> [1474459946.968761] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+COPS=3,2;+COPS?<CR>'
ModemManager[6194]: <debug> [1474459946.997783] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,2,"24201",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459946.998090] [mm-broadband-modem.c:3432] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24201
ModemManager[6194]: <debug> [1474459946.998276] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '1', Location area code: 'FFFE', Cell ID: '105880A')
ModemManager[6194]: <debug> [1474459946.998349] [mm-broadband-modem.c:3478] modem_3gpp_load_operator_name(): loading Operator Name...
ModemManager[6194]: <debug> [1474459946.998417] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 5 (open)
ModemManager[6194]: <debug> [1474459946.998574] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 4 (close)
ModemManager[6194]: <debug> [1474459946.998991] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG?<CR>'
ModemManager[6194]: <debug> [1474459947.015804] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGREG: 2,1,"FFFE","105880A",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.016258] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
ModemManager[6194]: <debug> [1474459947.016628] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGF=0<CR>'
ModemManager[6194]: <debug> [1474459947.033930] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.034134] [mm-broadband-modem.c:5585] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
ModemManager[6194]: <debug> [1474459947.034367] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 4 (open)
ModemManager[6194]: <debug> [1474459947.034676] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 3 (close)
ModemManager[6194]: <debug> [1474459947.035429] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+COPS=3,0;+COPS?<CR>'
ModemManager[6194]: <debug> [1474459947.067482] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+COPS: 0,0,"004E00300 0054003",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.067904] [mm-broadband-modem.c:3468] modem_3gpp_load_operator_name_finish(): loaded Operator Name: 004E00300 0054003
ModemManager[6194]: <debug> [1474459947.068112] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <info> [1474459947.069263] [mm-iface-modem-3gpp.c:1096] update_registration_reload_current_registration_info_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
ModemManager[6194]: <debug> [1474459947.069440] [mm-iface-modem.c:1600] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
ModemManager[6194]: <debug> [1474459947.070104] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SR","MT","MT"<CR>'
ModemManager[6194]: <debug> [1474459947.101439] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,0,0,255,0,255<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.101764] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
ModemManager[6194]: <debug> [1474459947.101849] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
ModemManager[6194]: <debug> [1474459947.101934] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.102029] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.102130] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="ME"<CR>'
ModemManager[6194]: <debug> [1474459947.122212] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,255,0,255,0,255<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.122427] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.122697] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.122804] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
ModemManager[6194]: <debug> [1474459947.138369] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.138743] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
ModemManager[6194]: <debug> [1474459947.138837] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
ModemManager[6194]: <debug> [1474459947.138920] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.139013] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.139123] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SM"<CR>'
ModemManager[6194]: <debug> [1474459947.160808] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,20,0,255,0,255<CR><LF><CR><LF>OK<CR><LF><CR><LF>+CIEV: 5,0<CR><LF>'
ModemManager[6194]: <debug> [1474459947.161045] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.161159] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.161291] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
ModemManager[6194]: <debug> [1474459947.178098] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.178431] [mm-broadband-modem.c:6357] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
ModemManager[6194]: <debug> [1474459947.178628] [mm-broadband-modem.c:5471] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
ModemManager[6194]: <debug> [1474459947.178767] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.178880] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.178996] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CPMS="SR"<CR>'
ModemManager[6194]: <debug> [1474459947.197756] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CPMS: 0,0,0,255,0,255<CR><LF><CR><LF>OK<CR><LF><CR><LF>+CIEV: 5,1<CR><LF>'
ModemManager[6194]: <debug> [1474459947.197979] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.198052] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.198119] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CMGL=4<CR>'
ModemManager[6194]: <debug> [1474459947.213582] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.213989] [mm-broadband-modem.c:5888] set_messaging_unsolicited_events_handlers(): (ttyUSB2) Setting messaging unsolicited events handlers
ModemManager[6194]: <debug> [1474459947.214209] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459947.214341] [mm-broadband-modem.c:6061] modem_messaging_enable_unsolicited_events(): (ttyUSB2) Enabling messaging unsolicited events on primary port
ModemManager[6194]: <debug> [1474459947.214427] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459947.214604] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,1,0<CR>'
ModemManager[6194]: <debug> [1474459947.240955] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
ModemManager[6194]: <debug> [1474459947.241238] [mm-serial-parsers.c:364] mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
ModemManager[6194]: <debug> [1474459947.241654] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CNMI=2,1,2,2,0<CR>'
ModemManager[6194]: <debug> [1474459947.268952] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.269156] [mm-broadband-modem.c:6022] modem_messaging_enable_unsolicited_events_primary_ready(): (ttyUSB2) Messaging unsolicited events enabled on primary
ModemManager[6194]: <debug> [1474459947.269276] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459947.269412] [mm-broadband-modem.c:9264] enabling_step(): Modem has voice capabilities, enabling the Voice interface...
ModemManager[6194]: <debug> [1474459947.269817] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Setting voice unsolicited events handlers
ModemManager[6194]: <debug> [1474459947.269966] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459947.270088] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLIP=1<CR>'
ModemManager[6194]: <debug> [1474459947.284774] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.285053] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRC=1<CR>'
ModemManager[6194]: <debug> [1474459947.299940] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.300277] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459947.300615] [mm-broadband-modem.c:9277] enabling_step(): Modem has time capabilities, enabling the Time interface...
ModemManager[6194]: <info> [1474459947.300871] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
ModemManager[6194]: <debug> [1474459947.301614] [mm-iface-modem.c:1355] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
ModemManager[6194]: <debug> [1474459947.301756] [mm-broadband-modem.c:2003] modem_load_signal_quality(): loading signal quality...
ModemManager[6194]: <debug> [1474459947.301845] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459947.301943] [mm-iface-modem.c:1075] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
ModemManager[6194]: <debug> [1474459947.302020] [mm-broadband-modem.c:2401] modem_load_access_technologies(): loading access technologies via QCDM...
ModemManager[6194]: <debug> [1474459947.302422] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[6194]: <debug> [1474459947.302567] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.302671] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CIND?<CR>'
ModemManager[6194]: <debug> [1474459947.302825] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 4b 08 01 00 66 f5 7e
ModemManager[6194]: <debug> [1474459947.305131] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 4b 08 01 00 08 0a 41 85 02 00 87 86 51 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 05 05 09 3a 62 7e
ModemManager[6194]: <debug> [1474459947.305754] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): --> 4b 04 0f 00 d5 ca 7e
ModemManager[6194]: <debug> [1474459947.308202] [mm-port-serial-qcdm.c:199] debug_log(): (ttyUSB0): <-- 4b 04 0f 00 08 0a 41 85 02 00 87 86 51 08 29 24 10 03 05 31 18 09 0a 06 78 7e
ModemManager[6194]: <debug> [1474459947.308717] [mm-broadband-modem.c:2100] access_tech_context_complete_and_free(): QCDM operating mode: 5
ModemManager[6194]: <debug> [1474459947.308873] [mm-broadband-modem.c:2101] access_tech_context_complete_and_free(): QCDM system mode: 9
ModemManager[6194]: <debug> [1474459947.308951] [mm-broadband-modem.c:2102] access_tech_context_complete_and_free(): QCDM hybrid pref: 0
ModemManager[6194]: <debug> [1474459947.309031] [mm-broadband-modem.c:2103] access_tech_context_complete_and_free(): QCDM WCDMA open: 0
ModemManager[6194]: <debug> [1474459947.309099] [mm-broadband-modem.c:2104] access_tech_context_complete_and_free(): QCDM EVDO open: 0
ModemManager[6194]: <debug> [1474459947.322851] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: 0,5,1,1,0,1,1,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.323256] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.323665] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (100)
ModemManager[6194]: <debug> [1474459947.323771] [mm-iface-modem.c:1274] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
ModemManager[6194]: <debug> [1474459947.324271] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CREG?<CR>'
ModemManager[6194]: <debug> [1474459947.339120] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CREG: 2,1,"FFFE","105880A",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.339564] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 3 (open)
ModemManager[6194]: <debug> [1474459947.339691] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 2 (close)
ModemManager[6194]: <debug> [1474459947.339820] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CGREG?<CR>'
ModemManager[6194]: <debug> [1474459947.355823] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGREG: 2,1,"FFFE","105880A",7<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459947.356469] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[6194]: <debug> [1474459947.356684] [mm-broadband-modem.c:8574] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished
ModemManager[6194]: <debug> [1474459952.474740] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[6194]: <debug> [1474459952.475081] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CCLK?<CR>'
ModemManager[6194]: <debug> [1474459952.492362] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CCLK: "80/01/06,03:39:32+08"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[6194]: <debug> [1474459952.493191] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
More information about the ModemManager-devel
mailing list