3G modem Huawei E1550 does not work under Oneiric (regression since Lucid/Natty)

Bug #884829 reported by Grigory Rechistov
36
This bug affects 8 people
Affects Status Importance Assigned to Milestone
modemmanager (Ubuntu)
Confirmed
Undecided
Unassigned

Bug Description

After fresh installation of Oneiric 11.10 x86_64 I attempted to connect to the internet through 3G modem. It works well on my Lucid x86_64 installation and worked well on Natty 32 bit. The network manager GUI was able to find my operator (Russian cell network Megafon) and create a profile for it. But when I try to use the new connection it quickly fails. Here is an excerpt of /var/log/syslog after the modem has been connected:

Nov 1 21:13:31 scilla kernel: [ 604.395549] usb 1-1.2: new full speed USB device number 7 using ehci_hcd
Nov 1 21:13:31 scilla mtp-probe: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2"
Nov 1 21:13:31 scilla kernel: [ 604.508109] scsi10 : usb-storage 1-1.2:1.0
Nov 1 21:13:31 scilla kernel: [ 604.508601] scsi11 : usb-storage 1-1.2:1.1
Nov 1 21:13:31 scilla mtp-probe: bus: 1, device: 7 was not an MTP device
Nov 1 21:13:32 scilla usb_modeswitch: switching 12d1:1446 (HUAWEI Technology: HUAWEI Mobile)
Nov 1 21:13:32 scilla kernel: [ 605.220328] usb 1-1.2: USB disconnect, device number 7
Nov 1 21:13:39 scilla kernel: [ 612.072324] usb 1-1.2: new full speed USB device number 8 using ehci_hcd
Nov 1 21:13:39 scilla mtp-probe: checking bus 1, device 8: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2"
Nov 1 21:13:39 scilla kernel: [ 612.184042] option 1-1.2:1.0: GSM modem (1-port) converter detected
Nov 1 21:13:39 scilla kernel: [ 612.184375] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
Nov 1 21:13:39 scilla kernel: [ 612.184713] option 1-1.2:1.1: GSM modem (1-port) converter detected
Nov 1 21:13:39 scilla kernel: [ 612.184886] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
Nov 1 21:13:39 scilla kernel: [ 612.185925] scsi14 : usb-storage 1-1.2:1.2
Nov 1 21:13:39 scilla kernel: [ 612.186915] scsi15 : usb-storage 1-1.2:1.3
Nov 1 21:13:39 scilla mtp-probe: bus: 1, device: 8 was not an MTP device
Nov 1 21:13:39 scilla modem-manager[405]: <info> (ttyUSB0) opening serial port...
Nov 1 21:13:40 scilla kernel: [ 613.188217] scsi 14:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
Nov 1 21:13:40 scilla kernel: [ 613.191846] scsi 15:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
Nov 1 21:13:40 scilla kernel: [ 613.658031] sr0: scsi-1 drive
Nov 1 21:13:40 scilla kernel: [ 613.658169] sr 14:0:0:0: Attached scsi CD-ROM sr0
Nov 1 21:13:40 scilla kernel: [ 613.658259] sr 14:0:0:0: Attached scsi generic sg1 type 5
Nov 1 21:13:40 scilla kernel: [ 613.658752] sd 15:0:0:0: Attached scsi generic sg2 type 0
Nov 1 21:13:40 scilla kernel: [ 613.668661] sd 15:0:0:0: [sdb] Attached SCSI removable disk
Nov 1 21:13:44 scilla modem-manager[405]: <info> (ttyUSB0) closing serial port...
Nov 1 21:13:44 scilla modem-manager[405]: <info> (ttyUSB0) serial port closed
Nov 1 21:13:44 scilla modem-manager[405]: <info> (ttyUSB0) opening serial port...
Nov 1 21:13:44 scilla modem-manager[405]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 claimed port ttyUSB0
Nov 1 21:13:45 scilla modem-manager[405]: <info> (ttyUSB1) opening serial port...
Nov 1 21:13:48 scilla modem-manager[405]: <info> (ttyUSB1) closing serial port...
Nov 1 21:13:48 scilla modem-manager[405]: <info> (ttyUSB1) serial port closed
Nov 1 21:13:48 scilla modem-manager[405]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2 claimed port ttyUSB1
Nov 1 21:13:52 scilla modem-manager[405]: <info> (ttyUSB0) closing serial port...
Nov 1 21:13:52 scilla modem-manager[405]: <info> (ttyUSB0) serial port closed
Nov 1 21:13:52 scilla NetworkManager[418]: <warn> (ttyUSB0): failed to look up interface index
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): exported as /org/freedesktop/NetworkManager/Devices/3
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): now managed
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): deactivating device (reason 'managed') [2]
Nov 1 21:13:52 scilla NetworkManager[418]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Nov 1 21:13:52 scilla NetworkManager[418]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed
Nov 1 21:13:52 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]

When I try to open a connection manually by clicking its icon in the NM GUI there are additional messages in /var/log/syslog:

Nov 1 21:17:54 scilla modem-manager[405]: <info> (ttyUSB0) opening serial port...
Nov 1 21:17:54 scilla modem-manager[405]: <info> Modem /org/freedesktop/ModemManager/Modems/1: state changed (disabled -> enabling)
Nov 1 21:17:54 scilla NetworkManager[418]: <info> Activation (ttyUSB0) starting connection 'Мегафон - Москва'
Nov 1 21:17:54 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Nov 1 21:17:54 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 1 21:17:54 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Nov 1 21:17:54 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Nov 1 21:17:54 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Nov 1 21:17:54 scilla modem-manager[405]: <info> (ttyUSB1) opening serial port...
Nov 1 21:17:54 scilla modem-manager[405]: <warn> (ttyUSB0): failed to enable USSD notifications.
Nov 1 21:17:55 scilla modem-manager[405]: <info> Modem /org/freedesktop/ModemManager/Modems/1: state changed (enabling -> disabled)
Nov 1 21:17:55 scilla modem-manager[405]: <info> (ttyUSB0) closing serial port...
Nov 1 21:17:55 scilla modem-manager[405]: <info> (ttyUSB0) serial port closed
Nov 1 21:17:55 scilla modem-manager[405]: <info> (ttyUSB1) closing serial port...
Nov 1 21:17:55 scilla modem-manager[405]: <info> (ttyUSB1) serial port closed
Nov 1 21:17:56 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Nov 1 21:17:56 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Nov 1 21:17:56 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Nov 1 21:17:56 scilla NetworkManager[418]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Nov 1 21:17:56 scilla modem-manager[405]: <info> (ttyUSB0) opening serial port...
Nov 1 21:17:56 scilla modem-manager[405]: <info> Modem /org/freedesktop/ModemManager/Modems/1: state changed (disabled -> enabling)
Nov 1 21:17:57 scilla modem-manager[405]: <info> (ttyUSB1) opening serial port...
Nov 1 21:17:57 scilla modem-manager[405]: <warn> (ttyUSB0): failed to enable USSD notifications.
Nov 1 21:17:57 scilla modem-manager[405]: <info> Modem /org/freedesktop/ModemManager/Modems/1: state changed (enabling -> disabled)
Nov 1 21:17:57 scilla modem-manager[405]: <info> (ttyUSB0) closing serial port...
Nov 1 21:17:57 scilla modem-manager[405]: <info> (ttyUSB0) serial port closed
Nov 1 21:17:57 scilla modem-manager[405]: <info> (ttyUSB1) closing serial port...
Nov 1 21:17:57 scilla modem-manager[405]: <info> (ttyUSB1) serial port closed
Nov 1 21:17:57 scilla NetworkManager[418]: <warn> GSM modem enable failed: (32) Failed to find a usable modem character set
Nov 1 21:17:57 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'modem-init-failed') [40 120 28]
Nov 1 21:17:57 scilla NetworkManager[418]: <warn> Activation (ttyUSB0) failed.
Nov 1 21:17:57 scilla NetworkManager[418]: <info> (ttyUSB0): device state change: failed -> disconnected (reason 'none') [120 30 0]
Nov 1 21:17:57 scilla NetworkManager[418]: <info> (ttyUSB0): deactivating device (reason 'none') [0]
Nov 1 21:17:57 scilla NetworkManager[418]: nm_system_iface_flush_routes: assertion `ifindex > 0' failed
Nov 1 21:17:57 scilla NetworkManager[418]: nm_system_iface_flush_addresses: assertion `ifindex > 0' failed

