Bug 4749 - VPN PPTP in NetworkManager doesn't work, pppd exiting with error code
: VPN PPTP in NetworkManager doesn't work, pppd exiting with error code
Status: CONFIRMED
Product: Desktop Bugs
Classification: ROSA Desktop
Component: Main Packages
: Fresh
: x86_64 Linux
: Normal normal
: ---
Assigned To: ROSA Linux Bugs
: ROSA Linux Bugs
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2014-11-29 00:36 MSK by adam.golanski
Modified: 2016-01-26 14:29 MSK (History)
4 users (show)

See Also:
RPM Package:
ISO-related:
Bad POT generating:
Upstream:


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description adam.golanski 2014-11-29 00:36:22 MSK
Description of problem:

In fresh installation of Rose FRESH, the VPN connection defined with knetworkmanager doesn't work. Configuration is valid and working on Kubuntu 14.04 LTS, yet soon after turning it on there is a pop up "The IP config of the VPN connection was invalid" and VPN fails.

The first indication of trouble, as seen in dmesg |tail was message

Use CAP_NET_ADMIN and alias netdev-ppp0 instead

I set alias netdev-ppp0 as ppp_async in /etc/modprobe.d/00_modprobe.conf

and message has dissapeared - but problem has remained.

there is /var/log/syslog:

Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> Starting VPN service 'pptp'...
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> VPN service 'pptp' started (org.freedesktop.NetworkManager.pptp), PID 31789
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> VPN service 'pptp' appeared; activating connections
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> VPN plugin state changed: starting (3)
Nov 28 21:29:33 rosebox NetworkManager[5267]: ** Message: pppd started with pid 31790
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> VPN connection 'company pptp' (Connect) reply received.
Nov 28 21:29:33 rosebox pppd[31790]: Plugin /usr/lib64/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
Nov 28 21:29:33 rosebox NetworkManager[5267]: Plugin /usr/lib64/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
Nov 28 21:29:33 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (plugin_init): initializing
Nov 28 21:29:33 rosebox pppd[31790]: pppd 2.4.7 started by root, uid 0
Nov 28 21:29:33 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Nov 28 21:29:33 rosebox pppd[31790]: Using interface ppp0
Nov 28 21:29:33 rosebox pppd[31790]: Connect: ppp0 <--> /dev/pts/4
Nov 28 21:29:33 rosebox NetworkManager[5267]: Using interface ppp0
Nov 28 21:29:33 rosebox NetworkManager[5267]: Connect: ppp0 <--> /dev/pts/4
Nov 28 21:29:33 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
Nov 28 21:29:33 rosebox pptp[31792]: nm-pptp-service-31789 log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Nov 28 21:29:33 rosebox systemd-sysctl[31801]: Overwriting earlier assignment of kernel/sysrq in file '/etc/sysctl.d/51-alt-sysrq.conf'.
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> (ppp0): new Generic device (driver: 'unknown' ifindex: 16)
Nov 28 21:29:33 rosebox NetworkManager[5267]: <info> (ppp0): exported as /org/freedesktop/NetworkManager/Devices/15
Nov 28 21:29:33 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Nov 28 21:29:33 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Nov 28 21:29:33 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Nov 28 21:29:33 rosebox vnstatd[22404]: Interface "ppp0" enabled.
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 28723).
Nov 28 21:29:34 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_disp:pptp_ctrl.c:953]:   send_accm is 00000000, recv_accm is FFFFFFFF
Nov 28 21:29:34 rosebox pptp[31811]: nm-pptp-service-31789 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Nov 28 21:29:34 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
Nov 28 21:29:34 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (get_credentials): got credentials from NetworkManager-pptp
Nov 28 21:29:34 rosebox pppd[31790]: CHAP authentication succeeded
Nov 28 21:29:34 rosebox NetworkManager[5267]: CHAP authentication succeeded
Nov 28 21:29:34 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
Nov 28 21:29:34 rosebox pppd[31790]: MPPE 128-bit stateless compression enabled
Nov 28 21:29:34 rosebox NetworkManager[5267]: MPPE 128-bit stateless compression enabled
Nov 28 21:29:36 rosebox pppd[31790]: local  IP address 194.0.171.23
Nov 28 21:29:36 rosebox pppd[31790]: remote IP address 194.0.171.17
Nov 28 21:29:36 rosebox pppd[31790]: primary   DNS address 194.0.171.137
Nov 28 21:29:36 rosebox pppd[31790]: secondary DNS address 194.0.171.138
Nov 28 21:29:36 rosebox NetworkManager[5267]: local  IP address 194.0.171.23
Nov 28 21:29:36 rosebox NetworkManager[5267]: remote IP address 194.0.171.17
Nov 28 21:29:36 rosebox NetworkManager[5267]: primary   DNS address 194.0.171.137
Nov 28 21:29:36 rosebox NetworkManager[5267]: secondary DNS address 194.0.171.138
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_ip_up): ip-up event
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_ip_up): sending Ip4Config to NetworkManager-pptp...
Nov 28 21:29:36 rosebox NetworkManager[5267]: <info> VPN connection 'company pptp' (IP4 Config Get) reply received from old-style plugin.
Nov 28 21:29:36 rosebox NetworkManager[5267]: <info>   Internal DNS: 194.0.171.138
Nov 28 21:29:36 rosebox NetworkManager[5267]: <info>   DNS Domain: '(none)'
Nov 28 21:29:36 rosebox NetworkManager[5267]: <info> No IPv6 configuration
Nov 28 21:29:36 rosebox NetworkManager[5267]: <error> [1417206576.735184] [platform/nm-linux-platform.c:1714] add_object(): Netlink error adding 194.0.171.128/25 via 194.0.171.3 dev ppp0 metric 1 mss 0 src user: Unspecific failure
Nov 28 21:29:36 rosebox NetworkManager[5267]: <warn> VPN connection 'company pptp' did not receive valid IP config information.
Nov 28 21:29:36 rosebox pppd[31790]: Terminating on signal 15
Nov 28 21:29:36 rosebox pppd[31790]: Modem hangup
Nov 28 21:29:36 rosebox pppd[31790]: Connect time 0.0 minutes.
Nov 28 21:29:36 rosebox pppd[31790]: Sent 0 bytes, received 0 bytes.
Nov 28 21:29:36 rosebox pptp[31811]: nm-pptp-service-31789 log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
Nov 28 21:29:36 rosebox pptp[31811]: nm-pptp-service-31789 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Nov 28 21:29:36 rosebox pptp[31811]: nm-pptp-service-31789 log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Nov 28 21:29:36 rosebox pppd[31790]: MPPE disabled
Nov 28 21:29:36 rosebox pppd[31790]: Connection terminated.
Nov 28 21:29:36 rosebox avahi-daemon[5275]: Withdrawing workstation service for ppp0.
Nov 28 21:29:36 rosebox NetworkManager[5267]: <error> [1417206576.736732] [platform/nm-linux-platform.c:1775] delete_object(): Netlink error deleting 194.0.171.23/32 lft forever pref forever lifetime 5189-0[4294967295,4294967295] dev ppp0 src kernel: No such device (-31)
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: PPTP service (IP Config Get) reply received.
Nov 28 21:29:36 rosebox NetworkManager[5267]: Application BUG: Your application must call nl_cache_mngt_provide() and
Nov 28 21:29:36 rosebox NetworkManager[5267]: provide a valid route/link cache to be used for internal lookups.
Nov 28 21:29:36 rosebox NetworkManager[5267]: See the  API documentation for more details.
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Nov 28 21:29:36 rosebox systemd[1]: Stopping vnStat - a lightweight network traffic monitor...
Nov 28 21:29:36 rosebox vnstatd[22404]: SIGTERM received, exiting.
Nov 28 21:29:36 rosebox systemd[1]: Starting vnStat - a lightweight network traffic monitor...
Nov 28 21:29:36 rosebox systemd[1]: Started vnStat - a lightweight network traffic monitor.
Nov 28 21:29:36 rosebox vnstatd[31860]: Monitoring: ppp0
Nov 28 21:29:36 rosebox vnstatd[31860]: Error: Unable to get interface "ppp0" statistics.
Nov 28 21:29:36 rosebox vnstatd[31860]: Interface "ppp0" not available, disabling.
Nov 28 21:29:36 rosebox postfix/postqueue[31871]: warning: unable to look up public/qmgr: No such file or directory
Nov 28 21:29:36 rosebox postfix/postqueue[31871]: fatal: Cannot flush mail queue - mail system is down
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** Message: nm-pptp-ppp-plugin: (nm_exit_notify): cleaning up
Nov 28 21:29:36 rosebox pppd[31790]: Exit.
Nov 28 21:29:36 rosebox NetworkManager[5267]: ** (nm-pptp-service:31789): WARNING **: pppd exited with error code 16



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

