NetworkManager makes PPTP VPN connection but it times out quickly; pon works

Bug #668025 reported by Eli Nistal
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
network-manager-pptp (Ubuntu)
New
Undecided
Unassigned

Bug Description

Binary package hint: network-manager

Description: Ubuntu 10.10
Release: 10.10

Using network-manager, network-manager-pptp and network-manager-pptp-gnome. Attempting to connect to Microsoft PPTP MSCHAPv2 VPN with NetworkManager. The connection succeeds, including setting up correct ppp0 device, but then times out and fails within a short time frame. Alternately, connection via the command line with pon succeeds and does not time out. Also, the same PPTP MSCHAPv2 VPN configuration in NetworkManager works under Fedora 13.

NetworkManager Relevant Entries in /var/log/syslog
-----------------------------------------------------------------
Oct 28 12:02:57 peregrine NetworkManager[1655]: <info> Starting VPN service 'org.freedesktop.NetworkManager.pptp'...
Oct 28 12:02:57 peregrine NetworkManager[1655]: <info> VPN service 'org.freedesktop.NetworkManager.pptp' started (org.freedesktop.NetworkManager.pptp), PID 5056
Oct 28 12:02:57 peregrine NetworkManager[1655]: <info> VPN service 'org.freedesktop.NetworkManager.pptp' appeared, activating connections
Oct 28 12:02:57 peregrine NetworkManager[1655]: <info> VPN plugin state changed: 1
Oct 28 12:03:14 peregrine NetworkManager[1655]: <info> VPN plugin state changed: 3
Oct 28 12:03:14 peregrine NetworkManager[1655]: <info> VPN connection 'REDACTED' (Connect) reply received.
Oct 28 12:03:14 peregrine pppd[5060]: Plugin /usr/lib/pppd/2.4.5//nm-pptp-pppd-plugin.so loaded.
Oct 28 12:03:14 peregrine pppd[5060]: pppd 2.4.5 started by root, uid 0
Oct 28 12:03:14 peregrine NetworkManager[1655]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 28 12:03:14 peregrine NetworkManager[1655]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 28 12:03:14 peregrine pppd[5060]: Using interface ppp0
Oct 28 12:03:14 peregrine modem-manager: (net/ppp0): could not get port's parent device
Oct 28 12:03:14 peregrine pppd[5060]: Connect: ppp0 <--> /dev/pts/6
Oct 28 12:03:14 peregrine pptp[5064]: nm-pptp-service-5056 log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Oct 28 12:03:14 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Oct 28 12:03:15 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Oct 28 12:03:15 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Oct 28 12:03:15 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Oct 28 12:03:16 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Oct 28 12:03:16 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 46115).
Oct 28 12:03:16 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Oct 28 12:03:16 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is 00000000, recv_accm is FFFFFFFF
Oct 28 12:03:16 peregrine pptp[5071]: nm-pptp-service-5056 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Oct 28 12:03:16 peregrine pppd[5060]: CHAP authentication succeeded
Oct 28 12:03:16 peregrine pppd[5060]: MPPE 128-bit stateless compression enabled
Oct 28 12:03:18 peregrine pppd[5060]: Cannot determine ethernet address for proxy ARP
Oct 28 12:03:18 peregrine pppd[5060]: local IP address REDACTED
Oct 28 12:03:18 peregrine pppd[5060]: remote IP address REDACTED
Oct 28 12:03:18 peregrine pppd[5060]: primary DNS address REDACTED
Oct 28 12:03:18 peregrine pppd[5060]: secondary DNS address REDACTED
Oct 28 12:03:55 peregrine NetworkManager[1655]: <warn> VPN connection 'REDACTED' (IP Config Get) timeout exceeded.
Oct 28 12:03:58 peregrine pppd[5060]: Terminating on signal 15
Oct 28 12:03:58 peregrine pppd[5060]: Connect time 0.7 minutes.
Oct 28 12:03:58 peregrine pppd[5060]: Sent 0 bytes, received 0 bytes.
Oct 28 12:03:58 peregrine NetworkManager[1655]: <info> Policy set 'REDACTED' (wlan0) as default for IPv4 routing and DNS.
Oct 28 12:03:58 peregrine pppd[5060]: MPPE disabled
Oct 28 12:03:58 peregrine pppd[5060]: Child process /usr/sbin/pptp REDACTED --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-5056 (pid 5062) terminated with signal 15
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is FFFFFFFF, recv_accm is FFFFFFFF
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Oct 28 12:03:58 peregrine pppd[5060]: Connection terminated.
Oct 28 12:03:58 peregrine avahi-daemon[1653]: Withdrawing workstation service for ppp0.
Oct 28 12:03:58 peregrine NetworkManager[1655]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 28 12:03:58 peregrine pptp[5064]: nm-pptp-service-5056 warn[decaps_hdlc:pptp_gre.c:204]: short read (-1): Input/output error
Oct 28 12:03:58 peregrine pptp[5064]: nm-pptp-service-5056 warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log
Oct 28 12:03:58 peregrine pppd[5060]: Exit.
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Oct 28 12:03:58 peregrine pptp[5071]: nm-pptp-service-5056 log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)

