Comment 6 for bug 284212

Revision history for this message
Jonathan Harker (jonathanharker) wrote : Re: VPN connection fails: "unable to find valid VPN secrets"

Just updated to latest intrepid updates (network-manager-pptp 0.7~~svn20081015t024626-0ubuntu1) and I still can't connect to any of my previous PPTP VPNs, no matter what I do with the password. Output of /var/log/syslog says this when connecting with password stored in the config:

Oct 28 10:24:26 zmog NetworkManager: <info> Starting VPN service 'org.freedesktop.NetworkManager.pptp'...
Oct 28 10:24:26 zmog NetworkManager: <info> VPN service 'org.freedesktop.NetworkManager.pptp' started (org.freedesktop.NetworkManager.pptp), PID 16090
Oct 28 10:24:26 zmog NetworkManager: <info> VPN service 'org.freedesktop.NetworkManager.pptp' just appeared, activating connections
Oct 28 10:24:26 zmog NetworkManager: <WARN> get_secrets_cb(): Couldn't get connection secrets: vpn-password-dialog.c.299 (nma_vpn_request_password): canceled.
Oct 28 10:24:26 zmog NetworkManager: <info> Policy set 'Auto eth0' (eth0) as default for routing and DNS.
Oct 28 10:24:38 zmog NetworkManager: <debug> [1225142678.628389] ensure_killed(): waiting for vpn service pid 16090 to exit
Oct 28 10:24:38 zmog NetworkManager: <debug> [1225142678.628687] ensure_killed(): vpn service pid 16090 cleaned up

And this when typed into a dialog box, which looks like a CHAP auth failure, except it isn't because the same user/pass works in Hardy:

Oct 28 10:25:01 zmog NetworkManager: <info> Starting VPN service 'org.freedesktop.NetworkManager.pptp'...
Oct 28 10:25:01 zmog NetworkManager: <info> VPN service 'org.freedesktop.NetworkManager.pptp' started (org.freedesktop.NetworkManager.pptp), PID 16115
Oct 28 10:25:01 zmog NetworkManager: <info> VPN service 'org.freedesktop.NetworkManager.pptp' just appeared, activating connections
Oct 28 10:25:01 zmog NetworkManager: <info> VPN plugin state changed: 1
Oct 28 10:25:06 zmog NetworkManager: <info> VPN plugin state changed: 3
Oct 28 10:25:06 zmog NetworkManager: <info> VPN connection 'UCol' (Connect) reply received.
Oct 28 10:25:06 zmog pppd[16119]: Plugin /usr/lib/pppd/2.4.4/nm-pptp-pppd-plugin.so loaded.
Oct 28 10:25:07 zmog kernel: [36106.541213] PPP generic driver version 2.4.2
Oct 28 10:25:07 zmog pppd[16119]: pppd 2.4.4 started by root, uid 0
Oct 28 10:25:07 zmog pptp[16136]: nm-pptp-service-16115 log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Oct 28 10:25:07 zmog pppd[16119]: Using interface ppp0
Oct 28 10:25:07 zmog pppd[16119]: Connect: ppp0 <--> /dev/pts/2
Oct 28 10:25:07 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Oct 28 10:25:07 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Oct 28 10:25:07 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 59084).
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is 00000000, recv_accm is FFFFFFFF
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Oct 28 10:25:08 zmog pppd[16119]: MS-CHAP authentication failed: E=691 Authentication failure
Oct 28 10:25:08 zmog pppd[16119]: CHAP authentication failed
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:950]: PPTP_SET_LINK_INFO received from peer_callid 0
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_disp:pptp_ctrl.c:953]: send_accm is FFFFFFFF, recv_accm is FFFFFFFF
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 warn[ctrlp_disp:pptp_ctrl.c:956]: Non-zero Async Control Character Maps are not supported!
Oct 28 10:25:08 zmog pppd[16119]: Connection terminated.
Oct 28 10:25:08 zmog NetworkManager: <info> VPN plugin failed: 1
Oct 28 10:25:08 zmog pptp[16136]: nm-pptp-service-16115 warn[decaps_hdlc:pptp_gre.c:204]: short read (-1): Input/output error
Oct 28 10:25:08 zmog pptp[16136]: nm-pptp-service-16115 warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Oct 28 10:25:08 zmog pptp[16155]: nm-pptp-service-16115 log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Oct 28 10:25:08 zmog pppd[16119]: Exit.
Oct 28 10:25:08 zmog NetworkManager: <info> VPN plugin failed: 1
Oct 28 10:25:08 zmog NetworkManager: <info> VPN plugin state changed: 6
Oct 28 10:25:08 zmog NetworkManager: <info> VPN plugin state change reason: 0
Oct 28 10:25:08 zmog NetworkManager: <WARN> connection_state_changed(): Could not process the request because no VPN connection was active.
Oct 28 10:25:08 zmog NetworkManager: <info> Policy set 'Auto eth0' (eth0) as default for routing and DNS.
Oct 28 10:25:20 zmog NetworkManager: <debug> [1225142720.444875] ensure_killed(): waiting for vpn service pid 16115 to exit
Oct 28 10:25:20 zmog NetworkManager: <debug> [1225142720.444987] ensure_killed(): vpn service pid 16115 cleaned up