networkmanager 0.9.10.0-4-rosa2014.1
networkmanager-pptp 0.9.10.0-1-rosa2014.1


How reproducible:

Everytime I try to use VPN PPTP, 


Steps to Reproduce:
1. set up vpn pptp configuration with additional route only for this connection
2. turn on VPN connection
3. see the connection fail
Comment 1 Andrey Ponomarenko 2014-12-02 00:56:40 MSK
Please upload your hardware details to our database, so that we can debug the problem:

$> su
$> hw-probe -all -upload -id PC_NAME

where PC_NAME - any name of your PC.

More info about hardware database: http://wiki.rosalab.ru/en/index.php/Blog:ROSA_Planet/ROSA_Hardware_DB

Thank you!
Comment 2 adam.golanski 2014-12-02 01:45:57 MSK
Спасибо за быстрый ответ :)

http://hw.rosalinux.ru/index.php?probe=4a9a982a1f
Comment 3 Andrey Bondrov 2014-12-02 12:50:26 MSK
This bug was already reported upstream and there's a solution (or workaround): https://bugzilla.gnome.org/show_bug.cgi?id=733296#c4
Comment 4 adam.golanski 2014-12-03 03:19:31 MSK
Thanks, it seems definitely upstream problem. The workaround works, yet the usability is low - after using it, NetworkManager doesn't allow traffic outside beyond VPN, so you have to constantly switch on/off connection.
Comment 5 Denis Silakov 2016-01-26 14:29:48 MSK
The fix was finally committed upstream a couple of weeks ago. Let's wait for the next NetworkManager release.