How to diagnose a non-working pptp coonnection
I connect to the internet over cables using pptp.
Up untill Thursday night everything worked fine, at which point it just stopped
connecting. A Long phone call to the ISP earthed that they performed a major
upgrade (which the first denied for an hour and a half of course).
Anyway, to cut a long story shory, when I try to bring up the pptp connection,
the pptp connection comes up, recieves local and remote IPs and DNS but then
nothing will go over the connection. It later dies about a minute and a half
later. I can't even ping the other side. The ISP say that they see the
connection come up on their side, but they can't ping me and they can't help any
further.
I tried upgrading pptp from 1.5 to 1.7 but to no avail. I connection a windows
machine and it connects fine, so it's not a hardware problem. The windows
connecion uses PAP and no encryption or compression.
I ran out of ideas and diagnostic options.
The log from syslog when running both pptp and pppd in debug mode:
Jan 27 18:26:22 rice pppd[25839]: pppd 2.4.3 started by root, uid 0
Jan 27 18:26:22 rice pppd[25839]: Using interface ppp0
Jan 27 18:26:22 rice pppd[25839]: Connect: ppp0 <--> /dev/ttyp1
Jan 27 18:26:22 rice pptp[25842]: anon log[main:pptp.c:276]: The synchronous
pptp option is NOT activated
Jan 27 18:26:22 rice pptp[25844]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 1 'Start-Control-Connection-Request'
Jan 27 18:26:22 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:738]: Received
Start Control Connection Reply
Jan 27 18:26:22 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:772]: Client
connection established.
Jan 27 18:26:23 rice pptp[25844]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 7 'Outgoing-Call-Request'
Jan 27 18:26:23 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:857]: Received
Outgoing Call Reply.
Jan 27 18:26:23 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:896]: Outgoing
call established (call ID 0, peer's call ID 33837).
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 1
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 2
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 3
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 4
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 5
Jan 27 18:26:23 rice pppd[25839]: PAP authentication succeeded
Jan 27 18:26:23 rice pppd[25839]: kernel does not support PPP filtering
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 6
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 7
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 8
Jan 27 18:26:23 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 9
Jan 27 18:26:23 rice pppd[25839]: replacing old default route to eth0
[172.29.208.1]
Jan 27 18:26:23 rice pppd[25839]: Cannot determine ethernet address for proxy
ARP
Jan 27 18:26:23 rice pppd[25839]: local IP address 89.0.9.77
Jan 27 18:26:23 rice pppd[25839]: remote IP address 172.26.255.17
Jan 27 18:26:23 rice pppd[25839]: primary DNS address 212.150.49.10
Jan 27 18:26:23 rice pppd[25839]: secondary DNS address 62.90.42.110
Jan 27 18:26:24 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 10
Jan 27 18:26:45 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 11
Jan 27 18:27:05 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 12
Jan 27 18:27:26 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 13
Jan 27 18:27:34 rice dnsmasq[442]: reading /etc/resolv.conf
Jan 27 18:27:34 rice dnsmasq[442]: using nameserver 62.90.42.110#53
Jan 27 18:27:34 rice dnsmasq[442]: using nameserver 212.150.49.10#53
Jan 27 18:27:34 rice dnsmasq[442]: using local addresses only for domain
luna.local
Jan 27 18:27:46 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 14
Jan 27 18:27:51 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 15
Jan 27 18:28:02 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 16
Jan 27 18:28:05 rice pptp[25842]: anon log[decaps_gre:pptp_gre.c:388]: accepting
packet 17
Jan 27 18:28:07 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:928]: Call
disconnect notification received (call id 33837)
Jan 27 18:28:07 rice pptp[25844]: anon log[ctrlp_disp:pptp_ctrl.c:787]: Received
Stop Control Connection Request.
Jan 27 18:28:07 rice pptp[25844]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 4 'Stop-Control-Connection-Reply'
Jan 27 18:28:07 rice pptp[25844]: anon log[callmgr_main:pptp_callmgr.c:255]:
Closing connection (shutdown)
Jan 27 18:28:07 rice pptp[25844]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 12 'Call-Clear-Request'
Jan 27 18:28:07 rice pptp[25844]: anon log[call_callback:pptp_callmgr.c:78]:
Closing connection (call state)
Jan 27 18:28:07 rice pppd[25839]: Modem hangup
Jan 27 18:28:07 rice pppd[25839]: Connect time 1.8 minutes.
Jan 27 18:28:07 rice pppd[25839]: Sent 199745970 bytes, received 164 bytes.
Jan 27 18:28:07 rice pppd[25839]: restoring old default route to eth0
[172.29.208.1]
Jan 27 18:28:07 rice pppd[25839]: Connection terminated.
Reply to: