Re: Alcatel X220 3G modem fails to connect using Network Manager

[Dan Williams <dcbw redhat com>]

On Fri, 2011-04-29 at 00:17 -0700, P Puty wrote:
> Alcatel X220 3G modem fails to connect using Network Manager
> 
> Running Fedora 14 with all updates
> 
> NetworkManager-0.8.3.998-2.fc14.i686
> ModemManager-0.4-4.git20100720.fc14.i686
> usb_modeswitch-1.1.4-1.fc14.i686
> usb_modeswitch-data-20100817-1.fc14.noarch
> ppp-2.4.5-12.fc14.i686

See my reply to your other message, I think you simply may not have a
new enough version of ModemManager, recent versions handle quirks of the
X220 device while your version likely does not.  Given that you're using
Fedora 14, that means I need to push a modem-manager update so that
things work for your device :)

Dan

> I have tested the modem on a windows xp machine and it works fine.
> 
> I verified that usb_modeswitch has switched the USB into modem mode:
> 
> lsusb:  
> Bus 002 Device 030: ID 1bbb:0017 T & A Mobile Phones 
> 
> I have also been able to connect using wvdial and that works too. When I
> did this I had to use ttyUSB4.
> 
> Attached is the messages log. I notice that NetworkManager is attempting
> to use ttyUSB3 and not ttyUSB4.
> 
> I suspect that ttyUSB4 is the port that ppp must use and that ttyUSB3 is
> the port for sending and receiving SMSes.
> 
> Thanks in advance.
> 
> Perazim
> 
> messages:
> 
> 
> Apr 28 12:12:39 travelmate kernel: [185829.156060] usb 2-1: new high
> speed USB device using ehci_hcd and address 29
> Apr 28 12:12:39 travelmate kernel: [185829.272812] usb 2-1: New USB
> device found, idVendor=1bbb, idProduct=f000
> Apr 28 12:12:39 travelmate kernel: [185829.272817] usb 2-1: New USB
> device strings: Mfr=3, Product=2, SerialNumber=4
> Apr 28 12:12:39 travelmate kernel: [185829.272820] usb 2-1: Product:
> HSPA Data Card
> Apr 28 12:12:39 travelmate kernel: [185829.272823] usb 2-1:
> Manufacturer: USBModem
> Apr 28 12:12:39 travelmate kernel: [185829.272825] usb 2-1:
> SerialNumber: 1234567890ABCDEF
> Apr 28 12:12:39 travelmate kernel: [185829.300179] scsi23 : usb-storage
> 2-1:1.0
> Apr 28 12:12:40 travelmate usb_modeswitch: switching 1bbb:f000
> (USBModem: HSPA Data Card)
> Apr 28 12:12:40 travelmate kernel: [185830.177350] usb 2-1: USB
> disconnect, address 29
> Apr 28 12:12:40 travelmate kernel: [185830.487045] usb 2-1: new high
> speed USB device using ehci_hcd and address 30
> Apr 28 12:12:40 travelmate kernel: [185830.609189] usb 2-1: New USB
> device found, idVendor=1bbb, idProduct=0017
> Apr 28 12:12:40 travelmate kernel: [185830.609194] usb 2-1: New USB
> device strings: Mfr=3, Product=2, SerialNumber=4
> Apr 28 12:12:40 travelmate kernel: [185830.609197] usb 2-1: Product:
> HSPA Data Card
> Apr 28 12:12:40 travelmate kernel: [185830.609200] usb 2-1:
> Manufacturer: USBModem
> Apr 28 12:12:40 travelmate kernel: [185830.609202] usb 2-1:
> SerialNumber: 1234567890ABCDEF
> Apr 28 12:12:40 travelmate kernel: [185830.611556] option 2-1:1.0: GSM
> modem (1-port) converter detected
> Apr 28 12:12:40 travelmate kernel: [185830.611725] usb 2-1: GSM modem
> (1-port) converter now attached to ttyUSB0
> Apr 28 12:12:40 travelmate kernel: [185830.611842] option 2-1:1.1: GSM
> modem (1-port) converter detected
> Apr 28 12:12:40 travelmate kernel: [185830.611948] usb 2-1: GSM modem
> (1-port) converter now attached to ttyUSB1
> Apr 28 12:12:40 travelmate kernel: [185830.612088] option 2-1:1.2: GSM
> modem (1-port) converter detected
> Apr 28 12:12:40 travelmate kernel: [185830.612199] usb 2-1: GSM modem
> (1-port) converter now attached to ttyUSB2
> Apr 28 12:12:40 travelmate kernel: [185830.612312] option 2-1:1.3: GSM
> modem (1-port) converter detected
> Apr 28 12:12:40 travelmate kernel: [185830.612419] usb 2-1: GSM modem
> (1-port) converter now attached to ttyUSB3
> Apr 28 12:12:40 travelmate kernel: [185830.613649] scsi24 : usb-storage
> 2-1:1.4
> Apr 28 12:12:40 travelmate kernel: [185830.613983] option 2-1:1.5: GSM
> modem (1-port) converter detected
> Apr 28 12:12:40 travelmate kernel: [185830.614198] usb 2-1: GSM modem
> (1-port) converter now attached to ttyUSB4
> Apr 28 12:12:41 travelmate modem-manager: (ttyUSB2) opening serial
> device...
> Apr 28 12:12:41 travelmate modem-manager: (ttyUSB1) opening serial
> device...
> Apr 28 12:12:41 travelmate modem-manager: (ttyUSB3) opening serial
> device...
> Apr 28 12:12:41 travelmate modem-manager: (ttyUSB4) opening serial
> device...
> Apr 28 12:12:41 travelmate modem-manager: (ttyUSB0) opening serial
> device...
> Apr 28 12:12:41 travelmate usb_modeswitch: switched to 1bbb:0017
> (USBModem: HSPA Data Card)
> Apr 28 12:12:41 travelmate kernel: [185831.633579] scsi 24:0:0:0:
> Direct-Access     ALCATEL  Mass Storage     2.31 PQ: 0 ANSI: 2
> Apr 28 12:12:42 travelmate kernel: [185831.640126] sd 24:0:0:0: Attached
> scsi generic sg3 type 0
> Apr 28 12:12:42 travelmate kernel: [185831.684198] sd 24:0:0:0: [sdc]
> Attached SCSI removable disk
> Apr 28 12:12:56 travelmate modem-manager: (ttyUSB3) closing serial
> device...
> Apr 28 12:12:56 travelmate modem-manager: (ttyUSB4) closing serial
> device...
> Apr 28 12:12:57 travelmate modem-manager: (ttyUSB3) opening serial
> device...
> Apr 28 12:12:57 travelmate modem-manager: (Longcheer): GSM
> modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1 claimed port ttyUSB3
> Apr 28 12:12:57 travelmate modem-manager: (Longcheer): GSM
> modem /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1 claimed port ttyUSB4
> Apr 28 12:13:07 travelmate modem-manager: (ttyUSB1) closing serial
> device...
> Apr 28 12:13:37 travelmate modem-manager: (ttyUSB1) opening serial
> device...
> Apr 28 12:13:41 travelmate modem-manager: (ttyUSB3) closing serial
> device...
> Apr 28 12:13:41 travelmate modem-manager: (ttyUSB2) closing serial
> device...
> Apr 28 12:13:41 travelmate modem-manager: (ttyUSB2) opening serial
> device...
> Apr 28 12:13:47 travelmate modem-manager: (ttyUSB0) closing serial
> device...
> Apr 28 12:14:17 travelmate modem-manager: (ttyUSB1) closing serial
> device...
> Apr 28 12:14:51 travelmate modem-manager: (ttyUSB2) closing serial
> device...
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <warn> (ttyUSB3):
> failed to look up interface index
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3): new
> GSM device (driver: 'option1' ifindex: -1)
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> exported as /org/freedesktop/NetworkManager/Devices/2
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3): now
> managed
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 1 -> 2 (reason 2)
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> deactivating device (reason: 2).
> Apr 28 12:14:51 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 2 -> 3 (reason 0)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) starting connection 'Orange Default'
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 3 -> 4 (reason 0)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) scheduled...
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) started...
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) complete.
> Apr 28 12:15:18 travelmate modem-manager: (ttyUSB3) opening serial
> device...
> Apr 28 12:15:18 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (disabled ->
> enabling)
> Apr 28 12:15:18 travelmate modem-manager: (ttyUSB4) opening serial
> device...
> Apr 28 12:15:18 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabling ->
> enabled)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> WWAN now enabled
> by radio killswitch
> Apr 28 12:15:18 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (enabled ->
> registered)
> Apr 28 12:15:18 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (registered
> -> connecting)
> Apr 28 12:15:18 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (connecting
> -> connected)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 2 of 5 (Device Configure) scheduled...
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 2 of 5 (Device Configure) starting...
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 4 -> 5 (reason 0)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 2 of 5 (Device Configure) successful.
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 3 of 5 (IP Configure Start) scheduled.
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 2 of 5 (Device Configure) complete.
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 3 of 5 (IP Configure Start) started...
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 5 -> 7 (reason 0)
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> starting PPP
> connection
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> pppd started
> with pid 28945
> Apr 28 12:15:18 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 3 of 5 (IP Configure Start) complete.
> Apr 28 12:15:18 travelmate pppd[28945]:
> Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
> Apr 28 12:15:19 travelmate kernel: [185988.690043] PPP generic driver
> version 2.4.2
> Apr 28 12:15:19 travelmate pppd[28945]: pppd 2.4.5 started by root, uid
> 0
> Apr 28 12:15:19 travelmate pppd[28945]: Using interface ppp0
> Apr 28 12:15:19 travelmate pppd[28945]: Connect: ppp0 <--> /dev/ttyUSB3
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <warn> pppd timed out
> or didn't initialize our dbus module
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 7 -> 9 (reason 5)
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> Marking
> connection 'Orange Default' invalid.
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <warn> Activation
> (ttyUSB3) failed.
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 9 -> 3 (reason 0)
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> deactivating device (reason: 0).
> Apr 28 12:15:39 travelmate pppd[28945]: Terminating on signal 15
> Apr 28 12:15:39 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed (connected
> -> disconnecting)
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> Policy set
> 'System eth0' (eth0) as default for IPv4 routing and DNS.
> Apr 28 12:15:39 travelmate NetworkManager[1223]: <info> Policy set
> 'System eth0' (eth0) as default for IPv4 routing and DNS.
> Apr 28 12:15:39 travelmate dbus: [system] Rejected send message, 1
> matched rules; type="error", sender=":1.1" (uid=0 pid=1223
> comm="NetworkManager) interface="(unset)" member="(unset)" error
> name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply=0
> destination=":1.281" (uid=0 pid=28945 comm="/usr/sbin/pppd))
> Apr 28 12:15:40 travelmate modem-manager:
> Modem /org/freedesktop/ModemManager/Modems/2: state changed
> (disconnecting -> registered)
> Apr 28 12:15:41 travelmate avahi-daemon[1243]: Withdrawing workstation
> service for ppp0.
> Apr 28 12:16:15 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) starting connection 'Orange Default'
> Apr 28 12:16:15 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 3 -> 4 (reason 0)
> Apr 28 12:16:15 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) scheduled...
> Apr 28 12:16:15 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) started...
> Apr 28 12:16:15 travelmate NetworkManager[1223]: <info> Activation
> (ttyUSB3) Stage 1 of 5 (Device Prepare) complete.
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <warn> GSM connection
> failed: (32) Sending command failed: 'Resource temporarily unavailable'
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 4 -> 9 (reason 1)
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> Marking
> connection 'Orange Default' invalid.
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <warn> Activation
> (ttyUSB3) failed.
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> device state change: 9 -> 3 (reason 0)
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> (ttyUSB3):
> deactivating device (reason: 0).
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> Policy set
> 'System eth0' (eth0) as default for IPv4 routing and DNS.
> Apr 28 12:16:25 travelmate NetworkManager[1223]: <info> Policy set
> 'System eth0' (eth0) as default for IPv4 routing and DNS.
> 
> 
> _______________________________________________
> networkmanager-list mailing list
> networkmanager-list gnome org
> http://mail.gnome.org/mailman/listinfo/networkmanager-list