I have great trouble connecting to a VPN and tried various methods now. Using network-manager-pptp under Linux Mint worked like a charm. But networkmanager-pptp and networkmanager-pptp-gnome not so much. I don’t get any error messages. It just cannot connect.
Can I collect any logs that are needed for diagnosing the problem?
Yes, examine /var/log/NetworkManager while connecting
sudo tail -f /var/log/NetworkManager
what I get is this:
2015-08-17T03:04:09.427094+02:00 machine NetworkManager[754]: <info> (enp2s0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]2015-08-17T03:04:09.427586+02:00 machine NetworkManager[754]: <info> Activation (enp2s0) Stage 5 of 5 (IPv4 Commit) complete.
2015-08-17T03:04:09.428397+02:00 machine NetworkManager[754]: <info> (enp2s0): device state change: secondaries -> activated (reason 'none') [90 100 0]
2015-08-17T03:04:10.203992+02:00 machine NetworkManager[754]: <info> NetworkManager state is now CONNECTED_GLOBAL
2015-08-17T03:04:10.204506+02:00 machine NetworkManager[754]: <info> Policy set 'Wired connection 1' (enp2s0) as default for IPv4 routing and DNS.
2015-08-17T03:04:10.238202+02:00 machine NetworkManager[754]: <info> Activation (enp2s0) successful, device activated.
2015-08-17T03:04:27.991931+02:00 machine NetworkManager[754]: <info> (enp2s0): IP6 addrconf timed out or failed.
2015-08-17T03:04:27.992508+02:00 machine NetworkManager[754]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
2015-08-17T03:04:27.992854+02:00 machine NetworkManager[754]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) started...
2015-08-17T03:04:27.993175+02:00 machine NetworkManager[754]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
2015-08-17T04:50:07.112178+02:00 machine NetworkManager[754]: <info> Starting VPN service 'pptp'...
2015-08-17T04:50:07.121553+02:00 machine NetworkManager[754]: <info> VPN service 'pptp' started (org.freedesktop.NetworkManager.pptp), PID 5931
2015-08-17T04:50:07.138372+02:00 machine NetworkManager[754]: <info> VPN service 'pptp' appeared; activating connections
2015-08-17T04:50:07.140350+02:00 machine NetworkManager[754]: <info> VPN plugin state changed: init (1)
2015-08-17T04:50:07.152060+02:00 machine NetworkManager[754]: <info> VPN plugin state changed: starting (3)
2015-08-17T04:50:07.195584+02:00 machine NetworkManager[754]: <info> VPN connection 'uniVPN' (Connect) reply received.
2015-08-17T04:50:07.228578+02:00 machine NetworkManager[754]: Plugin /usr/lib64/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
2015-08-17T04:50:07.232195+02:00 machine NetworkManager[754]: using channel 5
2015-08-17T04:50:07.232594+02:00 machine NetworkManager[754]: Using interface ppp0
2015-08-17T04:50:07.232966+02:00 machine NetworkManager[754]: Connect: ppp0 <--> /dev/pts/1
2015-08-17T04:50:07.330768+02:00 machine NetworkManager[754]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
2015-08-17T04:50:46.991850+02:00 machine NetworkManager[754]: <warn> VPN connection 'uniVPN' (IP Config Get) timeout exceeded.
2015-08-17T04:50:46.992391+02:00 machine NetworkManager[754]: <info> Policy set 'Wired connection 1' (enp2s0) as default for IPv4 routing and DNS.
2015-08-17T04:50:46.994955+02:00 machine NetworkManager[754]: Terminating on signal 15
2015-08-17T04:50:46.995306+02:00 machine NetworkManager[754]: Connection terminated.
2015-08-17T04:50:47.014726+02:00 machine NetworkManager[754]: Script /usr/sbin/pptp 141.89.249.72 --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-5931 finished (pid 5934), status = 0x0
2015-08-17T04:50:51.993742+02:00 machine NetworkManager[754]: <info> VPN service 'pptp' disappeared
The timeout is concerning…
2015-08-17T04:50:46.991850+02:00 machine NetworkManager[754]: <warn> VPN connection 'uniVPN' (IP Config Get) timeout exceeded.
2015-08-17T04:50:46.992391+02:00 machine NetworkManager[754]: <info> Policy set 'Wired connection 1' (enp2s0) as default for IPv4 routing and DNS.
2015-08-17T04:50:46.994955+02:00 machine NetworkManager[754]: Terminating on signal 15
2015-08-17T04:50:46.995306+02:00 machine NetworkManager[754]: Connection terminated.
Are you sure that you have configurd the PPTP gateway IP address correctly? Also, check that port 1723 is open
nmap <IP-address-of PPTP-server>
Other than that I’m not sure what else to suggest.
1723 is open:
Starting Nmap 6.40 ( http://nmap.org ) at 2015-08-17 05:50 CESTNmap scan report for <server> (xxx.xx.xxx.xx)
Host is up (0.040s latency).
Not shown: 904 filtered ports, 89 closed ports
PORT STATE SERVICE
22/tcp open ssh
80/tcp open http
443/tcp open https
993/tcp open imaps
995/tcp open pop3s
1723/tcp open pptp
5054/tcp open rlm-admin
Nmap done: 1 IP address (1 host up) scanned in 84.08 seconds
The gateway should be correct too. I have redone the connection setup like 5 times and its the same as the one on the Mint machine where it works.
Interesting. I wonder if it is an authentication issue perhaps?
The NM logging verbosity can be increased for diagnostic purposes
man NetworkManager.conf
[logging]
This section controls NetworkManager’s logging. Any settings here are overridden by the
–log-level and --log-domains command-line options.
level=<level> One of [ERR, WARN, INFO, DEBUG]. The ERR level logs only critical errors. WARN logs warn- ings that may reflect operation. INFO logs various informational messages that are useful for tracking state and operations. DEBUG enables verbose logging for debugging purposes. Subsequent levels also log all messages from earlier levels; thus setting the log level to INFO also logs error and warning messages.
Changes are made to /etc/NetworkManager.conf, then I think NM needs to be restarted with
systemctl restart NetworkManager
I turned it up to DEBUG
2015-08-17T06:20:54.996612+02:00 machine NetworkManager[7481]: <debug> [1439785254.992910] [nm-ip6-manager.c:1302] process_newlink(): ((none)): ignoring unknown or completed device2015-08-17T06:20:54.996969+02:00 machine NetworkManager[7481]: <debug> [1439785254.992924] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
2015-08-17T06:20:55.991975+02:00 machine NetworkManager[7481]: <info> (enp2s0): IP6 addrconf timed out or failed.
2015-08-17T06:20:55.992511+02:00 machine NetworkManager[7481]: <debug> [1439785255.991961] [NetworkManagerUtils.c:637] nm_utils_do_sysctl(): sysctl: setting '/proc/sys/net/ipv6/conf/enp2s0/disable_ipv6' to '0'
2015-08-17T06:20:55.992867+02:00 machine NetworkManager[7481]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
2015-08-17T06:20:55.993199+02:00 machine NetworkManager[7481]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) started...
2015-08-17T06:20:55.993523+02:00 machine NetworkManager[7481]: <info> Activation (enp2s0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
2015-08-17T06:20:57.697064+02:00 machine NetworkManager[7481]: <debug> [1439785257.696910] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
2015-08-17T06:20:57.697563+02:00 machine NetworkManager[7481]: <debug> [1439785257.696944] [nm-ip6-manager.c:1295] process_newlink(): ignoring netlink message family 0
2015-08-17T06:20:57.697924+02:00 machine NetworkManager[7481]: <debug> [1439785257.696965] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
2015-08-17T06:21:05.170816+02:00 machine NetworkManager[7481]: <info> Starting VPN service 'pptp'...
2015-08-17T06:21:05.191710+02:00 machine NetworkManager[7481]: <info> VPN service 'pptp' started (org.freedesktop.NetworkManager.pptp), PID 7666
2015-08-17T06:21:05.208510+02:00 machine NetworkManager[7481]: <info> VPN service 'pptp' appeared; activating connections
2015-08-17T06:21:05.209086+02:00 machine NetworkManager[7481]: <debug> [1439785265.208633] [nm-vpn-connection.c:1413] get_secrets(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) requesting VPN secrets pass #1
2015-08-17T06:21:05.209452+02:00 machine NetworkManager[7481]: <debug> [1439785265.208756] [nm-agent-manager.c:1102] nm_agent_manager_get_secrets(): Secrets requested for connection /org/freedesktop/NetworkManager/Settings/0 (vpn)
2015-08-17T06:21:05.209780+02:00 machine NetworkManager[7481]: <debug> [1439785265.208789] [nm-settings-connection.c:864] nm_settings_connection_get_secrets(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:1) secrets requested flags 0x80000004 hint '(null)'
2015-08-17T06:21:05.210121+02:00 machine NetworkManager[7481]: <debug> [1439785265.209211] [nm-agent-manager.c:1017] get_start(): (0x1eb00c0/vpn) system settings secrets sufficient
2015-08-17T06:21:05.210484+02:00 machine NetworkManager[7481]: <debug> [1439785265.209242] [nm-settings-connection.c:721] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:1) existing secrets returned
2015-08-17T06:21:05.210840+02:00 machine NetworkManager[7481]: <debug> [1439785265.209251] [nm-settings-connection.c:727] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:1) secrets request completed
2015-08-17T06:21:05.211196+02:00 machine NetworkManager[7481]: <debug> [1439785265.209941] [nm-settings-connection.c:767] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:1) new agent secrets processed
2015-08-17T06:21:05.211545+02:00 machine NetworkManager[7481]: <debug> [1439785265.209956] [nm-vpn-connection.c:1379] get_secrets_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) asking service if additional secrets are required
2015-08-17T06:21:05.211885+02:00 machine NetworkManager[7481]: <info> VPN plugin state changed: init (1)
2015-08-17T06:21:05.212215+02:00 machine NetworkManager[7481]: <debug> [1439785265.211281] [nm-vpn-connection.c:1340] plugin_need_secrets_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) service indicated additional secrets required
2015-08-17T06:21:05.212598+02:00 machine NetworkManager[7481]: <debug> [1439785265.211305] [nm-vpn-connection.c:1413] get_secrets(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) requesting VPN secrets pass #2
2015-08-17T06:21:05.213049+02:00 machine NetworkManager[7481]: <debug> [1439785265.211404] [nm-agent-manager.c:1102] nm_agent_manager_get_secrets(): Secrets requested for connection /org/freedesktop/NetworkManager/Settings/0 (vpn)
2015-08-17T06:21:05.213428+02:00 machine NetworkManager[7481]: <debug> [1439785265.211502] [nm-agent-manager.c:575] request_add_agent(): (:1.16/org.kde.networkmanagement/1000) agent allowed for secrets request 0x1eb61b0/vpn
2015-08-17T06:21:05.213778+02:00 machine NetworkManager[7481]: <debug> [1439785265.211524] [nm-settings-connection.c:864] nm_settings_connection_get_secrets(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:2) secrets requested flags 0x4 hint '(null)'
2015-08-17T06:21:05.214140+02:00 machine NetworkManager[7481]: <debug> [1439785265.211816] [nm-agent-manager.c:1023] get_start(): (0x1eb61b0/vpn) system settings secrets insufficient, asking agents
2015-08-17T06:21:05.214482+02:00 machine NetworkManager[7481]: <debug> [1439785265.211828] [nm-agent-manager.c:650] next_generic(): (:1.16/org.kde.networkmanagement/1000) agent getting secrets for request 0x1eb61b0/vpn
2015-08-17T06:21:05.214886+02:00 machine NetworkManager[7481]: <debug> [1439785265.211891] [nm-agent-manager.c:868] check_system_secrets_cb(): check_system_secrets_cb: secrets not needed
2015-08-17T06:21:05.215190+02:00 machine NetworkManager[7481]: <debug> [1439785265.211948] [nm-agent-manager.c:938] get_next_cb(): flags 4, existing 0x1e4d760, has_system 0
2015-08-17T06:21:05.215483+02:00 machine NetworkManager[7481]: <debug> [1439785265.211956] [nm-agent-manager.c:956] get_next_cb(): (0x1eb61b0/vpn) request has system secrets; checking agent :1.16 for MODIFY
2015-08-17T06:21:05.215778+02:00 machine NetworkManager[7481]: <debug> [1439785265.214678] [nm-agent-manager.c:841] get_agent_modify_auth_cb(): (0x1eb61b0/vpn) agent MODIFY check result 1
2015-08-17T06:21:05.259261+02:00 machine NetworkManager[7481]: <debug> [1439785265.259184] [nm-agent-manager.c:717] get_done_cb(): (:1.16/org.kde.networkmanagement/1000) agent returned secrets for request 0x1eb61b0/vpn
2015-08-17T06:21:05.259844+02:00 machine NetworkManager[7481]: <debug> [1439785265.259232] [nm-settings-connection.c:684] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:2) secrets returned from agent :1.16
2015-08-17T06:21:05.260217+02:00 machine NetworkManager[7481]: <debug> [1439785265.259267] [nm-settings-connection.c:727] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:2) secrets request completed
2015-08-17T06:21:05.260564+02:00 machine NetworkManager[7481]: <debug> [1439785265.260051] [nm-settings-connection.c:767] agent_secrets_done_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/vpn:2) new agent secrets processed
2015-08-17T06:21:05.260917+02:00 machine NetworkManager[7481]: <debug> [1439785265.260065] [nm-vpn-connection.c:1379] get_secrets_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) asking service if additional secrets are required
2015-08-17T06:21:05.261259+02:00 machine NetworkManager[7481]: <debug> [1439785265.261136] [nm-vpn-connection.c:1349] plugin_need_secrets_cb(): (dc5a8c60-30f2-419d-b971-50c738a53f90/uniVPN) service indicated no additional secrets required
2015-08-17T06:21:05.263477+02:00 machine NetworkManager[7481]: <info> VPN plugin state changed: starting (3)
2015-08-17T06:21:05.278157+02:00 machine NetworkManager[7481]: <info> VPN connection 'uniVPN' (Connect) reply received.
2015-08-17T06:21:05.323146+02:00 machine NetworkManager[7481]: Plugin /usr/lib64/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
2015-08-17T06:21:05.325987+02:00 machine NetworkManager[7481]: using channel 8
2015-08-17T06:21:05.326469+02:00 machine NetworkManager[7481]: <debug> [1439785265.326389] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
2015-08-17T06:21:05.326966+02:00 machine NetworkManager[7481]: <debug> [1439785265.326422] [nm-ip6-manager.c:1295] process_newlink(): ignoring netlink message family 0
2015-08-17T06:21:05.327338+02:00 machine NetworkManager[7481]: <debug> [1439785265.326459] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 11 flags 0x1090
2015-08-17T06:21:05.328446+02:00 machine NetworkManager[7481]: Using interface ppp0
2015-08-17T06:21:05.328790+02:00 machine NetworkManager[7481]: Connect: ppp0 <--> /dev/pts/4
2015-08-17T06:21:05.386154+02:00 machine NetworkManager[7481]: <debug> [1439785265.385730] [nm-udev-manager.c:568] handle_uevent(): UDEV event: action 'add' subsys 'net' device 'ppp0'
2015-08-17T06:21:05.386605+02:00 machine NetworkManager[7481]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
2015-08-17T06:21:05.386944+02:00 machine NetworkManager[7481]: <debug> [1439785265.386150] [nm-udev-manager.c:216] recheck_killswitches(): WiFi rfkill switch rfkill0 state now 1/0
2015-08-17T06:21:30.697456+02:00 machine NetworkManager[7481]: <debug> [1439785290.697388] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
2015-08-17T06:21:30.698062+02:00 machine NetworkManager[7481]: <debug> [1439785290.697419] [nm-ip6-manager.c:1295] process_newlink(): ignoring netlink message family 0
2015-08-17T06:21:30.698417+02:00 machine NetworkManager[7481]: <debug> [1439785290.697439] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
2015-08-17T06:21:45.997054+02:00 machine NetworkManager[7481]: <warn> VPN connection 'uniVPN' (IP Config Get) timeout exceeded.
2015-08-17T06:21:46.007363+02:00 machine NetworkManager[7481]: <debug> [1439785305.997054] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (vpn_connection_deactivated): queueing DNS updates (1)
2015-08-17T06:21:46.007776+02:00 machine NetworkManager[7481]: <debug> [1439785305.997065] [nm-dns-manager.c:995] nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (2)
2015-08-17T06:21:46.008107+02:00 machine NetworkManager[7481]: <info> Policy set 'Wired connection 1' (enp2s0) as default for IPv4 routing and DNS.
2015-08-17T06:21:46.008411+02:00 machine NetworkManager[7481]: <debug> [1439785305.997189] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
2015-08-17T06:21:46.008708+02:00 machine NetworkManager[7481]: <debug> [1439785305.997197] [nm-dns-manager.c:1017] nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (1)
2015-08-17T06:21:46.009033+02:00 machine NetworkManager[7481]: <debug> [1439785305.997206] [nm-dns-manager.c:1013] nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
2015-08-17T06:21:46.009342+02:00 machine NetworkManager[7481]: <debug> [1439785305.997213] [nm-dns-manager.c:1017] nm_dns_manager_end_updates(): (vpn_connection_deactivated): no DNS changes to commit (0)
2015-08-17T06:21:46.009716+02:00 machine NetworkManager[7481]: <debug> [1439785305.997222] [nm-policy.c:1111] process_secondaries(): Secondary connection 'uniVPN' FAILED; active path '/org/freedesktop/NetworkManager/ActiveConnection/1'
2015-08-17T06:21:46.011209+02:00 machine NetworkManager[7481]: <debug> [1439785305.998405] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 17
2015-08-17T06:21:46.012548+02:00 machine NetworkManager[7481]: <debug> [1439785306.7811] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 11 flags 0x1090
2015-08-17T06:21:46.013205+02:00 machine NetworkManager[7481]: <debug> [1439785306.9294] [nm-udev-manager.c:568] handle_uevent(): UDEV event: action 'remove' subsys 'net' device 'ppp0'
2015-08-17T06:21:46.013629+02:00 machine NetworkManager[7481]: <debug> [1439785306.12004] [nm-udev-manager.c:216] recheck_killswitches(): WiFi rfkill switch rfkill0 state now 1/0
2015-08-17T06:21:46.014255+02:00 machine NetworkManager[7481]: Terminating on signal 15
2015-08-17T06:21:46.014633+02:00 machine NetworkManager[7481]: Modem hangup
2015-08-17T06:21:46.015096+02:00 machine NetworkManager[7481]: Connection terminated.
2015-08-17T06:21:46.015464+02:00 machine NetworkManager[7481]: Script /usr/sbin/pptp 141.89.249.72 --nolaunchpppd --loglevel 0 --logstring nm-pptp-service-7666 finished (pid 7669), status = 0x0
2015-08-17T06:21:50.998034+02:00 machine NetworkManager[7481]: <info> VPN service 'pptp' disappeared
2015-08-17T06:21:52.991975+02:00 machine NetworkManager[7481]: <debug> [1439785312.991867] [nm-vpn-service.c:274] ensure_killed(): waiting for VPN service pid 7666 to exit
2015-08-17T06:21:52.992565+02:00 machine NetworkManager[7481]: <debug> [1439785312.991920] [nm-vpn-service.c:276] ensure_killed(): VPN service pid 7666 cleaned up
2015-08-17T06:22:13.696171+02:00 machine NetworkManager[7481]: <debug> [1439785333.696104] [nm-ip6-manager.c:1334] netlink_notification(): netlink event type 16
2015-08-17T06:22:13.696688+02:00 machine NetworkManager[7481]: <debug> [1439785333.696141] [nm-ip6-manager.c:1295] process_newlink(): ignoring netlink message family 0
2015-08-17T06:22:13.697052+02:00 machine NetworkManager[7481]: <debug> [1439785333.696166] [nm-netlink-monitor.c:164] link_msg_handler(): netlink link message: iface idx 3 flags 0x1003
Okay, I don’t see any more relevant messaging from the debug setting, but I’m not seeing an ‘authentication succeeded’ message as expected. You should compare the output with your successful VPN connectivity using Mint. Make sure that your configuration has the relevant authentication protocol(s) selected.
For reference, I get
2015-08-17T19:56:15.960532+12:00 linux-bbgi NetworkManager[2045]: Using interface ppp0
2015-08-17T19:56:15.961650+12:00 linux-bbgi NetworkManager[2045]: Connect: ppp0 <--> /dev/pts/2
2015-08-17T19:56:15.975772+12:00 linux-bbgi NetworkManager[2045]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
2015-08-17T19:56:17.174588+12:00 linux-bbgi NetworkManager[2045]: CHAP authentication succeeded
2015-08-17T19:56:17.248757+12:00 linux-bbgi NetworkManager[2045]: CCP terminated by peer (No compression negotiated)
2015-08-17T19:56:17.249086+12:00 linux-bbgi NetworkManager[2045]: Compression disabled by peer.
2015-08-17T19:56:17.286048+12:00 linux-bbgi NetworkManager[2045]: <info> VPN connection 'Araneo VPN' (IP4 Config Get) reply received from old-style plugin.
2015-08-17T19:56:17.287313+12:00 linux-bbgi NetworkManager[2045]: <info> VPN Gateway: xxx.x.xx.xxx
2015-08-17T19:56:17.288575+12:00 linux-bbgi NetworkManager[2045]: <info> Tunnel Device: ppp0
Hmm. The log for the mint machine uses a different format apparently. Hard to compare for me.
Aug 18 00:30:41 machine kernel: [18377.220620] cfg80211: (5250000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm)Aug 18 00:30:41 machine kernel: [18377.220626] cfg80211: (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm)
Aug 18 00:30:41 machine kernel: [18377.220631] cfg80211: (57240000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 4000 mBm)
Aug 18 00:30:41 machine NetworkManager[709]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
Aug 18 00:30:41 machine NetworkManager[709]: <info> (wlan0): roamed from BSSID (none) ((none)) to 9C:80:DF:C9:79:38 (WLAN-C97979)
Aug 18 00:31:00 machine anacron[11656]: Job `cron.daily' started
Aug 18 00:31:00 machine anacron[12487]: Updated timestamp for job `cron.daily' to 2015-08-18
Aug 18 00:31:02 machine cracklib: no dictionary update necessary.
Aug 18 00:31:03 machine console-kit-daemon[1240]: GLib-CRITICAL: Source ID 42 was not found when attempting to remove it
Aug 18 00:31:03 machine console-kit-daemon[1240]: GLib-CRITICAL: Source ID 53 was not found when attempting to remove it
Aug 18 00:31:47 machine NetworkManager[709]: <info> Starting VPN service 'pptp'...
Aug 18 00:31:47 machine NetworkManager[709]: <info> VPN service 'pptp' started (org.freedesktop.NetworkManager.pptp), PID 12651
Aug 18 00:31:47 machine NetworkManager[709]: <info> VPN service 'pptp' appeared; activating connections
Aug 18 00:31:47 machine NetworkManager[709]: <info> VPN plugin state changed: starting (3)
Aug 18 00:31:47 machine NetworkManager[709]: <info> VPN connection '<VPNNAME>' (Connect) reply received.
Aug 18 00:31:48 machine pppd[12652]: Plugin /usr/lib/pppd/2.4.5/nm-pptp-pppd-plugin.so loaded.
Aug 18 00:31:48 machine pppd[12652]: pppd 2.4.5 started by root, uid 0
Aug 18 00:31:48 machine pptp[12657]: nm-pptp-service-12651 log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Aug 18 00:31:48 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Aug 18 00:31:48 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Aug 18 00:31:48 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Aug 18 00:31:49 machine pppd[12652]: using channel 1
Aug 18 00:31:49 machine pppd[12652]: Using interface ppp0
Aug 18 00:31:49 machine pppd[12652]: Connect: ppp0 <--> /dev/pts/2
Aug 18 00:31:49 machine NetworkManager[709]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Aug 18 00:31:49 machine NetworkManager[709]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Aug 18 00:31:49 machine NetworkManager[709]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 7517).
Aug 18 00:31:50 machine pppd[12652]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Aug 18 00:31:52 machine pppd[12652]: rcvd [LCP ConfReq id=0x1 <auth chap MS>]
Aug 18 00:31:52 machine pppd[12652]: sent [LCP ConfAck id=0x1 <auth chap MS>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfRej id=0x1 <pcomp>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <accomp>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfRej id=0x2 <accomp>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x3 <asyncmap 0x0>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfAck id=0x3 <asyncmap 0x0>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Challenge id=0x1 <9134dca514332a7a>, name = ""]
Aug 18 00:31:53 machine pppd[12652]: sent [CHAP Response id=0x1 <000000000000000000000000000000000000000000000000055d30190cf8d915aa18cfb26dd8c552aaa8336abcb1541001>, name = <"NAME">]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Challenge id=0x2 <68aba995add69429>, name = ""]
Aug 18 00:31:53 machine pppd[12652]: sent [CHAP Response id=0x2 <000000000000000000000000000000000000000000000000b3c967c2ce9cfc65ae7ab3013c6b7681d7494c8c42e9d39401>, name = <"NAME">]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Success id=0x2 ""]
Aug 18 00:31:53 machine pppd[12652]: CHAP authentication succeeded
Aug 18 00:31:53 machine pppd[12652]: CHAP authentication succeeded
Aug 18 00:31:53 machine pppd[12652]: Disabling 40-bit MPPE; MS-CHAP LM not supported
Aug 18 00:31:53 machine pppd[12652]: sent [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [IPCP ConfReq id=0x0 <addr xxx.xx.xx.xxx>]
Aug 18 00:31:53 machine pppd[12652]: sent [IPCP TermAck id=0x0]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CCP ConfReq id=0x0 <mppe +H -M +S +L -D +C>]
Aug 18 00:31:53 machine pppd[12652]: sent [CCP ConfNak id=0x0 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine kernel: [18449.514463] PPP MPPE Compression module registered
Aug 18 00:31:53 machine pppd[12652]: rcvd [CCP ConfNak id=0x1 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: sent [CCP ConfReq id=0x2 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: sent [CCP ConfAck id=0x1 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CCP ConfAck id=0x2 <mppe +H -M +S -L -D -C>]
Aug 18 00:31:53 machine pppd[12652]: MPPE 128-bit stateless compression enabled
Aug 18 00:31:53 machine pppd[12652]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Aug 18 00:31:56 machine pppd[12652]: rcvd [IPCP ConfReq id=0x1 <addr xxx.xx.xx.xxx>]
Aug 18 00:31:56 machine pppd[12652]: sent [IPCP ConfAck id=0x1 <addr xxx.xx.xx.xxx>]
Aug 18 00:31:56 machine pppd[12652]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
Aug 18 00:31:56 machine pppd[12652]: rcvd [IPCP ConfNak id=0x1 <addr xxx.xx.xx.xxx> <ms-dns1 h> <ms-dns2 xxx.xx.xx.x>]
Aug 18 00:31:56 machine pppd[12652]: sent [IPCP ConfReq id=0x2 <addr xxx.xx.xx.xxx> <ms-dns1 xxx.xx.xx.x> <ms-dns2 xxx.xx.xx.x>]
Aug 18 00:31:56 machine pppd[12652]: rcvd [IPCP ConfAck id=0x2 <addr xxx.xx.xx.xxx> <ms-dns1 xxx.xx.xx.x> <ms-dns2 xxx.xx.xx.x>]
Aug 18 00:31:56 machine pppd[12652]: local IP address xxx.xx.xx.xxx
Aug 18 00:31:56 machine pppd[12652]: remote IP address xxx.xx.xx.xxx
Aug 18 00:31:56 machine pppd[12652]: primary DNS address xxx.xx.xx.x
Aug 18 00:31:56 machine pppd[12652]: secondary DNS address xxx.xx.xx.x
Aug 18 00:31:56 machine pppd[12652]: Script /etc/ppp/ip-up started (pid 12676)
Aug 18 00:31:56 machine NetworkManager[709]: <info> VPN connection '<VPNNAME>' (IP4 Config Get) reply received from old-style plugin.
Aug 18 00:31:56 machine NetworkManager[709]: <info> VPN Gateway: xxx.xx.xxx.xx
Aug 18 00:31:56 machine NetworkManager[709]: <info> Tunnel Device: ppp0
Aug 18 00:31:56 machine NetworkManager[709]: <info> IPv4 configuration:
Aug 18 00:31:56 machine NetworkManager[709]: <info> Internal Address: xxx.xx.xx.xxx
Aug 18 00:31:56 machine NetworkManager[709]: <info> Internal Prefix: 32
Aug 18 00:31:56 machine NetworkManager[709]: <info> Internal Point-to-Point Address: xxx.xx.xx.xxx
Aug 18 00:31:56 machine NetworkManager[709]: <info> Maximum Segment Size (MSS): 0
Aug 18 00:31:56 machine NetworkManager[709]: <info> Forbid Default Route: no
Aug 18 00:31:56 machine NetworkManager[709]: <info> Internal DNS: xxx.xx.xx.x
Aug 18 00:31:56 machine NetworkManager[709]: <info> Internal DNS: xxx.xx.xx.x
Aug 18 00:31:56 machine NetworkManager[709]: <info> DNS Domain: '(none)'
Aug 18 00:31:56 machine NetworkManager[709]: <info> No IPv6 configuration
Aug 18 00:31:57 machine pppd[12652]: Script /etc/ppp/ip-up finished (pid 12676), status = 0x0
Aug 18 00:31:57 machine NetworkManager[709]: <info> VPN connection '<VPNNAME>' (IP Config Get) complete.
Aug 18 00:31:57 machine NetworkManager[709]: <info> Policy set '<VPNNAME>' (ppp0) as default for IPv4 routing and DNS.
Aug 18 00:31:57 machine NetworkManager[709]: <info> Writing DNS information to /sbin/resolvconf
Aug 18 00:26:03 machine dnsmasq[796]: using nameserver 192.168.2.1#53
Aug 18 00:31:57 machine dnsmasq[796]: setting upstream servers from DBus
Aug 18 00:31:57 machine dnsmasq[796]: using nameserver 192.168.2.1#53
Aug 18 00:31:57 machine dnsmasq[796]: using nameserver xxx.xx.xx.x#53
Aug 18 00:31:57 machine dnsmasq[796]: using nameserver xxx.xx.xx.x#53
Aug 18 00:31:58 machine dbus[608]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Aug 18 00:31:58 machine NetworkManager[709]: <info> VPN plugin state changed: started (4)
Aug 18 00:31:58 machine dbus[608]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 18 00:31:58 machine ntpd[12064]: ntpd exiting on signal 15
Aug 18 00:31:58 machine dnsmasq[796]: nameserver 192.168.2.1 refused to do a recursive query
I notice at least that CHAP authentication succeeds…not clear to me why the openSUSE NM VPN connection doesn’t though
Aug 18 00:31:49 machine pppd[12652]: Using interface ppp0
Aug 18 00:31:49 machine pppd[12652]: Connect: ppp0 <--> /dev/pts/2
Aug 18 00:31:49 machine NetworkManager[709]: SCPlugin-Ifupdown: devices added (path: /sys/devices/virtual/net/ppp0, iface: ppp0)
Aug 18 00:31:49 machine NetworkManager[709]: SCPlugin-Ifupdown: device added (path: /sys/devices/virtual/net/ppp0, iface: ppp0): no ifupdown configuration found.
Aug 18 00:31:49 machine NetworkManager[709]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Aug 18 00:31:49 machine pptp[12663]: nm-pptp-service-12651 log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 7517).
Aug 18 00:31:50 machine pppd[12652]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Aug 18 00:31:52 machine pppd[12652]: rcvd [LCP ConfReq id=0x1 <auth chap MS>]
Aug 18 00:31:52 machine pppd[12652]: sent [LCP ConfAck id=0x1 <auth chap MS>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfRej id=0x1 <pcomp>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <accomp>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfRej id=0x2 <accomp>]
Aug 18 00:31:53 machine pppd[12652]: sent [LCP ConfReq id=0x3 <asyncmap 0x0>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [LCP ConfAck id=0x3 <asyncmap 0x0>]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Challenge id=0x1 <9134dca514332a7a>, name = ""]
Aug 18 00:31:53 machine pppd[12652]: sent [CHAP Response id=0x1 <000000000000000000000000000000000000000000000000055d30190cf8d915aa18cfb26dd8c552aaa8336abcb1541001>, name = <"NAME">]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Challenge id=0x2 <68aba995add69429>, name = ""]
Aug 18 00:31:53 machine pppd[12652]: sent [CHAP Response id=0x2 <000000000000000000000000000000000000000000000000b3c967c2ce9cfc65ae7ab3013c6b7681d7494c8c42e9d39401>, name = <"NAME">]
Aug 18 00:31:53 machine pppd[12652]: rcvd [CHAP Success id=0x2 ""]
Aug 18 00:31:53 machine pppd[12652]: CHAP authentication succeeded
Aug 18 00:31:53 machine pppd[12652]: CHAP authentication succeeded
Okay. Thanks for your help. I give up.
All I can tell you is that I’m using openSYSE 13.1 with KDE 4.14.10, and I frequently connect to a remote server via PPTP for work, and just don’t experience the issue you’re currently observing. It shouldn’t make a difference as to what NM front-end is being used, but I did wonder if the Gnome keyring was impacting here. The remote server obviously doesn’t get the response it was expecting, but it’s not obvious to me about what might be wrong or how to progress this further. It might be worth trying openSUSE 13.2 as a live distro for test purposes, but I completely understand if you feel you’ve invested enough time and energy with this.