opensuse 13.2 pptp crashes after successful connection

Hi eveyone, i’m having a problem connecting to my pptp vpn…

here’s the log…

2014-12-19T00:07:43.027223-02:00 UYMV00630 pppd[8298]: Plugin /usr/lib64/pppd/2.4.7/nm-pptp-pppd-plugin.so loaded.
2014-12-19T00:07:43.029560-02:00 UYMV00630 pppd[8298]: pppd 2.4.7 started by root, uid 0
2014-12-19T00:07:43.031113-02:00 UYMV00630 pppd[8298]: Using interface ppp0
2014-12-19T00:07:43.031529-02:00 UYMV00630 pppd[8298]: Connect: ppp0 <–> /dev/pts/6
2014-12-19T00:07:43.054930-02:00 UYMV00630 pptp[8300]: nm-pptp-service-8297 log[main: pptp.c:314]: The synchronous pptp option is NOT activated
2014-12-19T00:07:43.098132-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_rep: pptp_ctrl.c:251]: Sent control packet type is 1 ‘Start-Control-Connection-Request’
2014-12-19T00:07:43.135164-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:739]: Received Start Control Connection Reply
2014-12-19T00:07:43.135444-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:773]: Client connection established.
2014-12-19T00:07:44.098474-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_rep: pptp_ctrl.c:251]: Sent control packet type is 7 ‘Outgoing-Call-Request’
2014-12-19T00:07:44.245732-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:858]: Received Outgoing Call Reply.
2014-12-19T00:07:44.246086-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer’s call ID 53518).
2014-12-19T00:07:44.345209-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
2014-12-19T00:07:44.345452-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_disp: pptp_ctrl.c:953]: send_accm is 00000000, recv_accm is FFFFFFFF
2014-12-19T00:07:44.345628-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 warn[ctrlp_disp: pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
2014-12-19T00:07:44.408454-02:00 UYMV00630 pppd[8298]: CHAP authentication succeeded
2014-12-19T00:07:44.545199-02:00 UYMV00630 pppd[8298]: local IP address 10.64.33.5
2014-12-19T00:07:44.545605-02:00 UYMV00630 pppd[8298]: remote IP address 10.64.33.51
2014-12-19T00:07:44.558157-02:00 UYMV00630 pppd[8298]: Terminating on signal 15
2014-12-19T00:07:44.559341-02:00 UYMV00630 pppd[8298]: Connect time 0.0 minutes.
2014-12-19T00:07:44.559805-02:00 UYMV00630 pppd[8298]: Sent 0 bytes, received 0 bytes.
2014-12-19T00:07:44.560164-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[callmgr_main: pptp_callmgr.c:234]: Closing connection (unhandled)
2014-12-19T00:07:44.560310-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[ctrlp_rep: pptp_ctrl.c:251]: Sent control packet type is 12 ‘Call-Clear-Request’
2014-12-19T00:07:44.560432-02:00 UYMV00630 pptp[8307]: nm-pptp-service-8297 log[call_callback: pptp_callmgr.c:79]: Closing connection (call state)
2014-12-19T00:07:44.560534-02:00 UYMV00630 dbus[1018]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.191" (uid=0 pid=8297 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.192" (uid=0 pid=8298 comm="/usr/sbin/pppd pty /usr/sbin/pptp REMOVED_FOR_SECURITY --n")
2014-12-19T00:07:44.562594-02:00 UYMV00630 pppd[8298]: Script /usr/sbin/pptp REMOVED_FOR_SECURITY --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-8297 finished (pid 8300), status = 0x0
2014-12-19T00:07:44.562907-02:00 UYMV00630 dbus[1018]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.191" (uid=0 pid=8297 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.192" (uid=0 pid=8298 comm="/usr/sbin/pppd pty /usr/sbin/pptp REMOVED_FOR_SECURITY --n")
2014-12-19T00:07:44.563313-02:00 UYMV00630 pppd[8298]: Modem hangup
2014-12-19T00:07:44.563568-02:00 UYMV00630 pppd[8298]: Connection terminated.
2014-12-19T00:07:44.563728-02:00 UYMV00630 dbus[1018]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.191" (uid=0 pid=8297 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.192" (uid=0 pid=8298 comm="/usr/sbin/pppd pty /usr/sbin/pptp REMOVED_FOR_SECURITY --n")
2014-12-19T00:07:44.563884-02:00 UYMV00630 avahi-daemon[1002]: Withdrawing workstation service for ppp0.
2014-12-19T00:07:44.564125-02:00 UYMV00630 dbus[1018]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.191" (uid=0 pid=8297 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.192" (uid=0 pid=8298 comm="/usr/sbin/pppd pty /usr/sbin/pptp REMOVED_FOR_SECURITY --n")
2014-12-19T00:07:44.576164-02:00 UYMV00630 pppd[8298]: Exit.
2014-12-19T00:07:44.579421-02:00 UYMV00630 org.freedesktop.Telepathy.AccountManager[2152]: (process:2774): libnm-glib-WARNING **: async_got_type: could not read properties for /org/freedesktop/NetworkManager/ActiveConnection/34: Method “Get” with signature “ss” on interface “org.freedesktop.DBus.Properties” doesn’t exist

2014-12-19T00:07:44.558157-02:00 UYMV00630 pppd[8298]: Terminating on signal 15

If you Google the above two important results will be returned…

  • This is an intentional kill. This generally points to a mis-configuration, and especially in your case because you say that the PPTP session consistently is killed immediately and not after some random amount of time.
  • The first returned result on Ubuntu is a good candidate, see whether it fixes your issue. If so, report your result here <and> submit a but at http://bugzilla.opensuse.org
    http://ubuntuforums.org/showthread.php?t=1177021

I personally fixed it by changing /lib/udev/rules.d/85-ifupdown.rules by replacing “–allow auto” with “–allow hotplug”.

HTH,
TSU

that rule file doesn’t exist.
i’ve searched (with grep) on all existing .rules files but none of them has that line…
i’ve uninstalled and reinstalled all packages concerning pptp pppd , (also rebooted :slight_smile: ) but problem keeps happening.

i’ve set debug option on pppd config file…
this is the output

2014-12-19T20:24:40.301624-02:00 UYMV00630 pppd[17143]: Plugin /usr/lib64/pppd/2.4.7/nm-pptp-pppd-plugin.so loaded.
2014-12-19T20:24:40.305835-02:00 UYMV00630 pppd[17143]: pppd 2.4.7 started by root, uid 0
2014-12-19T20:24:40.306127-02:00 UYMV00630 pppd[17143]: using channel 26
2014-12-19T20:24:40.306341-02:00 UYMV00630 pppd[17143]: Using interface ppp0
2014-12-19T20:24:40.306553-02:00 UYMV00630 pppd[17143]: Connect: ppp0 <–> /dev/pts/4
2014-12-19T20:24:40.308223-02:00 UYMV00630 pptp[17145]: nm-pptp-service-17142 log[main:pptp.c:314]: The synchronous pptp option is NOT activated
2014-12-19T20:24:40.940629-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 ‘Start-Control-Connection-Request’
2014-12-19T20:24:41.070842-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
2014-12-19T20:24:41.071168-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
2014-12-19T20:24:41.306310-02:00 UYMV00630 pppd[17143]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xf078fdec> <pcomp> <accomp>]
2014-12-19T20:24:41.940778-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 ‘Outgoing-Call-Request’
2014-12-19T20:24:42.090917-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
2014-12-19T20:24:42.091229-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer’s call ID 31925).
2014-12-19T20:24:42.201066-02:00 UYMV00630 pppd[17143]: rcvd [LCP ConfReq id=0x0 <mru 1400> <auth chap MS-v2> <magic 0x5f85662e> <pcomp> <accomp> <callback CBCP> <mrru 1614> <endpoint [local:46.76.51.39.94.50.4f.48.af.ca.ce.e3.c7.cd.e1.26.00.00.00.00]>]
2014-12-19T20:24:42.216276-02:00 UYMV00630 pppd[17143]: sent [LCP ConfRej id=0x0 <callback CBCP> <mrru 1614>]
2014-12-19T20:24:42.216610-02:00 UYMV00630 pppd[17143]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xf078fdec> <pcomp> <accomp>]
2014-12-19T20:24:42.330864-02:00 UYMV00630 pppd[17143]: rcvd [LCP ConfReq id=0x1 <mru 1400> <auth chap MS-v2> <magic 0x5f85662e> <pcomp> <accomp> <endpoint [local:46.76.51.39.94.50.4f.48.af.ca.ce.e3.c7.cd.e1.26.00.00.00.00]>]
2014-12-19T20:24:42.331138-02:00 UYMV00630 pppd[17143]: sent [LCP ConfAck id=0x1 <mru 1400> <auth chap MS-v2> <magic 0x5f85662e> <pcomp> <accomp> <endpoint [local:46.76.51.39.94.50.4f.48.af.ca.ce.e3.c7.cd.e1.26.00.00.00.00]>]
2014-12-19T20:24:42.338151-02:00 UYMV00630 pppd[17143]: sent [LCP EchoReq id=0x0 magic=0xf078fdec]
2014-12-19T20:24:42.440943-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 43313
2014-12-19T20:24:42.450315-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is 00000000, recv_accm is FFFFFFFF
2014-12-19T20:24:42.450689-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
2014-12-19T20:24:42.450907-02:00 UYMV00630 pppd[17143]: rcvd [CHAP Challenge id=0x0 <ea5bf579c915e09e18bd8522c91a398c>, name = “LAMMVT-004-VM”]
2014-12-19T20:24:42.451086-02:00 UYMV00630 pppd[17143]: added response cache entry 0
2014-12-19T20:24:42.451229-02:00 UYMV00630 pppd[17143]: sent [CHAP Response id=0x0 <1de09151a4fd25f707846bc0bc11c03a00000000000000006f40ad0c3e7ce5f17fdf6cc68d3ece891d291b61ec6aebcd00>, name = “SITES-LAM-BULL\saravia-p”]
2014-12-19T20:24:42.451370-02:00 UYMV00630 pppd[17143]: rcvd [LCP EchoRep id=0x0 magic=0x5f85662e]
2014-12-19T20:24:42.600986-02:00 UYMV00630 pppd[17143]: rcvd [CHAP Success id=0x0 “S=026F7F08A17A5A4D55CEA1FAC7DF651777E0D003”]
2014-12-19T20:24:42.601313-02:00 UYMV00630 pppd[17143]: response found in cache (entry 0)
2014-12-19T20:24:42.601521-02:00 UYMV00630 pppd[17143]: CHAP authentication succeeded
2014-12-19T20:24:42.601679-02:00 UYMV00630 pppd[17143]: sent [CCP ConfReq id=0x1 <mppe +H -M +S +L -D -C>]
2014-12-19T20:24:42.610192-02:00 UYMV00630 pppd[17143]: rcvd [CCP ConfReq id=0x3 <mppe +H -M -S -L -D +C>]
2014-12-19T20:24:42.610475-02:00 UYMV00630 pppd[17143]: sent [CCP ConfNak id=0x3 <mppe +H -M +S +L -D -C>]
2014-12-19T20:24:42.611184-02:00 UYMV00630 pppd[17143]: rcvd [IPCP ConfReq id=0x4 <addr 10.64.33.51>]
2014-12-19T20:24:42.611353-02:00 UYMV00630 pppd[17143]: sent [IPCP TermAck id=0x4]
2014-12-19T20:24:42.690877-02:00 UYMV00630 pppd[17143]: rcvd [CCP ConfNak id=0x1 <mppe +H -M +S -L -D -C>]
2014-12-19T20:24:42.691169-02:00 UYMV00630 pppd[17143]: sent [CCP ConfReq id=0x2 <mppe +H -M +S -L -D -C>]
2014-12-19T20:24:42.700191-02:00 UYMV00630 pppd[17143]: rcvd [CCP ConfReq id=0x5 <mppe +H -M +S -L -D -C>]
2014-12-19T20:24:42.700478-02:00 UYMV00630 pppd[17143]: sent [CCP ConfAck id=0x5 <mppe +H -M +S -L -D -C>]
2014-12-19T20:24:42.780741-02:00 UYMV00630 pppd[17143]: rcvd [CCP ConfAck id=0x2 <mppe +H -M +S -L -D -C>]
2014-12-19T20:24:42.781028-02:00 UYMV00630 pppd[17143]: MPPE 128-bit stateless compression enabled
2014-12-19T20:24:42.781198-02:00 UYMV00630 pppd[17143]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
2014-12-19T20:24:42.870827-02:00 UYMV00630 pppd[17143]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
2014-12-19T20:24:42.871391-02:00 UYMV00630 pppd[17143]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
2014-12-19T20:24:42.962913-02:00 UYMV00630 pppd[17143]: rcvd [IPCP ConfNak id=0x2 <addr 10.64.33.5>]
2014-12-19T20:24:42.963169-02:00 UYMV00630 pppd[17143]: sent [IPCP ConfReq id=0x3 <addr 10.64.33.5>]
2014-12-19T20:24:43.050695-02:00 UYMV00630 pppd[17143]: rcvd [IPCP ConfAck id=0x3 <addr 10.64.33.5>]
2014-12-19T20:24:44.211605-02:00 UYMV00630 pppd[17143]: rcvd [IPCP ConfReq id=0x6 <addr 10.64.33.51>]
2014-12-19T20:24:44.211952-02:00 UYMV00630 pppd[17143]: sent [IPCP ConfAck id=0x6 <addr 10.64.33.51>]
2014-12-19T20:24:44.212157-02:00 UYMV00630 pppd[17143]: local IP address 10.64.33.5
2014-12-19T20:24:44.212364-02:00 UYMV00630 pppd[17143]: remote IP address 10.64.33.51
2014-12-19T20:24:44.257865-02:00 UYMV00630 pppd[17143]: Terminating on signal 15
2014-12-19T20:24:44.258098-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
2014-12-19T20:24:44.258179-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 ‘Call-Clear-Request’
2014-12-19T20:24:44.258255-02:00 UYMV00630 pptp[17152]: nm-pptp-service-17142 log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
2014-12-19T20:24:44.258325-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.258536-02:00 UYMV00630 pppd[17143]: Connect time 0.0 minutes.
2014-12-19T20:24:44.258756-02:00 UYMV00630 pppd[17143]: Sent 0 bytes, received 0 bytes.
2014-12-19T20:24:44.258975-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.259182-02:00 UYMV00630 pppd[17143]: MPPE disabled
2014-12-19T20:24:44.259403-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.259868-02:00 UYMV00630 pppd[17143]: sent [LCP TermReq id=0x2 “MPPE disabled”]
2014-12-19T20:24:44.260822-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.261061-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.272175-02:00 UYMV00630 pppd[17143]: sent [LCP TermReq id=0x3 “MPPE disabled”]
2014-12-19T20:24:44.272509-02:00 UYMV00630 pppd[17143]: Script /usr/sbin/pptp HIDDEN IP ADDRESS --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-17142 finished (pid 17145), status = 0x0
2014-12-19T20:24:44.272736-02:00 UYMV00630 pppd[17143]: Modem hangup
2014-12-19T20:24:44.272942-02:00 UYMV00630 pppd[17143]: Connection terminated.
2014-12-19T20:24:44.273145-02:00 UYMV00630 avahi-daemon[14978]: Withdrawing workstation service for ppp0.
2014-12-19T20:24:44.273358-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:24:44.303802-02:00 UYMV00630 pppd[17143]: Exit.
2014-12-19T20:24:44.303906-02:00 UYMV00630 dbus[14921]: [system] Rejected send message, 9 matched rules; type=“error”, sender=":1.63" (uid=0 pid=17142 comm="/usr/lib/nm-pptp-service “) interface=”(unset)" member="(unset)" error name=“org.freedesktop.DBus.Error.UnknownMethod” requested_reply=“0” destination=":1.64" (uid=0 pid=17143 comm="/usr/sbin/pppd pty /usr/sbin/pptp HIDDEN IP ADDRESS --n")
2014-12-19T20:28:19.469749-02:00 UYMV00630 dbus[14921]: [system] Activating via systemd: service name=‘org.freedesktop.PackageKit’ unit=‘packagekit.service’
2014-12-19T20:28:19.736543-02:00 UYMV00630 PackageKit: daemon start
2014-12-19T20:28:20.386272-02:00 UYMV00630 dbus[14921]: [system] Successfully activated service ‘org.freedesktop.PackageKit’
2014-12-19T20:28:20.481719-02:00 UYMV00630 PackageKit: uid 1000 is trying to obtain org.freedesktop.packagekit.system-sources-refresh auth (only_trusted:0)
2014-12-19T20:28:20.487181-02:00 UYMV00630 PackageKit: uid 1000 obtained auth
2014-12-19T20:28:21.744048-02:00 UYMV00630 PackageKit: refresh-cache transaction /1_ccadddcb from uid 1000 finished with success after 1241ms
2014-12-19T20:28:24.522034-02:00 UYMV00630 PackageKit: get-updates transaction /2_beaadeab from uid 1000 finished with success after 2665ms
2014-12-19T20:28:26.199419-02:00 UYMV00630 PackageKit: get-updates transaction /3_dbacdcbe from uid 1000 finished with success after 1675ms
2014-12-19T20:28:26.279009-02:00 UYMV00630 PackageKit: get-distro-upgrades transaction /4_bccaeceb from uid 1000 finished with success after 78ms
2014-12-19T20:28:45.387092-02:00 UYMV00630 PackageKit: daemon quit
2014-12-19T20:30:02.085167-02:00 UYMV00630 cron[17245]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-12-19T20:30:02.547924-02:00 UYMV00630 CRON[17245]: pam_unix(crond:session): session closed for user root

as it shows:

2014-12-19T20:24:44.257865-02:00 UYMV00630 pppd[17143]: Terminating on signal 15

2014-12-19T20:24:44.258536-02:00 UYMV00630 pppd[17143]: Connect time 0.0 minutes.

this started happening after a system upgrade, yesterday i’ve upgraded from 13.1 to 13.2, and before that, it was working fine (it’s my job’s VPN, i use it everyday)

i managed to do a strace to pptp when i try to connect vpn to my work.
strace output…

UYMV00630:~ # cat /tmp/pptplog
execve("/usr/sbin/pptp.ori", “/usr/sbin/pptp.ori”, “200.40.166.4”, “–nolaunchpppd”, “–loglevel”, “0”, “–logstring”, “nm-pptp-service-15266”], /* 7 vars /]) = 0
brk(0) = 0xc99000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b285000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=242625, …}) = 0
mmap(NULL, 242625, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f214b249000
close(3) = 0
open("/lib64/libutil.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, “\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\17\0\0\0\0\0\0”…, 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=14214, …}) = 0
mmap(NULL, 2105624, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f214ae63000
mprotect(0x7f214ae65000, 2093056, PROT_NONE) = 0
mmap(0x7f214b064000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x7f214b064000
close(3) = 0
open("/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3, “\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\34\2\0\0\0\0\0”…, 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=1978611, …}) = 0
mmap(NULL, 3832352, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f214aabb000
mprotect(0x7f214ac59000, 2097152, PROT_NONE) = 0
mmap(0x7f214ae59000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19e000) = 0x7f214ae59000
mmap(0x7f214ae5f000, 14880, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f214ae5f000
close(3) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b248000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b247000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b246000
arch_prctl(ARCH_SET_FS, 0x7f214b247700) = 0
mprotect(0x7f214ae59000, 16384, PROT_READ) = 0
mprotect(0x7f214b064000, 4096, PROT_READ) = 0
mprotect(0x60d000, 4096, PROT_READ) = 0
mprotect(0x7f214b286000, 4096, PROT_READ) = 0
munmap(0x7f214b249000, 242625) = 0
brk(0) = 0xc99000
brk(0xcba000) = 0xcba000
getpid() = 15276
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=842, …}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b284000
read(3, “### /etc/resolv.conf file autoge”…, 4096) = 842
read(3, “”, 4096) = 0
close(3) = 0
munmap(0x7f214b284000, 4096) = 0
open("/etc/localtime", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=2160, …}) = 0
fstat(3, {st_mode=S_IFREG|0644, st_size=2160, …}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f214b284000
read(3, “TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0 \0\0\0 \0\0\0\0”…, 4096) = 2160
lseek(3, -1361, SEEK_CUR) = 799
read(3, “TZif2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0 \0\0\0 \0\0\0\0”…, 4096) = 1361
close(3) = 0
munmap(0x7f214b284000, 4096) = 0
socket(PF_LOCAL, SOCK_DGRAM|SOCK_CLOEXEC, 0) = 3
connect(3, {sa_family=AF_LOCAL, sun_path="/dev/log"}, 110) = 0
sendto(3, “<29>Dec 19 22:33:56 pptp[15276]:”…, 122, MSG_NOSIGNAL, NULL, 0) = 122
socket(PF_INET, SOCK_RAW, IPPROTO_GRE) = 4
connect(4, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr(“200.40.166.4”)}, 16) = 0
close(1) = 0
socket(PF_LOCAL, SOCK_STREAM, 0) = 1
connect(1, {sa_family=AF_LOCAL, sun_path="/var/run/pptp/255.255.255.255:200.40.166.4"}, 110) = -1 ENOENT (No such file or directory)
unlink("/var/run/pptp/255.255.255.255:200.40.166.4") = -1 ENOENT (No such file or directory)
clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD, child_tidptr=0x7f214b2479d0) = 15277
wait4(15277, {WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 15277
— SIGCHLD {si_signo=SIGCHLD, si_code=CLD_EXITED, si_pid=15277, si_status=0, si_utime=0, si_stime=0} —
rt_sigprocmask(SIG_BLOCK, [CHLD], ], 8) = 0
rt_sigaction(SIGCHLD, NULL, {SIG_DFL, ], 0}, 8) = 0
rt_sigprocmask(SIG_SETMASK, ], NULL, 8) = 0
nanosleep({1, 0}, 0x7fff97bf6890) = 0
connect(1, {sa_family=AF_LOCAL, sun_path="/var/run/pptp/255.255.255.255:200.40.166.4"}, 110) = 0
write(1, “\0\0\0\0”, 4) = 4
write(1, “\254;\0\0”, 4) = 4
read(1, “\0\0”, 2) = 2
read(1, “+\245”, 2) = 2
open("/dev/null", O_WRONLY|O_CREAT|O_TRUNC, 0666) = 5
dup2(5, 2) = 2
close(5) = 0
ioctl(0, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, {B38400 -opost -isig -icanon -echo …}) = 0
fstat(0, {st_mode=S_IFCHR|0666, st_rdev=makedev(5, 2), …}) = 0
readlink("/proc/self/fd/0", “/dev/ptmx”, 4095) = 9
stat("/dev/ptmx", {st_mode=S_IFCHR|0666, st_rdev=makedev(5, 2), …}) = 0
prctl(PR_SET_NAME, 0x40965d, 0, 0, 0) = 0
rt_sigprocmask(SIG_BLOCK, NULL, ], 8) = 0
rt_sigaction(SIGINT, {0x402820, [INT], SA_RESTORER|SA_RESTART, 0x7f214aaf0200}, {SIG_DFL, ], 0}, 8) = 0
rt_sigaction(SIGTERM, {0x402820, [TERM], SA_RESTORER|SA_RESTART, 0x7f214aaf0200}, {SIG_DFL, ], 0}, 8) = 0
rt_sigaction(SIGKILL, {0x402820, [KILL], SA_RESTORER|SA_RESTART, 0x7f214aaf0200}, {SIG_DFL, ], 0}, 8) = -1 EINVAL (Invalid argument)
rt_sigaction(SIGCHLD, {0x402820, [CHLD], SA_RESTORER|SA_RESTART, 0x7f214aaf0200}, {SIG_DFL, ], 0}, 8) = 0
rt_sigaction(SIGUSR1, {0x402840, [USR1], SA_RESTORER|SA_RESTART, 0x7f214aaf0200}, {SIG_DFL, ], 0}, 8) = 0
select(5, [0 4], NULL, NULL, NULL) = 1 (in [0])
read(0, “~\377}#\300!}!}!} }4}”}&} } } } }%}&\324\312"…, 8196) = 46
write(4, “0\1\210\v\0\30\245+\0\0\0\1\377\3\300!\1\1\0\24\2\6\0\0\0\0\5\6\324\3123\7”…, 36) = 36
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0]0M\0\0n/\3015\310(\246\4\300\250+\3720\201\210\v\0009\0\0\0\0\0\0”…, 8260) = 93
write(0, “~\377}#\300!}!} } 5}!}$}%x}#}%\302#\201}%}&}”…, 91) = 91
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \00080N\0\0n/\301Y\310(\246\4\300\250+\3720\1\210\v\0\30\0\0\0\0\0\1”…, 8260) = 56
write(0, “~\377}#\300!}”}!} }4}"}&} } } } }%}&\324\312"…, 46) = 46
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499996})
read(0, “~\377}#\300!}$} } }+}-}#}&}1}$}&N\250\375~”, 8196) = 30
write(4, “0\201\210\v\0\17\245+\0\0\0\2\0\0\0\1\377\3\300!\4\0\0\v\r\3\6\21\4\6N”, 31) = 31
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0V0O\0\0n/\301:\310(\246\4\300\250+\3720\201\210\v\0002\0\0\0\0\0\2”…, 8260) = 86
write(0, “~\377}#\300!}!}!} .}!}$}%x}#}%\302#\201}%}&}”…, 77) = 77
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499288})
read(0, “~\377}#\300!}”}!} .}!}$}%x}#}%\302#\201}%}&}"…, 8196) = 97
write(4, “0\201\210\v\0002\245+\0\0\0\3\0\0\0\2\377\3\300!\2\1\0.\1\4\5x\3\5\302#”…, 66) = 66
write(4, “0\1\210\v\0
\245+\0\0\0\4\300! \0\0\10\324\3123\7”, 22) = 22
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0H0Q\0\0n/\301F\310(\246\4\300\250+\3720\201\210\v\0$\0\0\0\0\0\3”…, 8260) = 72
write(0, "~\302#}!} } “}0G|T\200}5\311\261b{z} \331^\360\314LA”…, 47) = 47
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [4], left {0, 499639})
read(4, "E \0
0R\0\0n/\301c\310(\246\4\300\250+\3720\1\210\v\0
\0\0\0\0\0\4"…, 8260) = 42
write(0, “~\300!}} } }(}>>Q\305}:\366~", 20) = 20
select(5, [0 4], NULL, NULL, {0, 1}) = 0 (Timeout)
write(4, " \201\210\v\0\0\245+\0\0\0\4", 12) = 12
select(5, [0 4], NULL, NULL, NULL) = 1 (in [0])
read(0, “\302#}”} } N1\247\230.J/\373,H}^\215\221\364}9\252\355}&} }"…, 8196) = 100
write(4, “0\1\210\v\0P\245+\0\0\0\5\302#\2\0\0N1\247\230.J/\373,H~\215\221\364\31”…, 92) = 92
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0 0S\0\0n/\301l\310(\246\4\300\250+\372 \201\210\v\0\0\0\0\0\0\0\5”, 8260) = 32
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0P0T\0\0n/\301;\310(\246\4\300\250+\3720\1\210\v\0000\0\0\0\0\0\5”…, 8260) = 80
write(0, “~\302#}#} } .S=383D8C64C5D71BD27E13”…, 55) = 55
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [4], left {0, 499884})
read(4, “E \0,0U\0\0n/\301^\310(\246\4\300\250+\3720\1\210\v\0\f\0\0\0\0\0\6”…, 8260) = 44
write(0, "~\200\375}!}#} }
}2}&}!} } }!\232\332~”, 26) = 26
select(5, [0 4], NULL, NULL, {0, 1}) = 0 (Timeout)
write(4, " \201\210\v\0\0\245+\0\0\0\6", 12) = 12
select(5, [0 4], NULL, NULL, NULL) = 2 (in [0 4])
read(0, “\200\375}!}!} }}2}&}!} } \3573~\200\375}#}#} "..., 8196) = 48 write(4, "0\1\210\v\0\f\245+\0\0\0\6\200\375\1\1\0 \22\6\1\0\0“, 24) = 24
write(4, “0\1\210\v\0\f\245+\0\0\0\7\200\375\3\3\0
\22\6\1\0\0", 24) = 24 read(4, "E \0,0V\0\0n/\301]\310(\246\4\300\250+\3720\1\210\v\0\f\0\0\0\0\0\7"..., 8260) = 44 write(0, "~\200!}!}$} }*}#}&}*@!3^W~", 23) = 23 select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499892}) read(0, "\200!}&}$} }$'\367~", 8196) = 13 write(4, "0\201\210\v\0\6\245+\0\0\0\10\0\0\0\7\200!\6\4\0\4", 22) = 22 select(5, [0 4], NULL, NULL, NULL) = 1 (in [4]) read(4, "E \00000W\0\0n/\301X\310(\246\4\300\250+\3720\201\210\v\0\f\0\0\0\0\0\10"..., 8260) = 48 write(0, "~\200\375}#}!} }*}2}&}!} } @\243J~", 25) = 25 select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [4], left {0, 499997}) read(4, "E \0,0X\0\0n/\301\310(\246\4\300\250+\3720\1\210\v\0\f\0\0\0\0\0 "..., 8260) = 44 write(0, "~\200\375}!}%} }*}2}&}!} } @}(-~", 26) = 26 select(5, [0 4], NULL, NULL, {0, 1}) = 1 (in [0], left {0, 0}) read(0, "\200\375}!}\"} }*}2}&}!} } @\352\304~", 8196) = 24 write(4, "0\201\210\v\0\f\245+\0\0\0 \0\0\0 \200\375\1\2\0 \22\6\1\0\0@", 28) = 28 select(5, [0 4], NULL, NULL, NULL) = 1 (in [0]) read(0, "\200\375}\"}%} }*}2}&}!} } @aY~", 8196) = 24 write(4, "0\1\210\v\0\f\245+\0\0\0 \200\375\2\5\0 \22\6\1\0\0@", 24) = 24 select(5, [0 4], NULL, NULL, NULL) = 1 (in [4]) read(4, "E \00000Y\0\0n/\301V\310(\246\4\300\250+\3720\201\210\v\0\f\0\0\0\0\0 "..., 8260) = 48 write(0, "~\200\375}\"}\"} }*}2}&}!} } @\203\260~", 25) = 25 select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499925}) read(0, "\200!}!}!} }<}\"}&} -}/}!}#}&} } } }"..., 8196) = 58 write(4, "0\201\210\v\0\36\245+\0\0\0\v\0\0\0 \200!\1\1\0\34\2\6\0-\17\1\3\6\0\0"..., 46) = 46 select(5, [0 4], NULL, NULL, NULL) = 1 (in [4]) read(4, "E \0<0Z\0\0n/\301I\310(\246\4\300\250+\3720\201\210\v\0\30\0\0\0\0\0\v"..., 8260) = 60 write(0, "~\200!}$}!} }6}\"}&} -}/}!\201}&} } } }"..., 47) = 47 select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499936}) read(0, "\200!}!}\"} }*}#}&} } } } j}0~", 8196) = 26 write(4, "0\201\210\v\0\f\245+\0\0\0\f\0\0\0\v\200!\1\2\0 \3\6\0\0\0\0", 28) = 28 select(5, [0 4], NULL, NULL, NULL) = 1 (in [4]) read(4, "E \00000\0\0n/\301T\310(\246\4\300\250+\3720\201\210\v\0\f\0\0\0\0\0\f"..., 8260) = 48 write(0, "~\200!}#}\"} }*}#}&}*@!}%\272\377~", 24) = 24 select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 499941}) read(0, "\200!}!}#} }*}#}&}*@!}%})\352~", 8196) = 24 write(4, "0\201\210\v\0\f\245+\0\0\0\r\0\0\0\f\200!\1\3\0 \3\6 @!\5", 28) = 28 select(5, [0 4], NULL, NULL, NULL) = 1 (in [4]) read(4, "E \00000\\\0\0n/\301S\310(\246\4\300\250+\3720\201\210\v\0\f\0\0\0\0\0\r"..., 8260) = 48 write(0, "~\200!}\"}#} }*}#}&}*@!}%\236~”, 24) = 24
select(5, [0 4], NULL, NULL, {0, 500000}) = 0 (Timeout)
write(4, " \201\210\v\0\0\245+\0\0\0\r”, 12) = 12
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0,0]\0\0n/\301V\310(\246\4\300\250+\3720\1\210\v\0\f\0\0\0\0\0\16”…, 8260) = 44
write(0, "~\200!}!}&} }
}#}&}@!3\244\314~", 23) = 23
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 496904})
read(0, “~\200!}”}&} }
}#}&}@!3\315\270~", 8196) = 23
write(4, “0\201\210\v\0\f\245+\0\0\0\16\0\0\0\16\200!\2\6\0
\3\6
@!3”, 28) = 28
select(5, [0 4], NULL, NULL, NULL) = 1 (in [0])
read(0, “~\377}#\300!}%}”} }1MPPE disabled}-@~~"…, 8196) = 62
write(4, “0\1\210\v\0\25\245+\0\0\0\17\377\3\300!\5\2\0\21MPPE disable”…, 33) = 33
write(4, “0\1\210\v\0\25\245+\0\0\0\20\377\3\300!\5\3\0\21MPPE disable”…, 33) = 33
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \0w0^\0\0n/\301
\310(\246\4\300\250+\3720\201\210\v\0S\0\0\0\0\0\17”…, 8260) = 119
write(0, "~\375\220} "\200K\216$\202@\347\231\277\213\2139\257Q} \231}<\323\204}
\200G”…, 97) = 97
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [4], left {0, 469328})
read(4, “E \00090`\0\0n/\301F\310(\246\4\300\250+\3720\201\210\v\0\25\0\0\0\0\0\20”…, 8260) = 57
write(0, “~\377}#\300!}&}”} }1MPPE disabled}<p~", 31) = 31
select(5, [0 4], NULL, NULL, {0, 1}) = 0 (Timeout)
write(4, " \201\210\v\0\0\245+\0\0\0\20", 12) = 12
select(5, [0 4], NULL, NULL, NULL) = 1 (in [4])
read(4, “E \00090a\0\0n/\301E\310(\246\4\300\250+\3720\201\210\v\0\25\0\0\0\0\0\21”…, 8260) = 57
write(0, “~\377}#\300!}&}#} }1MPPE disabled},\376~”, 31) = 31
select(5, [0 4], NULL, NULL, {0, 500000}) = 1 (in [0], left {0, 492469})
read(0, 0x7fff97bf4a40, 8196) = -1 EIO (Input/output error)
write(2, “nm-pptp-service-15266 warn[decap”…, 92) = 92
sendto(3, “<28>Dec 19 22:34:00 pptp[15276]:”…, 124, MSG_NOSIGNAL, NULL, 0) = 124
write(2, “nm-pptp-service-15266 warn[decap”…, 93) = 93
sendto(3, “<28>Dec 19 22:34:00 pptp[15276]:”…, 125, MSG_NOSIGNAL, NULL, 0) = 125
close(4) = 0
close(0) = 0
close(0) = -1 EBADF (Bad file descriptor)
close(1) = 0
close(3) = 0
— SIGTERM {si_signo=SIGTERM, si_code=SI_USER, si_pid=15280, si_uid=0} —
rt_sigprocmask(SIG_SETMASK, ], NULL, 8) = 0
close(0) = -1 EBADF (Bad file descriptor)
close(1) = -1 EBADF (Bad file descriptor)
exit_group(0) = ?
+++ exited with 0 +++