ProblemType: Bug
DistroRelease: Ubuntu 11.10
Package: modemmanager 0.5-1ubuntu1
ProcVersionSignature: Ubuntu 3.0.0-13.21-generic 3.0.6
Uname: Linux 3.0.0-13-generic x86_64
ApportVersion: 1.23-0ubuntu4
Architecture: amd64
Date: Tue Nov 1 21:15:28 2011
EcryptfsInUse: Yes
InstallationMedia: Ubuntu 11.10 "Oneiric Ocelot" - Release amd64 (20111011)
ProcEnviron:
 LANGUAGE=
 PATH=(custom, user)
 LANG=ru_RU.UTF-8
 SHELL=/bin/bash
SourcePackage: modemmanager
UpgradeStatus: No upgrade log present (probably fresh install)

Revision history for this message
Grigory Rechistov (atakua) wrote :
Revision history for this message
Grigory Rechistov (atakua) wrote :

Additional info: I use KDE Network Manager.

tags: added: regression-release
Revision history for this message
Launchpad Janitor (janitor) wrote :

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in modemmanager (Ubuntu):
status: New → Confirmed
Revision history for this message
Gerald Teschl (gerald-teschl) wrote :

I have the same Problem with an Huawei EM770W on my Netbook; I'm using 11.10 with Unity 32bit;

Revision history for this message
Grigory Rechistov (atakua) wrote :
Download full text (10.7 KiB)

I don't know what happened with my installation, but since last reboot I am able to connect with 3G modem. Here's an excerpt of /var/log/syslog when I insert the device and then connect with it:

Nov 8 23:38:04 scilla kernel: [ 274.210651] usb 2-1.1: new full speed USB device number 4 using ehci_hcd
Nov 8 23:38:04 scilla mtp-probe: checking bus 2, device 4: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1"
Nov 8 23:38:04 scilla kernel: [ 274.323221] option 2-1.1:1.0: GSM modem (1-port) converter detected
Nov 8 23:38:04 scilla kernel: [ 274.323458] usb 2-1.1: GSM modem (1-port) converter now attached to ttyUSB0
Nov 8 23:38:04 scilla kernel: [ 274.323759] option 2-1.1:1.1: GSM modem (1-port) converter detected
Nov 8 23:38:04 scilla kernel: [ 274.323874] usb 2-1.1: GSM modem (1-port) converter now attached to ttyUSB1
Nov 8 23:38:04 scilla kernel: [ 274.325053] scsi14 : usb-storage 2-1.1:1.2
Nov 8 23:38:04 scilla kernel: [ 274.326169] scsi15 : usb-storage 2-1.1:1.3
Nov 8 23:38:04 scilla mtp-probe: bus: 2, device: 4 was not an MTP device
Nov 8 23:38:05 scilla modem-manager[935]: <info> (ttyUSB0) opening serial port...
Nov 8 23:38:05 scilla kernel: [ 275.325533] scsi 14:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2
Nov 8 23:38:05 scilla kernel: [ 275.330313] scsi 15:0:0:0: Direct-Access HUAWEI MMC Storage 2.31 PQ: 0 ANSI: 2
Nov 8 23:38:06 scilla kernel: [ 275.628274] sr0: scsi-1 drive
Nov 8 23:38:06 scilla kernel: [ 275.628437] sr 14:0:0:0: Attached scsi CD-ROM sr0
Nov 8 23:38:06 scilla kernel: [ 275.628529] sr 14:0:0:0: Attached scsi generic sg1 type 5
Nov 8 23:38:06 scilla kernel: [ 275.628809] sd 15:0:0:0: Attached scsi generic sg2 type 0
Nov 8 23:38:06 scilla kernel: [ 275.639033] sd 15:0:0:0: [sdb] Attached SCSI removable disk
Nov 8 23:38:08 scilla modem-manager[935]: <info> (ttyUSB0) closing serial port...
Nov 8 23:38:08 scilla modem-manager[935]: <info> (ttyUSB0) serial port closed
Nov 8 23:38:08 scilla modem-manager[935]: <info> (ttyUSB0) opening serial port...
Nov 8 23:38:08 scilla modem-manager[935]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1 claimed port ttyUSB0
Nov 8 23:38:08 scilla modem-manager[935]: <info> (ttyUSB0) closing serial port...
Nov 8 23:38:08 scilla modem-manager[935]: <info> (ttyUSB0) serial port closed
Nov 8 23:38:11 scilla modem-manager[935]: <info> (ttyUSB1) opening serial port...
Nov 8 23:38:11 scilla modem-manager[935]: <info> (ttyUSB1) closing serial port...
Nov 8 23:38:11 scilla modem-manager[935]: <info> (ttyUSB1) serial port closed
Nov 8 23:38:11 scilla modem-manager[935]: <info> (Huawei): GSM modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.1 claimed port ttyUSB1
Nov 8 23:38:11 scilla NetworkManager[964]: <warn> (ttyUSB0): failed to look up interface index
Nov 8 23:38:11 scilla NetworkManager[964]: <info> WWAN now disabled by management service
Nov 8 23:38:11 scilla NetworkManager[964]: <info> (ttyUSB0): new GSM/UMTS device (driver: 'option1' ifindex: 0)
Nov 8 23:38:11 scilla NetworkManager[964]: <info> (ttyUSB0): exported as /org/freedesktop/...