pon Relevant Entries in /var/log/syslog
------------------------------------------------
Oct 28 12:14:12 peregrine pppd[5142]: pppd 2.4.5 started by REDACTED, uid 0
Oct 28 12:14:12 peregrine NetworkManager[1655]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Oct 28 12:14:12 peregrine NetworkManager[1655]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Oct 28 12:14:12 peregrine pppd[5142]: Using interface ppp0
Oct 28 12:14:12 peregrine modem-manager: (net/ppp0): could not get port's parent device
Oct 28 12:14:12 peregrine pppd[5142]: Connect: ppp0 <--> /dev/pts/6
Oct 28 12:14:12 peregrine pptp[5145]: anon log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Oct 28 12:14:12 peregrine pptp[5153]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Oct 28 12:14:12 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Oct 28 12:14:12 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Oct 28 12:14:13 peregrine pptp[5153]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Oct 28 12:14:13 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Oct 28 12:14:13 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 15422).
Oct 28 12:14:14 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Oct 28 12:14:14 peregrine pptp[5153]: anon log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is 00000000, recv_accm is FFFFFFFF
Oct 28 12:14:14 peregrine pptp[5153]: anon warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Oct 28 12:14:14 peregrine pppd[5142]: CHAP authentication succeeded
Oct 28 12:14:14 peregrine pppd[5142]: MPPE 128-bit stateless compression enabled
Oct 28 12:14:16 peregrine pppd[5142]: replacing old default route to wlan0 [192.168.0.1]
Oct 28 12:14:16 peregrine pppd[5142]: Cannot determine ethernet address for proxy ARP
Oct 28 12:14:16 peregrine pppd[5142]: local IP address REDACTED
Oct 28 12:14:16 peregrine pppd[5142]: remote IP address REDACTED
Oct 28 12:14:16 peregrine pppd[5142]: primary DNS address REDACTED
Oct 28 12:14:16 peregrine pppd[5142]: secondary DNS address REDACTED
Oct 28 12:15:12 peregrine pptp[5153]: anon log[logecho:pptp_ctrl.c:677]: Echo Request received.
Oct 28 12:15:12 peregrine pptp[5153]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 6 'Echo-Reply'

ProblemType: Bug
DistroRelease: Ubuntu 10.10
Package: linux-image-2.6.35-22-generic 2.6.35-22.35
Regression: Yes
Reproducible: Yes
ProcVersionSignature: Ubuntu 2.6.35-22.35-generic 2.6.35.4
Uname: Linux 2.6.35-22-generic x86_64
AlsaVersion: Advanced Linux Sound Architecture Driver Version 1.0.23.
Architecture: amd64
ArecordDevices:
 **** List of CAPTURE Hardware Devices ****
 card 0: Intel [HDA Intel], device 0: STAC92xx Analog [STAC92xx Analog]
   Subdevices: 1/1
   Subdevice #0: subdevice #0
AudioDevicesInUse:
 USER PID ACCESS COMMAND
 /dev/snd/controlC0: enistal 2270 F.... pulseaudio
CRDA: Error: [Errno 2] No such file or directory
Card0.Amixer.info:
 Card hw:0 'Intel'/'HDA Intel at 0xc4500000 irq 47'
   Mixer name : 'IDT 92HD81B1X5'
   Components : 'HDA:111d7605,103c1436,00100402'
   Controls : 14
   Simple ctrls : 9
Card1.Amixer.info:
 Card hw:1 'Generic'/'HD-Audio Generic at 0xc4420000 irq 49'
   Mixer name : 'ATI R6xx HDMI'
   Components : 'HDA:1002aa01,00aa0100,00100200'
   Controls : 4
   Simple ctrls : 1
Card1.Amixer.values:
 Simple mixer control 'IEC958',0
   Capabilities: pswitch pswitch-joined penum
   Playback channels: Mono
   Mono: Playback [on]
Date: Thu Oct 28 13:30:31 2010
HibernationDevice: RESUME=UUID=d2886ab9-e5c0-4058-8cd3-9092855d0109
InstallationMedia: Ubuntu 10.10 "Maverick Meerkat" - Release amd64 (20101007)
MachineType: Hewlett-Packard HP ENVY 14 Notebook PC
ProcCmdLine: BOOT_IMAGE=/vmlinuz-2.6.35-22-generic root=/dev/mapper/vg_sparrow-lv_root ro quiet splash
ProcEnviron:
 LANG=en_US.UTF-8
 SHELL=/bin/bash
RelatedPackageVersions: linux-firmware 1.38
SourcePackage: linux
dmi.bios.date: 08/27/2010
dmi.bios.vendor: Hewlett-Packard
dmi.bios.version: F.13
dmi.board.asset.tag: Base Board Asset Tag
dmi.board.name: 1436
dmi.board.vendor: Hewlett-Packard
dmi.board.version: 59.21
dmi.chassis.asset.tag: CNU0415RDV
dmi.chassis.type: 10
dmi.chassis.vendor: Hewlett-Packard
dmi.chassis.version: Chassis Version
dmi.modalias: dmi:bvnHewlett-Packard:bvrF.13:bd08/27/2010:svnHewlett-Packard:pnHPENVY14NotebookPC:pvr058C110000241910001622100:rvnHewlett-Packard:rn1436:rvr59.21:cvnHewlett-Packard:ct10:cvrChassisVersion:
dmi.product.name: HP ENVY 14 Notebook PC
dmi.product.version: 058C110000241910001622100
dmi.sys.vendor: Hewlett-Packard

Revision history for this message
Eli Nistal (emnistal) wrote :
Thomas Hood (jdthood)
summary: - NetworkManager PPTP VPN Connection Timeout
+ NetworkManager makes PPTP VPN connection but it times out quickly; pon
+ works
affects: network-manager (Ubuntu) → network-manager-pptp (Ubuntu)
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

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