Bug 1326542

Summary:	Frequent PPTP VPN drop-outs with log message: short read (-1): Protocol not available
Product:	[Fedora] Fedora	Reporter:	Tim Wegener <twegener>
Component:	pptp	Assignee:	Jaroslav Škarvada <jskarvad>
Status:	CLOSED EOL	QA Contact:	Fedora Extras Quality Assurance <extras-qa>
Severity:	high	Docs Contact:
Priority:	unspecified
Version:	22	CC:	jskarvad, msekleta
Target Milestone:	---
Target Release:	---
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:		Doc Type:	Bug Fix
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2016-07-19 19:25:23 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:

Description Tim Wegener 2016-04-13 02:21:56 UTC

Description of problem:

PPTP VPN connection frequently drops out (every minute or so). Rare for the connection to stay up for more than a couple of minutes when in use.


Version-Release number of selected component (if applicable):

pptp-1.8.0-6.fc22.x86_64
NetworkManager-pptp-1.1.0-1.20150428git695d4f2.fc22.x86_64
NetworkManager-pptp-gnome-1.1.0-1.20150428git695d4f2.fc22.x86_64


How reproducible:

Happens whenever I use this VPN connection.


Steps to Reproduce:

1. Add a PPTP VPN connection in network manager.
2. Connect to the VPN.
3. Observe the connection drop out after some seconds/minutes with a message along the lines of "VPN connection failed for an unknown reason".


Actual results:

VPN connection drops out frequently, with notification along the lines of "VPN connection failed for an unknown reason".

/var/log/messages has messages like the following:

