hi Craig i got the sam problem on feisty when i already have a DSL pppoe connection on ppp0 (it's just all fine when i use the Ethernet or wireless link to dial the pptp connection)
this is my syslog with debugging Jul 1 00:11:49 host NetworkManager: <information>^IWill activate VPN connection 'pptp', service 'org.freedesktop.NetworkManager.ppp_starter', user_name 'username', vpn_data 'ppp-connection-type / pptp / pptp-remote / xxx.xxx.xxx.xxx / usepeerdns / yes / encrypt-mppe / no / encrypt-mppe-128 / yes / compress-mppc / no / compress-deflate / no / compress-bsd / no / ppp-lock / yes / ppp-auth-peer / no / ppp-refuse-eap / yes / ppp-refuse-chap / yes / ppp-refuse-mschap / yes / mtu / 1416 / mru / 1416 / lcp-echo-failure / 10 / lcp-echo-interval / 10 / ppp-extra / / ppp-debug / yes / usepeerdns-overtunnel / yes / routes / 192.168.0.0/16 10.10.0.0/16 20.20.0.0/16 / use-routes / no', route ''. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 1 of 4 (Connection Prepare) scheduled... Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 1 of 4 (Connection Prepare) ran VPN service daemon org.freedesktop.NetworkManager.ppp_starter (PID 1697) Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 1 of 4 (Connection Prepare) complete. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 2 of 4 (Connection Prepare Wait) scheduled... Jul 1 00:11:49 host NetworkManager: <information>^IVPN service 'org.freedesktop.NetworkManager.ppp_starter' signaled state change 1 -> 6. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 2 of 4 (Connection Prepare Wait) waiting... Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 2 of 4 (Connection Prepare Wait) complete. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 3 of 4 (Connect) scheduled... Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 3 of 4 (Connect) sending connect request. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 3 of 4 (Connect) request sent, waiting for reply... Jul 1 00:11:49 host NetworkManager: <information>^IVPN service 'org.freedesktop.NetworkManager.ppp_starter' signaled state change 6 -> 3. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 3 of 4 (Connect) reply received. Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 4 of 4 (IP Config Get) timeout scheduled... Jul 1 00:11:49 host NetworkManager: <information>^IVPN Activation (pptp) Stage 3 of 4 (Connect) complete, waiting for IP configuration... Jul 1 00:11:49 host pppd[1698]: Plugin nm-pppd-plugin.so loaded. Jul 1 00:11:49 host pppd[1698]: nm-pppd-plugin: plugin initialized. Jul 1 00:11:49 host pppd[1700]: pppd 2.4.4 started by root, uid 0 Jul 1 00:11:49 host pptp[1702]: anon log[main:pptp.c:267]: The synchronous pptp option is NOT activated Jul 1 00:11:49 host pppd[1700]: using channel 5 Jul 1 00:11:49 host pppd[1700]: Using interface ppp1 Jul 1 00:11:49 host pppd[1700]: Connect: ppp1 <--> /dev/pts/3 Jul 1 00:11:49 host pptp[1705]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request' Jul 1 00:11:49 host pptp[1705]: anon log[ctrlp_disp:pptp_ctrl.c:738]: Received Start Control Connection Reply Jul 1 00:11:49 host pptp[1705]: anon log[ctrlp_disp:pptp_ctrl.c:772]: Client connection established. Jul 1 00:11:50 host pppd[1700]: nm-pppd-plugin: CHAP check hook. Jul 1 00:11:50 host pppd[1700]: sent [LCP ConfReq id=0x1 <mru 1416> <asyncmap 0x0> <magic 0x9583c89f> <pcomp> <accomp>] Jul 1 00:11:50 host pptp[1705]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request' Jul 1 00:11:50 host pptp[1705]: anon log[ctrlp_disp:pptp_ctrl.c:857]: Received Outgoing Call Reply. Jul 1 00:11:50 host pptp[1705]: anon log[ctrlp_disp:pptp_ctrl.c:896]: Outgoing call established (call ID 0, peer's call ID 26240). Jul 1 00:11:50 host pppd[1700]: rcvd [LCP ConfReq id=0x1 <accomp> <pcomp> <asyncmap 0x0> <mru 1500> <magic 0xf198c3be> <auth chap MS-v2>] Jul 1 00:11:50 host pppd[1700]: sent [LCP ConfAck id=0x1 <accomp> <pcomp> <asyncmap 0x0> <mru 1500> <magic 0xf198c3be> <auth chap MS-v2>] Jul 1 00:11:50 host pppd[1700]: rcvd [LCP ConfAck id=0x1 <mru 1416> <asyncmap 0x0> <magic 0x9583c89f> <pcomp> <accomp>] Jul 1 00:11:50 host pppd[1700]: sent [LCP EchoReq id=0x0 magic=0x9583c89f] Jul 1 00:11:50 host pppd[1700]: rcvd [CHAP Challenge id=0x1 <xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx>, name = ""] Jul 1 00:11:50 host pppd[1700]: nm-pppd-plugin: CHAP credentials requested. Jul 1 00:11:50 host pppd[1700]: sent [CHAP Response id=0x1 <XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX>, name = "username"] Jul 1 00:11:50 host pppd[1700]: rcvd [LCP EchoRep id=0x0 magic=0xf198c3be] Jul 1 00:11:50 host pppd[1700]: rcvd [CHAP Success id=0x1 "xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx"] Jul 1 00:11:50 host pppd[1700]: CHAP authentication succeeded Jul 1 00:11:50 host pppd[1700]: sent [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>] Jul 1 00:11:50 host pppd[1700]: rcvd [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>] Jul 1 00:11:50 host pppd[1700]: sent [CCP ConfAck id=0x1 <mppe +H -M +S -L -D -C>] Jul 1 00:11:50 host pppd[1700]: rcvd [IPCP ConfReq id=0x1 <addr 10.10.64.10> <compress VJ 0f 01>] Jul 1 00:11:50 host pppd[1700]: sent [IPCP TermAck id=0x1] Jul 1 00:11:50 host pppd[1700]: rcvd [CCP ConfAck id=0x1 <mppe +H -M +S -L -D -C>] Jul 1 00:11:50 host pppd[1700]: MPPE 128-bit stateless compression enabled Jul 1 00:11:50 host pppd[1700]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>] Jul 1 00:11:50 host pppd[1700]: rcvd [IPCP ConfNak id=0x1 <addr 10.10.64.109> <ms-dns1 10.10.64.2> <ms-dns3 10.10.64.2>] Jul 1 00:11:50 host pppd[1700]: sent [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 10.10.64.109> <ms-dns1 10.10.64.2> <ms-dns3 10.10.64.2>] Jul 1 00:11:50 host pppd[1700]: rcvd [IPCP ConfAck id=0x2 <compress VJ 0f 01> <addr 10.10.64.109> <ms-dns1 10.10.64.2> <ms-dns3 10.10.64.2>] Jul 1 00:11:53 host pppd[1700]: rcvd [IPCP ConfReq id=0x2 <addr 10.10.64.10> <compress VJ 0f 01>] Jul 1 00:11:53 host pppd[1700]: sent [IPCP ConfAck id=0x2 <addr 10.10.64.10> <compress VJ 0f 01>] Jul 1 00:11:53 host pppd[1700]: local IP address 10.10.64.109 Jul 1 00:11:53 host pppd[1700]: remote IP address 10.10.64.10 Jul 1 00:11:53 host pppd[1700]: primary DNS address 10.10.64.2 Jul 1 00:11:53 host pppd[1700]: secondary DNS address 10.10.64.2 Jul 1 00:11:53 host pppd[1700]: nm-pppd-plugin: didn't receive an Internal IP4 Address from ppp. Jul 1 00:11:53 host pppd[1700]: Script /etc/ppp/ip-up started (pid 1714) Jul 1 00:11:53 host pptp[1705]: anon log[callmgr_main:pptp_callmgr.c:255]: Closing connection (shutdown) Jul 1 00:11:53 host pptp[1705]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request' Jul 1 00:11:53 host pptp[1705]: anon log[call_callback:pptp_callmgr.c:78]: Closing connection (call state) Jul 1 00:11:53 host pppd[1700]: Terminating on signal 15 Jul 1 00:11:53 host pppd[1700]: Connect time 0.0 minutes. Jul 1 00:11:53 host pppd[1700]: Sent 0 bytes, received 0 bytes. Jul 1 00:11:53 host NetworkManager: <WARNING>^I nm_vpn_service_process_signal (): VPN failed for service 'org.freedesktop.NetworkManager.ppp_starter', signal 'IPConfigBad', with message 'VPN Connection failed'. Jul 1 00:11:53 host NetworkManager: <information>^IVPN service 'org.freedesktop.NetworkManager.ppp_starter' signaled state change 3 -> 5. Jul 1 00:11:53 host NetworkManager: <WARNING>^I nm_vpn_service_stop_connection (): (VPN Service org.freedesktop.NetworkManager.ppp_starter): could not stop connection 'pptp' because service was 5. Jul 1 00:11:53 host NetworkManager: <information>^IVPN service 'org.freedesktop.NetworkManager.ppp_starter' signaled state change 5 -> 6. Jul 1 00:11:53 host pppd[1700]: MPPE disabled Jul 1 00:11:53 host pppd[1700]: sent [LCP TermReq id=0x2 "MPPE disabled"] Jul 1 00:11:53 host pppd[1700]: sent [LCP TermReq id=0x3 "MPPE disabled"] Jul 1 00:11:53 host pppd[1700]: Child process /usr/sbin/pptp xxx.xxx.xxx.10 --nolaunchpppd (pid 1701) terminated with signal 15 Jul 1 00:11:53 host pppd[1700]: Modem hangup Jul 1 00:11:53 host pppd[1700]: Connection terminated. Jul 1 00:11:53 host pppd[1700]: Waiting for 1 child processes... Jul 1 00:11:53 host pppd[1700]: script /etc/ppp/ip-up, pid 1714 Jul 1 00:11:54 host pppd[1700]: Script /etc/ppp/ip-up finished (pid 1714), status = 0x0 Jul 1 00:11:54 host pppd[1700]: Script /etc/ppp/ip-down started (pid 1748) Jul 1 00:11:54 host pppd[1700]: Script /etc/ppp/ip-down finished (pid 1748), status = 0x0 Jul 1 00:11:54 host pppd[1700]: Exit. and the tcpdump log (i think the GRE tunnel have being established) 00:07:03.199815 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: S 3647154529:3647154529(0) win 5808 <mss 1452,sackOK,timestamp 1145978 0,nop,wscale 5> 00:07:03.249810 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: S 398061590:398061590(0) ack 3647154530 win 57344 <mss 1460,nop,wscale 0,nop,nop,timestamp 807773979 1145978> 00:07:03.249907 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: . ack 1 win 182 <nop,nop,timestamp 1145991 807773979> 00:07:03.302231 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: P 1:157(156) ack 1 win 182 <nop,nop,timestamp 1146004 807773979>: pptp CTRL_MSGTYPE=SCCRQ PROTO_VER(1.0) FRAME_CAP(AS) BEARER_CAP(DA) MAX_CHAN(65535) FIRM_REV(1) [|pptp] 00:07:03.364903 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: P 1:157(156) ack 157 win 57600 <nop,nop,timestamp 807773990 1146004>: pptp CTRL_MSGTYPE=SCCRP PROTO_VER(1.0) RESULT_CODE(1) ERR_CODE(0) FRAME_CAP() BEARER_CAP() MAX_CHAN(1) FIRM_REV(1) [|pptp] 00:07:03.365004 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: . ack 157 win 215 <nop,nop,timestamp 1146020 807773990> 00:07:04.320320 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: P 157:325(168) ack 157 win 215 <nop,nop,timestamp 1146258 807773990>: pptp CTRL_MSGTYPE=OCRQ CALL_ID(0) CALL_SER_NUM(0) MIN_BPS(2400) MAX_BPS(10000000) BEARER_TYPE(Any) [|pptp] 00:07:04.382989 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: P 157:189(32) ack 325 win 57600 <nop,nop,timestamp 807774092 1146258>: pptp CTRL_MSGTYPE=OCRP CALL_ID(25984) PEER_CALL_ID(0) RESULT_CODE(1) ERR_CODE(0) CAUSE_CODE(0) CONN_SPEED(10000000) RECV_WIN(3) PROC_DELAY(0) [|pptp] 00:07:04.383083 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: . ack 189 win 215 <nop,nop,timestamp 1146274 807774092> 00:07:04.389055 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 1, length 40: LCP, Conf-Request (0x01), id 1, length 26 00:07:04.497689 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, ack 1, no-payload, length 12 00:07:04.504871 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 0, length 45: LCP, Conf-Request (0x01), id 1, length 31 00:07:04.504885 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 1, length 40: LCP, Conf-Ack (0x02), id 1, length 26 00:07:04.521242 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 2, ack 1, length 49: LCP, Conf-Ack (0x02), id 1, length 31 00:07:04.521489 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 3, length 22: LCP, Echo-Request (0x09), id 0, length 10 00:07:04.576782 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 2, ack 2, length 39: CHAP, Challenge (0x01), id 1, Value xxxxxxxxxxxxxxxxxxxxxxxxxxx, Name 00:07:04.578750 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 3, ack 3, length 28: LCP, Echo-Reply (0x0a), id 0, length 10 00:07:04.589843 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, ack 3, no-payload, length 12 00:07:04.592859 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 4, length 76: CHAP, Response (0x02), id 1, Value xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx[|chap] 00:07:04.648762 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 4, ack 4, length 64: CHAP, Success (0x03), id 1, Msg xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx[|chap] 00:07:04.648811 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 5, length 24: unknown ctrl-proto (0x80fd), Conf-Request (0x01), id 1, length 12 00:07:04.648814 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 6, length 30: IPCP, Conf-Request (0x01), id 1, length 18 00:07:04.661281 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 5, ack 6, length 28: unknown ctrl-proto (0x80fd), Conf-Request (0x01), id 1, length 12 00:07:04.661313 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 6, length 24: unknown ctrl-proto (0x80fd), Conf-Ack (0x02), id 1, length 12 00:07:04.661326 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 7, length 18: IPCP, Term-Ack (0x06), id 1, length 6 00:07:04.714767 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 7, ack 5, length 28: unknown ctrl-proto (0x80fd), Conf-Ack (0x02), id 1, length 12 00:07:04.726851 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 8, ack 7, length 46: IPCP, Conf-Request (0x01), id 1, length 30 00:07:04.776843 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, ack 7, no-payload, length 12 00:07:04.780772 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 8, ack 8, length 40: IPCP, Conf-Nack (0x03), id 1, length 24 00:07:04.781354 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 9, ack 8, length 46: IPCP, Conf-Request (0x01), id 2, length 30 00:07:04.835727 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 9, ack 9, length 46: IPCP, Conf-Ack (0x02), id 2, length 30 00:07:05.332658 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, ack 9, no-payload, length 12 00:07:07.756832 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, seq 10, length 30: IPCP, Conf-Request (0x01), id 2, length 18 00:07:07.761992 IP 123.123.123.151 > xxx.xxx.xxx.10: GREv1, call 25984, seq 10, ack 10, length 34: IPCP, Conf-Ack (0x02), id 2, length 18 00:07:07.867893 IP xxx.xxx.xxx.10 > 123.123.123.151: GREv1, call 0, ack 10, no-payload, length 12 00:07:07.867980 IP 123.123.123.151 > xxx.xxx.xxx.10: ICMP 123.123.123.151 protocol 47 unreachable, length 40 00:07:07.920760 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: P 325:341(16) ack 189 win 215 <nop,nop,timestamp 1147158 807774092>: pptp CTRL_MSGTYPE=CCRQ CALL_ID(0) 00:07:07.921181 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: F 341:341(0) ack 189 win 215 <nop,nop,timestamp 1147159 807774092> 00:07:07.976815 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: P 189:337(148) ack 341 win 57600 <nop,nop,timestamp 807774451 1147158>: pptp CTRL_MSGTYPE=CDN CALL_ID(25984) RESULT_CODE(4) ERR_CODE(0) CAUSE_CODE(0) [|pptp] 00:07:07.976911 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: R 3647154870:3647154870(0) win 0 00:07:07.978748 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: . ack 342 win 57600 <nop,nop,timestamp 807774452 1147159> 00:07:07.978791 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: R 3647154871:3647154871(0) win 0 00:07:07.979726 IP xxx.xxx.xxx.10.1723 > 123.123.123.151.50551: F 337:337(0) ack 342 win 57600 <nop,nop,timestamp 807774452 1147159> 00:07:07.979759 IP 123.123.123.151.50551 > xxx.xxx.xxx.10.1723: R 3647154871:3647154871(0) win 0 -- Cannot connect (terminating on signal 15) https://bugs.launchpad.net/bugs/87870 You received this bug notification because you are a member of Ubuntu Bugs, which is the bug contact for Ubuntu. -- ubuntu-bugs mailing list ubuntu-bugs@lists.ubuntu.com https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
