On Tue, 2008-09-02 at 23:20 +0100, Mat Booth wrote:
On Tue, Sep 2, 2008 at 4:26 PM, Dan Williams <dcbw(a)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