← Back to team overview

desktop-packages team mailing list archive

[Bug 1525392] Re: unable to use Sprint/Franklin U301 modem (16d8:6008, CMOTECH CMO6008) on modern Ubuntu (Wily/Xenial)

 

from Debian 8.2, packages :

ii  linux-image-3.16.0-4-amd64    3.16.7-ckt11-1+deb8u6  amd64      Linux 3.16 for 64-bit PCs
ii  modemmanager                          1.4.0-1                                 amd64      D-Bus service for managing modems
ii  network-manager                       0.9.10.0-7                           amd64      network management framework (daemon and userspace tools)

logs:

Dec 11 22:41:49 debian kernel: usb 1-2.2: new full-speed USB device number 6 using xhci_hcd
Dec 11 22:41:49 debian kernel: usb 1-2.2: device descriptor read/64, error -32
Dec 11 22:41:49 debian kernel: usb 1-2.2: New USB device found, idVendor=16d8, idProduct=6008
Dec 11 22:41:49 debian kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Dec 11 22:41:49 debian kernel: usb 1-2.2: Product: CMOTECH CDMA Technologies
Dec 11 22:41:49 debian kernel: usb 1-2.2: Manufacturer: CMOTECH CO., LTD.
Dec 11 22:41:49 debian kernel: usb-storage 1-2.2:1.4: USB Mass Storage device detected
Dec 11 22:41:49 debian kernel: scsi5 : usb-storage 1-2.2:1.4
Dec 11 22:41:49 debian kernel: scsi 5:0:0:0: CD-ROM            CMOTECH  Mass Storage     2.31 PQ: 0 ANSI: 6
Dec 11 22:41:49 debian kernel: scsi 5:0:0:0: Attached scsi generic sg2 type 5
Dec 11 22:41:49 debian kernel: sr0: scsi3-mmc drive: 85x/0x cdda 
Dec 11 22:41:49 debian kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Dec 11 22:41:49 debian kernel: sr 5:0:0:0: Attached scsi CD-ROM sr0
Dec 11 22:41:49 debian kernel: sr0: CDROM (ioctl) error, command: Xpwrite, Read disk info 51 00 00 00 00 00 00 00 02 00
Dec 11 22:41:49 debian kernel: sr: Sense Key : Hardware Error [current] 
Dec 11 22:41:49 debian kernel: sr: Add. Sense: No additional sense information
Dec 11 22:41:50 debian mtp-probe[234]: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2"
Dec 11 22:41:50 debian mtp-probe[237]: checking bus 1, device 4: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-5"
Dec 11 22:41:50 debian mtp-probe[238]: checking bus 1, device 5: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-6"
Dec 11 22:41:50 debian mtp-probe[233]: checking bus 1, device 2: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-1"
Dec 11 22:41:50 debian mtp-probe[233]: bus: 1, device: 2 was not an MTP device
Dec 11 22:41:50 debian mtp-probe[237]: bus: 1, device: 4 was not an MTP device
Dec 11 22:41:50 debian mtp-probe[238]: bus: 1, device: 5 was not an MTP device
Dec 11 22:41:50 debian mtp-probe[234]: bus: 1, device: 6 was not an MTP device
Dec 11 22:41:51 debian kernel: usbcore: registered new interface driver cdc_wdm
Dec 11 22:41:51 debian kernel: qmi_wwan 1-2.2:1.0: cdc-wdm0: USB WDM device
Dec 11 22:41:51 debian kernel: qmi_wwan 1-2.2:1.0 wwan0: register 'qmi_wwan' at usb-0000:00:14.0-2.2, WWAN/QMI device, be:1d:e8:35:e4:4f
Dec 11 22:41:51 debian kernel: usbcore: registered new interface driver qmi_wwan
Dec 11 22:41:51 debian kernel: usbcore: registered new interface driver usbserial
Dec 11 22:41:51 debian kernel: usbcore: registered new interface driver usbserial_generic
Dec 11 22:41:51 debian kernel: usbserial: USB Serial support registered for generic
Dec 11 22:41:51 debian kernel: usbcore: registered new interface driver option
Dec 11 22:41:51 debian kernel: usbserial: USB Serial support registered for GSM modem (1-port)
Dec 11 22:41:51 debian kernel: option 1-2.2:1.1: GSM modem (1-port) converter detected
Dec 11 22:41:51 debian kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB0
Dec 11 22:41:51 debian kernel: option 1-2.2:1.2: GSM modem (1-port) converter detected
Dec 11 22:41:51 debian kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB1
Dec 11 22:41:51 debian kernel: option 1-2.2:1.3: GSM modem (1-port) converter detected
Dec 11 22:41:51 debian kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB2
Dec 11 22:41:54 debian ModemManager[542]: <info>  ModemManager (version 1.4.0) starting in system bus...
Dec 11 22:41:55 debian NetworkManager[545]: <info> devices added (path: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.0/net/wwan0, iface: wwan0)
Dec 11 22:41:55 debian NetworkManager[545]: <info> device added (path: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.0/net/wwan0, iface: wwan0): no ifupdown configuration found.
Dec 11 22:41:55 debian NetworkManager[545]: <info> ModemManager disappeared from bus
Dec 11 22:41:55 debian NetworkManager[545]: <info> ModemManager available in the bus
Dec 11 22:41:55 debian ModemManager[542]: <warn>  (ttyUSB0): port attributes not fully set
Dec 11 22:41:55 debian ModemManager[542]: <warn>  (ttyUSB1): port attributes not fully set
Dec 11 22:41:55 debian ModemManager[542]: <warn>  (ttyUSB2): port attributes not fully set
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Dec 11 22:41:55 debian ModemManager[542]: cannot connect to proxy: Could not connect: Connection refused
Dec 11 22:41:55 debian ModemManager[542]: spawning new qmi-proxy (try 1)...
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0] QMI Device supports 4 services:
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0]    ctl (1.0)
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0]    wds (1.1)
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0]    dms (1.1)
Dec 11 22:41:55 debian ModemManager[542]: [/dev/cdc-wdm0]    nas (1.0)
Dec 11 22:41:57 debian ModemManager[542]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:02:00.0': not supported by any plugin
Dec 11 22:41:57 debian ModemManager[542]: <warn>  (ttyUSB0): port attributes not fully set
Dec 11 22:41:59 debian ModemManager[542]: <warn>  (ttyUSB0): port attributes not fully set
Dec 11 22:42:14 debian ModemManager[542]: <info>  Creating modem with plugin 'Generic' and '5' ports
Dec 11 22:42:14 debian ModemManager[542]: <warn>  Could not grab port (tty/ttyUSB2): 'Cannot add port 'tty/ttyUSB2', unhandled serial type'
Dec 11 22:42:14 debian ModemManager[542]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2' successfully created
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0] QMI Device supports 4 services:
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0]    ctl (1.0)
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0]    wds (1.1)
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0]    dms (1.1)
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0]    nas (1.0)
Dec 11 22:42:14 debian ModemManager[542]: [/dev/cdc-wdm0] Setting network port data format...
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0] Checking version info (10 retries)...
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0] QMI Device supports 4 services:
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0]    ctl (1.0)
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0]    wds (1.1)
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0]    dms (1.1)
Dec 11 22:42:19 debian ModemManager[542]: [/dev/cdc-wdm0]    nas (1.0)
Dec 11 22:42:23 debian ModemManager[542]: [/dev/cdc-wdm0] Allocating new client ID...
Dec 11 22:42:23 debian ModemManager[542]: [/dev/cdc-wdm0] Registered 'dms' (version 1.1) client with ID '2'
Dec 11 22:42:25 debian ModemManager[542]: [/dev/cdc-wdm0] Allocating new client ID...
Dec 11 22:42:25 debian ModemManager[542]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '2'
Dec 11 22:42:25 debian ModemManager[542]: <warn>  (ttyUSB0): port attributes not fully set
Dec 11 22:42:30 debian ModemManager[542]: <warn>  couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Dec 11 22:42:35 debian ModemManager[542]: <warn>  couldn't load Power State: 'QMI operation failed: Transaction timed out'
Dec 11 22:42:35 debian ModemManager[542]: <warn>  Couldn't load unlock retries: 'Couldn't get unlock retries: QMI protocol error (25): 'DeviceUnsupported''
Dec 11 22:42:40 debian ModemManager[542]: <warn>  couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
Dec 11 22:42:41 debian ModemManager[542]: <warn>  couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
Dec 11 22:42:41 debian ModemManager[542]: <warn>  couldn't load MEID: 'Device doesn't report a valid MEID'
Dec 11 22:42:41 debian ModemManager[542]: <warn>  couldn't load activation state: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
Dec 11 22:42:41 debian ModemManager[542]: <info>  Modem: state changed (unknown -> disabled)
Dec 11 22:42:41 debian NetworkManager[545]: <warn> (cdc-wdm0): failed to look up interface index
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): new Broadband device (driver: 'option1, qmi_wwan' ifindex: 0)
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): exported as /org/freedesktop/NetworkManager/Devices/2
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): preparing device
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): modem state 'enabling'
Dec 11 22:42:41 debian NetworkManager[545]: <info> (cdc-wdm0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Dec 11 22:42:43 debian ModemManager[542]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Dec 11 22:42:43 debian ModemManager[542]: <warn>  (ttyUSB0): port attributes not fully set
Dec 11 22:42:48 debian ModemManager[542]: Couldn't reload current power state: QMI operation failed: Transaction timed out
Dec 11 22:43:06 debian NetworkManager[545]: <warn> (cdc-wdm0) failed to enable modem: Час очікування вичерпано
Dec 11 22:43:08 debian ModemManager[542]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> disabled)
Dec 11 22:43:08 debian NetworkManager[545]: <info> (cdc-wdm0): modem state changed, 'enabling' --> 'disabled' (reason: unknown)
Dec 11 22:47:21 debian NetworkManager[545]: <info> Auto-activating connection 'InterTelecom'.
Dec 11 22:47:21 debian NetworkManager[545]: <info> Activation (cdc-wdm0) starting connection 'InterTelecom'
Dec 11 22:47:21 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Dec 11 22:47:21 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Dec 11 22:47:21 debian NetworkManager[545]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Dec 11 22:47:21 debian NetworkManager[545]: <info> NetworkManager state is now CONNECTING
Dec 11 22:47:21 debian ModemManager[542]: <info>  Simple connect started...
Dec 11 22:47:21 debian ModemManager[542]: <info>  Simple connect state (3/8): Enable
Dec 11 22:47:21 debian ModemManager[542]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Dec 11 22:47:21 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Dec 11 22:47:21 debian NetworkManager[545]: <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user-requested)
Dec 11 22:47:26 debian ModemManager[542]: Couldn't reload current power state: QMI operation failed: Transaction timed out
Dec 11 22:47:26 debian anacron[539]: Job `cron.daily' terminated
Dec 11 22:47:46 debian ModemManager[542]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> disabled)
Dec 11 22:47:46 debian NetworkManager[545]: <info> (cdc-wdm0): modem state changed, 'enabling' --> 'disabled' (reason: unknown)
Dec 11 22:47:46 debian NetworkManager[545]: <info> (cdc-wdm0): device state change: prepare -> disconnected (reason 'user-requested') [40 30 39]
Dec 11 22:47:46 debian NetworkManager[545]: <info> (cdc-wdm0): deactivating device (reason 'user-requested') [39]
Dec 11 22:47:46 debian NetworkManager[545]: <info> NetworkManager state is now DISCONNECTED
Dec 11 22:47:46 debian NetworkManager[545]: <warn> (cdc-wdm0) failed to connect modem: QMI operation failed: Transaction timed out
Dec 11 22:47:46 debian NetworkManager[545]: <info> Auto-activating connection 'InterTelecom'.
Dec 11 22:47:46 debian NetworkManager[545]: <info> Activation (cdc-wdm0) starting connection 'InterTelecom'
Dec 11 22:47:46 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) scheduled...
Dec 11 22:47:46 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) started...
Dec 11 22:47:46 debian NetworkManager[545]: <info> (cdc-wdm0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Dec 11 22:47:46 debian NetworkManager[545]: <info> NetworkManager state is now CONNECTING
Dec 11 22:47:46 debian NetworkManager[545]: <info> Activation (cdc-wdm0) Stage 1 of 5 (Device Prepare) complete.
Dec 11 22:47:46 debian NetworkManager[545]: (NetworkManager:545): NetworkManager-wwan-CRITICAL **: modem_prepare_result: assertion 'state == NM_DEVICE_STATE_PREPARE' failed
Dec 11 22:47:46 debian ModemManager[542]: <info>  Simple connect started...
Dec 11 22:47:46 debian ModemManager[542]: <info>  Simple connect state (3/8): Enable
Dec 11 22:47:46 debian ModemManager[542]: <info>  Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
Dec 11 22:47:46 debian NetworkManager[545]: <info> (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user-requested)
Dec 11 22:47:51 debian ModemManager[542]: Couldn't reload current power state: QMI operation failed: Transaction timed out

... and so on. I see that ModemManager is equally unable to initialize
the modem. NetworkManager acts differently most probably because of
version difference.

Next I will try to run this mode with older Ubuntus, like 12.04

-- 
You received this bug notification because you are a member of Desktop
Packages, which is subscribed to modemmanager in Ubuntu.
https://bugs.launchpad.net/bugs/1525392

Title:
  unable to use Sprint/Franklin U301 modem (16d8:6008, CMOTECH CMO6008)
  on modern Ubuntu (Wily/Xenial)

Status in modemmanager package in Ubuntu:
  New

Bug description:
  This modem is quite old, its firmware date is from 2008 (as shown in modem-manager-gui)
  In NetworkMnaager, it adds 'mobile broadband' icon to the menu, with enable/disable checkbox (which is changed on clicking it).
  In NM Connection Editor it allows me to create Mobile Broadband connection, it sees CMOTECH CDMA modem device, and allows me to select country and operator (Ukraine/InterTelecom), which fills dial number, login and password with good-looking strings, but then I was never able to connect to this connection (connect/disconnect buttons are disabled/greyed-out).

  in the logs I see this :

  Dec 11 22:12:47 hostname kernel: usb 1-2: new high-speed USB device number 15 using xhci_hcd
  Dec 11 22:12:47 hostname kernel: usb 1-2: New USB device found, idVendor=1a40, idProduct=0101
  Dec 11 22:12:47 hostname kernel: usb 1-2: New USB device strings: Mfr=0, Product=1, SerialNumber=0
  Dec 11 22:12:47 hostname kernel: usb 1-2: Product: USB 2.0 Hub [MTT]
  Dec 11 22:12:47 hostname kernel: hub 1-2:1.0: USB hub found
  Dec 11 22:12:47 hostname kernel: hub 1-2:1.0: 4 ports detected
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: new full-speed USB device number 17 using xhci_hcd
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: New USB device found, idVendor=16d8, idProduct=6008
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: Product: CMOTECH CDMA Technologies
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: Manufacturer: CMOTECH CO., LTD.
  Dec 11 22:12:50 hostname kernel: qmi_wwan 1-2.2:1.0: cdc-wdm0: USB WDM device
  Dec 11 22:12:50 hostname kernel: qmi_wwan 1-2.2:1.0 wwan0: register 'qmi_wwan' at usb-0000:00:14.0-2.2, WWAN/QMI device, e6:d0:dc:04:ec:c2
  Dec 11 22:12:50 hostname kernel: option 1-2.2:1.1: GSM modem (1-port) converter detected
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB0
  Dec 11 22:12:50 hostname kernel: option 1-2.2:1.2: GSM modem (1-port) converter detected
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB1
  Dec 11 22:12:50 hostname kernel: option 1-2.2:1.3: GSM modem (1-port) converter detected
  Dec 11 22:12:50 hostname kernel: usb 1-2.2: GSM modem (1-port) converter now attached to ttyUSB2
  Dec 11 22:12:50 hostname kernel: usb-storage 1-2.2:1.4: USB Mass Storage device detected
  Dec 11 22:12:50 hostname kernel: scsi host8: usb-storage 1-2.2:1.4
  Dec 11 22:12:50 hostname mtp-probe[5056]: checking bus 1, device 17: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2"
  Dec 11 22:12:50 hostname mtp-probe[5056]: bus: 1, device: 17 was not an MTP device
  Dec 11 22:12:50 hostname ModemManager[4666]: <warn>  (ttyUSB1): port attributes not fully set
  Dec 11 22:12:50 hostname ModemManager[4666]: <warn>  (ttyUSB0): port attributes not fully set
  Dec 11 22:12:50 hostname ModemManager[4666]: <warn>  (ttyUSB2): port attributes not fully set
  Dec 11 22:12:51 hostname kernel: scsi 8:0:0:0: CD-ROM            CMOTECH  Mass Storage     2.31 PQ: 0 ANSI: 6
  Dec 11 22:12:51 hostname kernel: sr 8:0:0:0: [sr0] scsi3-mmc drive: 11x/99x xa/form2 caddy
  Dec 11 22:12:51 hostname kernel: sr 8:0:0:0: Attached scsi CD-ROM sr0
  Dec 11 22:12:51 hostname kernel: sr 8:0:0:0: Attached scsi generic sg1 type 5
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0] Checking version info (10 retries)...
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0] QMI Device supports 4 services:
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0]    ctl (1.0)
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0]    wds (1.1)
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0]    dms (1.1)
  Dec 11 22:12:51 hostname ModemManager[4666]: [/dev/cdc-wdm0]    nas (1.0)
  Dec 11 22:12:51 hostname NetworkManager[788]: <info>  devices added (path: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.0/net/wwan0, iface: wwan0)
  Dec 11 22:12:51 hostname NetworkManager[788]: <info>  device added (path: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2/1-2.2:1.0/net/wwan0, iface: wwan0): no ifupdown configuration found.
  Dec 11 22:12:59 hostname ModemManager[4666]: <warn>  (ttyUSB0): port attributes not fully set
  Dec 11 22:13:09 hostname ModemManager[4666]: <info>  Creating modem with plugin 'Generic' and '5' ports
  Dec 11 22:13:09 hostname ModemManager[4666]: <warn>  Could not grab port (tty/ttyUSB2): 'Cannot add port 'tty/ttyUSB2', unhandled serial type'
  Dec 11 22:13:09 hostname ModemManager[4666]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.2' successfully created
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0] Checking version info (10 retries)...
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0] QMI Device supports 4 services:
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0]    ctl (1.0)
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0]    wds (1.1)
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0]    dms (1.1)
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0]    nas (1.0)
  Dec 11 22:13:09 hostname ModemManager[4666]: [/dev/cdc-wdm0] Setting network port data format...
  Dec 11 22:13:09 hostname wpa_supplicant[900]: wlp3s0: WPA: Group rekeying completed with f8:d1:11:33:7b:d4 [GTK=CCMP]
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Checking version info (10 retries)...
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] QMI Device supports 4 services:
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0]    ctl (1.0)
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0]    wds (1.1)
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0]    dms (1.1)
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0]    nas (1.0)
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Allocating new client ID...
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Registered 'dms' (version 1.1) client with ID '1'
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Allocating new client ID...
  Dec 11 22:13:14 hostname ModemManager[4666]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '1'
  Dec 11 22:13:14 hostname ModemManager[4666]: <warn>  (ttyUSB0): port attributes not fully set
  Dec 11 22:13:19 hostname ModemManager[4666]: <warn>  couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
  Dec 11 22:13:24 hostname ModemManager[4666]: <warn>  couldn't load Power State: 'QMI operation failed: Transaction timed out'
  Dec 11 22:13:24 hostname ModemManager[4666]: <warn>  Couldn't load unlock retries: 'Couldn't get unlock retries: QMI protocol error (25): 'DeviceUnsupported''
  Dec 11 22:13:29 hostname ModemManager[4666]: <warn>  couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
  Dec 11 22:13:29 hostname ModemManager[4666]: <warn>  couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got version '1.0''
  Dec 11 22:13:29 hostname ModemManager[4666]: <warn>  couldn't load MEID: 'Device doesn't report a valid MEID'
  Dec 11 22:13:29 hostname ModemManager[4666]: <warn>  couldn't load activation state: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, got version '1.1''
  Dec 11 22:13:29 hostname ModemManager[4666]: <info>  Modem: state changed (unknown -> disabled)
  Dec 11 22:13:29 hostname NetworkManager[788]: <info>  (cdc-wdm0): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
  Dec 11 22:13:29 hostname NetworkManager[788]: <info>  (cdc-wdm0): new Broadband device (carrier: UNKNOWN, driver: 'option1, qmi_wwan', ifindex: 0)
  Dec 11 22:13:29 hostname NetworkManager[788]: <info>  (cdc-wdm0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
  Dec 11 22:13:29 hostname ModemManager[4666]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling)
  Dec 11 22:13:29 hostname ModemManager[4666]: <warn>  (ttyUSB0): port attributes not fully set
  Dec 11 22:13:29 hostname NetworkManager[788]: <info>  (cdc-wdm0): modem state 'enabling'
  Dec 11 22:13:29 hostname NetworkManager[788]: <warn>  (cdc-wdm0): failed to retrieve SIM object: No SIM object available
  Dec 11 22:13:34 hostname ModemManager[4666]: Couldn't reload current power state: QMI operation failed: Transaction timed out
  Dec 11 22:13:54 hostname ModemManager[4666]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> disabled)
  Dec 11 22:13:54 hostname NetworkManager[788]: <info>  (cdc-wdm0): modem state changed, 'enabling' --> 'disabled' (reason: unknown)
  Dec 11 22:13:54 hostname NetworkManager[788]: <warn>  (cdc-wdm0) failed to enable modem: GDBus.Error:org.freedesktop.libqmi.Error.Core.Timeout: QMI operation failed: Transaction timed out

  I notice that ModemManager gets unexpected QMI service versions for dms and nas svcs.
  I plugged it into Win10 system, and it worked after minimal manual setup (phone number #777, login and password given by operator)
  I tried to replace modemmanager with wader-core but I could not make it work. Returned back to modemmanager.

  Please advise what to do next.

  ProblemType: Bug
  DistroRelease: Ubuntu 16.04
  Package: modemmanager 1.4.12-1ubuntu1
  ProcVersionSignature: Ubuntu 4.3.0-2.11-generic 4.3.0
  Uname: Linux 4.3.0-2-generic x86_64
  NonfreeKernelModules: wl
  ApportVersion: 2.19.3-0ubuntu2
  Architecture: amd64
  CurrentDesktop: KDE
  Date: Fri Dec 11 22:13:19 2015
  InstallationDate: Installed on 2009-11-01 (2231 days ago)
  InstallationMedia: Kubuntu 9.10 "Karmic Koala" - Release amd64 (20091027)
  SourcePackage: modemmanager
  UpgradeStatus: No upgrade log present (probably fresh install)

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/modemmanager/+bug/1525392/+subscriptions


References