Revision history for this message
Francisco Cribari (cribari) wrote :

Same problem with modem Huawei E156B.

summary: 3G modem Huawei E1550 does not work under Oneiric (regression since
- Lucid)
+ Lucid/Natty)
Revision history for this message
Xendon (y-launchpad-xendon-safersignup-com) wrote :
Download full text (3.6 KiB)

It's not a kernel bug or something. Must be something about NetworkManager.

Kernel: 3.5.1
DE: Cinnamon 1.4UP3

network-manager 0.9.4.0-0ubuntu4.1
network-manager-gnome 0.9.4.1-0ubuntu2
network-manager-pptp 0.9.4.0-0ubuntu1

Bus 002 Device 005: ID 12d1:1404 Huawei Technologies Co., Ltd.
Couldn't open device, some information will be missing
Device Descriptor:
  bLength 18
  bDescriptorType 1
  bcdUSB 2.00
  bDeviceClass 0 (Defined at Interface level)
  bDeviceSubClass 0
  bDeviceProtocol 0
  bMaxPacketSize0 64
  idVendor 0x12d1 Huawei Technologies Co., Ltd.
  idProduct 0x1404
  bcdDevice 0.00
  iManufacturer 3
  iProduct 2
  iSerial 0
  bNumConfigurations 1

Syslog:

Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) starting connection 'T-Mobile(Telekom) Vorgabe'
Aug 15 11:39:59 black NetworkManager[911]: <info> (ttyUSB0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Aug 15 11:39:59 black NetworkManager[911]: <info> (ttyUSB0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Aug 15 11:39:59 black NetworkManager[911]: <info> (ttyUSB0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
Aug 15 11:39:59 black NetworkManager[911]: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Aug 15 11:39:59 black modem-manager[837]: <info> (ttyUSB0) opening serial port...
Aug 15 11:39:59 black modem-manager[837]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
Aug 15 11:40:00 black modem-manager[837]: <warn> (ttyUSB0): failed to query SMS mode, assuming text mode
Aug 15 11:40:00 black modem-manager[837]: <warn> (ttyUSB0): failed to enable USSD notifications.
Aug 15 11:40:00 black modem-manager[837]: <info> Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> disabled)
Aug 15 11:40:00 black modem-manager[837]: <info> (ttyUSB0) closing serial port...
Aug 15 11:40:00 black modem-manager[837]: <info> (ttyUSB0) serial port closed
Aug 15 11:40:00 black NetworkManager[911]: <warn> GSM modem enable failed: (32) Failed to find a usable modem character set
Aug 15 11:40:00 black NetworkManager[911]: <info> (ttyUSB0): device state change: prepare -> failed (reason 'modem-init-failed') [40 120 28]
Aug 15 11:40:00 black NetworkManager[911]: <info> Marking connection 'T-Mobile(Telekom) Vorgabe' invalid.
Aug 15 11:40:00 black NetworkManager[911]: <warn> Acti...

Read more...

To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.