AW: MC7455 not working

Thomas Lang thomas.lang at tactilo.eu
Fri Jun 17 15:27:34 UTC 2016


Hmm, so itried dieffrent things out and what I have observed is that if I don’t use NM and MM as sudo systemctl enable NetworkManager ModemManager. The SIM card fails. You could see this in the last mail. When I start the PC after power down. And stop MM with :

sudo service ModemManager stop
go at ubuntu:~$ mmcli -L

No modems were found

go at ubuntu:~$ mmcli -m 0

/org/freedesktop/ModemManager1/Modem/0 (device id '7b4cfd7e877aff3cade0b53c88a7b33e7a95c7df')
  -------------------------
  Hardware |   manufacturer: 'Sierra Wireless, Incorporated'
           |          model: 'MC7455'
           |       revision: 'SWI9X30C_02.08.02.00 r5428 CARMD-EV-FRMWR2 2016/01/06 20:38:53'
           |      supported: 'gsm-umts
           |                  lte
           |                  gsm-umts, lte'
           |        current: 'gsm-umts, lte'
           |   equipment id: '359072060132616'
  -------------------------
  System   |         device: '/sys/devices/pci0000:00/0000:00:13.0/usb1/1-1/1-1.3'
           |        drivers: 'qcserial, qmi_wwan'
           |         plugin: 'Sierra'
           |   primary port: 'cdc-wdm0'
           |          ports: 'ttyUSB2 (at), ttyUSB0 (qcdm), wwp0s19u1u3i10 (net), cdc-wdm0 (qmi), cdc-wdm1 (qmi), wwan0 (net)'
  -------------------------
  Numbers  |           own : 'unknown'
  -------------------------
  Status   |           lock: 'sim-pin2'
           | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10), sim-puk2 (10)'
           |          state: 'registered'
           |    power state: 'on'
           |    access tech: 'lte'
           | signal quality: '54' (recent)
  -------------------------
  Modes    |      supported: 'allowed: 2g, 3g, 4g; preferred: none'
           |        current: 'allowed: 2g, 3g, 4g; preferred: none'
  -------------------------
  Bands    |      supported: 'u2100, u1800, u1900, u17iv, u850, u900, eutran-i, eutran-ii, eutran-iii, eutran-iv, eutran-v, eutran-vii, eutran-viii, eutran-xii, eutran-xiii, eutran-xx, eutran-xxv, eutran-xli'
           |        current: 'u2100, u1800, u1900, u17iv, u850, u900, eutran-i, eutran-ii, eutran-iii, eutran-iv, eutran-v, eutran-vii, eutran-viii, eutran-xii, eutran-xiii, eutran-xx, eutran-xxv, eutran-xli'
  -------------------------
  IP       |      supported: 'ipv4, ipv6, ipv4v6'
  -------------------------
  3GPP     |           imei: '359072060132616'
           |  enabled locks: 'none'
           |    operator id: '26202'
           |  operator name: 'Vodafone'
           |   subscription: 'unknown'
           |   registration: 'home'
  -------------------------
  SIM      |           path: '/org/freedesktop/ModemManager1/SIM/0'

  -------------------------
  Bearers  |          paths: 'none'

go at ubuntu:~$ sudo nmcli d
DEVICE    TYPE      STATE         CONNECTION
cdc-wdm0  gsm       disconnected  --
wlp4s0    wifi      disconnected  --
enp1s0    ethernet  unavailable   --
enp3s0    ethernet  unavailable   --
enp2s0    ethernet  unmanaged     --
lo        loopback  unmanaged     --
go at ubuntu:~$ sudo nmcli d c cdc-wdm0
Device 'cdc-wdm0' successfully activated with '9264dfab-d648-4497-b851-97bd6a3ad1ce'.

It works. Below find the debug trace. But still if NM and MM are enable as service. PC start after powerdown I still need to restart MM once to get it running. My idea would be to restart MM somewhere with a script. Do you see another solution for now?


>>>>>>   tlv_length  = 7
>>>>>>   message     = "Set Event Report" (0x0002)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:03:07:00:07:00:03:00:04:00:13:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Register Indications" (0x0003)
<<<<<< TLV:
<<<<<<   type       = "Serving System Events" (0x13)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = yes
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:03:07:02:07:00:03:00:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 7
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Register Indications" (0x0003)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: <debug> [1466176578.472516] [mm-broadband-modem.c:9238] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[1157]: <debug> [1466176578.472670] [mm-iface-modem-location.c:762] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[1157]: <debug> [1466176578.472987] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:07:00:08:00:24:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Serving System" (0x0024)
ModemManager[1157]: <debug> [1466176578.473697] [mm-broadband-modem.c:9251] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[1157]: <debug> [1466176578.474168] [mm-broadband-modem-qmi.c:7456] messaging_set_default_storage(): setting default messaging routes...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 26
<<<<<<   data   = 01:19:00:00:05:06:00:01:00:32:00:0D:00:01:0A:00:02:00:00:00:01:01:00:01:01:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 25
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 13
<<<<<<   message     = "Set Routes" (0x0032)
<<<<<< TLV:
<<<<<<   type       = "Route List" (0x01)
<<<<<<   length     = 10
<<<<<<   value      = 02:00:00:00:01:01:00:01:01:01
<<<<<<   translated = { [0] = '[ message_type = 'point' message_class = '0' storage = 'nv' receipt_action = 'store-and-notify' ] ' [1] = '[ message_type = 'point' message_class = '1' storage = 'nv' receipt_action = 'store-and-notify' ] '}
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 93
>>>>>>   data   = 01:5C:00:80:03:07:02:08:00:24:00:50:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:08:10:01:00:01:11:02:00:01:0B:12:0D:00:06:01:02:00:08:56:6F:64:61:66:6F:6E:65:15:03:00:01:08:01:1C:02:00:FE:FF:1D:04:00:03:D6:44:01:21:05:00:02:03:00:00:00:24:02:00:9E:BB:27:05:00:06:01:02:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 92
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 8
>>>>>>   tlv_length  = 80
>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Serving System" (0x01)
>>>>>>   length     = 6
>>>>>>   value      = 01:01:01:02:01:08
>>>>>>   translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
>>>>>> TLV:
>>>>>>   type       = "Roaming Indicator" (0x10)
>>>>>>   length     = 1
>>>>>>   value      = 01
>>>>>>   translated = off
>>>>>> TLV:
>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>   length     = 2
>>>>>>   value      = 01:0B
>>>>>>   translated = { [0] = 'lte '}
>>>>>> TLV:
>>>>>>   type       = "Current PLMN" (0x12)
>>>>>>   length     = 13
>>>>>>   value      = 06:01:02:00:08:56:6F:64:61:66:6F:6E:65
>>>>>>   translated = [ mcc = '262' mnc = '2' description = 'Vodafone' ]
>>>>>> TLV:
>>>>>>   type       = "Roaming Indicator List" (0x15)
>>>>>>   length     = 3
>>>>>>   value      = 01:08:01
>>>>>>   translated = { [0] = '[ radio_interface = 'lte' roaming_indicator = 'off' ] '}
>>>>>> TLV:
>>>>>>   type       = "LAC 3GPP" (0x1c)
>>>>>>   length     = 2
>>>>>>   value      = FE:FF
>>>>>>   translated = 65534
>>>>>> TLV:
>>>>>>   type       = "CID 3GPP" (0x1d)
>>>>>>   length     = 4
>>>>>>   value      = 03:D6:44:01
>>>>>>   translated = 21288451
>>>>>> TLV:
>>>>>>   type       = "Detailed Service Status" (0x21)
>>>>>>   length     = 5
>>>>>>   value      = 02:03:00:00:00
>>>>>>   translated = [ status = 'available' capability = 'cs-ps' hdr_status = 'none' hdr_hybrid = 'no' forbidden = 'no' ]
>>>>>> TLV:
>>>>>>   type       = "LTE TAC" (0x24)
>>>>>>   length     = 2
>>>>>>   value      = 9E:BB
>>>>>>   translated = 48030
>>>>>> TLV:
>>>>>>   type       = "MNC PCS Digit Include Status" (0x27)
>>>>>>   length     = 5
>>>>>>   value      = 06:01:02:00:00
>>>>>>   translated = [ mcc = '262' mnc = '2' includes_pcs_digit = 'no' ]
ModemManager[1157]: <debug> [1466176578.542943] [mm-broadband-modem-qmi.c:4607] common_process_serving_system_3gpp(): Processing 3GPP info...
ModemManager[1157]: <info>  [1466176578.543124] [mm-iface-modem-3gpp.c:1171] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
ModemManager[1157]: <debug> [1466176578.543324] [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: '144D603')
ModemManager[1157]: <debug> [1466176578.543733] [mm-iface-modem-location.c:297] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '262', MNC: '2', Location area code: 'FFFE', Cell ID: '144D603')
ModemManager[1157]: <info>  [1466176578.544951] [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[1157]: <debug> [1466176578.545406] [mm-iface-modem.c:1600] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:01:00:32:00:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Set Routes" (0x0032)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: <debug> [1466176578.600667] [mm-broadband-modem-qmi.c:7750] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'sm'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 21
<<<<<<   data   = 01:14:00:00:05:06:00:02:00:31:00:08:00:12:01:00:01:01:01:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 20
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 8
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = uim
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:02:00:31:00:07:00:02:04:00:01:00:11:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 2
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:11:00
>>>>>>   translated = FAILURE: MissingArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (17): 'MissingArgument'
ModemManager[1157]: <debug> [1466176578.665229] [mm-broadband-modem-qmi.c:7755] load_initial_sms_parts_step(): loading 3GPP MT-read messages from storage 'sm'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:03:00:31:00:0C:00:12:01:00:01:11:01:00:00:01:01:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = mt-read
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = uim
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:03:00:31:00:07:00:02:04:00:01:00:34:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 3
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:34:00
>>>>>>   translated = FAILURE: DeviceNotReady
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (52): 'DeviceNotReady'
ModemManager[1157]: <debug> [1466176578.729029] [mm-broadband-modem-qmi.c:7761] load_initial_sms_parts_step(): loading 3GPP MT-not-read messages from storage 'sm'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:04:00:31:00:0C:00:12:01:00:01:11:01:00:01:01:01:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = mt-not-read
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = uim
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 27
>>>>>>   data   = 01:1A:00:80:05:06:02:04:00:31:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 26
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 4
>>>>>>   tlv_length  = 14
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Message List" (0x01)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = {}
ModemManager[1157]: <debug> [1466176578.792964] [mm-broadband-modem-qmi.c:7767] load_initial_sms_parts_step(): loading 3GPP MO-sent messages from storage 'sm'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:05:00:31:00:0C:00:12:01:00:01:11:01:00:02:01:01:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = mo-sent
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = uim
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:05:00:31:00:07:00:02:04:00:01:00:30:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 5
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:30:00
>>>>>>   translated = FAILURE: InvalidArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'
ModemManager[1157]: <debug> [1466176578.856904] [mm-broadband-modem-qmi.c:7773] load_initial_sms_parts_step(): loading 3GPP MO-not-sent messages from storage 'sm'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:06:00:31:00:0C:00:12:01:00:01:11:01:00:03:01:01:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 03
<<<<<<   translated = mo-not-sent
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = uim
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:06:00:31:00:07:00:02:04:00:01:00:30:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 6
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:30:00
>>>>>>   translated = FAILURE: InvalidArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'
ModemManager[1157]: <debug> [1466176578.921127] [mm-broadband-modem-qmi.c:7750] load_initial_sms_parts_step(): loading all 3GPP messages from storage 'me'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 21
<<<<<<   data   = 01:14:00:00:05:06:00:07:00:31:00:08:00:12:01:00:01:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 20
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 7
<<<<<<   tlv_length  = 8
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = nv
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:07:00:31:00:07:00:02:04:00:01:00:11:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 7
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:11:00
>>>>>>   translated = FAILURE: MissingArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (17): 'MissingArgument'
ModemManager[1157]: <debug> [1466176578.984907] [mm-broadband-modem-qmi.c:7755] load_initial_sms_parts_step(): loading 3GPP MT-read messages from storage 'me'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:08:00:31:00:0C:00:12:01:00:01:11:01:00:00:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 8
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 00
<<<<<<   translated = mt-read
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = nv
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:08:00:31:00:07:00:02:04:00:01:00:34:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 8
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:34:00
>>>>>>   translated = FAILURE: DeviceNotReady
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (52): 'DeviceNotReady'
ModemManager[1157]: <debug> [1466176579.049070] [mm-broadband-modem-qmi.c:7761] load_initial_sms_parts_step(): loading 3GPP MT-not-read messages from storage 'me'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:09:00:31:00:0C:00:12:01:00:01:11:01:00:01:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = mt-not-read
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = nv
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 42
>>>>>>   data   = 01:29:00:80:05:06:02:09:00:31:00:1D:00:02:04:00:00:00:00:00:01:13:00:03:00:00:00:00:00:00:00:00:01:00:00:00:00:02:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 41
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 9
>>>>>>   tlv_length  = 29
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Message List" (0x01)
>>>>>>   length     = 19
>>>>>>   value      = 03:00:00:00:00:00:00:00:00:01:00:00:00:00:02:00:00:00:00
>>>>>>   translated = { [0] = '[ memory_index = '0' message_tag = 'mt-read' ] ' [1] = '[ memory_index = '1' message_tag = 'mt-read' ] ' [2] = '[ memory_index = '2' message_tag = 'mt-read' ] '}
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:0A:00:22:00:0C:00:10:01:00:01:01:05:00:01:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Raw Read" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Memory Storage ID" (0x01)
<<<<<<   length     = 5
<<<<<<   value      = 01:00:00:00:00
<<<<<<   translated = [ storage_type = 'nv' memory_index = '0' ]
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 198
>>>>>>   data   = 01:C5:00:80:05:06:02:0A:00:22:00:B9:00:02:04:00:00:00:00:00:01:AF:00:00:06:AB:00:07:91:94:71:22:72:30:33:60:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:A0:05:00:03:BA:03:01:98:E9:B2:B8:2C:07:59:DF:E4:B0:F9:ED:2E:B7:96:75:37:B9:CC:02:B5:D3:74:50:12:2D:2F:B7:41:D4:B0:3C:6D:06:CD:C7:E8:B2:7B:5D:76:83:EE:69:39:28:89:76:97:DD:A0:19:A8:F9:76:87:E9:65:50:39:ED:06:15:DD:F4:B2:9C:1E:4E:BB:DB:65:37:BD:05:0D:AF:CB:74:17:E8:BA:47:B3:CB:6E:D0:34:5D:06:95:D3:6E:73:78:8C:D6:81:9A:69:36:3B:FD:76:97:DD:A0:E9:DB:7D:9E:83:EA:6E:32:08:C9:97:8B:FD:63:74:59:0E:12:97:D3:20:62:B9:AC:2F:CB:41
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 197
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 10
>>>>>>   tlv_length  = 185
>>>>>>   message     = "Raw Read" (0x0022)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Raw Message Data" (0x01)
>>>>>>   length     = 175
>>>>>>   value      = 00:06:AB:00:07:91:94:71:22:72:30:33:60:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:A0:05:00:03:BA:03:01:98:E9:B2:B8:2C:07:59:DF:E4:B0:F9:ED:2E:B7:96:75:37:B9:CC:02:B5:D3:74:50:12:2D:2F:B7:41:D4:B0:3C:6D:06:CD:C7:E8:B2:7B:5D:76:83:EE:69:39:28:89:76:97:DD:A0:19:A8:F9:76:87:E9:65:50:39:ED:06:15:DD:F4:B2:9C:1E:4E:BB:DB:65:37:BD:05:0D:AF:CB:74:17:E8:BA:47:B3:CB:6E:D0:34:5D:06:95:D3:6E:73:78:8C:D6:81:9A:69:36:3B:FD:76:97:DD:A0:E9:DB:7D:9E:83:EA:6E:32:08:C9:97:8B:FD:63:74:59:0E:12:97:D3:20:62:B9:AC:2F:CB:41
>>>>>>   translated = [ message_tag = 'mt-read' format = 'gsm-wcdma-point-to-point' raw_data = '{ [0] = '7 ' [1] = '145 ' [2] = '148 ' [3] = '113 ' [4] = '34 ' [5] = '114 ' [6] = '48 ' [7] = '51 ' [8] = '96 ' [9] = '20 ' [10] = '208 ' [11] = '214 ' [12] = '55 ' [13] = '57 ' [14] = '108 ' [15] = '126 ' [16] = '187 ' [17] = '203 ' [18] = '46 ' [19] = '114 ' [20] = '25 ' [21] = '0 ' [22] = '0 ' [23] = '97 ' [24] = '80 ' [25] = '19 ' [26] = '113 ' [27] = '52 ' [28] = '114 ' [29] = '128 ' [30] = '160 ' [31] = '5 ' [32] = '0 ' [33] = '3 ' [34] = '186 ' [35] = '3 ' [36] = '1 ' [37] = '152 ' [38] = '233 ' [39] = '178 ' [40] = '184 ' [41] = '44 ' [42] = '7 ' [43] = '89 ' [44] = '223 ' [45] = '228 ' [46] = '176 ' [47] = '249 ' [48] = '237 ' [49] = '46 ' [50] = '183 ' [51] = '150 ' [52] = '117 ' [53] = '55 ' [54] = '185 ' [55] = '204 ' [56] = '2 ' [57] = '181 ' [58] = '211 ' [59] = '116 ' [60] = '80 ' [61] = '18 ' [62] = '45 ' [63] = '47 ' [64] = '183 ' [65] = '65 ' [66] = '212 ' [67] = '176 ' [68] = '60 ' [69] = '109 ' [70] = '6 ' [71] = '205 ' [72] = '199 ' [73] = '232 ' [74] = '178 ' [75] = '123 ' [76] = '93 ' [77] = '118 ' [78] = '131 ' [79] = '238 ' [80] = '105 ' [81] = '57 ' [82] = '40 ' [83] = '137 ' [84] = '118 ' [85] = '151 ' [86] = '221 ' [87] = '160 ' [88] = '25 ' [89] = '168 ' [90] = '249 ' [91] = '118 ' [92] = '135 ' [93] = '233 ' [94] = '101 ' [95] = '80 ' [96] = '57 ' [97] = '237 ' [98] = '6 ' [99] = '21 ' [100] = '221 ' [101] = '244 ' [102] = '178 ' [103] = '156 ' [104] = '30 ' [105] = '78 ' [106] = '187 ' [107] = '219 ' [108] = '101 ' [109] = '55 ' [110] = '189 ' [111] = '5 ' [112] = '13 ' [113] = '175 ' [114] = '203 ' [115] = '116 ' [116] = '23 ' [117] = '232 ' [118] = '186 ' [119] = '71 ' [120] = '179 ' [121] = '203 ' [122] = '110 ' [123] = '208 ' [124] = '52 ' [125] = '93 ' [126] = '6 ' [127] = '149 ' [128] = '211 ' [129] = '110 ' [130] = '115 ' [131] = '120 ' [132] = '140 ' [133] = '214 ' [134] = '129 ' [135] = '154 ' [136] = '105 ' [137] = '54 ' [138] = '59 ' [139] = '253 ' [140] = '118 ' [141] = '151 ' [142] = '221 ' [143] = '160 ' [144] = '233 ' [145] = '219 ' [146] = '125 ' [147] = '158 ' [148] = '131 ' [149] = '234 ' [150] = '110 ' [151] = '50 ' [152] = '8 ' [153] = '201 ' [154] = '151 ' [155] = '139 ' [156] = '253 ' [157] = '99 ' [158] = '116 ' [159] = '89 ' [160] = '14 ' [161] = '18 ' [162] = '151 ' [163] = '211 ' [164] = '32 ' [165] = '98 ' [166] = '185 ' [167] = '172 ' [168] = '47 ' [169] = '203 ' [170] = '65 '}' ]
ModemManager[1157]: <debug> [1466176579.178047] [mm-sms-part-3gpp.c:368] mm_sms_part_3gpp_new_from_binary_pdu(): Parsing PDU (0)...
ModemManager[1157]: <debug> [1466176579.178109] [mm-sms-part-3gpp.c:397] mm_sms_part_3gpp_new_from_binary_pdu():   SMSC address parsed: '+491722270333'
ModemManager[1157]: <debug> [1466176579.178150] [mm-sms-part-3gpp.c:410] mm_sms_part_3gpp_new_from_binary_pdu():   Deliver type PDU detected
ModemManager[1157]: <debug> [1466176579.178267] [mm-sms-part-3gpp.c:471] mm_sms_part_3gpp_new_from_binary_pdu():   Number parsed: 'Vodafone.de'
ModemManager[1157]: <debug> [1466176579.178313] [mm-sms-part-3gpp.c:575] mm_sms_part_3gpp_new_from_binary_pdu():   PID: 0
ModemManager[1157]: <debug> [1466176579.178353] [mm-sms-part-3gpp.c:586] mm_sms_part_3gpp_new_from_binary_pdu():   user data encoding is GSM7
ModemManager[1157]: <debug> [1466176579.178422] [mm-sms-part-3gpp.c:614] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 160 elements
ModemManager[1157]: <debug> [1466176579.178462] [mm-sms-part-3gpp.c:620] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 140 bytes
ModemManager[1157]: <debug> [1466176579.178532] [mm-sms-part-3gpp.c:695] mm_sms_part_3gpp_new_from_binary_pdu(): Decoding SMS text with '153' elements
ModemManager[1157]: <debug> [1466176579.178571] [mm-sms-part-3gpp.c:244] sms_decode_text(): Converting SMS part text from GSM7 to UTF8...
ModemManager[1157]: <debug> [1466176579.178642] [mm-sms-part-3gpp.c:247] sms_decode_text():    Got UTF-8 text: 'Lieber Vodafone-Kunde, mit Ihrem Tarif schenken wir Ihnen 3 Monate ein Entertainment-Paket. Wählen Sie einfach: Millionen Songs und Hörbücher bei Deezer '
ModemManager[1157]: <debug> [1466176579.178726] [mm-broadband-modem-qmi.c:7568] add_new_read_sms_part(): Correctly parsed PDU (0)
ModemManager[1157]: <debug> [1466176579.178829] [mm-sms-list.c:371] mm_sms_list_take_part(): SMS part at 'me/0' is from a multipart SMS (reference: '186', sequence: '1')
ModemManager[1157]: <debug> [1466176579.180362] [mm-iface-modem-messaging.c:511] sms_added(): Added received SMS at '/org/freedesktop/ModemManager1/SMS/0'
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:0B:00:22:00:0C:00:10:01:00:01:01:05:00:01:01:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 11
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Raw Read" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Memory Storage ID" (0x01)
<<<<<<   length     = 5
<<<<<<   value      = 01:01:00:00:00
<<<<<<   translated = [ storage_type = 'nv' memory_index = '1' ]
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 198
>>>>>>   data   = 01:C5:00:80:05:06:02:0B:00:22:00:B9:00:02:04:00:00:00:00:00:01:AF:00:00:06:AB:00:07:91:94:71:22:72:30:33:60:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:A0:05:00:03:BA:03:02:DE:E4:B2:1C:44:4E:97:41:C2:BA:9B:5C:9E:B3:D3:E7:30:88:9D:B6:97:41:ED:34:1D:34:5D:E7:41:E2:72:1A:D4:7C:8B:D3:EC:32:D5:EA:02:05:D7:F4:B4:3D:5D:96:97:DD:A0:69:BA:0C:4A:A2:E5:20:68:78:5D:A6:83:D4:65:BA:9E:0E:0A:D7:CD:A0:FB:FD:EE:B2:BF:C9:61:F3:DB:5D:76:91:CB:AF:B2:9B:5E:96:D3:C3:69:77:BB:EC:A6:BF:64:20:17:68:9A:2E:83:C4:E5:F5:BB:DD:2E:BB:41:F6:B7:1C:14:14:B3:C3:75:33:88:5C:96:83:66:A0:E6:DB:1D:A6:97:41
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 197
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 11
>>>>>>   tlv_length  = 185
>>>>>>   message     = "Raw Read" (0x0022)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Raw Message Data" (0x01)
>>>>>>   length     = 175
>>>>>>   value      = 00:06:AB:00:07:91:94:71:22:72:30:33:60:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:A0:05:00:03:BA:03:02:DE:E4:B2:1C:44:4E:97:41:C2:BA:9B:5C:9E:B3:D3:E7:30:88:9D:B6:97:41:ED:34:1D:34:5D:E7:41:E2:72:1A:D4:7C:8B:D3:EC:32:D5:EA:02:05:D7:F4:B4:3D:5D:96:97:DD:A0:69:BA:0C:4A:A2:E5:20:68:78:5D:A6:83:D4:65:BA:9E:0E:0A:D7:CD:A0:FB:FD:EE:B2:BF:C9:61:F3:DB:5D:76:91:CB:AF:B2:9B:5E:96:D3:C3:69:77:BB:EC:A6:BF:64:20:17:68:9A:2E:83:C4:E5:F5:BB:DD:2E:BB:41:F6:B7:1C:14:14:B3:C3:75:33:88:5C:96:83:66:A0:E6:DB:1D:A6:97:41
>>>>>>   translated = [ message_tag = 'mt-read' format = 'gsm-wcdma-point-to-point' raw_data = '{ [0] = '7 ' [1] = '145 ' [2] = '148 ' [3] = '113 ' [4] = '34 ' [5] = '114 ' [6] = '48 ' [7] = '51 ' [8] = '96 ' [9] = '20 ' [10] = '208 ' [11] = '214 ' [12] = '55 ' [13] = '57 ' [14] = '108 ' [15] = '126 ' [16] = '187 ' [17] = '203 ' [18] = '46 ' [19] = '114 ' [20] = '25 ' [21] = '0 ' [22] = '0 ' [23] = '97 ' [24] = '80 ' [25] = '19 ' [26] = '113 ' [27] = '52 ' [28] = '114 ' [29] = '128 ' [30] = '160 ' [31] = '5 ' [32] = '0 ' [33] = '3 ' [34] = '186 ' [35] = '3 ' [36] = '2 ' [37] = '222 ' [38] = '228 ' [39] = '178 ' [40] = '28 ' [41] = '68 ' [42] = '78 ' [43] = '151 ' [44] = '65 ' [45] = '194 ' [46] = '186 ' [47] = '155 ' [48] = '92 ' [49] = '158 ' [50] = '179 ' [51] = '211 ' [52] = '231 ' [53] = '48 ' [54] = '136 ' [55] = '157 ' [56] = '182 ' [57] = '151 ' [58] = '65 ' [59] = '237 ' [60] = '52 ' [61] = '29 ' [62] = '52 ' [63] = '93 ' [64] = '231 ' [65] = '65 ' [66] = '226 ' [67] = '114 ' [68] = '26 ' [69] = '212 ' [70] = '124 ' [71] = '139 ' [72] = '211 ' [73] = '236 ' [74] = '50 ' [75] = '213 ' [76] = '234 ' [77] = '2 ' [78] = '5 ' [79] = '215 ' [80] = '244 ' [81] = '180 ' [82] = '61 ' [83] = '93 ' [84] = '150 ' [85] = '151 ' [86] = '221 ' [87] = '160 ' [88] = '105 ' [89] = '186 ' [90] = '12 ' [91] = '74 ' [92] = '162 ' [93] = '229 ' [94] = '32 ' [95] = '104 ' [96] = '120 ' [97] = '93 ' [98] = '166 ' [99] = '131 ' [100] = '212 ' [101] = '101 ' [102] = '186 ' [103] = '158 ' [104] = '14 ' [105] = '10 ' [106] = '215 ' [107] = '205 ' [108] = '160 ' [109] = '251 ' [110] = '253 ' [111] = '238 ' [112] = '178 ' [113] = '191 ' [114] = '201 ' [115] = '97 ' [116] = '243 ' [117] = '219 ' [118] = '93 ' [119] = '118 ' [120] = '145 ' [121] = '203 ' [122] = '175 ' [123] = '178 ' [124] = '155 ' [125] = '94 ' [126] = '150 ' [127] = '211 ' [128] = '195 ' [129] = '105 ' [130] = '119 ' [131] = '187 ' [132] = '236 ' [133] = '166 ' [134] = '191 ' [135] = '100 ' [136] = '32 ' [137] = '23 ' [138] = '104 ' [139] = '154 ' [140] = '46 ' [141] = '131 ' [142] = '196 ' [143] = '229 ' [144] = '245 ' [145] = '187 ' [146] = '221 ' [147] = '46 ' [148] = '187 ' [149] = '65 ' [150] = '246 ' [151] = '183 ' [152] = '28 ' [153] = '20 ' [154] = '20 ' [155] = '179 ' [156] = '195 ' [157] = '117 ' [158] = '51 ' [159] = '136 ' [160] = '92 ' [161] = '150 ' [162] = '131 ' [163] = '102 ' [164] = '160 ' [165] = '230 ' [166] = '219 ' [167] = '29 ' [168] = '166 ' [169] = '151 ' [170] = '65 '}' ]
ModemManager[1157]: <debug> [1466176579.242071] [mm-sms-part-3gpp.c:368] mm_sms_part_3gpp_new_from_binary_pdu(): Parsing PDU (1)...
ModemManager[1157]: <debug> [1466176579.242194] [mm-sms-part-3gpp.c:397] mm_sms_part_3gpp_new_from_binary_pdu():   SMSC address parsed: '+491722270333'
ModemManager[1157]: <debug> [1466176579.242266] [mm-sms-part-3gpp.c:410] mm_sms_part_3gpp_new_from_binary_pdu():   Deliver type PDU detected
ModemManager[1157]: <debug> [1466176579.242383] [mm-sms-part-3gpp.c:471] mm_sms_part_3gpp_new_from_binary_pdu():   Number parsed: 'Vodafone.de'
ModemManager[1157]: <debug> [1466176579.242457] [mm-sms-part-3gpp.c:575] mm_sms_part_3gpp_new_from_binary_pdu():   PID: 0
ModemManager[1157]: <debug> [1466176579.242497] [mm-sms-part-3gpp.c:586] mm_sms_part_3gpp_new_from_binary_pdu():   user data encoding is GSM7
ModemManager[1157]: <debug> [1466176579.242599] [mm-sms-part-3gpp.c:614] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 160 elements
ModemManager[1157]: <debug> [1466176579.242669] [mm-sms-part-3gpp.c:620] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 140 bytes
ModemManager[1157]: <debug> [1466176579.242740] [mm-sms-part-3gpp.c:695] mm_sms_part_3gpp_new_from_binary_pdu(): Decoding SMS text with '153' elements
ModemManager[1157]: <debug> [1466176579.242782] [mm-sms-part-3gpp.c:244] sms_decode_text(): Converting SMS part text from GSM7 to UTF8...
ModemManager[1157]: <debug> [1466176579.242852] [mm-sms-part-3gpp.c:247] sms_decode_text():    Got UTF-8 text: 'oder die Bundesliga live mit Sky bei MobileTV. Aktivieren Sie Ihr Paket jetzt auf www.vodafone.de/entertainment/2 . Sie bekommen vor Ablauf der 3 Monate '
ModemManager[1157]: <debug> [1466176579.242937] [mm-broadband-modem-qmi.c:7568] add_new_read_sms_part(): Correctly parsed PDU (1)
ModemManager[1157]: <debug> [1466176579.243126] [mm-sms-list.c:371] mm_sms_list_take_part(): SMS part at 'me/1' is from a multipart SMS (reference: '186', sequence: '2')
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:0C:00:22:00:0C:00:10:01:00:01:01:05:00:01:02:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 12
<<<<<<   tlv_length  = 12
<<<<<<   message     = "Raw Read" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Memory Storage ID" (0x01)
<<<<<<   length     = 5
<<<<<<   value      = 01:02:00:00:00
<<<<<<   translated = [ storage_type = 'nv' memory_index = '2' ]
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 184
>>>>>>   data   = 01:B7:00:80:05:06:02:0C:00:22:00:AB:00:02:04:00:00:00:00:00:01:A1:00:00:06:9D:00:07:91:94:71:22:72:30:33:64:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:8F:05:00:03:BA:03:03:CA:69:77:19:34:6D:4E:5D:A0:A5:DF:4D:4E:9F:CB:6E:D0:34:5D:06:B9:D3:63:34:9D:05:D2:87:D1:EC:B2:1B:34:4D:97:41:69:37:88:5C:76:83:9A:6F:77:98:5E:76:83:C8:61:77:78:8C:06:89:CB:F1:7A:B9:0D:F2:8B:CB:72:50:12:2D:2F:83:9A:6F:71:9A:6D:AE:BB:D7:2D:69:79:8C:76:D7:DD:67:17:C8:28:2F:D7:DD:64:76:7A:8C:2E:83:8E:72:BF:A7:CC:02:25:D1:72:90:F5:4D:0E:9B:DF:EE:72:8B:5A:0E:B7:01
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 183
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 12
>>>>>>   tlv_length  = 171
>>>>>>   message     = "Raw Read" (0x0022)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Raw Message Data" (0x01)
>>>>>>   length     = 161
>>>>>>   value      = 00:06:9D:00:07:91:94:71:22:72:30:33:64:14:D0:D6:37:39:6C:7E:BB:CB:2E:72:19:00:00:61:50:13:71:34:72:80:8F:05:00:03:BA:03:03:CA:69:77:19:34:6D:4E:5D:A0:A5:DF:4D:4E:9F:CB:6E:D0:34:5D:06:B9:D3:63:34:9D:05:D2:87:D1:EC:B2:1B:34:4D:97:41:69:37:88:5C:76:83:9A:6F:77:98:5E:76:83:C8:61:77:78:8C:06:89:CB:F1:7A:B9:0D:F2:8B:CB:72:50:12:2D:2F:83:9A:6F:71:9A:6D:AE:BB:D7:2D:69:79:8C:76:D7:DD:67:17:C8:28:2F:D7:DD:64:76:7A:8C:2E:83:8E:72:BF:A7:CC:02:25:D1:72:90:F5:4D:0E:9B:DF:EE:72:8B:5A:0E:B7:01
>>>>>>   translated = [ message_tag = 'mt-read' format = 'gsm-wcdma-point-to-point' raw_data = '{ [0] = '7 ' [1] = '145 ' [2] = '148 ' [3] = '113 ' [4] = '34 ' [5] = '114 ' [6] = '48 ' [7] = '51 ' [8] = '100 ' [9] = '20 ' [10] = '208 ' [11] = '214 ' [12] = '55 ' [13] = '57 ' [14] = '108 ' [15] = '126 ' [16] = '187 ' [17] = '203 ' [18] = '46 ' [19] = '114 ' [20] = '25 ' [21] = '0 ' [22] = '0 ' [23] = '97 ' [24] = '80 ' [25] = '19 ' [26] = '113 ' [27] = '52 ' [28] = '114 ' [29] = '128 ' [30] = '143 ' [31] = '5 ' [32] = '0 ' [33] = '3 ' [34] = '186 ' [35] = '3 ' [36] = '3 ' [37] = '202 ' [38] = '105 ' [39] = '119 ' [40] = '25 ' [41] = '52 ' [42] = '109 ' [43] = '78 ' [44] = '93 ' [45] = '160 ' [46] = '165 ' [47] = '223 ' [48] = '77 ' [49] = '78 ' [50] = '159 ' [51] = '203 ' [52] = '110 ' [53] = '208 ' [54] = '52 ' [55] = '93 ' [56] = '6 ' [57] = '185 ' [58] = '211 ' [59] = '99 ' [60] = '52 ' [61] = '157 ' [62] = '5 ' [63] = '210 ' [64] = '135 ' [65] = '209 ' [66] = '236 ' [67] = '178 ' [68] = '27 ' [69] = '52 ' [70] = '77 ' [71] = '151 ' [72] = '65 ' [73] = '105 ' [74] = '55 ' [75] = '136 ' [76] = '92 ' [77] = '118 ' [78] = '131 ' [79] = '154 ' [80] = '111 ' [81] = '119 ' [82] = '152 ' [83] = '94 ' [84] = '118 ' [85] = '131 ' [86] = '200 ' [87] = '97 ' [88] = '119 ' [89] = '120 ' [90] = '140 ' [91] = '6 ' [92] = '137 ' [93] = '203 ' [94] = '241 ' [95] = '122 ' [96] = '185 ' [97] = '13 ' [98] = '242 ' [99] = '139 ' [100] = '203 ' [101] = '114 ' [102] = '80 ' [103] = '18 ' [104] = '45 ' [105] = '47 ' [106] = '131 ' [107] = '154 ' [108] = '111 ' [109] = '113 ' [110] = '154 ' [111] = '109 ' [112] = '174 ' [113] = '187 ' [114] = '215 ' [115] = '45 ' [116] = '105 ' [117] = '121 ' [118] = '140 ' [119] = '118 ' [120] = '215 ' [121] = '221 ' [122] = '103 ' [123] = '23 ' [124] = '200 ' [125] = '40 ' [126] = '47 ' [127] = '215 ' [128] = '221 ' [129] = '100 ' [130] = '118 ' [131] = '122 ' [132] = '140 ' [133] = '46 ' [134] = '131 ' [135] = '142 ' [136] = '114 ' [137] = '191 ' [138] = '167 ' [139] = '204 ' [140] = '2 ' [141] = '37 ' [142] = '209 ' [143] = '114 ' [144] = '144 ' [145] = '245 ' [146] = '77 ' [147] = '14 ' [148] = '155 ' [149] = '223 ' [150] = '238 ' [151] = '114 ' [152] = '139 ' [153] = '90 ' [154] = '14 ' [155] = '183 ' [156] = '1 '}' ]
ModemManager[1157]: <debug> [1466176579.306058] [mm-sms-part-3gpp.c:368] mm_sms_part_3gpp_new_from_binary_pdu(): Parsing PDU (2)...
ModemManager[1157]: <debug> [1466176579.306119] [mm-sms-part-3gpp.c:397] mm_sms_part_3gpp_new_from_binary_pdu():   SMSC address parsed: '+491722270333'
ModemManager[1157]: <debug> [1466176579.306162] [mm-sms-part-3gpp.c:410] mm_sms_part_3gpp_new_from_binary_pdu():   Deliver type PDU detected
ModemManager[1157]: <debug> [1466176579.306253] [mm-sms-part-3gpp.c:471] mm_sms_part_3gpp_new_from_binary_pdu():   Number parsed: 'Vodafone.de'
ModemManager[1157]: <debug> [1466176579.306299] [mm-sms-part-3gpp.c:575] mm_sms_part_3gpp_new_from_binary_pdu():   PID: 0
ModemManager[1157]: <debug> [1466176579.306369] [mm-sms-part-3gpp.c:586] mm_sms_part_3gpp_new_from_binary_pdu():   user data encoding is GSM7
ModemManager[1157]: <debug> [1466176579.306410] [mm-sms-part-3gpp.c:614] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 143 elements
ModemManager[1157]: <debug> [1466176579.306450] [mm-sms-part-3gpp.c:620] mm_sms_part_3gpp_new_from_binary_pdu():   user data length: 126 bytes
ModemManager[1157]: <debug> [1466176579.306499] [mm-sms-part-3gpp.c:695] mm_sms_part_3gpp_new_from_binary_pdu(): Decoding SMS text with '136' elements
ModemManager[1157]: <debug> [1466176579.306541] [mm-sms-part-3gpp.c:244] sms_decode_text(): Converting SMS part text from GSM7 to UTF8...
ModemManager[1157]: <debug> [1466176579.306697] [mm-sms-part-3gpp.c:247] sms_decode_text():    Got UTF-8 text: 'eine SMS. Kündigen Sie nicht, zahlen Sie in den Monaten danach bequem über Ihre Mobilfunk-Rechnung. Freundliche Grüße, Ihr Vodafone-Team'
ModemManager[1157]: <debug> [1466176579.306778] [mm-broadband-modem-qmi.c:7568] add_new_read_sms_part(): Correctly parsed PDU (2)
ModemManager[1157]: <debug> [1466176579.306929] [mm-sms-list.c:371] mm_sms_list_take_part(): SMS part at 'me/2' is from a multipart SMS (reference: '186', sequence: '3')
ModemManager[1157]: <debug> [1466176579.307659] [mm-broadband-modem-qmi.c:7767] load_initial_sms_parts_step(): loading 3GPP MO-sent messages from storage 'me'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:0D:00:31:00:0C:00:12:01:00:01:11:01:00:02:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 13
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 02
<<<<<<   translated = mo-sent
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = nv
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:0D:00:31:00:07:00:02:04:00:01:00:30:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 13
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:30:00
>>>>>>   translated = FAILURE: InvalidArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'
ModemManager[1157]: <debug> [1466176579.368885] [mm-broadband-modem-qmi.c:7773] load_initial_sms_parts_step(): loading 3GPP MO-not-sent messages from storage 'me'...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 25
<<<<<<   data   = 01:18:00:00:05:06:00:0E:00:31:00:0C:00:12:01:00:01:11:01:00:03:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 24
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 14
<<<<<<   tlv_length  = 12
<<<<<<   message     = "List Messages" (0x0031)
<<<<<< TLV:
<<<<<<   type       = "Message Mode" (0x12)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = gsm-wcdma
<<<<<< TLV:
<<<<<<   type       = "Message Tag" (0x11)
<<<<<<   length     = 1
<<<<<<   value      = 03
<<<<<<   translated = mo-not-sent
<<<<<< TLV:
<<<<<<   type       = "Storage Type" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = nv
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:0E:00:31:00:07:00:02:04:00:01:00:30:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 14
>>>>>>   tlv_length  = 7
>>>>>>   message     = "List Messages" (0x0031)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:30:00
>>>>>>   translated = FAILURE: InvalidArgument
ModemManager[1157]: Couldn't read SMS messages: QMI protocol error (48): 'InvalidArgument'
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:05:06:00:0F:00:01:00:04:00:10:01:00:01
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "wms"
<<<<<<   client  = 6
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 15
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Set Event Report" (0x0001)
<<<<<< TLV:
<<<<<<   type       = "New MT Message Indicator" (0x10)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = [ report = 'yes' ]
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:05:06:02:0F:00:01:00:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wms"
>>>>>>   client  = 6
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 15
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Set Event Report" (0x0001)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: <debug> [1466176579.496217] [mm-broadband-modem.c:9264] enabling_step(): Modem has voice capabilities, enabling the Voice interface...
ModemManager[1157]: <debug> [1466176579.496666] [mm-broadband-modem.c:6525] set_voice_unsolicited_events_handlers(): (ttyUSB2) Setting voice unsolicited events handlers
ModemManager[1157]: <debug> [1466176579.496792] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[1157]: <debug> [1466176579.496985] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CLIP=1<CR>'
ModemManager[1157]: <debug> [1466176579.513561] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[1157]: <debug> [1466176579.513796] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CRC=1<CR>'
ModemManager[1157]: <debug> [1466176579.527703] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
ModemManager[1157]: <debug> [1466176579.527858] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[1157]: <debug> [1466176579.527997] [mm-broadband-modem.c:9277] enabling_step(): Modem has time capabilities, enabling the Time interface...
ModemManager[1157]: <debug> [1466176579.528208] [mm-broadband-modem.c:9290] enabling_step(): Modem has extended signal reporting capabilities, enabling the Signal interface...
ModemManager[1157]: <debug> [1466176579.528282] [mm-iface-modem-signal.c:206] setup_refresh_context(): Extended signal information reporting disabled (rate: 0 seconds)
ModemManager[1157]: <info>  [1466176579.528549] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
ModemManager[1157]: <debug> [1466176579.529419] [mm-iface-modem.c:1355] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
ModemManager[1157]: <debug> [1466176579.529578] [mm-broadband-modem-qmi.c:2951] load_signal_quality(): loading signal quality...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:07:00:09:00:20:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 9
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Signal Strength" (0x0020)
ModemManager[1157]: <debug> [1466176579.530439] [mm-iface-modem-3gpp.c:761] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:07:00:0A:00:24:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 10
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Serving System" (0x0024)
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 25
>>>>>>   data   = 01:18:00:80:03:07:02:09:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:B0:08
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 24
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 9
>>>>>>   tlv_length  = 12
>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>   length     = 2
>>>>>>   value      = B0:08
>>>>>>   translated = [ strength = '-80' radio_interface = 'lte' ]
ModemManager[1157]: <debug> [1466176579.560563] [mm-broadband-modem-qmi.c:2841] signal_strength_get_quality_and_access_tech(): Signal strength (lte): -80 dBm
ModemManager[1157]: <debug> [1466176579.560633] [mm-broadband-modem-qmi.c:2874] signal_strength_get_quality_and_access_tech(): Signal strength: -80 dBm --> 54%
ModemManager[1157]: <debug> [1466176579.560875] [mm-iface-modem.c:933] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> lte)
ModemManager[1157]: <debug> [1466176579.561180] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
ModemManager[1157]: <debug> [1466176579.561291] [mm-iface-modem.c:1274] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 93
>>>>>>   data   = 01:5C:00:80:03:07:02:0A:00:24:00:50:00:02:04:00:00:00:00:00:01:06:00:01:01:01:02:01:08:10:01:00:01:11:02:00:01:0B:12:0D:00:06:01:02:00:08:56:6F:64:61:66:6F:6E:65:15:03:00:01:08:01:1C:02:00:FE:FF:1D:04:00:03:D6:44:01:21:05:00:02:03:00:00:00:24:02:00:9E:BB:27:05:00:06:01:02:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 92
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 10
>>>>>>   tlv_length  = 80
>>>>>>   message     = "Get Serving System" (0x0024)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Serving System" (0x01)
>>>>>>   length     = 6
>>>>>>   value      = 01:01:01:02:01:08
>>>>>>   translated = [ registration_state = 'registered' cs_attach_state = 'attached' ps_attach_state = 'attached' selected_network = '3gpp' radio_interfaces = '{ [0] = 'lte '}' ]
>>>>>> TLV:
>>>>>>   type       = "Roaming Indicator" (0x10)
>>>>>>   length     = 1
>>>>>>   value      = 01
>>>>>>   translated = off
>>>>>> TLV:
>>>>>>   type       = "Data Service Capability" (0x11)
>>>>>>   length     = 2
>>>>>>   value      = 01:0B
>>>>>>   translated = { [0] = 'lte '}
>>>>>> TLV:
>>>>>>   type       = "Current PLMN" (0x12)
>>>>>>   length     = 13
>>>>>>   value      = 06:01:02:00:08:56:6F:64:61:66:6F:6E:65
>>>>>>   translated = [ mcc = '262' mnc = '2' description = 'Vodafone' ]
>>>>>> TLV:
>>>>>>   type       = "Roaming Indicator List" (0x15)
>>>>>>   length     = 3
>>>>>>   value      = 01:08:01
>>>>>>   translated = { [0] = '[ radio_interface = 'lte' roaming_indicator = 'off' ] '}
>>>>>> TLV:
>>>>>>   type       = "LAC 3GPP" (0x1c)
>>>>>>   length     = 2
>>>>>>   value      = FE:FF
>>>>>>   translated = 65534
>>>>>> TLV:
>>>>>>   type       = "CID 3GPP" (0x1d)
>>>>>>   length     = 4
>>>>>>   value      = 03:D6:44:01
>>>>>>   translated = 21288451
>>>>>> TLV:
>>>>>>   type       = "Detailed Service Status" (0x21)
>>>>>>   length     = 5
>>>>>>   value      = 02:03:00:00:00
>>>>>>   translated = [ status = 'available' capability = 'cs-ps' hdr_status = 'none' hdr_hybrid = 'no' forbidden = 'no' ]
>>>>>> TLV:
>>>>>>   type       = "LTE TAC" (0x24)
>>>>>>   length     = 2
>>>>>>   value      = 9E:BB
>>>>>>   translated = 48030
>>>>>> TLV:
>>>>>>   type       = "MNC PCS Digit Include Status" (0x27)
>>>>>>   length     = 5
>>>>>>   value      = 06:01:02:00:00
>>>>>>   translated = [ mcc = '262' mnc = '2' includes_pcs_digit = 'no' ]
ModemManager[1157]: <debug> [1466176579.625332] [mm-broadband-modem-qmi.c:4607] common_process_serving_system_3gpp(): Processing 3GPP info...
ModemManager[1157]: <debug> [1466176579.625576] [mm-broadband-modem.c:8574] modem_3gpp_run_registration_checks_ready(): Initial 3GPP registration checks finished
ModemManager[1157]: <debug> [1466176584.950205] [mm-port-serial.c:1288] mm_port_serial_open(): (ttyUSB2) device open count is 2 (open)
ModemManager[1157]: <debug> [1466176584.950457] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): --> 'AT+CCLK?<CR>'
ModemManager[1157]: <debug> [1466176584.961000] [mm-port-serial-at.c:459] debug_log(): (ttyUSB2): <-- '<CR><LF>+CCLK: "80/01/06,00:16:00"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[1157]: <debug> [1466176584.961555] [mm-iface-modem-time.c:622] update_network_timezone_ready(): Couldn't update network timezone: 'Couldn't match +CCLK reply'
ModemManager[1157]: <debug> [1466176584.961628] [mm-port-serial.c:1345] _close_internal(): (ttyUSB2) device open count is 1 (close)
ModemManager[1157]: <debug> [1466176609.970046] [mm-broadband-modem-qmi.c:2951] load_signal_quality(): loading signal quality...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:07:00:0B:00:20:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 11
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Signal Strength" (0x0020)
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 25
>>>>>>   data   = 01:18:00:80:03:07:02:0B:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:B0:08
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 24
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 11
>>>>>>   tlv_length  = 12
>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>   length     = 2
>>>>>>   value      = B0:08
>>>>>>   translated = [ strength = '-80' radio_interface = 'lte' ]
ModemManager[1157]: <debug> [1466176609.990036] [mm-broadband-modem-qmi.c:2841] signal_strength_get_quality_and_access_tech(): Signal strength (lte): -80 dBm
ModemManager[1157]: <debug> [1466176609.990100] [mm-broadband-modem-qmi.c:2874] signal_strength_get_quality_and_access_tech(): Signal strength: -80 dBm --> 54%
ModemManager[1157]: <debug> [1466176609.990387] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
ModemManager[1157]: <info>  [1466176629.591001] [mm-iface-modem-simple.c:641] connect_auth_ready(): Simple connect started...
ModemManager[1157]: <debug> [1466176629.591196] [mm-iface-modem-simple.c:651] connect_auth_ready():    PIN: unspecified
ModemManager[1157]: <debug> [1466176629.591263] [mm-iface-modem-simple.c:653] connect_auth_ready():    Operator ID: unspecified
ModemManager[1157]: <debug> [1466176629.591344] [mm-iface-modem-simple.c:655] connect_auth_ready():    Allowed roaming: yes
ModemManager[1157]: <debug> [1466176629.591412] [mm-iface-modem-simple.c:657] connect_auth_ready():    APN: web.vodafone.de
ModemManager[1157]: <debug> [1466176629.591457] [mm-iface-modem-simple.c:662] connect_auth_ready():    IP family: ipv4v6
ModemManager[1157]: <debug> [1466176629.591524] [mm-iface-modem-simple.c:673] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[1157]: <debug> [1466176629.591564] [mm-iface-modem-simple.c:675] connect_auth_ready():    User: unspecified
ModemManager[1157]: <debug> [1466176629.591628] [mm-iface-modem-simple.c:677] connect_auth_ready():    Password: unspecified
ModemManager[1157]: <debug> [1466176629.591668] [mm-iface-modem-simple.c:679] connect_auth_ready():    Number: *99#
ModemManager[1157]: <info>  [1466176629.591730] [mm-iface-modem-simple.c:469] connection_step(): Simple connect state (4/8): Wait to get fully enabled
ModemManager[1157]: <info>  [1466176629.591865] [mm-iface-modem-simple.c:478] connection_step(): Simple connect state (5/8): Register
ModemManager[1157]: <debug> [1466176629.592006] [mm-iface-modem-3gpp.c:400] mm_iface_modem_3gpp_register_in_network(): Already registered in network '26202', automatic registration not launched...
ModemManager[1157]: <info>  [1466176629.592130] [mm-iface-modem-simple.c:501] connection_step(): Simple connect state (6/8): Bearer
ModemManager[1157]: <debug> [1466176629.592183] [mm-iface-modem-simple.c:521] connection_step(): Creating new bearer...
ModemManager[1157]: <debug> [1466176629.592288] [mm-broadband-modem-qmi.c:332] modem_create_bearer(): Creating QMI bearer in QMI modem
ModemManager[1157]: <debug> [1466176629.593847] [mm-broadband-modem-qmi.c:304] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[1157]: <info>  [1466176629.594706] [mm-iface-modem-simple.c:583] connection_step(): Simple connect state (7/8): Connect
ModemManager[1157]: <debug> [1466176629.595214] [mm-base-bearer.c:703] mm_base_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[1157]: <info>  [1466176629.595400] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[1157]: <debug> [1466176629.596696] [mm-bearer-qmi.c:1251] _connect(): Launching connection with QMI port (usb/cdc-wdm1) and data port (net/wwp0s19u1u3i10)
ModemManager[1157]: <debug> [1466176629.596941] [mm-port-qmi.c:328] port_open_context_step(): Opening QMI device...
ModemManager[1157]: <debug> [1466176629.597007] [mm-port-qmi.c:333] port_open_context_step(): Checking if QMI device already opening...
ModemManager[1157]: <debug> [1466176629.597071] [mm-port-qmi.c:346] port_open_context_step(): Checking if QMI device already open...
ModemManager[1157]: <debug> [1466176629.597235] [mm-port-qmi.c:367] port_open_context_step(): Creating QMI device...
ModemManager[1157]: <debug> [1466176629.600353] [mm-port-qmi.c:380] port_open_context_step(): Opening device without data format update...
ModemManager[1157]: [/dev/cdc-wdm1] Opening device with flags 'version-info, proxy'...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 28
<<<<<<   data   = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 27
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 16
<<<<<<   message     = "Internal Proxy Open" (0xFF00)
<<<<<< TLV:
<<<<<<   type       = "Device Path" (0x01)
<<<<<<   length     = 13
<<<<<<   value      = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:31
<<<<<<   translated = /dev/cdc-wdm1
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 19
>>>>>>   data   = 01:12:00:00:00:00:01:01:00:FF:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 18
>>>>>>   flags   = 0x00
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Internal Proxy Open" (0xFF00)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: [/dev/cdc-wdm1] Checking version info (10 retries)...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 12
<<<<<<   data   = 01:0B:00:00:00:00:00:02:21:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 11
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Version Info" (0x0021)
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 183
>>>>>>   data   = 01:B6:00:80:00:00:01:02:21:00:AB:00:02:04:00:00:00:00:00:01:A1:00:20:00:01:00:05:00:01:01:00:43:00:02:01:00:0E:00:03:01:00:19:00:04:01:00:06:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:2D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:2B:01:00:00:00:2E:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:E1:01:00:00:00:F0:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 182
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 2
>>>>>>   tlv_length  = 171
>>>>>>   message     = "Get Version Info" (0x0021)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Service list" (0x01)
>>>>>>   length     = 161
>>>>>>   value      = 20:00:01:00:05:00:01:01:00:43:00:02:01:00:0E:00:03:01:00:19:00:04:01:00:06:00:05:01:00:0A:00:07:01:00:03:00:08:01:00:02:00:09:02:00:01:00:0A:02:00:18:00:0B:01:00:2D:00:0C:01:00:04:00:0F:01:00:00:00:10:02:00:00:00:11:01:00:00:00:17:01:00:00:00:18:01:00:00:00:1A:01:00:10:00:1D:01:00:01:00:22:01:00:00:00:24:01:00:00:00:29:01:00:00:00:2A:01:00:00:00:2B:01:00:00:00:2E:01:00:00:00:30:01:00:00:00:31:01:00:00:00:36:01:00:00:00:E1:01:00:00:00:F0:01:00:00:00:F5:01:00:00:00:F6:01:00:00:00
>>>>>>   translated = { [0] = '[ service = 'ctl' major_version = '1' minor_version = '5' ] ' [1] = '[ service = 'wds' major_version = '1' minor_version = '67' ] ' [2] = '[ service = 'dms' major_version = '1' minor_version = '14' ] ' [3] = '[ service = 'nas' major_version = '1' minor_version = '25' ] ' [4] = '[ service = 'qos' major_version = '1' minor_version = '6' ] ' [5] = '[ service = 'wms' major_version = '1' minor_version = '10' ] ' [6] = '[ service = 'auth' major_version = '1' minor_version = '3' ] ' [7] = '[ service = 'at' major_version = '1' minor_version = '2' ] ' [8] = '[ service = 'voice' major_version = '2' minor_version = '1' ] ' [9] = '[ service = 'cat2' major_version = '2' minor_version = '24' ] ' [10] = '[ service = 'uim' major_version = '1' minor_version = '45' ] ' [11] = '[ service = 'pbm' major_version = '1' minor_version = '4' ] ' [12] = '[ service = 'test' major_version = '1' minor_version = '0' ] ' [13] = '[ service = 'loc' major_version = '2' minor_version = '0' ] ' [14] = '[ service = 'sar' major_version = '1' minor_version = '0' ] ' [15] = '[ service = 'ts' major_version = '1' minor_version = '0' ] ' [16] = '[ service = 'tmd' major_version = '1' minor_version = '0' ] ' [17] = '[ service = 'wda' major_version = '1' minor_version = '16' ] ' [18] = '[ service = 'csvt' major_version = '1' minor_version = '1' ] ' [19] = '[ service = 'coex' major_version = '1' minor_version = '0' ] ' [20] = '[ service = 'pdc' major_version = '1' minor_version = '0' ] ' [21] = '[ service = 'rfrpe' major_version = '1' minor_version = '0' ] ' [22] = '[ service = 'dsd' major_version = '1' minor_version = '0' ] ' [23] = '[ service = 'ssctl' major_version = '1' minor_version = '0' ] ' [24] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [25] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [26] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [27] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [28] = '[ service = 'rms' major_version = '1' minor_version = '0' ] ' [29] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [30] = '[ service = '(null)' major_version = '1' minor_version = '0' ] ' [31] = '[ service = '(null)' major_version = '1' minor_version = '0' ] '}
ModemManager[1157]: [/dev/cdc-wdm1] QMI Device supports 32 services:
ModemManager[1157]: [/dev/cdc-wdm1]    ctl (1.5)
ModemManager[1157]: [/dev/cdc-wdm1]    wds (1.67)
ModemManager[1157]: [/dev/cdc-wdm1]    dms (1.14)
ModemManager[1157]: [/dev/cdc-wdm1]    nas (1.25)
ModemManager[1157]: [/dev/cdc-wdm1]    qos (1.6)
ModemManager[1157]: [/dev/cdc-wdm1]    wms (1.10)
ModemManager[1157]: [/dev/cdc-wdm1]    auth (1.3)
ModemManager[1157]: [/dev/cdc-wdm1]    at (1.2)
ModemManager[1157]: [/dev/cdc-wdm1]    voice (2.1)
ModemManager[1157]: [/dev/cdc-wdm1]    cat2 (2.24)
ModemManager[1157]: [/dev/cdc-wdm1]    uim (1.45)
ModemManager[1157]: [/dev/cdc-wdm1]    pbm (1.4)
ModemManager[1157]: [/dev/cdc-wdm1]    test (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    loc (2.0)
ModemManager[1157]: [/dev/cdc-wdm1]    sar (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    ts (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    tmd (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    wda (1.16)
ModemManager[1157]: [/dev/cdc-wdm1]    csvt (1.1)
ModemManager[1157]: [/dev/cdc-wdm1]    coex (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    pdc (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    rfrpe (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    dsd (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    ssctl (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0x2e] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0x30] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0x31] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0x36] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    rms (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0xf0] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0xf5] (1.0)
ModemManager[1157]: [/dev/cdc-wdm1]    unknown [0xf6] (1.0)
ModemManager[1157]: <debug> [1466176629.672354] [mm-port-qmi.c:391] port_open_context_step(): Querying kernel data format...
ModemManager[1157]: [/dev/cdc-wdm1] Reading expected data format from: /sys/class/net/wwp0s19u1u3i10/qmi/raw_ip
ModemManager[1157]: <debug> [1466176629.672939] [mm-port-qmi.c:405] port_open_context_step(): Allocating WDA client...
ModemManager[1157]: [/dev/cdc-wdm1] Allocating new client ID...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:03:22:00:04:00:01:01:00:1A
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 1A
<<<<<<   translated = wda
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:00:00:01:03:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:1A:01
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 3
>>>>>>   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      = 1A:01
>>>>>>   translated = [ service = 'wda' cid = '1' ]
ModemManager[1157]: [/dev/cdc-wdm1] Registered 'wda' (version 1.16) client with ID '1'
ModemManager[1157]: <debug> [1466176629.733680] [mm-port-qmi.c:418] port_open_context_step(): Querying device data format...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:1A:01:00:01:00:21:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "wda"
<<<<<<   client  = 1
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Data Format" (0x0021)
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 91
>>>>>>   data   = 01:5A:00:80:1A:01:02:01:00:21:00:4E:00:02:04:00:00:00:00:00:10:01:00:00:11:04:00:02:00:00:00:12:04:00:00:00:00:00:13:04:00:00:00:00:00:14:04:00:00:00:00:00:15:04:00:00:00:00:00:16:04:00:00:00:00:00:17:04:00:01:00:00:00:18:04:00:FF:FF:FF:FF:1A:04:00:00:00:00:00:1B:01:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 90
>>>>>>   flags   = 0x80
>>>>>>   service = "wda"
>>>>>>   client  = 1
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 78
>>>>>>   message     = "Get Data Format" (0x0021)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "QoS Format" (0x10)
>>>>>>   length     = 1
>>>>>>   value      = 00
>>>>>>   translated = no
>>>>>> TLV:
>>>>>>   type       = "Link Layer Protocol" (0x11)
>>>>>>   length     = 4
>>>>>>   value      = 02:00:00:00
>>>>>>   translated = raw-ip
>>>>>> TLV:
>>>>>>   type       = "Uplink Data Aggregation Protocol" (0x12)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = disabled
>>>>>> TLV:
>>>>>>   type       = "Downlink Data Aggregation Protocol" (0x13)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = disabled
>>>>>> TLV:
>>>>>>   type       = "NDP Signature" (0x14)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = 0
>>>>>> TLV:
>>>>>>   type       = "Uplink Data Aggregation Max Size" (0x15)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = 0
>>>>>> TLV:
>>>>>>   type       = "Downlink Data Aggregation Max Size" (0x16)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = 0
>>>>>> TLV:
>>>>>>   type   = 0x17
>>>>>>   length = 4
>>>>>>   value  = 01:00:00:00
>>>>>> TLV:
>>>>>>   type   = 0x18
>>>>>>   length = 4
>>>>>>   value  = FF:FF:FF:FF
>>>>>> TLV:
>>>>>>   type   = 0x1a
>>>>>>   length = 4
>>>>>>   value  = 00:00:00:00
>>>>>> TLV:
>>>>>>   type   = 0x1b
>>>>>>   length = 1
>>>>>>   value  = 00
ModemManager[1157]: <debug> [1466176629.797954] [mm-port-qmi.c:432] port_open_context_step(): Checking data format: kernel raw-ip, device raw-ip
ModemManager[1157]: <debug> [1466176629.798056] [mm-port-qmi.c:494] port_open_context_step(): QMI port open operation finished
ModemManager[1157]: [/dev/cdc-wdm1] Releasing 'wda' client with flags 'release-cid'...
ModemManager[1157]: [/dev/cdc-wdm1] Unregistered 'wda' client with ID '1'
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:00:00:00:04:23:00:05:00:01:02:00:1A:01
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 5
<<<<<<   message     = "Release CID" (0x0023)
<<<<<< TLV:
<<<<<<   type       = "Release Info" (0x01)
<<<<<<   length     = 2
<<<<<<   value      = 1A:01
<<<<<<   translated = [ service = 'wda' cid = '1' ]
ModemManager[1157]: <debug> [1466176629.798593] [mm-bearer-qmi.c:934] connect_context_step(): Running IPv4 connection setup
ModemManager[1157]: <debug> [1466176629.798690] [mm-bearer-qmi.c:947] connect_context_step(): Allocating IPv4-specific WDS client
ModemManager[1157]: [/dev/cdc-wdm1] Allocating new client ID...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:05:22:00:04:00:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 5
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = wds
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:00:00:01:04:23:00:0C:00:02:04:00:00:00:00:00:01:02:00:1A:01
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 4
>>>>>>   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      = 1A:01
>>>>>>   translated = [ service = 'wda' cid = '1' ]
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:00:00:01:05:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:01:25
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 5
>>>>>>   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      = 01:25
>>>>>>   translated = [ service = 'wds' cid = '37' ]
ModemManager[1157]: [/dev/cdc-wdm1] Registered 'wds' (version 1.67) client with ID '37'
ModemManager[1157]: <debug> [1466176629.926367] [mm-bearer-qmi.c:968] connect_context_step(): Setting default IP family to: IPv4
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:01:25:00:01:00:4D:00:04:00:01:01:00:04
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 37
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Set IP Family" (0x004D)
<<<<<< TLV:
<<<<<<   type       = "Preference" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 04
<<<<<<   translated = ipv4
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:01:25:02:01:00:4D:00:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 37
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Set IP Family" (0x004D)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: <debug> [1466176629.989671] [mm-bearer-qmi.c:997] connect_context_step(): Starting IPv4 connection...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 31
<<<<<<   data   = 01:1E:00:00:01:25:00:02:00:20:00:12:00:14:0F:00:77:65:62:2E:76:6F:64:61:66:6F:6E:65:2E:64:65
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 30
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 37
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 18
<<<<<<   message     = "Start Network" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "APN" (0x14)
<<<<<<   length     = 15
<<<<<<   value      = 77:65:62:2E:76:6F:64:61:66:6F:6E:65:2E:64:65
<<<<<<   translated = web.vodafone.de
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 27
>>>>>>   data   = 01:1A:00:80:01:25:02:02:00:20:00:0E:00:02:04:00:00:00:00:00:01:04:00:00:B6:C0:03
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 26
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 37
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 2
>>>>>>   tlv_length  = 14
>>>>>>   message     = "Start Network" (0x0020)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Packet Data Handle" (0x01)
>>>>>>   length     = 4
>>>>>>   value      = 00:B6:C0:03
>>>>>>   translated = 62961152
ModemManager[1157]: <debug> [1466176630.053154] [mm-bearer-qmi.c:1012] connect_context_step(): Getting IPv4 configuration...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:00:01:25:00:03:00:2D:00:07:00:10:04:00:30:E3:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 37
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 3
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Get Current Settings" (0x002D)
<<<<<< TLV:
<<<<<<   type       = "Requested Settings" (0x10)
<<<<<<   length     = 4
<<<<<<   value      = 30:E3:00:00
<<<<<<   translated = dns-address, granted-qos, ip-address, gateway-info, mtu, domain-name-list, ip-family
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 31
>>>>>>   data   = 01:1E:00:80:01:25:04:01:00:22:00:12:00:01:02:00:02:00:12:01:00:04:13:02:00:80:88:14:01:00:05
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 30
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 37
>>>>>> QMI:
>>>>>>   flags       = "indication"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 18
>>>>>>   message     = "Packet Service Status" (0x0022)
>>>>>> TLV:
>>>>>>   type       = "Connection Status" (0x01)
>>>>>>   length     = 2
>>>>>>   value      = 02:00
>>>>>>   translated = [ status = 'connected' reconfiguration_required = 'no' ]
>>>>>> TLV:
>>>>>>   type       = "IP Family" (0x12)
>>>>>>   length     = 1
>>>>>>   value      = 04
>>>>>>   translated = ipv4
>>>>>> TLV:
>>>>>>   type   = 0x13
>>>>>>   length = 2
>>>>>>   value  = 80:88
>>>>>> TLV:
>>>>>>   type   = 0x14
>>>>>>   length = 1
>>>>>>   value  = 05
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 70
>>>>>>   data   = 01:45:00:80:01:25:02:03:00:2D:00:39:00:02:04:00:00:00:00:00:1E:04:00:E1:DD:4D:64:15:04:00:7E:1E:07:8B:16:04:00:7D:1E:07:8B:20:04:00:E2:DD:4D:64:21:04:00:FC:FF:FF:FF:29:04:00:DC:05:00:00:2A:01:00:00:2B:01:00:04
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 69
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 37
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 3
>>>>>>   tlv_length  = 57
>>>>>>   message     = "Get Current Settings" (0x002D)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "IPv4 Address" (0x1e)
>>>>>>   length     = 4
>>>>>>   value      = E1:DD:4D:64
>>>>>>   translated = 1682824673
>>>>>> TLV:
>>>>>>   type       = "Primary IPv4 DNS Address" (0x15)
>>>>>>   length     = 4
>>>>>>   value      = 7E:1E:07:8B
>>>>>>   translated = 2332499582
>>>>>> TLV:
>>>>>>   type       = "Secondary IPv4 DNS Address" (0x16)
>>>>>>   length     = 4
>>>>>>   value      = 7D:1E:07:8B
>>>>>>   translated = 2332499581
>>>>>> TLV:
>>>>>>   type       = "IPv4 Gateway Address" (0x20)
>>>>>>   length     = 4
>>>>>>   value      = E2:DD:4D:64
>>>>>>   translated = 1682824674
>>>>>> TLV:
>>>>>>   type       = "IPv4 Gateway Subnet Mask" (0x21)
>>>>>>   length     = 4
>>>>>>   value      = FC:FF:FF:FF
>>>>>>   translated = 4294967292
>>>>>> TLV:
>>>>>>   type       = "MTU" (0x29)
>>>>>>   length     = 4
>>>>>>   value      = DC:05:00:00
>>>>>>   translated = 1500
>>>>>> TLV:
>>>>>>   type       = "Domain Name List" (0x2a)
>>>>>>   length     = 1
>>>>>>   value      = 00
>>>>>>   translated = {}
>>>>>> TLV:
>>>>>>   type       = "IP Family" (0x2b)
>>>>>>   length     = 1
>>>>>>   value      = 04
>>>>>>   translated = ipv4
ModemManager[1157]: <debug> [1466176630.245754] [mm-bearer-qmi.c:677] get_current_settings_ready():  IP Family: IPv4
ModemManager[1157]: <info>  [1466176630.246015] [mm-bearer-qmi.c:495] get_ipv4_config(): QMI IPv4 Settings:
ModemManager[1157]: <info>  [1466176630.246232] [mm-bearer-qmi.c:508] get_ipv4_config():     Address: 100.77.221.225/30
ModemManager[1157]: <info>  [1466176630.246317] [mm-bearer-qmi.c:514] get_ipv4_config():     Gateway: 100.77.221.226
ModemManager[1157]: <info>  [1466176630.246422] [mm-bearer-qmi.c:524] get_ipv4_config():     DNS #1: 139.7.30.126
ModemManager[1157]: <info>  [1466176630.246468] [mm-bearer-qmi.c:534] get_ipv4_config():     DNS #2: 139.7.30.125
ModemManager[1157]: <info>  [1466176630.246550] [mm-bearer-qmi.c:545] get_ipv4_config():        MTU: 1500
ModemManager[1157]: <debug> [1466176630.246596] [mm-bearer-qmi.c:699] get_current_settings_ready():    Domains:
ModemManager[1157]: <debug> [1466176630.246671] [mm-bearer-qmi.c:1029] connect_context_step(): Running IPv6 connection setup
ModemManager[1157]: <debug> [1466176630.246744] [mm-bearer-qmi.c:1042] connect_context_step(): Allocating IPv6-specific WDS client
ModemManager[1157]: [/dev/cdc-wdm1] Allocating new client ID...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 16
<<<<<<   data   = 01:0F:00:00:00:00:00:06:22:00:04:00:01:01:00:01
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 15
<<<<<<   flags   = 0x00
<<<<<<   service = "ctl"
<<<<<<   client  = 0
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 6
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Allocate CID" (0x0022)
<<<<<< TLV:
<<<<<<   type       = "Service" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 01
<<<<<<   translated = wds
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 24
>>>>>>   data   = 01:17:00:80:00:00:01:06:22:00:0C:00:02:04:00:00:00:00:00:01:02:00:01:26
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 23
>>>>>>   flags   = 0x80
>>>>>>   service = "ctl"
>>>>>>   client  = 0
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 6
>>>>>>   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      = 01:26
>>>>>>   translated = [ service = 'wds' cid = '38' ]
ModemManager[1157]: [/dev/cdc-wdm1] Registered 'wds' (version 1.67) client with ID '38'
ModemManager[1157]: <debug> [1466176630.309925] [mm-bearer-qmi.c:1065] connect_context_step(): Setting default IP family to: IPv6
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 17
<<<<<<   data   = 01:10:00:00:01:26:00:01:00:4D:00:04:00:01:01:00:06
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 16
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 38
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 1
<<<<<<   tlv_length  = 4
<<<<<<   message     = "Set IP Family" (0x004D)
<<<<<< TLV:
<<<<<<   type       = "Preference" (0x01)
<<<<<<   length     = 1
<<<<<<   value      = 06
<<<<<<   translated = ipv6
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 20
>>>>>>   data   = 01:13:00:80:01:26:02:01:00:4D:00:07:00:02:04:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 19
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 38
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 1
>>>>>>   tlv_length  = 7
>>>>>>   message     = "Set IP Family" (0x004D)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
ModemManager[1157]: <debug> [1466176630.373545] [mm-bearer-qmi.c:1094] connect_context_step(): Starting IPv6 connection...
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 31
<<<<<<   data   = 01:1E:00:00:01:26:00:02:00:20:00:12:00:14:0F:00:77:65:62:2E:76:6F:64:61:66:6F:6E:65:2E:64:65
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 30
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 38
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 2
<<<<<<   tlv_length  = 18
<<<<<<   message     = "Start Network" (0x0020)
<<<<<< TLV:
<<<<<<   type       = "APN" (0x14)
<<<<<<   length     = 15
<<<<<<   value      = 77:65:62:2E:76:6F:64:61:66:6F:6E:65:2E:64:65
<<<<<<   translated = web.vodafone.de
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 32
>>>>>>   data   = 01:1F:00:80:01:26:02:02:00:20:00:13:00:02:04:00:01:00:0E:00:10:02:00:01:00:11:04:00:02:00:D2:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 31
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 38
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 2
>>>>>>   tlv_length  = 19
>>>>>>   message     = "Start Network" (0x0020)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 01:00:0E:00
>>>>>>   translated = FAILURE: CallFailed
>>>>>> TLV:
>>>>>>   type       = "Call End Reason" (0x10)
>>>>>>   length     = 2
>>>>>>   value      = 01:00
>>>>>>   translated = generic-unspecified
>>>>>> TLV:
>>>>>>   type       = "Verbose Call End Reason" (0x11)
>>>>>>   length     = 4
>>>>>>   value      = 02:00:D2:00
>>>>>>   translated = [ type = 'internal' reason = '210' ]
ModemManager[1157]: <info>  [1466176630.437626] [mm-bearer-qmi.c:354] start_network_ready(): error: couldn't start network: QMI protocol error (14): 'CallFailed'
ModemManager[1157]: <info>  [1466176630.437730] [mm-bearer-qmi.c:368] start_network_ready(): call end reason (1): 'generic-unspecified'
ModemManager[1157]: <info>  [1466176630.437904] [mm-bearer-qmi.c:379] start_network_ready(): verbose call end reason (2,210): [internal] pdn-ipv6-call-disallowed
ModemManager[1157]: <debug> [1466176630.437975] [mm-port.c:95] mm_port_set_connected(): (wwp0s19u1u3i10): port now connected
ModemManager[1157]: <debug> [1466176630.438144] [mm-base-bearer.c:601] connect_ready(): Connected bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[1157]: [/dev/cdc-wdm1] Sent message...
<<<<<< RAW:
<<<<<<   length = 20
<<<<<<   data   = 01:13:00:00:01:25:00:04:00:24:00:07:00:01:04:00:C0:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 19
<<<<<<   flags   = 0x00
<<<<<<   service = "wds"
<<<<<<   client  = 37
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 4
<<<<<<   tlv_length  = 7
<<<<<<   message     = "Get Packet Statistics" (0x0024)
<<<<<< TLV:
<<<<<<   type       = "Mask" (0x01)
<<<<<<   length     = 4
<<<<<<   value      = C0:00:00:00
<<<<<<   translated = tx-bytes-ok, rx-bytes-ok
ModemManager[1157]: <info>  [1466176630.439305] [mm-iface-modem.c:1431] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
ModemManager[1157]: <info>  [1466176630.441643] [mm-iface-modem-simple.c:602] connection_step(): Simple connect state (8/8): All done
ModemManager[1157]: [/dev/cdc-wdm1] Received message...
>>>>>> RAW:
>>>>>>   length = 42
>>>>>>   data   = 01:29:00:80:01:25:02:04:00:24:00:1D:00:02:04:00:00:00:00:00:1A:08:00:00:00:00:00:00:00:00:00:19:08:00:00:00:00:00:00:00:00:00
ModemManager[1157]: [/dev/cdc-wdm1] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 41
>>>>>>   flags   = 0x80
>>>>>>   service = "wds"
>>>>>>   client  = 37
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 4
>>>>>>   tlv_length  = 29
>>>>>>   message     = "Get Packet Statistics" (0x0024)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Rx Bytes Ok" (0x1a)
>>>>>>   length     = 8
>>>>>>   value      = 00:00:00:00:00:00:00:00
>>>>>>   translated = 0
>>>>>> TLV:
>>>>>>   type       = "Tx Bytes Ok" (0x19)
>>>>>>   length     = 8
>>>>>>   value      = 00:00:00:00:00:00:00:00
>>>>>>   translated = 0
ModemManager[1157]: <debug> [1466176639.955496] [mm-broadband-modem-qmi.c:2951] load_signal_quality(): loading signal quality...
ModemManager[1157]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<<   length = 13
<<<<<<   data   = 01:0C:00:00:03:07:00:0C:00:20:00:00:00
ModemManager[1157]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< QMUX:
<<<<<<   length  = 12
<<<<<<   flags   = 0x00
<<<<<<   service = "nas"
<<<<<<   client  = 7
<<<<<< QMI:
<<<<<<   flags       = "none"
<<<<<<   transaction = 12
<<<<<<   tlv_length  = 0
<<<<<<   message     = "Get Signal Strength" (0x0020)
ModemManager[1157]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>>   length = 25
>>>>>>   data   = 01:18:00:80:03:07:02:0C:00:20:00:0C:00:02:04:00:00:00:00:00:01:02:00:B0:08
ModemManager[1157]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> QMUX:
>>>>>>   length  = 24
>>>>>>   flags   = 0x80
>>>>>>   service = "nas"
>>>>>>   client  = 7
>>>>>> QMI:
>>>>>>   flags       = "response"
>>>>>>   transaction = 12
>>>>>>   tlv_length  = 12
>>>>>>   message     = "Get Signal Strength" (0x0020)
>>>>>> TLV:
>>>>>>   type       = "Result" (0x02)
>>>>>>   length     = 4
>>>>>>   value      = 00:00:00:00
>>>>>>   translated = SUCCESS
>>>>>> TLV:
>>>>>>   type       = "Signal Strength" (0x01)
>>>>>>   length     = 2
>>>>>>   value      = B0:08
>>>>>>   translated = [ strength = '-80' radio_interface = 'lte' ]
ModemManager[1157]: <debug> [1466176639.971632] [mm-broadband-modem-qmi.c:2841] signal_strength_get_quality_and_access_tech(): Signal strength (lte): -80 dBm
ModemManager[1157]: <debug> [1466176639.971710] [mm-broadband-modem-qmi.c:2874] signal_strength_get_quality_and_access_tech(): Signal strength: -80 dBm --> 54%
ModemManager[1157]: <debug> [1466176639.972086] [mm-iface-modem.c:1204] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)


More information about the ModemManager-devel mailing list