NetworkManager-pptp

[Dan Williams]

On Tue, 2008-09-02 at 23:20 +0100, Mat Booth wrote:
> 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?)

You could be; there are probably some combinations the GUI doesn't yet
recognize as mutually exclusive.  I can stick some logging info in there
to print out the command line that gets passed to ppp if you like.

Dan

> 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
>