Comment 0 for bug 282207

Revision history for this message
Li Hu (xsd12) wrote : NM 0.7 does not set APN for AT&T 3G connection

Binary package hint: network-manager

System: Intrepid / current snapshop
Kernel: 2.6.27-7
3G Device: Sierra Aircard 881
3G Provider: AT&T US

Problem: Network Manager is unable to initiate a 3G connection with an Sierra Aircard 881.

The card is properly detected and supported by the Sierra Kernel driver 1.3.2. NM 0.7 properly detects the card as well and starts the configuration assistant and offers the selection of some AT&T options.

On the Sierra Homepage Sierra provides required settings and a sample ppp peer and ppp chat script for AT&T, both can be successfully used to control the card and open a connection manually controlling pppd.
http://www.sierrawireless.com/faq/ShowFAQ.aspx?ID=607
http://www.sierrawireless.com/resources/support/Software/Linux/ppp-scripts.tar.gz

#1: AT&T options not very self describing (what is AT&T, AT&T tethering, AT&T tethering with data acceleration??)
#2: 'AT&T tethering with data acceleration' seems to have the settings required for a standard AT&T HSDPA data only flatrate contract
#3: NM 0.7 does not connect. NM 0.7 fails to set the APN (ISP.CINGULAR, which is properly configured in NM 0.7)

syslog snippet:
part1: NM recognizing the device
Oct 11 10:05:18 X31 NetworkManager: <info> Found new Modem device 'ttyUSB0'.
Oct 11 10:05:18 X31 NetworkManager: <info> (ttyUSB0): exported as /org/freedesktop/Hal/devices/usb_device_1199_6851_noserial_if0_serial_usb_0
Oct 11 10:05:22 X31 NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2
Oct 11 10:05:22 X31 NetworkManager: <info> (ttyUSB0): deactivating device.
Oct 11 10:05:22 X31 NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Oct 11 10:05:22 X31 NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
Oct 11 10:05:22 X31 NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 11 10:05:22 X31 NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3
Oct 11 10:05:22 X31 NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)

part 2: NM trying to activate the connection
Oct 11 10:05:38 X31 NetworkManager: <info> Activation (ttyUSB0) starting connection 'AT&T (Tethering with data acceleration)'
Oct 11 10:05:38 X31 NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
Oct 11 10:05:38 X31 NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 11 10:05:38 X31 NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 11 10:05:38 X31 NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 11 10:05:38 X31 NetworkManager: <debug> [1223744738.786793] nm_serial_device_open(): (ttyUSB0) opening device...
Oct 11 10:05:38 X31 NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 11 10:05:38 X31 NetworkManager: <info> (ttyUSB0): powering up...
Oct 11 10:05:38 X31 NetworkManager: <info> Registered on Home network
Oct 11 10:05:38 X31 NetworkManager: <info> Associated with network: +COPS: 0,0,"",2
Oct 11 10:05:39 X31 NetworkManager: <WARN> set_apn_done(): Setting APN failed
Oct 11 10:05:39 X31 NetworkManager: <info> (ttyUSB0): device state change: 4 -> 9
Oct 11 10:05:39 X31 NetworkManager: <debug> [1223744739.028278] nm_serial_device_close(): Closing device 'ttyUSB0'
Oct 11 10:05:39 X31 NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Oct 11 10:05:39 X31 NetworkManager: <info> Marking connection 'AT&T (Tethering with data acceleration)' invalid.
Oct 11 10:05:39 X31 NetworkManager: <info> Activation (ttyUSB0) failed.
Oct 11 10:05:39 X31 NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
Oct 11 10:05:39 X31 NetworkManager: <info> (ttyUSB0): deactivating device.

Syslog snippet for a successful 3G connection manually using pppd with the gsm scripts from Sierra:

