Help with PPTP VPN connection keeps failing

Tue Apr 19 15:43:32 UTC 2011

On 04/19/2011 11:23 AM, Eric B. wrote:
> On 04/17/2011 09:52 PM, Matt Domsch wrote:
>> On Tue, Apr 12, 2011 at 10:21:17PM -0400, Eric B. wrote:
>>> Hi,
>>>
>>> I just recently freshly installed FC14 on my laptop, and have been
>>> trying to get a PPTP VPN connection working, but am having significant
>>> difficulty.
>>>
>>> I've properly configured the VPN settings, and see the connection
>>> succeeds, but then shuts right back down.  If I try the same credentials
>>> from within a Windows machine, it connects without fail.
>>
>> [snip]
>>
>>
>>> Apr 12 22:11:55 eric-laptop pppd[3520]: LCP terminated by peer
>>
>> That's the magic message.  You need to enable further debugging to
>> figure out why it threw that.
>>
>> http://pptpclient.sourceforge.net/howto-diagnosis.phtml#lcp_term_authentication
>
>
> Thanks for the reference.  Right now I am struggling to understand how
> Fedora's Network Manager calls the pptp tunnel so I can enable
> additional logging.  I did find this reference
> (http://live.gnome.org/NetworkManager/Debugging), but the output log
> that is produced doesn't seem to help me pinpoint the issue.  Can you
> see anything of value in it?
>
> Thanks,
>
> Eric
>
> ** Message:<info>   pppd started with pid 3591
>
> Plugin /usr/lib/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
> ** Message: nm-pptp-ppp-plugin: (plugin_init): initializing
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 3 / phase
> 'serial connection'
> Using interface ppp0
> Connect: ppp0<-->  /dev/pts/1
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 5 / phase
> 'establish'
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 6 / phase
> 'authenticate'
> ** Message: nm-pptp-ppp-plugin: (get_credentials): passwd-hook,
> requesting credentials...
> ** Message: nm-pptp-ppp-plugin: (get_credentials): got credentials from
> NetworkManager-pptp
> CHAP authentication succeeded
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
> local  IP address 192.168.252.224
> remote IP address 208.88.112.210
> primary   DNS address 192.168.254.1
> secondary DNS address 208..222.222
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
> ** Message: nm-pptp-ppp-plugin: (nm_ip_up): ip-up event
> ** Message: nm-pptp-ppp-plugin: (nm_ip_up): sending Ip4Config to
> NetworkManager-pptp...
> ** Message:<info>   PPTP service (IP Config Get) reply received.
>
> CCP terminated by peer
> Compression disabled by peer.
> LCP terminated by peer
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
> Connect time 0.0 minutes.
> Sent 18 bytes, received 8 bytes.
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 5 / phase
> 'establish'
> Modem hangup
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 11 / phase
> 'disconnect'
> Connection terminated.
> ** Message:<info>   Terminated ppp daemon with PID 3591.
>
> ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
> Terminating on signal 15
> ** Message: nm-pptp-ppp-plugin: (nm_exit_notify): cleaning up
>
> ** (process:3589): WARNING **:<WARN>   pppd_watch_cb(): pppd exited with
> error code 5
>
>

I just realized that there was stuff in /var/log/messages as well, 
although I don't see anything in there that is more indicative of what 
might be causing the problems.

Apr 19 11:27:40 eric-laptop NetworkManager[1376]: <info> VPN service 
'pptp' started (org.freedesktop.NetworkManager.pptp), PID 3808
Apr 19 11:27:40 eric-laptop NetworkManager[1376]: <info> VPN service 
'pptp' appeared; activating connections
Apr 19 11:27:40 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state changed: 1
Apr 19 11:27:43 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state changed: 3
Apr 19 11:27:43 eric-laptop NetworkManager[1376]: <info> VPN connection 
'IA Firewall' (Connect) reply received.
Apr 19 11:27:43 eric-laptop pppd[3810]: Warning: can't open options file 
/root/.ppprc: Permission denied
Apr 19 11:27:44 eric-laptop pppd[3810]: Plugin 
/usr/lib/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
Apr 19 11:27:44 eric-laptop pppd[3810]: pppd 2.4.5 started by root, uid 0
Apr 19 11:27:44 eric-laptop pppd[3810]: Using interface ppp0
Apr 19 11:27:44 eric-laptop pppd[3810]: Connect: ppp0 <--> /dev/pts/1
Apr 19 11:27:44 eric-laptop pptp[3812]: nm-pptp-service-3808 
log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Apr 19 11:27:45 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_rep:pptp_ctrl.c:254]: Sent control packet type is 1 
'Start-Control-Connection-Request'
Apr 19 11:27:45 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_disp:pptp_ctrl.c:754]: Received Start Control Connection Reply
Apr 19 11:27:45 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_disp:pptp_ctrl.c:788]: Client connection established.
Apr 19 11:27:46 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_rep:pptp_ctrl.c:254]: Sent control packet type is 7 
'Outgoing-Call-Request'
Apr 19 11:27:46 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_disp:pptp_ctrl.c:873]: Received Outgoing Call Reply.
Apr 19 11:27:46 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_disp:pptp_ctrl.c:912]: Outgoing call established (call ID 0, 
peer's call ID 62739).
Apr 19 11:27:46 eric-laptop pppd[3810]: CHAP authentication succeeded
Apr 19 11:27:48 eric-laptop pppd[3810]: local  IP address 192.168.252.224
Apr 19 11:27:48 eric-laptop pppd[3810]: remote IP address 208.88.112.210
Apr 19 11:27:48 eric-laptop pppd[3810]: primary   DNS address 192.168.254.1
Apr 19 11:27:48 eric-laptop pppd[3810]: secondary DNS address 208.88.222.222
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> VPN connection 
'IA Firewall' (IP Config Get) reply received.
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> VPN Gateway: 
208.85.112.211
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Tunnel Device: ppp0
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Internal IP4 
Address: 192.168.254.224
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Internal IP4 
Prefix: 32
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Internal IP4 
Point-to-Point Address: 208.88.112.210
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Maximum Segment 
Size (MSS): 0
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Forbid Default 
Route: no
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Internal IP4 
DNS: 192.168.254.1
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> Internal IP4 
DNS: 208.88.222.222
Apr 19 11:27:48 eric-laptop NetworkManager[1376]: <info> DNS Domain: 
'(none)'
Apr 19 11:27:48 eric-laptop pppd[3810]: LCP terminated by peer
Apr 19 11:27:48 eric-laptop pppd[3810]: Connect time 0.0 minutes.
Apr 19 11:27:48 eric-laptop pppd[3810]: Sent 0 bytes, received 0 bytes.
Apr 19 11:27:48 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_disp:pptp_ctrl.c:803]: Received Stop Control Connection Request.
Apr 19 11:27:48 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_rep:pptp_ctrl.c:254]: Sent control packet type is 4 
'Stop-Control-Connection-Reply'
Apr 19 11:27:48 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[callmgr_main:pptp_callmgr.c:259]: Closing connection (shutdown)
Apr 19 11:27:48 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[ctrlp_rep:pptp_ctrl.c:254]: Sent control packet type is 12 
'Call-Clear-Request'
Apr 19 11:27:48 eric-laptop pptp[3818]: nm-pptp-service-3808 
log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Apr 19 11:27:48 eric-laptop pppd[3810]: Modem hangup
Apr 19 11:27:48 eric-laptop pppd[3810]: Connection terminated.
Apr 19 11:27:48 eric-laptop pppd[3810]: Terminating on signal 15
Apr 19 11:27:48 eric-laptop pppd[3810]: Exit.
Apr 19 11:27:48 eric-laptop dbus: [system] Rejected send message, 1 
matched rules; type="error", sender=":1.123" (uid=0 pid=3808 
comm="/usr/libexec/nm-pptp-service) interface="(unset)" member="(unset)" 
error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply=0 
destination=":1.124" (uid=0 pid=3810 comm="/usr/sbin/pppd))
Apr 19 11:27:49 eric-laptop avahi-daemon[1389]: Withdrawing workstation 
service for ppp0.
Apr 19 11:27:49 eric-laptop NetworkManager[1376]: <error> 
[1303226869.107062] [nm-system.c:318] sync_addresses(): (ppp0): error 
-19 returned from rtnl_addr_add():#012Netlink Error (errno = No such device)
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> VPN connection 
'IA Firewall' (IP Config Get) complete.
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <error> 
[1303226870.719042] [nm-system.c:974] 
nm_system_replace_default_ip4_route_vpn(): (ppp0): failed to set IPv4 
default route: -19
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> Policy set 'IA 
Firewall' (ppp0) as default for IPv4 routing and DNS.
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state changed: 4
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state changed: 5
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state changed: 6
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <info> VPN plugin 
state change reason: 0
Apr 19 11:27:50 eric-laptop NetworkManager[1376]: <warn> error 
disconnecting VPN: Could not process the request because no VPN 
connection was active.
Apr 19 11:27:52 eric-laptop NetworkManager[1376]: <info> Policy set 
'Auto eth0' (eth0) as default for IPv4 routing and DNS.
Apr 19 11:27:58 eric-laptop NetworkManager[1376]: <info> VPN service 
'pptp' disappeared