2008-10-12 14:29:05 |
Li Hu |
bug |
|
|
added bug |
2008-10-12 14:35:04 |
Li Hu |
description |
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 = "ISPDA@CINGULARGPRS.COM"]
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) |
Binary package hint: network-manager
System: Intrepid / current snapshot
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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) |
|
2008-10-12 15:01:49 |
Li Hu |
description |
Binary package hint: network-manager
System: Intrepid / current snapshot
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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) |
Binary package hint: network-manager
System: Intrepid / current snapshot
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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)
# apt-cache policy network-manager
network-manager:
Installed: 0.7~~svn20081008t224042-0ubuntu2
Candidate: 0.7~~svn20081008t224042-0ubuntu2
Version table:
*** 0.7~~svn20081008t224042-0ubuntu2 0
500 http://us.archive.ubuntu.com intrepid/main Packages
100 /var/lib/dpkg/status
|
|
2008-10-16 01:40:04 |
Alexander Sack |
network-manager: status |
New |
Incomplete |
|
2008-10-16 01:40:04 |
Alexander Sack |
network-manager: bugtargetdisplayname |
network-manager (Ubuntu) |
mobile-broadband-provider-info (Ubuntu) |
|
2008-10-16 01:40:04 |
Alexander Sack |
network-manager: bugtargetname |
network-manager (Ubuntu) |
mobile-broadband-provider-info (Ubuntu) |
|
2008-10-16 01:40:04 |
Alexander Sack |
network-manager: statusexplanation |
|
could you please identify which setting is wrong and how it should be? |
|
2008-10-16 01:40:04 |
Alexander Sack |
network-manager: title |
Bug #282207 in network-manager (Ubuntu): "NM 0.7 does not set APN for AT&T 3G connection" |
Bug #282207 in mobile-broadband-provider-info (Ubuntu): "NM 0.7 does not set APN for AT&T 3G connection" |
|
2008-10-18 06:33:43 |
Li Hu |
bug |
|
|
added attachment 'patch.gz' (patch.gz) |
2008-10-22 10:22:54 |
Alexander Sack |
network-manager: status |
New |
Incomplete |
|
2008-10-22 10:22:54 |
Alexander Sack |
network-manager: statusexplanation |
|
could you please test the patch posted by Jeroen in bug 278992 |
|
2008-10-22 10:25:29 |
Alexander Sack |
mobile-broadband-provider-info: status |
Incomplete |
Invalid |
|
2008-10-22 10:25:29 |
Alexander Sack |
mobile-broadband-provider-info: statusexplanation |
could you please identify which setting is wrong and how it should be? |
|
|
2008-10-22 10:27:30 |
Alexander Sack |
network-manager: status |
Incomplete |
Triaged |
|
2008-10-22 10:27:30 |
Alexander Sack |
network-manager: importance |
Undecided |
Medium |
|
2008-10-22 10:27:30 |
Alexander Sack |
network-manager: statusexplanation |
could you please test the patch posted by Jeroen in bug 278992 |
first patch to test is: https://bugs.edge.launchpad.net/ubuntu/+source/network-manager/+bug/278992/comments/7 |
|
2008-10-22 10:28:07 |
Alexander Sack |
title |
NM 0.7 does not set APN for AT&T 3G connection |
[Sierra Aircard] NM 0.7 does not set APN for AT&T 3G connection |
|
2008-10-22 10:28:28 |
Alexander Sack |
title |
[Sierra Aircard] NM 0.7 does not set APN for AT&T 3G connection |
[Sierra] NM 0.7 does not set APN for AT&T 3G connection |
|
2008-11-04 02:39:24 |
Alexander Sack |
network-manager: status |
New |
Triaged |
|
2008-11-04 02:39:24 |
Alexander Sack |
network-manager: importance |
Undecided |
High |
|
2008-11-04 02:39:24 |
Alexander Sack |
network-manager: statusexplanation |
|
|
|
2008-11-04 02:39:24 |
Alexander Sack |
network-manager: milestone |
|
intrepid-updates |
|
2008-11-04 02:39:34 |
Alexander Sack |
mobile-broadband-provider-info: status |
New |
Invalid |
|
2008-11-04 02:39:34 |
Alexander Sack |
mobile-broadband-provider-info: statusexplanation |
|
|
|
2008-11-04 02:40:03 |
Alexander Sack |
description |
Binary package hint: network-manager
System: Intrepid / current snapshot
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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)
# apt-cache policy network-manager
network-manager:
Installed: 0.7~~svn20081008t224042-0ubuntu2
Candidate: 0.7~~svn20081008t224042-0ubuntu2
Version table:
*** 0.7~~svn20081008t224042-0ubuntu2 0
500 http://us.archive.ubuntu.com intrepid/main Packages
100 /var/lib/dpkg/status
|
Binary package hint: network-manager
System: Intrepid / current snapshot
Kernel: 2.6.27-7
3G Device: Sierra Aircard 881
3G Provider: AT&T US
Patch that landed upstream: http://mail.gnome.org/archives/networkmanager-list/2008-October/msg00261.html
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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)
# apt-cache policy network-manager
network-manager:
Installed: 0.7~~svn20081008t224042-0ubuntu2
Candidate: 0.7~~svn20081008t224042-0ubuntu2
Version table:
*** 0.7~~svn20081008t224042-0ubuntu2 0
500 http://us.archive.ubuntu.com intrepid/main Packages
100 /var/lib/dpkg/status
|
|
2008-11-04 15:51:16 |
Martin Pitt |
network-manager: assignee |
|
asac |
|
2008-11-04 15:51:16 |
Martin Pitt |
network-manager: statusexplanation |
first patch to test is: https://bugs.edge.launchpad.net/ubuntu/+source/network-manager/+bug/278992/comments/7 |
|
|
2008-11-04 15:55:06 |
Martin Pitt |
network-manager: assignee |
|
asac |
|
2008-11-13 01:41:52 |
Luqe |
bug |
|
|
added attachment 'syslog.txt' (syslog.txt) |
2008-11-28 10:35:46 |
Alexander Sack |
network-manager: status |
Triaged |
Fix Committed |
|
2008-11-28 12:21:20 |
Alexander Sack |
description |
Binary package hint: network-manager
System: Intrepid / current snapshot
Kernel: 2.6.27-7
3G Device: Sierra Aircard 881
3G Provider: AT&T US
Patch that landed upstream: http://mail.gnome.org/archives/networkmanager-list/2008-October/msg00261.html
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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)
# apt-cache policy network-manager
network-manager:
Installed: 0.7~~svn20081008t224042-0ubuntu2
Candidate: 0.7~~svn20081008t224042-0ubuntu2
Version table:
*** 0.7~~svn20081008t224042-0ubuntu2 0
500 http://us.archive.ubuntu.com intrepid/main Packages
100 /var/lib/dpkg/status
|
Binary package hint: network-manager
System: Intrepid / current snapshot
Kernel: 2.6.27-7
3G Device: Sierra Aircard 881
3G Provider: AT&T US
To verify:
1. check that you cannot connect with the setup from above with old package
2. check that you can connect with the setup from above with new package
Patch that landed upstream: http://mail.gnome.org/archives/networkmanager-list/2008-October/msg00261.html
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
NM Problems:
#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 = "ISPDA@CINGULARGPRS.COM"]
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)
# apt-cache policy network-manager
network-manager:
Installed: 0.7~~svn20081008t224042-0ubuntu2
Candidate: 0.7~~svn20081008t224042-0ubuntu2
Version table:
*** 0.7~~svn20081008t224042-0ubuntu2 0
500 http://us.archive.ubuntu.com intrepid/main Packages
100 /var/lib/dpkg/status
|
|
2008-11-28 12:21:34 |
Alexander Sack |
bug |
|
|
added subscriber Ubuntu Stable Release Updates Team |
2008-11-28 12:53:41 |
Alexander Sack |
network-manager: status |
Triaged |
In Progress |
|
2008-11-28 12:53:41 |
Alexander Sack |
network-manager: statusexplanation |
|
upload SRU: network-manager_0.7~~svn20081018t105859-0ubuntu1.8.10.1_source.changes to ubuntu/intrepid-proposed |
|
2008-11-28 13:00:07 |
Launchpad Janitor |
network-manager: status |
Fix Committed |
Fix Released |
|
2008-11-28 13:44:09 |
Martin Pitt |
network-manager: status |
In Progress |
Fix Committed |
|
2008-11-28 13:44:09 |
Martin Pitt |
network-manager: statusexplanation |
upload SRU: network-manager_0.7~~svn20081018t105859-0ubuntu1.8.10.1_source.changes to ubuntu/intrepid-proposed |
|
|
2008-11-28 13:44:09 |
Martin Pitt |
network-manager: milestone |
intrepid-updates |
|
|
2008-11-28 13:44:37 |
Martin Pitt |
bug |
|
|
added subscriber SRU Verification |
2008-12-15 19:51:10 |
Launchpad Janitor |
network-manager: status |
Fix Committed |
Fix Released |
|
2009-12-05 02:43:10 |
Launchpad Janitor |
branch linked |
|
lp:ubuntu/network-manager |
|