Alcatel X220 3G modem fails to connect using Network Manager

[P Puty <pputy2010 yahoo com>]

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

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.