Bug 1326542 - Frequent PPTP VPN drop-outs with log message: short read (-1): Protocol not available
Summary: Frequent PPTP VPN drop-outs with log message: short read (-1): Protocol not a...
Keywords:
Status: CLOSED EOL
Alias: None
Product: Fedora
Classification: Fedora
Component: pptp
Version: 22
Hardware: Unspecified
OS: Unspecified
unspecified
high
Target Milestone: ---
Assignee: Jaroslav Škarvada
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2016-04-13 02:21 UTC by Tim Wegener
Modified: 2016-07-19 19:25 UTC (History)
2 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2016-07-19 19:25:23 UTC
Type: Bug


Attachments (Terms of Use)

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.


Note You need to log in before you can comment on or make changes to this bug.