Apr 13 11:42:21 seraph NetworkManager: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Apr 13 11:42:21 seraph pptp[25267]: nm-pptp-service-21604 log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 1 'Start-Control-Co
quest'
Apr 13 11:42:22 seraph pptp[25267]: nm-pptp-service-21604 log[ctrlp_disp:pptp_ctrl.c:877]: Received Outgoing Call Reply.
Apr 13 11:42:22 seraph pptp[25267]: nm-pptp-service-21604 log[ctrlp_disp:pptp_ctrl.c:916]: Outgoing call established (call ID 0, peer's call ID 48780).
Apr 13 11:42:22 seraph pptp[25258]: nm-pptp-service-21604 warn[decaps_gre:pptp_gre.c:347]: short read (-1): Protocol not available
Apr 13 11:42:22 seraph pppd[25257]: Modem hangup
Apr 13 11:42:22 seraph pptp[25267]: nm-pptp-service-21604 log[callmgr_main:pptp_callmgr.c:245]: Closing connection (unhandled)
Apr 13 11:42:22 seraph pptp[25267]: nm-pptp-service-21604 log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 12 'Call-Clear-Request'
Apr 13 11:42:22 seraph pptp[25267]: nm-pptp-service-21604 log[call_callback:pptp_callmgr.c:84]: Closing connection (call state)
Apr 13 11:42:22 seraph pppd[25257]: Connection terminated.
Apr 13 11:42:22 seraph NetworkManager: Modem hangup
Apr 13 11:42:22 seraph NetworkManager: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Apr 13 11:42:22 seraph NetworkManager: Connection terminated.
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  VPN plugin failed: connect-failed (1)
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  (ppp0): failed to disable userspace IPv6LL address handling
Apr 13 11:42:22 seraph NetworkManager: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Apr 13 11:42:22 seraph NetworkManager: Connection terminated.
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  VPN plugin failed: connect-failed (1)
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  (ppp0): failed to disable userspace IPv6LL address handling
Apr 13 11:42:22 seraph NetworkManager: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Apr 13 11:42:22 seraph pppd[25257]: Exit.
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  VPN plugin failed: connect-failed (1)
Apr 13 11:42:22 seraph NetworkManager: ** Message: nm-pptp-ppp-plugin: (nm_exit_notify): cleaning up
Apr 13 11:42:22 seraph NetworkManager: ** (nm-pptp-service:21604): WARNING **: pppd exited with error code 16
Apr 13 11:42:22 seraph NetworkManager[1505]: <warn>  VPN plugin failed: connect-failed (1)


Attempts to reconnect then failed with "Error: Connection activation failed: unknown reason."


Retrying a few times, waiting half a minute (say) between tries eventually reconnects successfully. However, drop-outs occur again not long afterwards.



Expected results:

VPN connection should not drop out all the time.


Additional info:

Disabling BSD/Deflate/TCP header compression doesn't seem to help much, if at all. (Cf. https://askubuntu.com/questions/144456/pptp-connection-disconnect )

Doing 'sudo modprobe ip_gre' doesn't seem to help. (Cf. http://pptpclient.sourceforge.net/howto-diagnosis.phtml (

This problem does not occur when connecting to the VPN from Windows 7.


The impact of this problem is quite severe.

Comment 1 Tim Wegener 2016-04-14 01:37:43 UTC

Here's some sample output from running the following auto-reconnect shell script, to show the typical problematic drop-out / reconnection pattern:

Script:

INTERVAL=40

while true; do
    if !(nmcli con show --active | grep "$VPN_UUID" >/dev/null); then
        echo "Reconnecting VPN... (" `date` ")"
        nmcli con up "$VPN_UUID"
    fi
    sleep $INTERVAL
done



Output:

Reconnecting VPN... ( Thu Apr 14 09:03:59 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/808)
Reconnecting VPN... ( Thu Apr 14 09:08:44 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:09:26 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:10:08 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:10:49 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/813)
Reconnecting VPN... ( Thu Apr 14 09:14:54 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:15:36 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/816)
Reconnecting VPN... ( Thu Apr 14 09:16:19 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:17:00 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:17:42 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/820)
Reconnecting VPN... ( Thu Apr 14 09:21:06 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:21:47 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:22:30 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:23:11 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/825)
Reconnecting VPN... ( Thu Apr 14 09:27:56 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/827)
Reconnecting VPN... ( Thu Apr 14 09:28:39 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:29:21 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/830)
Reconnecting VPN... ( Thu Apr 14 09:33:25 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:34:07 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:34:49 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:35:30 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/835)
Reconnecting VPN... ( Thu Apr 14 09:40:55 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:41:36 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/838)
Reconnecting VPN... ( Thu Apr 14 09:45:40 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:46:21 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 09:47:03 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/842)
Reconnecting VPN... ( Thu Apr 14 09:53:08 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/844)
Reconnecting VPN... ( Thu Apr 14 09:59:53 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/846)
Reconnecting VPN... ( Thu Apr 14 10:03:57 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:04:39 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:05:21 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/850)
Reconnecting VPN... ( Thu Apr 14 10:10:45 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:11:27 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/853)
Reconnecting VPN... ( Thu Apr 14 10:16:11 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:16:52 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/856)
Reconnecting VPN... ( Thu Apr 14 10:22:17 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:22:58 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/859)
Reconnecting VPN... ( Thu Apr 14 10:28:22 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:29:04 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:29:45 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/863)
Reconnecting VPN... ( Thu Apr 14 10:35:10 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:35:52 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/866)
Reconnecting VPN... ( Thu Apr 14 10:40:36 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:41:17 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:41:59 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/870)
Reconnecting VPN... ( Thu Apr 14 10:46:03 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:46:45 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:47:26 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:48:08 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/875)
Reconnecting VPN... ( Thu Apr 14 10:52:12 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:52:54 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:53:35 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:54:17 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/880)
Reconnecting VPN... ( Thu Apr 14 10:58:21 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 10:59:03 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/883)
Reconnecting VPN... ( Thu Apr 14 10:59:46 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 11:00:27 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/886)
Reconnecting VPN... ( Thu Apr 14 11:05:12 ACST 2016 )
Error: Connection activation failed: unknown reason.
Reconnecting VPN... ( Thu Apr 14 11:05:54 ACST 2016 )
VPN connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/889)



This output shows that once successfully connected, the connection will drop out after about 3-6 minutes. It then takes a variable amount of time to reconnect, roughly 1-2 minutes.

Comment 2 Jaroslav Škarvada 2016-04-14 08:37:01 UTC

Could you try without networkmanager and provide logs (e.g. with  --loglevel 2)?

Comment 3 Tim Wegener 2016-04-20 23:34:17 UTC

I'm not really sure how to do it without networkmanager.

