NetworkManager-pptp

[Mat Booth]

On Tue, Sep 2, 2008 at 4:26 PM, Dan Williams <dcbw at redhat.com> wrote:
> On Tue, 2008-09-02 at 15:20 +0000, Mat Booth wrote:
>> No, I run with SELinux disabled because of work. It doesn't spit out
>> any message at all if I run it like that.
>
> Ok, how about if you run it like that, then try to connect your VPN
> connection via the GUI?
>
> Dan
>
>

After some experimenting (and by experimenting I mean mostly just
dicking about with it :-), I may have made progress as now I'm getting
different output in /var/log/messages to what I reported originally.

(As an aside, I've been having trouble getting settings to "stick"
when I change the VPN config. Often, after I hit ok and close and
reopen the nm-connection-editor, the settings have gone back to the
way they were before. I can't figure out a pattern to it yet, but I
obviously can't be confident that the settings I've chosen is the
config that's used. Could I be choosing some mutually exclusive
options that are being silently rejected? Where are my settings stored
on disk so I can verify them?)

Here's what I'm seeing now (this seems to include the stdout from
/usr/libexec/nm-pptp-service):

Sep  2 23:03:45 sd NetworkManager: <info>  Starting VPN service
'org.freedesktop.NetworkManager.pptp'...
Sep  2 23:03:45 sd NetworkManager: <info>  VPN service
'org.freedesktop.NetworkManager.pptp' started
(org.freedesktop.NetworkManager.pptp), PID 11201
Sep  2 23:03:45 sd NetworkManager: <info>  VPN service
'org.freedesktop.NetworkManager.pptp' just appeared, activating
connections
Sep  2 23:03:45 sd NetworkManager: <info>  VPN plugin state changed: 3
Sep  2 23:03:45 sd NetworkManager: <info>  VPN connection 'Servelec'
(Connect) reply received.
Sep  2 23:03:45 sd pppd[11202]: Plugin
/usr/lib64/pppd/2.4.4/nm-pptp-pppd-plugin.so loaded.
Sep  2 23:03:45 sd pppd[11202]: pppd 2.4.4 started by root, uid 0
Sep  2 23:03:45 sd pppd[11202]: Using interface ppp0
Sep  2 23:03:45 sd pppd[11202]: Connect: ppp0 <--> /dev/pts/3
Sep  2 23:03:45 sd pptp[11203]: nm-pptp-service-11201
log[main:pptp.c:276]: The synchronous pptp option is NOT activated
Sep  2 23:03:45 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1
'Start-Control-Connection-Request'
Sep  2 23:03:45 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:738]: Received Start Control Connection
Reply
Sep  2 23:03:45 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:772]: Client connection established.
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7
'Outgoing-Call-Request'
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:857]: Received Outgoing Call Reply.
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:896]: Outgoing call established (call ID 0,
peer's call ID 2560).
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:949]: PPTP_SET_LINK_INFO received from
peer_callid 0
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:952]:   send_accm is 00000000, recv_accm is
FFFFFFFF
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
warn[ctrlp_disp:pptp_ctrl.c:955]: Non-zero Async Control Character
Maps are not supported!
Sep  2 23:03:46 sd pppd[11202]: LCP terminated by peer
(^HM-WoM-^H^@<M-Mt^@^@^BM-3)
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:949]: PPTP_SET_LINK_INFO received from
peer_callid 0
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:952]:   send_accm is FFFFFFFF, recv_accm is
FFFFFFFF
Sep  2 23:03:46 sd pptp[11210]: nm-pptp-service-11201
warn[ctrlp_disp:pptp_ctrl.c:955]: Non-zero Async Control Character
Maps are not supported!
Sep  2 23:03:47 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_disp:pptp_ctrl.c:911]: Received Call Clear Request.
Sep  2 23:03:49 sd pppd[11202]: Connection terminated.
Sep  2 23:03:49 sd pppd[11202]: Modem hangup
Sep  2 23:03:49 sd pptp[11203]: nm-pptp-service-11201
warn[decaps_hdlc:pptp_gre.c:197]: short read (-1): Input/output error
Sep  2 23:03:49 sd pptp[11203]: nm-pptp-service-11201
warn[decaps_hdlc:pptp_gre.c:209]: pppd may have shutdown, see pppd log
Sep  2 23:03:49 sd pptp[11210]: nm-pptp-service-11201
log[callmgr_main:pptp_callmgr.c:231]: Closing connection (unhandled)
Sep  2 23:03:49 sd pptp[11210]: nm-pptp-service-11201
log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12
'Call-Clear-Request'
Sep  2 23:03:49 sd pptp[11210]: nm-pptp-service-11201
log[call_callback:pptp_callmgr.c:78]: Closing connection (call state)
Sep  2 23:03:49 sd pppd[11202]: Exit.
Sep  2 23:03:49 sd NetworkManager: <info>  VPN plugin state changed: 6
Sep  2 23:03:49 sd NetworkManager: <WARN>  connection_state_changed():
Could not process the request because no VPN connection was active.

Thanks for your help.
Mat

-- 
Mat Booth
www.matbooth.co.uk