Lenovo (x260) and EM7455
Bjørnar Ness
bjornar.ness at gmail.com
Wed Aug 17 10:48:35 UTC 2016
I see others are having similar problems, and wonder if you have any
idea what is going on here?
Some details:
Kernel: 4.7
libmbim-1.14.0
libqmi-1.16.0
ModemManager-1.6.0
Logfile attached. Sim card is verified working in x230
--
Bj(/)rnar
-------------- next part --------------
# usb-devices
T: Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=480 MxCh=12
D: Ver= 2.00 Cls=09(hub ) Sub=00 Prot=01 MxPS=64 #Cfgs= 1
P: Vendor=1d6b ProdID=0002 Rev=04.07
S: Manufacturer=Linux 4.7.0 xhci-hcd
S: Product=xHCI Host Controller
S: SerialNumber=0000:00:14.0
C: #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=0mA
I: If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
T: Bus=01 Lev=01 Prnt=01 Port=02 Cnt=01 Dev#= 2 Spd=480 MxCh= 0
D: Ver= 2.10 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs= 1
P: Vendor=1199 ProdID=9079 Rev=00.06
S: Manufacturer=Sierra Wireless, Incorporated
S: Product=Sierra Wireless EM7455 Qualcomm Snapdragon X7 LTE-A
S: SerialNumber=LF60820129031013
C: #Ifs= 2 Cfg#= 1 Atr=a0 MxPwr=500mA
/usr/bin/usb-devices: line 79: printf: c: invalid number
I: If#= 0 Alt= 0 #EPs= 1 Cls=02(commc) Sub=0e Prot=00 Driver=cdc_mbim
/usr/bin/usb-devices: line 79: printf: d: invalid number
I: If#= 0 Alt= 1 #EPs= 2 Cls=0a(data ) Sub=00 Prot=02 Driver=cdc_mbim
T: Bus=01 Lev=01 Prnt=01 Port=06 Cnt=02 Dev#= 3 Spd=12 MxCh= 0
D: Ver= 2.00 Cls=e0(wlcon) Sub=01 Prot=01 MxPS=64 #Cfgs= 1
P: Vendor=8087 ProdID=0a2b Rev=00.01
C: #Ifs= 2 Cfg#= 1 Atr=e0 MxPwr=100mA
I: If#= 0 Alt= 0 #EPs= 3 Cls=e0(wlcon) Sub=01 Prot=01 Driver=btusb
I: If#= 1 Alt= 0 #EPs= 2 Cls=e0(wlcon) Sub=01 Prot=01 Driver=btusb
T: Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#= 1 Spd=5000 MxCh= 6
D: Ver= 3.00 Cls=09(hub ) Sub=00 Prot=03 MxPS= 9 #Cfgs= 1
P: Vendor=1d6b ProdID=0003 Rev=04.07
S: Manufacturer=Linux 4.7.0 xhci-hcd
S: Product=xHCI Host Controller
S: SerialNumber=0000:00:14.0
C: #Ifs= 1 Cfg#= 1 Atr=e0 MxPwr=0mA
I: If#= 0 Alt= 0 #EPs= 1 Cls=09(hub ) Sub=00 Prot=00 Driver=hub
# qmicli -v -p -d /dev/cdc-wdm0 --device-open-mbim --dms-set-fcc-authentication
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Opening device with flags 'proxy, mbim'...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] creating MBIM device...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] MBIM device created
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] opening MBIM device...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] MBIM device open
[17 Aug 2016, 12:34:29] [Debug] QMI Device at '/dev/cdc-wdm0' ready
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Assuming service 'dms' is supported...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Allocating new client ID...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:01:22:00:04:00:01:01:00:02
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = dms
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] sending message as MBIM...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received MBIM message
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:01:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:03
>>>>>> translated = [ service = 'dms' cid = '3' ]
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '3'
[17 Aug 2016, 12:34:29] [Debug] Asynchronously setting FCC auth...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:03:00:01:00:5F:55:00:00
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 3
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Set FCC Authentication" (0x555F)
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] sending message as MBIM...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received MBIM message
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:03:02:01:00:5F:55:07:00:02:04:00:01:00:1A:00
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 3
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Set FCC Authentication" (0x555F)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:1A:00
>>>>>> translated = FAILURE: NoEffect
error: couldn't set FCC authentication: QMI protocol error (26): 'NoEffect'
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Unregistered 'dms' client with ID '3'
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:00:00:00:02:23:00:05:00:01:02:00:02:03
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 5
<<<<<< message = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<< type = "Release Info" (0x01)
<<<<<< length = 2
<<<<<< value = 02:03
<<<<<< translated = [ service = 'dms' cid = '3' ]
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] sending message as MBIM...
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received MBIM message
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:02:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
[17 Aug 2016, 12:34:29] [Debug] [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 12
>>>>>> message = "Release CID" (0x0023)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Release Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:03
>>>>>> translated = [ service = 'dms' cid = '3' ]
[17 Aug 2016, 12:34:29] [Debug] Client released
# qmicli -p -d /dev/cdc-wdm0 --device-open-mbim --uim-get-card-status
[/dev/cdc-wdm0] Successfully got card status
Provisioning applications:
Primary GW: slot '0', application '0'
Primary 1X: session doesn't exist
Secondary GW: session doesn't exist
Secondary 1X: session doesn't exist
Card [0]:
Card state: 'present'
UPIN state: 'not-initialized'
UPIN retries: '0'
UPUK retries: '0'
Application [0]:
Application type: 'usim (2)'
Application state: 'ready'
Application ID:
A0:00:00:00:87:10:02:FF:49:FF:05:89
Personalization state: 'ready'
UPIN replaces PIN1: 'no'
PIN1 state: 'disabled'
PIN1 retries: '3'
PUK1 retries: '10'
PIN2 state: 'enabled-not-verified'
PIN2 retries: '3'
PUK2 retries: '10'
Application [1]:
Application type: 'unknown (0)'
Application state: 'detected'
Application ID:
A0:00:00:00:63:50:4B:43:53:2D:31:35
Personalization state: 'unknown'
UPIN replaces PIN1: 'no'
PIN1 state: 'not-initialized'
PIN1 retries: '0'
PUK1 retries: '0'
PIN2 state: 'not-initialized'
PIN2 retries: '0'
PUK2 retries: '0'
Card [1]:
Card state: 'absent'
UPIN state: 'not-initialized'
UPIN retries: '0'
UPUK retries: '0'
# systemctl stop ModemManager
# lsof /dev/cdc-wdm0
# ModemManager --debug
ModemManager[3517]: <info> [1471430147.483982] [main.c:158] main(): ModemManager (version 1.6.0) starting in system bus...
ModemManager[3517]: <debug> [1471430147.486483] [mm-sleep-monitor.c:245] mm_sleep_monitor_get(): create MMSleepMonitor singleton (0x891090)
ModemManager[3517]: <debug> [1471430147.492325] [main.c:83] bus_acquired_cb(): Bus acquired, creating manager...
ModemManager[3517]: <debug> [1471430147.500190] [mm-plugin-manager.c:1559] load_plugins(): [plugin manager] looking for plugins in '/usr/lib/ModemManager'
ModemManager[3517]: <debug> [1471430147.502209] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'X22X'
ModemManager[3517]: <debug> [1471430147.503438] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Thuraya'
ModemManager[3517]: <debug> [1471430147.504590] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Altair LTE'
ModemManager[3517]: <debug> [1471430147.505555] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra'
ModemManager[3517]: <debug> [1471430147.506170] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Ericsson MBM'
ModemManager[3517]: <debug> [1471430147.506720] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'AnyDATA'
ModemManager[3517]: <debug> [1471430147.507280] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Via CBP7'
ModemManager[3517]: <debug> [1471430147.507922] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Huawei'
ModemManager[3517]: <debug> [1471430147.508552] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Sierra (legacy)'
ModemManager[3517]: <debug> [1471430147.509127] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'MTK'
ModemManager[3517]: <debug> [1471430147.509792] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option High-Speed'
ModemManager[3517]: <debug> [1471430147.510373] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Pantech'
ModemManager[3517]: <debug> [1471430147.510847] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Option'
ModemManager[3517]: <debug> [1471430147.511226] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Wavecom'
ModemManager[3517]: <debug> [1471430147.511682] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Longcheer'
ModemManager[3517]: <debug> [1471430147.512152] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Dell'
ModemManager[3517]: <debug> [1471430147.512564] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Motorola'
ModemManager[3517]: <debug> [1471430147.512958] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'SimTech'
ModemManager[3517]: <debug> [1471430147.513406] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia (Icera)'
ModemManager[3517]: <debug> [1471430147.513791] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Linktop'
ModemManager[3517]: <debug> [1471430147.514161] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Generic'
ModemManager[3517]: <debug> [1471430147.514599] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Cinterion'
ModemManager[3517]: <debug> [1471430147.515028] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel'
ModemManager[3517]: <debug> [1471430147.515463] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'ZTE'
ModemManager[3517]: <debug> [1471430147.515772] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Novatel LTE'
ModemManager[3517]: <debug> [1471430147.516084] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Iridium'
ModemManager[3517]: <debug> [1471430147.516388] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Nokia'
ModemManager[3517]: <debug> [1471430147.516736] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Telit'
ModemManager[3517]: <debug> [1471430147.517062] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Haier'
ModemManager[3517]: <debug> [1471430147.517388] [mm-plugin-manager.c:1584] load_plugins(): [plugin manager] loaded plugin 'Samsung'
ModemManager[3517]: <debug> [1471430147.517571] [mm-plugin-manager.c:1609] load_plugins(): [plugin manager] successfully loaded 30 plugins
ModemManager[3517]: <debug> [1471430147.517894] [mm-sleep-monitor.c:108] take_inhibitor(): [sleep-monitor] taking systemd sleep inhibitor
ModemManager[3517]: <debug> [1471430147.519015] [main.c:105] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
ModemManager[3517]: <debug> [1471430147.519278] [mm-base-manager.c:498] mm_base_manager_start(): Starting automatic device scan...
ModemManager[3517]: <debug> [1471430147.527161] [mm-base-manager.c:537] mm_base_manager_start(): Finished device scan...
ModemManager[3517]: <debug> [1471430147.527513] [mm-sleep-monitor.c:97] inhibit_done(): [sleep-monitor] inhibitor fd is 9
ModemManager[3517]: <debug> [1471430147.527792] [mm-base-manager.c:388] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.528020] [mm-base-manager.c:388] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.528254] [mm-base-manager.c:388] device_added(): (tty/ttyS10): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.528459] [mm-base-manager.c:388] device_added(): (tty/ttyS11): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.528664] [mm-base-manager.c:388] device_added(): (tty/ttyS12): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.528865] [mm-base-manager.c:388] device_added(): (tty/ttyS13): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529064] [mm-base-manager.c:388] device_added(): (tty/ttyS14): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529136] [mm-base-manager.c:388] device_added(): (tty/ttyS15): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529204] [mm-base-manager.c:388] device_added(): (tty/ttyS16): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529270] [mm-base-manager.c:388] device_added(): (tty/ttyS17): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529342] [mm-base-manager.c:388] device_added(): (tty/ttyS18): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529405] [mm-base-manager.c:388] device_added(): (tty/ttyS19): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529473] [mm-base-manager.c:388] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529539] [mm-base-manager.c:388] device_added(): (tty/ttyS20): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529603] [mm-base-manager.c:388] device_added(): (tty/ttyS21): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529668] [mm-base-manager.c:388] device_added(): (tty/ttyS22): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529732] [mm-base-manager.c:388] device_added(): (tty/ttyS23): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529797] [mm-base-manager.c:388] device_added(): (tty/ttyS24): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529860] [mm-base-manager.c:388] device_added(): (tty/ttyS25): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529924] [mm-base-manager.c:388] device_added(): (tty/ttyS26): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.529992] [mm-base-manager.c:388] device_added(): (tty/ttyS27): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530054] [mm-base-manager.c:388] device_added(): (tty/ttyS28): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530116] [mm-base-manager.c:388] device_added(): (tty/ttyS29): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530180] [mm-base-manager.c:388] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530243] [mm-base-manager.c:388] device_added(): (tty/ttyS30): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530306] [mm-base-manager.c:388] device_added(): (tty/ttyS31): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530384] [mm-base-manager.c:388] device_added(): (tty/ttyS4): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530444] [mm-base-manager.c:388] device_added(): (tty/ttyS5): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530503] [mm-base-manager.c:388] device_added(): (tty/ttyS6): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530562] [mm-base-manager.c:388] device_added(): (tty/ttyS7): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530620] [mm-base-manager.c:388] device_added(): (tty/ttyS8): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.530679] [mm-base-manager.c:388] device_added(): (tty/ttyS9): port's parent platform driver is not whitelisted
ModemManager[3517]: <debug> [1471430147.531112] [mm-base-manager.c:364] device_added(): (tty/ttyprintk): could not get port's parent device
ModemManager[3517]: <debug> [1471430147.531376] [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-3
ModemManager[3517]: <debug> [1471430147.531611] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: wwan0
ModemManager[3517]: <debug> [1471430147.531761] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,wwan0: new support task for port
ModemManager[3517]: <debug> [1471430147.531775] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,wwan0: deferred until min wait time elapsed
ModemManager[3517]: <debug> [1471430147.531946] [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[3517]: <debug> [1471430147.531982] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 1: port grabbed: wlp4s0
ModemManager[3517]: <debug> [1471430147.531991] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 1,wlp4s0: new support task for port
ModemManager[3517]: <debug> [1471430147.531998] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 1,wlp4s0: deferred until min wait time elapsed
ModemManager[3517]: <debug> [1471430147.532109] [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[3517]: <debug> [1471430147.532138] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 2: port grabbed: enp0s31f6
ModemManager[3517]: <debug> [1471430147.532146] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 2,enp0s31f6: new support task for port
ModemManager[3517]: <debug> [1471430147.532152] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 2,enp0s31f6: deferred until min wait time elapsed
ModemManager[3517]: <debug> [1471430147.532341] [mm-plugin-manager.c:1167] device_context_port_grabbed(): [plugin manager] task 0: port grabbed: cdc-wdm0
ModemManager[3517]: <debug> [1471430147.532351] [mm-plugin-manager.c:1192] device_context_port_grabbed(): [plugin manager] task 0,cdc-wdm0: new support task for port
ModemManager[3517]: <debug> [1471430147.532358] [mm-plugin-manager.c:1197] device_context_port_grabbed(): [plugin manager) task 0,cdc-wdm0: deferred until min wait time elapsed
ModemManager[3517]: <debug> [1471430149.034202] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 0: min wait time elapsed
ModemManager[3517]: <debug> [1471430149.034264] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [cdc-wdm0] filtered by implicit MBIM driver
ModemManager[3517]: <debug> [1471430149.034284] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [cdc-wdm0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034301] [mm-plugin.c:212] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034331] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,cdc-wdm0: found '2' plugins to try
ModemManager[3517]: <debug> [1471430149.034347] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Sierra'
ModemManager[3517]: <debug> [1471430149.034368] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,cdc-wdm0: will try with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.034399] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,cdc-wdm0: started
ModemManager[3517]: <debug> [1471430149.034424] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,cdc-wdm0: checking with plugin 'Sierra'
ModemManager[3517]: <debug> [1471430149.034492] [mm-plugin.c:804] mm_plugin_supports_port(): (Sierra) [cdc-wdm0] probe required: 'mbim'
ModemManager[3517]: <debug> [1471430149.034524] [mm-port-probe.c:1377] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
ModemManager[3517]: <debug> [1471430149.034556] [mm-plugin.c:310] apply_pre_probing_filters(): (X22X) [wwan0] filtered by implicit MBIM driver
ModemManager[3517]: <debug> [1471430149.034574] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wwan0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034590] [mm-plugin.c:310] apply_pre_probing_filters(): (Altair LTE) [wwan0] filtered by implicit MBIM driver
ModemManager[3517]: <debug> [1471430149.034606] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 0,wwan0: found '2' plugins to try
ModemManager[3517]: <debug> [1471430149.034619] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,wwan0: will try with plugin 'Sierra'
ModemManager[3517]: <debug> [1471430149.034634] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 0,wwan0: will try with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.034651] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 0,wwan0: started
ModemManager[3517]: <debug> [1471430149.034664] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,wwan0: checking with plugin 'Sierra'
ModemManager[3517]: <debug> [1471430149.034686] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwan0] probing deferred until result suggested
ModemManager[3517]: <debug> [1471430149.034714] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 1: min wait time elapsed
ModemManager[3517]: <debug> [1471430149.034734] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034750] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034769] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034786] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [wlp4s0] filtered by drivers
ModemManager[3517]: <debug> [1471430149.034809] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [wlp4s0] filtered by udev tags
ModemManager[3517]: <debug> [1471430149.034829] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034843] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034855] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034869] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [wlp4s0] filtered by drivers
ModemManager[3517]: <debug> [1471430149.034883] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034897] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [wlp4s0] filtered by drivers
ModemManager[3517]: <debug> [1471430149.034910] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034923] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034937] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034951] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.034966] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.034987] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035038] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035056] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035071] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035085] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035097] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035109] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035123] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035134] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035148] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035162] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035176] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [wlp4s0] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035189] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [wlp4s0] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035205] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 1,wlp4s0: found '1' plugins to try
ModemManager[3517]: <debug> [1471430149.035219] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 1,wlp4s0: will try with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.035242] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 1,wlp4s0: started
ModemManager[3517]: <debug> [1471430149.035259] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 1,wlp4s0: checking with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.035314] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [wlp4s0] probing deferred until result suggested
ModemManager[3517]: <debug> [1471430149.035344] [mm-plugin-manager.c:1110] device_context_min_wait_time_elapsed(): [plugin manager] task 2: min wait time elapsed
ModemManager[3517]: <debug> [1471430149.035359] [mm-plugin.c:376] apply_pre_probing_filters(): (X22X) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035370] [mm-plugin.c:212] apply_pre_probing_filters(): (Thuraya) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035382] [mm-plugin.c:376] apply_pre_probing_filters(): (Altair LTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035393] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra) [enp0s31f6] filtered by drivers
ModemManager[3517]: <debug> [1471430149.035406] [mm-plugin.c:430] apply_pre_probing_filters(): (Ericsson MBM) [enp0s31f6] filtered by udev tags
ModemManager[3517]: <debug> [1471430149.035417] [mm-plugin.c:376] apply_pre_probing_filters(): (AnyDATA) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035428] [mm-plugin.c:212] apply_pre_probing_filters(): (Via CBP7) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035440] [mm-plugin.c:376] apply_pre_probing_filters(): (Huawei) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035451] [mm-plugin.c:264] apply_pre_probing_filters(): (Sierra (legacy)) [enp0s31f6] filtered by drivers
ModemManager[3517]: <debug> [1471430149.035463] [mm-plugin.c:212] apply_pre_probing_filters(): (MTK) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035475] [mm-plugin.c:264] apply_pre_probing_filters(): (Option High-Speed) [enp0s31f6] filtered by drivers
ModemManager[3517]: <debug> [1471430149.035486] [mm-plugin.c:376] apply_pre_probing_filters(): (Pantech) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035497] [mm-plugin.c:212] apply_pre_probing_filters(): (Option) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035508] [mm-plugin.c:212] apply_pre_probing_filters(): (Wavecom) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035519] [mm-plugin.c:212] apply_pre_probing_filters(): (Longcheer) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035531] [mm-plugin.c:376] apply_pre_probing_filters(): (Dell) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035542] [mm-plugin.c:212] apply_pre_probing_filters(): (Motorola) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035553] [mm-plugin.c:376] apply_pre_probing_filters(): (SimTech) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035565] [mm-plugin.c:376] apply_pre_probing_filters(): (Nokia (Icera)) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035576] [mm-plugin.c:212] apply_pre_probing_filters(): (Linktop) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035646] [mm-plugin.c:376] apply_pre_probing_filters(): (Cinterion) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035658] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035671] [mm-plugin.c:376] apply_pre_probing_filters(): (ZTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035682] [mm-plugin.c:376] apply_pre_probing_filters(): (Novatel LTE) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035693] [mm-plugin.c:212] apply_pre_probing_filters(): (Iridium) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035704] [mm-plugin.c:212] apply_pre_probing_filters(): (Nokia) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035715] [mm-plugin.c:212] apply_pre_probing_filters(): (Telit) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035726] [mm-plugin.c:212] apply_pre_probing_filters(): (Haier) [enp0s31f6] filtered by subsystem
ModemManager[3517]: <debug> [1471430149.035737] [mm-plugin.c:376] apply_pre_probing_filters(): (Samsung) [enp0s31f6] filtered by vendor/product IDs
ModemManager[3517]: <debug> [1471430149.035749] [mm-plugin-manager.c:599] port_context_run(): [plugin manager] task 2,enp0s31f6: found '1' plugins to try
ModemManager[3517]: <debug> [1471430149.035760] [mm-plugin-manager.c:622] port_context_run(): [plugin manager] task 2,enp0s31f6: will try with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.035775] [mm-plugin-manager.c:635] port_context_run(): [plugin manager) task 2,enp0s31f6: started
ModemManager[3517]: <debug> [1471430149.035785] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 2,enp0s31f6: checking with plugin 'Generic'
ModemManager[3517]: <debug> [1471430149.035801] [mm-plugin.c:730] mm_plugin_supports_port(): (Generic) [enp0s31f6] probing deferred until result suggested
ModemManager[3517]: <debug> [1471430149.035829] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 0,wwan0: deferring support check until result suggested
ModemManager[3517]: <debug> [1471430149.035852] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 1,wlp4s0: deferring support check until result suggested
ModemManager[3517]: <debug> [1471430149.035868] [mm-plugin-manager.c:453] port_context_defer_until_suggested(): [plugin manager] task 2,enp0s31f6: deferring support check until result suggested
ModemManager[3517]: <debug> [1471430149.035891] [mm-port-probe.c:551] wdm_probe_mbim(): (usbmisc/cdc-wdm0) probing MBIM...
ModemManager[3517]: opening device...
ModemManager[3517]: cannot connect to proxy: Could not connect: Connection refused
ModemManager[3517]: spawning new mbim-proxy (try 1)...
ModemManager[3517]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[3517]: [/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[3517]: [/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[3517]: <debug> [1471430150.033476] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 0: min probing time elapsed
ModemManager[3517]: <debug> [1471430150.033594] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 2 running probes (1 active): cdc-wdm0, wwan0
ModemManager[3517]: <debug> [1471430150.033692] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 1: min probing time elapsed
ModemManager[3517]: <debug> [1471430150.033734] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 1: still 1 running probes (0 active): wlp4s0
ModemManager[3517]: <debug> [1471430150.033772] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 1: no active tasks to probe
ModemManager[3517]: <debug> [1471430150.033919] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 1,wlp4s0: deferred task completed, no suggested plugin
ModemManager[3517]: <debug> [1471430150.034121] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 1,wlp4s0: finished in '2.502124' seconds
ModemManager[3517]: <debug> [1471430150.034333] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 1,wlp4s0: not supported by any plugin
ModemManager[3517]: <debug> [1471430150.034390] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/wlp4s0' from now on
ModemManager[3517]: <debug> [1471430150.034468] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 1: no more ports to probe
ModemManager[3517]: <debug> [1471430150.034522] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 1: finished in '2.502571' seconds
ModemManager[3517]: <info> [1471430150.034625] [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[3517]: <debug> [1471430150.034742] [mm-plugin-manager.c:1058] device_context_min_probing_time_elapsed(): [plugin manager] task 2: min probing time elapsed
ModemManager[3517]: <debug> [1471430150.034788] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 2: still 1 running probes (0 active): enp0s31f6
ModemManager[3517]: <debug> [1471430150.034828] [mm-plugin-manager.c:1009] device_context_continue(): [plugin manager] task 2: no active tasks to probe
ModemManager[3517]: <debug> [1471430150.034863] [mm-plugin-manager.c:337] port_context_set_suggestion(): [plugin manager] task 2,enp0s31f6: deferred task completed, no suggested plugin
ModemManager[3517]: <debug> [1471430150.034900] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 2,enp0s31f6: finished in '2.502755' seconds
ModemManager[3517]: <debug> [1471430150.034945] [mm-plugin-manager.c:892] device_context_set_best_plugin(): [plugin manager] task 2,enp0s31f6: not supported by any plugin
ModemManager[3517]: <debug> [1471430150.035006] [mm-device.c:373] mm_device_ignore_port(): Fully ignoring port 'net/enp0s31f6' from now on
ModemManager[3517]: <debug> [1471430150.035048] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 2: no more ports to probe
ModemManager[3517]: <debug> [1471430150.035089] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 2: finished in '2.502976' seconds
ModemManager[3517]: <info> [1471430150.035145] [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[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:10:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
ModemManager[3517]: <debug> [1471430152.447073] [mm-port-probe.c:304] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is MBIM-capable
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 02:00:00:00:0C:00:00:00:03:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 12
<<<<<< type = close (0x00000002)
<<<<<< transaction = 3
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 02:00:00:80:10:00:00:00:03:00:00:00:00:00:00:00
ModemManager[3517]: <debug> [1471430152.448399] [mm-plugin-manager.c:283] port_context_supported(): [plugin manager] task 0,cdc-wdm0: found best plugin for port (Sierra)
ModemManager[3517]: <debug> [1471430152.448465] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,cdc-wdm0: finished in '4.916113' seconds
ModemManager[3517]: <debug> [1471430152.448524] [mm-plugin-manager.c:913] device_context_set_best_plugin(): [plugin manager] task 0,cdc-wdm0: found best plugin: Sierra
ModemManager[3517]: <debug> [1471430152.448566] [mm-plugin-manager.c:326] port_context_set_suggestion(): [plugin manager] task 0,wwan0: deferred task completed, got suggested plugin (Sierra)
ModemManager[3517]: <debug> [1471430152.448617] [mm-plugin-manager.c:1005] device_context_continue(): [plugin Manager] task 0: still 1 running probes (1 active): wwan0
ModemManager[3517]: <debug> [1471430152.448727] [mm-plugin-manager.c:519] port_context_next(): [plugin manager] task 0,wwan0: checking with plugin 'Sierra'
ModemManager[3517]: <debug> [1471430152.448798] [mm-plugin.c:730] mm_plugin_supports_port(): (Sierra) [wwan0] probing deferred until result suggested
ModemManager[3517]: <debug> [1471430152.448859] [mm-plugin-manager.c:434] port_context_defer_until_suggested(): [plugin manager] task 0,wwan0: completed, got suggested plugin (Sierra)
ModemManager[3517]: <debug> [1471430152.448900] [mm-plugin-manager.c:265] port_context_complete(): [plugin manager] task 0,wwan0: finished in '4.917140' seconds
ModemManager[3517]: <debug> [1471430152.448945] [mm-plugin-manager.c:966] device_context_set_best_plugin(): [plugin manager] task 0,wwan0: best plugin matches device reported one: Sierra
ModemManager[3517]: <debug> [1471430152.448985] [mm-plugin-manager.c:979] device_context_continue(): [plugin manager] task 0: no more ports to probe
ModemManager[3517]: <debug> [1471430152.449021] [mm-plugin-manager.c:813] device_context_complete(): [plugin manager] task 0: finished in '4.917648' seconds
ModemManager[3517]: <info> [1471430152.449107] [mm-device.c:525] mm_device_create_modem(): Creating modem with plugin 'Sierra' and '2' ports
ModemManager[3517]: <debug> [1471430152.449175] [sierra/mm-plugin-sierra.c:66] create_modem(): MBIM-powered Sierra modem found...
ModemManager[3517]: <debug> [1471430152.452747] [mm-base-modem.c:280] mm_base_modem_grab_port(): (cdc-wdm0) type 'mbim' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
ModemManager[3517]: <debug> [1471430152.452924] [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-3
ModemManager[3517]: <debug> [1471430152.453053] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) net/wwan0 data (primary)
ModemManager[3517]: <debug> [1471430152.453118] [mm-base-modem.c:864] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3) usb/cdc-wdm0 mbim (primary)
ModemManager[3517]: <info> [1471430152.454015] [mm-base-manager.c:175] device_support_check_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' successfully created
ModemManager[3517]: opening device...
ModemManager[3517]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[3517]: [/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[3517]: [/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[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:10:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
ModemManager[3517]: <debug> [1471430152.510215] [mm-broadband-modem-mbim.c:1608] parent_initialization_started_ready(): Couldn't start parent initialization: Couldn't open ports during modem initialization: Couldn't get primary port
ModemManager[3517]: <debug> [1471430152.510757] [mm-broadband-modem-mbim.c:218] modem_load_current_capabilities(): loading current capabilities...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 3
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'device-caps' (0x00000001)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 196
>>>>>> data = 03:00:00:80:C4:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:94:00:00:00:03:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:3C:00:00:00:03:00:00:00:01:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:40:00:00:00:1E:00:00:00:60:00:00:00:28:00:00:00:88:00:00:00:0C:00:00:00:30:00:31:00:34:00:35:00:38:00:32:00:30:00:30:00:30:00:35:00:39:00:38:00:33:00:38:00:34:00:00:00:53:00:57:00:49:00:39:00:58:00:33:00:30:00:43:00:5F:00:30:00:32:00:2E:00:30:00:38:00:2E:00:30:00:32:00:2E:00:30:00:30:00:45:00:4D:00:37:00:34:00:35:00:35:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 196
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 3
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'device-caps' (0x00000001)
ModemManager[3517]: <debug> [1471430152.573961] [mm-broadband-modem-mbim.c:144] modem_load_current_capabilities_finish(): loaded modem capabilities: gsm-umts, lte
ModemManager[3517]: <debug> [1471430152.574055] [mm-iface-modem.c:4345] interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[3517]: <debug> [1471430152.574070] [mm-bearer-list.c:270] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[3517]: <debug> [1471430152.574200] [mm-modem-helpers.c:211] mm_create_device_identifier(): Device ID source '0000119900009079014582000598384SWI9X30C_02.08.02.00MBIM[1199:9079]Sierra'
ModemManager[3517]: <debug> [1471430152.574213] [mm-modem-helpers.c:212] mm_create_device_identifier(): Device ID '1611fa8f855f2d64b4cb38ba5cc30037091cc99b'
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 4
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data = 03:00:00:80:38:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 4
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 5
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:34:00:32:00:30:00:31:00:34:00:35:00:37:00:30:00:30:00:32:00:32:00:35:00:37:00:31:00:00:00:38:00:39:00:34:00:37:00:30:00:34:00:35:00:37:00:31:00:33:00:30:00:35:00:31:00:30:00:30:00:31:00:39:00:32:00:30:00:33:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 5
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 6
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin' (0x00000004)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 60
>>>>>> data = 03:00:00:80:3C:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:03:00:00:00:01:00:00:00:03:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 60
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 6
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin' (0x00000004)
ModemManager[3517]: <debug> [1471430152.766238] [mm-iface-modem.c:3110] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 7
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:34:00:32:00:30:00:31:00:34:00:35:00:37:00:30:00:30:00:32:00:32:00:35:00:37:00:31:00:00:00:38:00:39:00:34:00:37:00:30:00:34:00:35:00:37:00:31:00:33:00:30:00:35:00:31:00:30:00:30:00:31:00:39:00:32:00:30:00:33:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 7
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 8
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:34:00:32:00:30:00:31:00:34:00:35:00:37:00:30:00:30:00:32:00:32:00:35:00:37:00:31:00:00:00:38:00:39:00:34:00:37:00:30:00:34:00:35:00:37:00:31:00:33:00:30:00:35:00:31:00:30:00:30:00:31:00:39:00:32:00:30:00:33:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 8
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 9
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 108
>>>>>> data = 03:00:00:80:6C:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:3C:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:0E:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:32:00:34:00:32:00:30:00:31:00:00:00:50:00:68:00:6F:00:6E:00:65:00:72:00:6F:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 108
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 9
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 10
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'home-provider' (0x00000006)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 108
>>>>>> data = 03:00:00:80:6C:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:3C:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:0E:00:00:00:01:00:00:00:63:00:00:00:63:00:00:00:32:00:34:00:32:00:30:00:31:00:00:00:50:00:68:00:6F:00:6E:00:65:00:72:00:6F:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 108
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 10
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'home-provider' (0x00000006)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 11
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'subscriber-ready-status' (0x00000002)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 148
>>>>>> data = 03:00:00:80:94:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:34:00:32:00:30:00:31:00:34:00:35:00:37:00:30:00:30:00:32:00:32:00:35:00:37:00:31:00:00:00:38:00:39:00:34:00:37:00:30:00:34:00:35:00:37:00:31:00:33:00:30:00:35:00:31:00:30:00:30:00:31:00:39:00:32:00:30:00:33:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 148
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 11
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'subscriber-ready-status' (0x00000002)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 12
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'pin-list' (0x00000005)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 208
>>>>>> data = 03:00:00:80:D0:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:A0:00:00:00:02:00:00:00:01:00:00:00:04:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00:04:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 208
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 12
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'pin-list' (0x00000005)
ModemManager[3517]: <debug> [1471430153.150526] [mm-iface-modem-3gpp-ussd.c:786] check_support_ready(): USSD support check failed: 'No AT port available to run command'
ModemManager[3517]: <debug> [1471430153.150629] [mm-broadband-modem.c:9627] iface_modem_3gpp_ussd_initialize_ready(): Couldn't initialize interface: 'USSD not supported'
ModemManager[3517]: <debug> [1471430153.150990] [mm-broadband-modem-mbim.c:2911] messaging_check_support(): Messaging capabilities supported
ModemManager[3517]: <debug> [1471430153.151056] [mm-iface-modem-messaging.c:1149] load_supported_storages_ready(): Supported storages loaded:
ModemManager[3517]: <debug> [1471430153.151077] [mm-iface-modem-messaging.c:1150] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'mt'
ModemManager[3517]: <debug> [1471430153.151098] [mm-iface-modem-messaging.c:1151] load_supported_storages_ready(): mem2 (write/send) storages: 'mt'
ModemManager[3517]: <debug> [1471430153.151118] [mm-iface-modem-messaging.c:1152] load_supported_storages_ready(): mem3 (reception) storages: 'mt'
ModemManager[3517]: <debug> [1471430153.151169] [mm-iface-modem-messaging.c:1229] init_current_storages_ready(): Couldn't initialize current storages: 'No AT port available to run command'
ModemManager[3517]: <debug> [1471430153.151316] [mm-iface-modem-voice.c:916] check_support_ready(): Voice support check failed: 'No AT port available to run command'
ModemManager[3517]: <debug> [1471430153.151353] [mm-broadband-modem.c:9631] iface_modem_voice_initialize_ready(): Couldn't initialize interface: 'Voice not supported'
ModemManager[3517]: <debug> [1471430153.151439] [mm-iface-modem-time.c:831] check_support_ready(): Time support check failed: 'No AT port available to run command'
ModemManager[3517]: <debug> [1471430153.151484] [mm-broadband-modem.c:9632] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
ModemManager[3517]: <debug> [1471430153.151591] [mm-broadband-modem.c:9633] iface_modem_signal_initialize_ready(): Couldn't initialize interface: 'Extended Signal information not supported'
ModemManager[3517]: <debug> [1471430153.151732] [mm-broadband-modem.c:9634] iface_modem_oma_initialize_ready(): Couldn't initialize interface: 'OMA not supported'
ModemManager[3517]: <debug> [1471430153.151816] [mm-broadband-modem.c:9635] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware interface not available'
ModemManager[3517]: <info> [1471430153.151918] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[3517]: <debug> [1471430153.152013] [mm-base-modem.c:833] initialize_ready(): modem properly initialized
ModemManager[3517]: <debug> [1471430153.152569] [mm-device.c:452] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[3517]: <debug> [1471430153.152604] [mm-device.c:462] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Sierra' modem, VID 0x1199 PID 0x9079 (usb)
ModemManager[3517]: <info> [1471430153.160026] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[3517]: <debug> [1471430153.160501] [mm-broadband-modem.c:8539] enabling_started(): Skipping modem initialization: not required
ModemManager[3517]: <debug> [1471430153.160537] [mm-broadband-modem-mbim.c:1535] parent_enabling_started_ready(): Couldn't start parent enabling: Couldn't open ports during modem enabling: Couldn't get primary port
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 48
<<<<<< data = 03:00:00:00:30:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 48
<<<<<< type = command (0x00000003)
<<<<<< transaction = 13
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'query' (0x00000000)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data = 03:00:00:80:38:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 13
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 52
<<<<<< data = 03:00:00:00:34:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:01:00:00:00:04:00:00:00:01:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 52
<<<<<< type = command (0x00000003)
<<<<<< transaction = 14
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
<<<<<< cid = 'radio-state' (0x00000003)
<<<<<< type = 'set' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 56
>>>>>> data = 03:00:00:80:38:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:02:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 56
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 14
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'Failure' (0x00000002)
>>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df)
>>>>>> cid = 'radio-state' (0x00000003)
ModemManager[3517]: Failure
ModemManager[3517]: [/dev/cdc-wdm0] Opening device with flags 'proxy, mbim'...
ModemManager[3517]: [/dev/cdc-wdm0] creating MBIM device...
ModemManager[3517]: [/dev/cdc-wdm0] MBIM device created
ModemManager[3517]: [/dev/cdc-wdm0] opening MBIM device...
ModemManager[3517]: opening device...
ModemManager[3517]: [/dev/cdc-wdm0] Read max control message size from descriptors file: 4096
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 88
<<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:0F:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:30:00:00:00
ModemManager[3517]: [/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[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 48
>>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 48
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 1
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b)
>>>>>> cid = 'configuration' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:10:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 2
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] MBIM device open
ModemManager[3517]: [/dev/cdc-wdm0] Assuming service 'dms' is supported...
ModemManager[3517]: [/dev/cdc-wdm0] Allocating new client ID...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:0F:00:00:00:00:00:01:22:00:04:00:01:01:00:02
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 15
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 4
<<<<<< message = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<< type = "Service" (0x01)
<<<<<< length = 1
<<<<<< value = 02
<<<<<< translated = dms
ModemManager[3517]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 64
<<<<<< data = 03:00:00:00:40:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:10:00:00:00:01:0F:00:00:00:00:00:01:22:00:04:00:01:01:00:02
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 64
<<<<<< type = command (0x00000003)
<<<<<< transaction = 3
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<< cid = 'msg' (0x00000001)
<<<<<< type = 'set' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 72
>>>>>> data = 03:00:00:80:48:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:18:00:00:00:01:17:00:80:00:00:01:01:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 72
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 3
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:01:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 12
>>>>>> message = "Allocate CID" (0x0022)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Allocation Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:03
>>>>>> translated = [ service = 'dms' cid = '3' ]
ModemManager[3517]: [/dev/cdc-wdm0] Registered 'dms' (version unknown) client with ID '3'
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 13
<<<<<< data = 01:0C:00:00:02:03:00:01:00:5F:55:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 12
<<<<<< flags = 0x00
<<<<<< service = "dms"
<<<<<< client = 3
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 1
<<<<<< tlv_length = 0
<<<<<< message = "Set FCC Authentication" (0x555F)
ModemManager[3517]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 61
<<<<<< data = 03:00:00:00:3D:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:0D:00:00:00:01:0C:00:00:02:03:00:01:00:5F:55:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 61
<<<<<< type = command (0x00000003)
<<<<<< transaction = 4
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<< cid = 'msg' (0x00000001)
<<<<<< type = 'set' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 68
>>>>>> data = 03:00:00:80:44:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:14:00:00:00:01:13:00:80:02:03:02:01:00:5F:55:07:00:02:04:00:01:00:1A:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 68
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 4
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 20
>>>>>> data = 01:13:00:80:02:03:02:01:00:5F:55:07:00:02:04:00:01:00:1A:00
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 19
>>>>>> flags = 0x80
>>>>>> service = "dms"
>>>>>> client = 3
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 1
>>>>>> tlv_length = 7
>>>>>> message = "Set FCC Authentication" (0x555F)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 01:00:1A:00
>>>>>> translated = FAILURE: NoEffect
ModemManager[3517]: error: couldn't set FCC auth: QMI protocol error (26): 'NoEffect'
ModemManager[3517]: [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
ModemManager[3517]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '3'
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 17
<<<<<< data = 01:10:00:00:00:00:00:02:23:00:05:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<< length = 16
<<<<<< flags = 0x00
<<<<<< service = "ctl"
<<<<<< client = 0
<<<<<< QMI:
<<<<<< flags = "none"
<<<<<< transaction = 2
<<<<<< tlv_length = 5
<<<<<< message = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<< type = "Release Info" (0x01)
<<<<<< length = 2
<<<<<< value = 02:03
<<<<<< translated = [ service = 'dms' cid = '3' ]
ModemManager[3517]: [/dev/cdc-wdm0] sending message as MBIM...
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 65
<<<<<< data = 03:00:00:00:41:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:01:00:00:00:11:00:00:00:01:10:00:00:00:00:00:02:23:00:05:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 65
<<<<<< type = command (0x00000003)
<<<<<< transaction = 5
<<<<<< Fragment header:
<<<<<< total = 1
<<<<<< current = 0
<<<<<< Contents:
<<<<<< service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
<<<<<< cid = 'msg' (0x00000001)
<<<<<< type = 'set' (0x00000001)
ModemManager[3517]: <info> [1471430153.471634] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> disabled)
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 72
>>>>>> data = 03:00:00:80:48:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:D1:A3:0B:C2:F9:7A:6E:43:BF:65:C7:E2:4F:B0:F0:D3:01:00:00:00:00:00:00:00:18:00:00:00:01:17:00:80:00:00:01:02:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 72
>>>>>> type = command-done (0x80000003)
>>>>>> transaction = 5
>>>>>> Fragment header:
>>>>>> total = 1
>>>>>> current = 0
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
>>>>>> service = 'qmi' (d1a30bc2-f97a-6e43-bf65-c7e24fb0f0d3)
>>>>>> cid = 'msg' (0x00000001)
ModemManager[3517]: [/dev/cdc-wdm0] Received MBIM message
ModemManager[3517]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 24
>>>>>> data = 01:17:00:80:00:00:01:02:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:02:03
ModemManager[3517]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>> length = 23
>>>>>> flags = 0x80
>>>>>> service = "ctl"
>>>>>> client = 0
>>>>>> QMI:
>>>>>> flags = "response"
>>>>>> transaction = 2
>>>>>> tlv_length = 12
>>>>>> message = "Release CID" (0x0023)
>>>>>> TLV:
>>>>>> type = "Result" (0x02)
>>>>>> length = 4
>>>>>> value = 00:00:00:00
>>>>>> translated = SUCCESS
>>>>>> TLV:
>>>>>> type = "Release Info" (0x01)
>>>>>> length = 2
>>>>>> value = 02:03
>>>>>> translated = [ service = 'dms' cid = '3' ]
^C
ModemManager[3517]: <info> [1471430213.939141] [main.c:46] quit_cb(): Caught signal, shutting down...
ModemManager[3517]: <debug> [1471430213.939190] [mm-base-manager.c:849] set_property(): Stopping connection in object manager server
ModemManager[3517]: <debug> [1471430213.939503] [mm-device.c:398] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' from path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[3517]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 12
<<<<<< data = 02:00:00:00:0C:00:00:00:0F:00:00:00
ModemManager[3517]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 12
<<<<<< type = close (0x00000002)
<<<<<< transaction = 15
ModemManager[3517]: <info> [1471430213.941947] [main.c:218] main(): ModemManager is shut down
ModemManager[3517]: <debug> [1471430213.942083] [mm-sleep-monitor.c:245] _singleton_instance_weak_ref_cb(): disposing MMSleepMonitor singleton (0x891090)
ModemManager[3517]: <debug> [1471430213.942123] [mm-sleep-monitor.c:68] drop_inhibitor(): [sleep-monitor] dropping systemd sleep inhibitor
# mbim-network /dev/cdc-wdm0 start
Loading profile at /etc/mbim-network.conf...
APN: telenor
APN auth protocol: unset
APN user: unset
APN password: unset
mbim-proxy: yes
Querying subscriber ready status 'mbimcli -d /dev/cdc-wdm0 --query-subscriber-ready-status --no-close --device-open-proxy'...
[/dev/cdc-wdm0] Subscriber ready status retrieved: Ready state: 'initialized' Subscriber ID: '242014570022571' SIM ICCID: '89470457130510019203' Ready info: 'unknown' Telephone numbers: (0) 'unknown' [/dev/cdc-wdm0] Session not closed: TRID: '4'
Saving state at /tmp/mbim-network-state-cdc-wdm0... (TRID: 4)
Querying registration state 'mbimcli -d /dev/cdc-wdm0 --query-registration-state --no-open=4 --no-close --device-open-proxy'...
[/dev/cdc-wdm0] Registration status: Network error: 'unknown' Register state: 'deregistered' Register mode: 'automatic' Available data classes: 'unknown' Current cellular class: 'gsm' Provider ID: 'unknown' Provider name: 'unknown' Roaming text: 'unknown' Registration flags: 'packet-service-automatic-attach' [/dev/cdc-wdm0] Session not closed: TRID: '6'
Saving state at /tmp/mbim-network-state-cdc-wdm0... (TRID: 6)
Attaching to packet service with 'mbimcli -d /dev/cdc-wdm0 --attach-packet-service --no-open=6 --no-close --device-open-proxy'...
error: operation failed: RadioPowerOff
Saving state at /tmp/mbim-network-state-cdc-wdm0... (TRID: 8)
Starting network with 'mbimcli -d /dev/cdc-wdm0 --connect=apn='telenor' --no-open=8 --no-close --device-open-proxy'...
error: operation failed: RadioPowerOff
Network start failed
[/dev/cdc-wdm0] Session not closed: TRID: '10'
Saving state at /tmp/mbim-network-state-cdc-wdm0... (TRID: 10)
More information about the ModemManager-devel
mailing list