My pptp vpn connection worked fine about a month ago, but now I can’t get it to connect. When I wireshark the connection attempt, I see that it’s sending a ‘Configuration Request’ over and over again.
This is the exchange that I see:
Start Control Connection Request/Reply
Outgoing Call Request/Reply
Configuration Request (client)
Configuration Request (server)
Configuration Ack (server)
← right here the client should either Ack the Configuration, or send a ‘Configuration Reject’, then renegotiate, instead the client sends another ‘Configuration Request’ →
Configuration Request (client)
Configuration Request (server)
Configuration Ack (server)
Configuration Request (client)
Configuration Request (server)
Configuration Ack (server)
etc…
Here’s a brief description of how a pptp exchange should work: http://www.antionline.com/showthread.php?267021-PPTP-VPN-Operation
Have I run into a bug in pptp, or does pptp just handle a configuration reject strangely, and I’ve got something configured wrong? :sarcastic:
Also, I can boot into windows on the same machine, connected through the same network, and the vpn connection works fine. I wiresharked the connection in windows also, and I see the ‘Configuration Request’ exchange work normally.
edit:
Guess I should add a few details about my machine…
openSUSE 13.2 x86_64
Dell Precision M3800
i | pptp | package | 1.7.2-44.1.3 | x86_64 | openSUSE-13.2-0
i | ppp | package | 2.4.7-2.1.3 | x86_64 | openSUSE-13.2-0
Ethernet is through a usb dongle
Here’s the debug from the connection attempt:
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> Starting VPN service 'pptp'...
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> VPN service 'pptp' started (org.freedesktop.NetworkManager.pptp), PID 6760
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> VPN service 'pptp' appeared; activating connections
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> VPN connection 'Dell' (ConnectInteractive) reply received.
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> VPN plugin state changed: starting (3)
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: pppd started with pid 6763
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> VPN connection 'Dell' (Connect) reply received.
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pppd[6763]: Plugin /usr/lib64/pppd/2.4.7/nm-pptp-pppd-plugin.so loaded.
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: Plugin /usr/lib64/pppd/2.4.7/nm-pptp-pppd-plugin.so loaded.
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (plugin_init): initializing
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pppd[6763]: pppd 2.4.7 started by root, uid 0
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pppd[6763]: Using interface ppp0
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pppd[6763]: Connect: ppp0 <--> /dev/pts/4
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: Using interface ppp0
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: Connect: ppp0 <--> /dev/pts/4
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> (ppp0): new Generic device (driver: 'unknown' ifindex: 13)
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp NetworkManager[967]: <info> (ppp0): exported as /org/freedesktop/NetworkManager/Devices/12
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pptp[6765]: nm-pptp-service-6760 log[main:pptp.c:333]: The synchronous pptp option is NOT activated
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 1 'Start-Control-Connection-Request'
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_disp:pptp_ctrl.c:758]: Received Start Control Connection Reply
Aug 04 10:12:04 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_disp:pptp_ctrl.c:792]: Client connection established.
Aug 04 10:12:05 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 7 'Outgoing-Call-Request'
Aug 04 10:12:05 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_disp:pptp_ctrl.c:877]: Received Outgoing Call Reply.
Aug 04 10:12:05 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_disp:pptp_ctrl.c:916]: Outgoing call established (call ID 0, peer's call ID 15718).
Aug 04 10:12:43 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_disp:pptp_ctrl.c:931]: Received Call Clear Request.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: <warn> VPN connection 'Dell' connect timeout exceeded.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: Terminated ppp daemon with PID 6763.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pppd[6763]: Terminating on signal 15
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pppd[6763]: Modem hangup
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pppd[6763]: Connection terminated.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[callmgr_main:pptp_callmgr.c:245]: Closing connection (unhandled)
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[ctrlp_rep:pptp_ctrl.c:258]: Sent control packet type is 12 'Call-Clear-Request'
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pptp[6772]: nm-pptp-service-6760 log[call_callback:pptp_callmgr.c:84]: Closing connection (call state)
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp avahi-daemon[817]: Withdrawing workstation service for ppp0.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp dbus[827]: [system] Rejected send message, 9 matched rules; type="error", sender=":1.114" (uid=0 pid=6760 comm="/usr/lib/nm-pptp-serv
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: Terminating on signal 15
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: Modem hangup
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: Connection terminated.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pppd[6763]: Script /usr/sbin/pptp 192.41.81.77 --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-6760 finished (pid 6765), stat
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp pppd[6763]: Exit.
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp dbus[827]: [system] Rejected send message, 9 matched rules; type="error", sender=":1.114" (uid=0 pid=6760 comm="/usr/lib/nm-pptp-serv
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: Script /usr/sbin/pptp 192.41.81.77 --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-6760 finished (pid 67
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** Message: nm-pptp-ppp-plugin: (nm_exit_notify): cleaning up
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp NetworkManager[967]: ** (nm-pptp-service:6760): WARNING **: pppd exited with error code 16
Aug 04 10:12:45 SLCDMULDER.prod.quest.corp gnome-session[3280]: Gjs-Message: JS LOG: Removing a network device that was not added
Apparently I’m having the same issue described here:
https://forums.opensuse.org/showthread.php/507157-NetworkManager-pptp-doesn-t-work-after-lastest-update?p=2708116#post2708116
If I run this command:
sudo modprobe nf_conntrack_pptp
My vpn is back up and running again. Anybody know when this will be fixed?
The RH bug report (comment #15) mentions
The update to 3.19.5-200.fc21 has not made a change to this bug.
However, as noted in comment c13, the issue is resolved in user space. It only awaits a fix for FC20 and RHEL. FC21 and FC22 fixes are available.
I think it can be closed now.
Perhaps we need to raise a bugzilla bug report, mentioning the loading of the ‘nf_conntrack_pptp’ module. I wonder if NM should take care of this when a VPN connection process is started?