Here is an excerpt from /var/log/messages when running 'sudo pptp --loglevel=2 <vpn_server_ip>':

Apr 21 08:52:08 seraph pptp[14131]: anon log[main:pptp.c:333]: The synchronous pptp option is NOT activated
Apr 21 08:52:08 seraph pptp[14136]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 1 'Start-Control-Connection-Request'
Apr 21 08:52:08 seraph pptp[14136]: anon log[ctrlp_disp:pptp_ctrl.c:758]: Received Start Control Connection Reply
Apr 21 08:52:08 seraph pptp[14136]: anon log[ctrlp_disp:pptp_ctrl.c:792]: Client connection established.
Apr 21 08:52:09 seraph pptp[14136]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 7 'Outgoing-Call-Request'
Apr 21 08:52:09 seraph pptp[14136]: anon log[ctrlp_disp:pptp_ctrl.c:877]: Received Outgoing Call Reply.
Apr 21 08:52:09 seraph pptp[14136]: anon log[ctrlp_disp:pptp_ctrl.c:916]: Outgoing call established (call ID 0, peer's call ID 63841).
Apr 21 08:52:09 seraph pppd[14137]: pppd 2.4.7 started by tim, uid 0
Apr 21 08:52:09 seraph audit: <audit-1106> pid=14127 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:52:09 seraph audit: <audit-1104> pid=14127 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:52:09 seraph pppd[14137]: Using interface ppp0
Apr 21 08:52:09 seraph pppd[14137]: Connect: ppp0 <--> /dev/pts/9
Apr 21 08:52:09 seraph NetworkManager[1398]: <info>  (ppp0): new Generic device (carrier: UNKNOWN, driver: 'unknown', ifindex: 20)
Apr 21 08:52:11 seraph pptp[14143]: anon log[decaps_gre:pptp_gre.c:411]: accepting packet 0
Apr 21 08:52:11 seraph pptp[14143]: anon warn[decaps_gre:pptp_gre.c:347]: short read (-1): Protocol not available
Apr 21 08:52:11 seraph pppd[14137]: Hangup (SIGHUP)
Apr 21 08:52:11 seraph pppd[14137]: Modem hangup
Apr 21 08:52:11 seraph pppd[14137]: Connection terminated.
Apr 21 08:52:11 seraph pptp[14136]: anon log[callmgr_main:pptp_callmgr.c:245]: Closing connection (unhandled)
Apr 21 08:52:11 seraph pptp[14136]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 12 'Call-Clear-Request'
Apr 21 08:52:11 seraph pptp[14136]: anon log[call_callback:pptp_callmgr.c:84]: Closing connection (call state)
Apr 21 08:52:11 seraph NetworkManager[1398]: <warn>  (ppp0): failed to disable userspace IPv6LL address handling
Apr 21 08:52:12 seraph pppd[14137]: Exit.


And here is the log excerpt from trying that again:

Apr 21 08:53:28 seraph pptp[14166]: anon log[main:pptp.c:333]: The synchronous pptp option is NOT activated
Apr 21 08:53:28 seraph pptp[14171]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 1 'Start-Control-Connection-Request'
Apr 21 08:53:28 seraph pptp[14171]: anon log[ctrlp_disp:pptp_ctrl.c:758]: Received Start Control Connection Reply
Apr 21 08:53:28 seraph pptp[14171]: anon log[ctrlp_disp:pptp_ctrl.c:792]: Client connection established.
Apr 21 08:53:29 seraph pptp[14171]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 7 'Outgoing-Call-Request'
Apr 21 08:53:29 seraph pptp[14171]: anon log[ctrlp_disp:pptp_ctrl.c:877]: Received Outgoing Call Reply.
Apr 21 08:53:29 seraph pptp[14171]: anon log[ctrlp_disp:pptp_ctrl.c:916]: Outgoing call established (call ID 0, peer's call ID 2552).
Apr 21 08:53:29 seraph pppd[14172]: pppd 2.4.7 started by tim, uid 0
Apr 21 08:53:29 seraph pppd[14172]: Using interface ppp0
Apr 21 08:53:29 seraph pppd[14172]: Connect: ppp0 <--> /dev/pts/9
Apr 21 08:53:29 seraph audit: <audit-1106> pid=14165 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_close grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:53:29 seraph audit: <audit-1104> pid=14165 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:53:29 seraph NetworkManager[1398]: <info>  (ppp0): new Generic device (carrier: UNKNOWN, driver: 'unknown', ifindex: 21)
Apr 21 08:53:31 seraph pptp[14167]: anon log[decaps_gre:pptp_gre.c:411]: accepting packet 0
Apr 21 08:53:31 seraph pptp[14167]: anon log[decaps_gre:pptp_gre.c:411]: accepting packet 1
Apr 21 08:53:31 seraph pptp[14167]: anon log[decaps_gre:pptp_gre.c:411]: accepting packet 2
Apr 21 08:53:31 seraph pptp[14171]: anon log[ctrlp_disp:pptp_ctrl.c:932]: Received Call Clear Request.
Apr 21 08:53:33 seraph audit: <audit-1123> pid=14177 uid=1000 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='cwd="/etc" cmd=6C657373202F7661722F6C6F672F6D65737361676573 terminal=pts/1 res=success'
Apr 21 08:53:33 seraph audit: <audit-1110> pid=14177 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:setcred grantors=pam_env,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:53:33 seraph audit: <audit-1105> pid=14177 uid=0 auid=1000 ses=1 subj=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023 msg='op=PAM:session_open grantors=pam_keyinit,pam_limits,pam_keyinit,pam_limits,pam_systemd,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Apr 21 08:54:02 seraph pppd[14172]: LCP: timeout sending Config-Requests
Apr 21 08:54:02 seraph pppd[14172]: Connection terminated.
Apr 21 08:54:02 seraph NetworkManager[1398]: <warn>  (ppp0): failed to disable userspace IPv6LL address handling
Apr 21 08:54:02 seraph pptp[14171]: anon log[pptp_read_some:pptp_ctrl.c:563]: read returned zero, peer has closed
Apr 21 08:54:02 seraph pptp[14171]: anon log[callmgr_main:pptp_callmgr.c:269]: Closing connection (shutdown)
Apr 21 08:54:02 seraph pptp[14171]: anon log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 12 'Call-Clear-Request'
Apr 21 08:54:02 seraph pptp[14171]: anon log[pptp_read_some:pptp_ctrl.c:563]: read returned zero, peer has closed
Apr 21 08:54:02 seraph pptp[14171]: anon log[call_callback:pptp_callmgr.c:84]: Closing connection (call state)
Apr 21 08:54:02 seraph pppd[14172]: Hangup (SIGHUP)
Apr 21 08:54:02 seraph pppd[14172]: Modem hangup
Apr 21 08:54:02 seraph pppd[14172]: Exit.

Comment 4 Jaroslav Škarvada 2016-04-21 09:02:10 UTC

This looks like some firewalling or NAT issue, e.g.:

Apr 21 08:52:11 seraph pptp[14143]: anon warn[decaps_gre:pptp_gre.c:347]: short read (-1): Protocol not available
Apr 21 08:52:11 seraph pppd[14137]: Hangup (SIGHUP)

This can be caused by something on the way dropping the packets. You especially need port 1723 open on both server/client and enabled pass through for the protocol 47 (i.e. GRE protocol, NOT port 47). And all devices - routers, wifi APs need to correctly pass through the protocol 47.

Apr 21 08:54:02 seraph pppd[14172]: LCP: timeout sending Config-Requests
Apr 21 08:54:02 seraph pppd[14172]: Connection terminated.

This is an general error meaning that pppd didn't receive LCP configuration from the server or was unable to agree on LCP parameters.

For my test case it seems to work and I cannot get more from the logs. Try adding the following to the command:
debug dump logfd 2 nodetach

Comment 5 Fedora End Of Life 2016-07-19 19:25:23 UTC

Fedora 22 changed to end-of-life (EOL) status on 2016-07-19. Fedora 22 is
no longer maintained, which means that it will not receive any further
security or bug fix updates. As a result we are closing this bug.

If you can reproduce this bug against a currently maintained version of
Fedora please feel free to reopen this bug against that version. If you
are unable to reopen this bug, please file a new report against the
current release. If you experience problems, please add a comment to this
bug.

Thank you for reporting this bug and we are sorry it could not be fixed.