Public bug reported:
Binary package hint: network-manager-pptp
If I connect a VPN connection which pushes its own DNS servers
everything works fine. Until the dhcp client does a renew on the network
interface that the pptp session is running over. When this happens, the
DNS servers in /etc/resolv.conf are set back to only the ones provided
by the local DHCP server.
Aug 6 21:24:13 baldur NetworkManager: <info> Will activate connection
'wlan0/james'.
Aug 6 21:24:13 baldur NetworkManager: <info> Device wlan0 activation
scheduled...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) started...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) scheduled...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) started...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) scheduled...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) complete.
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) starting...
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0/wireless):
access point 'james' is encrypted, but NO valid key exists. New key needed.
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) New wireless
user key requested for network 'james'.
Aug 6 21:24:13 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) complete.
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) New wireless
user key for network 'james' received.
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) scheduled...
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) started...
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) scheduled...
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) Stage 1 of 5
(Device Prepare) complete.
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) starting...
Aug 6 21:24:14 baldur NetworkManager: <info> Activation (wlan0/wireless):
access point 'james' is encrypted, and a key exists. No new key needed.
Aug 6 21:24:16 baldur NetworkManager: <info> retry to connect to global
supplicant socket (try=1)
Aug 6 21:24:16 baldur NetworkManager: <info> SUP: sending command
'INTERFACE_ADD wlan0^I^Iwext^I/var/run/wpa_supplicant0^I'
Aug 6 21:24:16 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command 'AP_SCAN 1'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'ADD_NETWORK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was '0'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'SET_NETWORK 0 ssid 6a616d6573'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'SET_NETWORK 0 proto WPA'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'SET_NETWORK 0 key_mgmt WPA-PSK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'SET_NETWORK 0 psk <key>'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: sending command
'ENABLE_NETWORK 0'
Aug 6 21:24:17 baldur NetworkManager: <info> SUP: response was 'OK'
Aug 6 21:24:17 baldur NetworkManager: <info> Activation (wlan0) Stage 2 of 5
(Device Configure) complete.
Aug 6 21:24:19 baldur klogd: [ 89.414928] wlan0: Initial auth_alg=0
Aug 6 21:24:19 baldur klogd: [ 89.414928] wlan0: authenticate with AP
00:15:77:4e:5a:a4
Aug 6 21:24:19 baldur klogd: [ 89.420583] wlan0: RX authentication from
00:15:77:4e:5a:a4 (alg=0 transaction=2 status=0)
Aug 6 21:24:19 baldur klogd: [ 89.420583] wlan0: authenticated
Aug 6 21:24:19 baldur klogd: [ 89.420583] wlan0: associate with AP
00:15:77:4e:5a:a4
Aug 6 21:24:19 baldur klogd: [ 89.428461] wlan0: RX AssocResp from
00:15:77:4e:5a:a4 (capab=0x431 status=0 aid=3)
Aug 6 21:24:19 baldur klogd: [ 89.428461] wlan0: associated
Aug 6 21:24:19 baldur klogd: [ 89.428461] wlan0: switched to short barker
preamble (BSSID=00:15:77:4e:5a:a4)
Aug 6 21:24:20 baldur klogd: [ 89.458094] ADDRCONF(NETDEV_CHANGE): wlan0:
link becomes ready
Aug 6 21:24:20 baldur NetworkManager: <info> Supplicant state changed: 1
Aug 6 21:24:20 baldur NetworkManager: <info> Activation (wlan0/wireless)
Stage 2 of 5 (Device Configure) successful. Connected to access point 'james'.
Aug 6 21:24:20 baldur NetworkManager: <info> Activation (wlan0) Stage 3 of 5
(IP Configure Start) scheduled.
Aug 6 21:24:20 baldur NetworkManager: <info> Activation (wlan0) Stage 3 of 5
(IP Configure Start) started...
Aug 6 21:24:21 baldur NetworkManager: <info> Activation (wlan0) Beginning
DHCP transaction.
Aug 6 21:24:21 baldur NetworkManager: <info> Activation (wlan0) Stage 3 of 5
(IP Configure Start) complete.
Aug 6 21:24:21 baldur NetworkManager: <info> DHCP daemon state is now 12
(successfully started) for interface wlan0
Aug 6 21:24:21 baldur dhclient: wmaster0: unknown hardware address type 801
Aug 6 21:24:23 baldur NetworkManager: <info> DHCP daemon state is now 1
(starting) for interface wlan0
Aug 6 21:24:23 baldur dhclient: wmaster0: unknown hardware address type 801
Aug 6 21:24:24 baldur NetworkManager: <info> Old device 'wlan0' activating,
won't change.
Aug 6 21:24:26 baldur dhclient: DHCPREQUEST of 202.124.101.80 on wlan0 to
255.255.255.255 port 67
Aug 6 21:24:26 baldur dhclient: DHCPACK of 202.124.101.80 from 202.36.221.9
Aug 6 21:24:26 baldur NetworkManager: <info> DHCP daemon state is now 4
(reboot) for interface wlan0
Aug 6 21:24:26 baldur NetworkManager: <info> Activation (wlan0) Stage 4 of 5
(IP Configure Get) scheduled...
Aug 6 21:24:26 baldur NetworkManager: <info> Activation (wlan0) Stage 4 of 5
(IP Configure Get) started...
Aug 6 21:24:26 baldur dhcdbd: message_handler: message handler not found under
/com/redhat/dhcp/wlan0 for sub-path wlan0.dbus.get.host_name
Aug 6 21:24:26 baldur dhcdbd: message_handler: message handler not found under
/com/redhat/dhcp/wlan0 for sub-path wlan0.dbus.get.nis_domain
Aug 6 21:24:26 baldur dhcdbd: message_handler: message handler not found under
/com/redhat/dhcp/wlan0 for sub-path wlan0.dbus.get.nis_servers
Aug 6 21:24:26 baldur NetworkManager: <info> Retrieved the following IP4
configuration from the DHCP daemon:
Aug 6 21:24:26 baldur NetworkManager: <info> address 202.124.101.80
Aug 6 21:24:26 baldur NetworkManager: <info> netmask 255.255.255.224
Aug 6 21:24:26 baldur NetworkManager: <info> broadcast 202.124.101.95
Aug 6 21:24:26 baldur NetworkManager: <info> gateway 202.124.101.65
Aug 6 21:24:26 baldur NetworkManager: <info> nameserver 202.37.101.1
Aug 6 21:24:26 baldur NetworkManager: <info> nameserver 202.37.101.2
Aug 6 21:24:26 baldur NetworkManager: <info> domain name 'jb.net.nz'
Aug 6 21:24:26 baldur dhcdbd: message_handler: message handler not found under
/com/redhat/dhcp/wlan0 for sub-path wlan0.dbus.get.interface_mtu
Aug 6 21:24:26 baldur NetworkManager: <info> Activation (wlan0) Stage 5 of 5
(IP Configure Commit) scheduled...
Aug 6 21:24:26 baldur NetworkManager: <info> Activation (wlan0) Stage 4 of 5
(IP Configure Get) complete.
Aug 6 21:24:26 baldur NetworkManager: <info> Activation (wlan0) Stage 5 of 5
(IP Configure Commit) started...
Aug 6 21:24:26 baldur dhclient: bound to 202.124.101.80 -- renewal in 2392
seconds.
Aug 6 21:24:27 baldur NetworkManager: <info> Clearing nscd hosts cache.
Aug 6 21:24:27 baldur NetworkManager: <WARN> nm_spawn_process():
nm_spawn_process('/usr/sbin/nscd -i hosts'): could not spawn process. (Failed
to execute child process "/usr/sbin/nscd" (No such file or directory))
Aug 6 21:24:27 baldur NetworkManager: <info> Activation (wlan0) Finish
handler scheduled.
Aug 6 21:24:27 baldur NetworkManager: <info> Activation (wlan0) Stage 5 of 5
(IP Configure Commit) complete.
Aug 6 21:24:27 baldur NetworkManager: <info> Activation (wlan0) successful,
device activated.
Aug 6 21:24:37 baldur klogd: [ 107.499539] wlan0: no IPv6 routers present
...
Aug 6 22:04:18 baldur dhclient: DHCPREQUEST of 202.124.101.80 on wlan0 to
202.36.221.9 port 67
Aug 6 22:04:18 baldur dhclient: DHCPACK of 202.124.101.80 from 202.124.101.65
Aug 6 22:04:18 baldur NetworkManager: <info> DHCP daemon state is now 3
(renew) for interface wlan0
Aug 6 22:04:18 baldur dhclient: bound to 202.124.101.80 -- renewal in 2755
seconds.
...
Aug 6 22:21:09 baldur NetworkManager: <info> Will activate VPN connection
'Snap', service 'org.freedesktop.NetworkManager.ppp_starter', user_name
'jbergler', vpn_data 'ppp-connection-type / pptp / pptp-remote / <server
address removed> / usepeerdns / yes / encrypt-mppe / no / encrypt-mppe-128 /
yes / encrypt-mppe-stateful / yes / compress-mppc / no / compress-deflate / no
/ compress-bsd / no / ppp-lock / yes / ppp-auth-peer / no / ppp-refuse-eap /
yes / ppp-refuse-chap / no / ppp-refuse-mschap / no / mtu / 1416 / mru / 1416 /
lcp-echo-failure / 10 / lcp-echo-interval / 10 / ppp-extra / / ppp-debug / no
/ usepeerdns-overtunnel / yes / routes / 202.37.101.0/24 192.168.29.0/24
202.36.220.0/24 / use-routes / yes', route '202.37.101.0/24 / 192.168.29.0/24 /
202.36.220.0/24'.
Aug 6 22:21:09 baldur NetworkManager: <info> VPN Activation (Snap) Stage 1 of
4 (Connection Prepare) scheduled...
Aug 6 22:21:09 baldur NetworkManager: <info> VPN Activation (Snap) Stage 1 of
4 (Connection Prepare) ran VPN service daemon
org.freedesktop.NetworkManager.ppp_starter (PID 9669)
Aug 6 22:21:09 baldur NetworkManager: <info> VPN Activation (Snap) Stage 1 of
4 (Connection Prepare) complete.
Aug 6 22:21:09 baldur NetworkManager: <info> VPN Activation (Snap) Stage 2 of
4 (Connection Prepare Wait) scheduled...
Aug 6 22:21:09 baldur NetworkManager: <info> VPN service
'org.freedesktop.NetworkManager.ppp_starter' signaled state change 1 -> 6.
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 2 of
4 (Connection Prepare Wait) waiting...
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 2 of
4 (Connection Prepare Wait) complete.
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 3 of
4 (Connect) scheduled...
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 3 of
4 (Connect) sending connect request.
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 3 of
4 (Connect) request sent, waiting for reply...
Aug 6 22:21:10 baldur NetworkManager: <info> VPN service
'org.freedesktop.NetworkManager.ppp_starter' signaled state change 6 -> 3.
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 3 of
4 (Connect) reply received.
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 4 of
4 (IP Config Get) timeout scheduled...
Aug 6 22:21:10 baldur NetworkManager: <info> VPN Activation (Snap) Stage 3 of
4 (Connect) complete, waiting for IP configuration...
Aug 6 22:21:10 baldur pppd[9670]: Plugin nm-pppd-plugin.so loaded.
Aug 6 22:21:10 baldur pppd[9670]: nm-pppd-plugin: plugin initialized.
Aug 6 22:21:10 baldur klogd: [ 3524.986506] PPP generic driver version 2.4.2
Aug 6 22:21:10 baldur pppd[9681]: pppd 2.4.4 started by root, uid 0
Aug 6 22:21:10 baldur pptp[9684]: anon log[main:pptp.c:314]: The synchronous
pptp option is NOT activated
Aug 6 22:21:10 baldur pptp[9692]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 1 'Start-Control-Connection-Request'
Aug 6 22:21:10 baldur pppd[9681]: Using interface ppp0
Aug 6 22:21:10 baldur pppd[9681]: Connect: ppp0 <--> /dev/pts/0
Aug 6 22:21:10 baldur pptp[9692]: anon log[ctrlp_disp:pptp_ctrl.c:739]:
Received Start Control Connection Reply
Aug 6 22:21:10 baldur pptp[9692]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client
connection established.
Aug 6 22:21:11 baldur pptp[9692]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 7 'Outgoing-Call-Request'
Aug 6 22:21:11 baldur pppd[9681]: nm-pppd-plugin: CHAP check hook.
Aug 6 22:21:11 baldur pptp[9692]: anon log[ctrlp_disp:pptp_ctrl.c:858]:
Received Outgoing Call Reply.
Aug 6 22:21:11 baldur pptp[9692]: anon log[ctrlp_disp:pptp_ctrl.c:897]:
Outgoing call established (call ID 0, peer's call ID 122).
Aug 6 22:21:11 baldur pppd[9681]: nm-pppd-plugin: CHAP credentials requested.
Aug 6 22:21:11 baldur pppd[9681]: CHAP authentication succeeded
Aug 6 22:21:11 baldur klogd: [ 3526.328806] PPP MPPE Compression module
registered
Aug 6 22:21:11 baldur pppd[9681]: MPPE 128-bit stateless compression enabled
Aug 6 22:21:13 baldur pppd[9681]: Cannot determine ethernet address for proxy
ARP
Aug 6 22:21:13 baldur pppd[9681]: local IP address 192.168.29.42
Aug 6 22:21:13 baldur pppd[9681]: remote IP address 192.168.29.249
Aug 6 22:21:13 baldur pppd[9681]: primary DNS address 192.168.29.3
Aug 6 22:21:13 baldur NetworkManager: <info> VPN Activation (Snap) Stage 4 of
4 (IP Config Get) reply received.
Aug 6 22:21:14 baldur NetworkManager: <info> Clearing nscd hosts cache.
Aug 6 22:21:14 baldur NetworkManager: <WARN> nm_spawn_process():
nm_spawn_process('/usr/sbin/nscd -i hosts'): could not spawn process. (Failed
to execute child process "/usr/sbin/nscd" (No such file or directory))
Aug 6 22:21:14 baldur NetworkManager: <info> VPN Activation (Snap) Stage 4 of
4 (IP Config Get) complete.
Aug 6 22:21:14 baldur NetworkManager: <info> VPN Activation (Snap)
successful.
Aug 6 22:21:14 baldur NetworkManager: <info> VPN service
'org.freedesktop.NetworkManager.ppp_starter' signaled state change 3 -> 4.
Aug 6 22:22:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:23:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:24:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:25:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:26:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:27:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:28:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:29:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:30:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:31:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:677]: Echo
Reply received.
Aug 6 22:31:11 baldur pptp[9692]: anon log[logecho:pptp_ctrl.c:679]: no more
Echo Reply/Request packets will be reported.
Aug 6 22:43:27 baldur -- MARK --
Aug 6 22:50:13 baldur dhclient: DHCPREQUEST of 202.124.101.80 on wlan0 to
202.36.221.9 port 67
Aug 6 22:50:13 baldur dhclient: DHCPACK of 202.124.101.80 from 202.124.101.65
Aug 6 22:50:13 baldur NetworkManager: <info> DHCP daemon state is now 3
(renew) for interface wlan0
Aug 6 22:50:13 baldur dhclient: bound to 202.124.101.80 -- renewal in 2675
seconds.
** Affects: network-manager-pptp (Ubuntu)
Importance: Undecided
Status: New
--
VPN connection in Network Manager looses DNS server with DHCP renew of parent
connection.
https://bugs.launchpad.net/bugs/255480
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
--
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