Oct 12 07:13:39 X31 pppd[25996]: pppd 2.4.4 started by root, uid 0
Oct 12 07:13:40 X31 chat[25998]: abort on (NO DIAL TONE)
Oct 12 07:13:40 X31 chat[25998]: abort on (NO ANSWER)
Oct 12 07:13:40 X31 chat[25998]: abort on (NO CARRIER)
Oct 12 07:13:40 X31 chat[25998]: abort on (DELAYED)
Oct 12 07:13:40 X31 chat[25998]: send (AT^M)
Oct 12 07:13:40 X31 chat[25998]: expect (OK)
Oct 12 07:13:40 X31 chat[25998]: ^M
Oct 12 07:13:40 X31 chat[25998]: OK
Oct 12 07:13:40 X31 chat[25998]: -- got it
Oct 12 07:13:40 X31 chat[25998]: send (ATZ^M)
Oct 12 07:13:40 X31 chat[25998]: expect (OK)
Oct 12 07:13:40 X31 chat[25998]: ^M
Oct 12 07:13:40 X31 chat[25998]: ^M
Oct 12 07:13:40 X31 chat[25998]: OK
Oct 12 07:13:40 X31 chat[25998]: -- got it
Oct 12 07:13:40 X31 chat[25998]: send (AT+CGDCONT=1,"IP","ISP.CINGULAR"^M)
Oct 12 07:13:41 X31 chat[25998]: expect (OK)
Oct 12 07:13:41 X31 chat[25998]: ^M
Oct 12 07:13:41 X31 chat[25998]: ^M
Oct 12 07:13:41 X31 chat[25998]: OK
Oct 12 07:13:41 X31 chat[25998]: -- got it
Oct 12 07:13:41 X31 chat[25998]: send (ATD*99#^M)
Oct 12 07:13:41 X31 chat[25998]: expect (CONNECT)
Oct 12 07:13:41 X31 chat[25998]: ^M
Oct 12 07:13:41 X31 chat[25998]: ^M
Oct 12 07:13:41 X31 chat[25998]: CONNECT
Oct 12 07:13:41 X31 chat[25998]: -- got it
Oct 12 07:13:41 X31 chat[25998]: send (^M)
Oct 12 07:13:41 X31 pppd[25996]: Serial connection established.
Oct 12 07:13:41 X31 pppd[25996]: using channel 7
Oct 12 07:13:41 X31 pppd[25996]: Using interface ppp0
Oct 12 07:13:41 X31 pppd[25996]: Connect: ppp0 <--> /dev/ttyUSB0
Oct 12 07:13:42 X31 pppd[25996]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xa111e4b3> <pcomp> <accomp>]
Oct 12 07:13:42 X31 pppd[25996]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x70b3db1d> <pcomp> <accomp>]
Oct 12 07:13:42 X31 pppd[25996]: sent [LCP ConfAck id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0x70b3db1d> <pcomp> <accomp>]
Oct 12 07:13:42 X31 pppd[25996]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xa111e4b3> <pcomp> <accomp>]
Oct 12 07:13:42 X31 pppd[25996]: rcvd [LCP DiscReq id=0x1 magic=0x70b3db1d]
Oct 12 07:13:42 X31 pppd[25996]: rcvd [CHAP Challenge id=0x1 <284556127e4ed6ddfaa3bb20de820aff>, name = "UMTS_CHAP_SRVR"]
Oct 12 07:13:42 X31 pppd[25996]: sent [CHAP Response id=0x1 <b299758d8142dbf42cd356b168899b39>, name = "<email address hidden>"]
Oct 12 07:13:42 X31 pppd[25996]: rcvd [CHAP Success id=0x1 ""]
Oct 12 07:13:42 X31 pppd[25996]: CHAP authentication succeeded
Oct 12 07:13:42 X31 pppd[25996]: CHAP authentication succeeded
Oct 12 07:13:42 X31 pppd[25996]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Oct 12 07:13:43 X31 pppd[25996]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 12 07:13:43 X31 pppd[25996]: sent [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 12 07:13:44 X31 pppd[25996]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 12 07:13:44 X31 pppd[25996]: sent [IPCP ConfReq id=0x3 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 12 07:13:45 X31 pppd[25996]: rcvd [IPCP ConfReq id=0x0]
Oct 12 07:13:45 X31 pppd[25996]: sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>]
Oct 12 07:13:45 X31 pppd[25996]: rcvd [IPCP ConfRej id=0x3 <compress VJ 0f 01>]
Oct 12 07:13:45 X31 pppd[25996]: sent [IPCP ConfReq id=0x4 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 12 07:13:45 X31 pppd[25996]: rcvd [IPCP ConfReq id=0x1]
Oct 12 07:13:45 X31 pppd[25996]: sent [IPCP ConfAck id=0x1]
Oct 12 07:13:45 X31 pppd[25996]: rcvd [IPCP ConfNak id=0x4 <addr 166.129.200.46> <ms-dns1 209.183.54.151> <ms-dns3 209.183.54.151>]
Oct 12 07:13:45 X31 pppd[25996]: sent [IPCP ConfReq id=0x5 <addr 166.129.200.46> <ms-dns1 209.183.54.151> <ms-dns3 209.183.54.151>]
Oct 12 07:13:45 X31 pppd[25996]: rcvd [IPCP ConfAck id=0x5 <addr 166.129.200.46> <ms-dns1 209.183.54.151> <ms-dns3 209.183.54.151>]
Oct 12 07:13:45 X31 pppd[25996]: Could not determine remote IP address: defaulting to 10.64.64.64
Oct 12 07:13:45 X31 pppd[25996]: local IP address 166.129.200.46
Oct 12 07:13:45 X31 pppd[25996]: remote IP address 10.64.64.64
Oct 12 07:13:45 X31 pppd[25996]: primary DNS address 209.183.54.151
Oct 12 07:13:45 X31 pppd[25996]: secondary DNS address 209.183.54.151
Oct 12 07:13:45 X31 pppd[25996]: Script /etc/ppp/ip-up started (pid 26000)
Oct 12 07:13:45 X31 pppd[25996]: Script /etc/ppp/ip-up finished (pid 26000), status = 0x0

lshal output:
# lshal -u `hal-find-by-capability --capability modem`
udi = '/org/freedesktop/Hal/devices/usb_device_1199_6851_noserial_if0_serial_usb_0'
  info.capabilities = {'serial', 'modem'} (string list)
  info.category = 'serial' (string)
  info.parent = '/org/freedesktop/Hal/devices/usb_device_1199_6851_noserial_if0' (string)
  info.product = 'AirCard 881 Device' (string)
  info.subsystem = 'tty' (string)
  info.udi = '/org/freedesktop/Hal/devices/usb_device_1199_6851_noserial_if0_serial_usb_0' (string)
  linux.device_file = '/dev/ttyUSB0' (string)
  linux.hotplug_type = 2 (0x2) (int)
  linux.subsystem = 'tty' (string)
  linux.sysfs_path = '/sys/devices/pci0000:00/0000:00:1e.0/0000:02:00.0/0000:03:00.0/usb5/5-1/5-1:1.0/ttyUSB0/tty/ttyUSB0' (string)
  modem.command_sets = {'GSM-07.07', 'GSM-07.05'} (string list)
  serial.device = '/dev/ttyUSB0' (string)
  serial.originating_device = '/org/freedesktop/Hal/devices/usb_device_1199_6851_noserial_if0' (string)
  serial.port = 0 (0x0) (int)
  serial.type = 'usb' (string)