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@ubuntu:~$ mmcli -L No modems were found go@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@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@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) _______________________________________________ ModemManager-devel mailing list ModemManager-devel@lists.freedesktop.org https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel