My ISP upgraded their pptp server last week and I can no longer connect using
pptp.
Thursday night they upgraded their system (don't know more details) and since
then I can't connect using linux anymore. Windows works fine, so it's not a
hardware issue. I tried two machines, one debian stable (powerpc) both with the
standard pptp package and the latest version compiled from source. The other is
debian unstable.
Both machines show the same symptoms.
The machine connects, bringing up the ppp connection and recieves an IP. It then
seems to send (according to ifconfig) about 300MB to 1GB over the course of a
minute and a half or so and the connection then dies.
I tried both sycronous and asyncronous connections but it didn't help
I'm not sure how to decipher the info or whether there is a solution.
Ifconfig:
-----------
ppp0 Link encap:Point-to-Point Protocol
inet addr:89.0.121.41 P-t-P:172.26.255.17 Mask:255.255.255.255
UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1500 Metric:1
RX packets:4 errors:0 dropped:0 overruns:0 frame:0
TX packets:884278 errors:0 dropped:0 overruns:0 carrier:0 collisions:0
txqueuelen:3
RX bytes:64 (64.0 b) TX bytes:321621278 (306.7 MiB)
Tcpdump produces the following output (repeating over and over) which I don't
know how to decipher:
02:23:26.104538 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885576, length 108: IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885562, length 72: IP [|ip]
02:23:26.104666 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885577, length 144: IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885563, length 108: IP [|ip]
02:23:26.104790 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885578, length 180: IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885564, length 144: IP [|ip]
02:23:26.104946 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885579, length 432: IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885568, length 396: IP [|ip]
02:23:26.105275 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885580, length 1480: IP truncated-ip - 36 bytes missing! 172.29.214.255 >
172.26.255.17: GREv1, call 63029, seq 885569, length 1480: IP [|ip]
02:23:26.105381 IP 172.29.214.255 > 172.26.255.17: gre
02:23:26.107608 IP 172.29.214.255 > 172.26.255.17: GREv1, call 63029, seq
885581, length 72: IP 172.29.214.255 > 172.26.255.17: gre
The output in syslog:
Jan 30 02:21:16 litshi kernel: ppp_async: Unknown symbol crc_ccitt_table
Jan 30 02:21:37 litshi pptp[20157]: anon log[main:pptp.c:267]: The synchronous
pptp option is NOT activated
Jan 30 02:21:37 litshi pptp[20160]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 1 'Start-Control-Connection-Request'
Jan 30 02:21:37 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:738]:
Received Start Control Connection Reply
Jan 30 02:21:37 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:772]: Client
connection established.
Jan 30 02:21:38 litshi pptp[20160]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 7 'Outgoing-Call-Request'
Jan 30 02:21:38 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:857]:
Received Outgoing Call Reply.
Jan 30 02:21:38 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:896]:
Outgoing call established (call ID 0, peer's call ID 63029).
Jan 30 02:21:38 litshi pppd[20163]: pppd 2.4.4 started by root, uid 0
Jan 30 02:21:38 litshi pppd[20163]: using channel 2
Jan 30 02:21:38 litshi pppd[20163]: Using interface ppp0
Jan 30 02:21:38 litshi pppd[20163]: Connect: ppp0 <--> /dev/pts/2
Jan 30 02:21:38 litshi pppd[20163]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0xc56c1737> <pcomp> <accomp>]
Jan 30 02:21:40 litshi kernel: Shorewall:net2fw:DROP:IN=eth0 OUT=
MAC=08:00:46:5b:70:62:00:05:00:e7:dd:9b:08:00 SRC=172.26.255.17
DST=172.29.214.255 LEN=54 TOS=0x00 PREC=0x00 TTL=253 ID=51790 PROTO=47
Jan 30 02:21:40 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 2
Jan 30 02:21:40 litshi pppd[20163]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0xc56c1737> <pcomp> <accomp>]
Jan 30 02:21:41 litshi pppd[20163]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0xc56c1737> <pcomp> <accomp>]
Jan 30 02:21:41 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 3
Jan 30 02:21:41 litshi pppd[20163]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0>
<magic 0xc56c1737> <pcomp> <accomp>]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 4
Jan 30 02:21:42 litshi pppd[20163]: rcvd [LCP ConfReq id=0x2 <auth pap> <magic
0xd088f19e>]
Jan 30 02:21:42 litshi pppd[20163]: sent [LCP ConfAck id=0x2 <auth pap> <magic
0xd088f19e>]
Jan 30 02:21:42 litshi pppd[20163]: sent [LCP EchoReq id=0x0 magic=0xc56c1737]
Jan 30 02:21:42 litshi pppd[20163]: sent [PAP AuthReq id=0x1 user="lipshi"
password=<hidden>]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 5
Jan 30 02:21:42 litshi pppd[20163]: rcvd [LCP EchoRep id=0x0 magic=0xd088f19e]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 6
Jan 30 02:21:42 litshi pppd[20163]: rcvd [PAP AuthAck id=0x1 ""]
Jan 30 02:21:42 litshi pppd[20163]: PAP authentication succeeded
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 7
Jan 30 02:21:42 litshi pppd[20163]: sent [CCP ConfReq id=0x1 <bsd v1 15>]
Jan 30 02:21:42 litshi pppd[20163]: 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>]
Jan 30 02:21:42 litshi pppd[20163]: rcvd [IPCP ConfReq id=0x1 <addr
172.26.255.17>]
Jan 30 02:21:42 litshi pppd[20163]: sent [IPCP ConfAck id=0x1 <addr
172.26.255.17>]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 8
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 9
Jan 30 02:21:42 litshi pppd[20163]: rcvd [LCP ProtRej id=0x3 80 fd 01 01 00 07
15 03 2f]
Jan 30 02:21:42 litshi pppd[20163]: Protocol-Reject for 'Compression Control
Protocol' (0x80fd) received
Jan 30 02:21:42 litshi pppd[20163]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f
01>]
Jan 30 02:21:42 litshi pppd[20163]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>
<ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 10
Jan 30 02:21:42 litshi pppd[20163]: rcvd [IPCP ConfNak id=0x2 <addr 89.0.121.41>
<ms-dns1 212.150.49.10> <ms-dns3 62.90.42.110>]
Jan 30 02:21:42 litshi pppd[20163]: sent [IPCP ConfReq id=0x3 <addr 89.0.121.41>
<ms-dns1 212.150.49.10> <ms-dns3 62.90.42.110>]
Jan 30 02:21:42 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 11
Jan 30 02:21:42 litshi pppd[20163]: rcvd [IPCP ConfAck id=0x3 <addr 89.0.121.41>
<ms-dns1 212.150.49.10> <ms-dns3 62.90.42.110>]
Jan 30 02:21:42 litshi pppd[20163]: replacing old default route to eth0
[172.29.208.1]
Jan 30 02:21:42 litshi pppd[20163]: Cannot determine ethernet address for proxy
ARP
Jan 30 02:21:42 litshi pppd[20163]: local IP address 89.0.121.41
Jan 30 02:21:42 litshi pppd[20163]: remote IP address 172.26.255.17
Jan 30 02:21:42 litshi pppd[20163]: primary DNS address 212.150.49.10
Jan 30 02:21:42 litshi pppd[20163]: secondary DNS address 62.90.42.110
Jan 30 02:21:42 litshi pppd[20163]: Script /etc/ppp/ip-up started (pid 20178)
Jan 30 02:21:43 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 12
Jan 30 02:21:49 litshi kernel: device ppp0 entered promiscuous mode
Jan 30 02:22:04 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 13
Jan 30 02:22:07 litshi pppd[20163]: Script /etc/ppp/ip-up finished (pid 20178),
status = 0x0
Jan 30 02:22:24 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 14
Jan 30 02:22:45 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 15
Jan 30 02:23:05 litshi pptp[20158]: anon log[decaps_gre:pptp_gre.c:388]:
accepting packet 16
Jan 30 02:23:26 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:928]: Call
disconnect notification received (call id 63029)
Jan 30 02:23:26 litshi pptp[20160]: anon log[ctrlp_disp:pptp_ctrl.c:787]:
Received Stop Control Connection Request.
Jan 30 02:23:26 litshi pptp[20160]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 4 'Stop-Control-Connection-Reply'
Jan 30 02:23:26 litshi pptp[20160]: anon log[callmgr_main:pptp_callmgr.c:255]:
Closing connection (shutdown)
Jan 30 02:23:26 litshi pptp[20160]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent
control packet type is 12 'Call-Clear-Request'
Jan 30 02:23:26 litshi pptp[20160]: anon log[call_callback:pptp_callmgr.c:78]:
Closing connection (call state)
Jan 30 02:23:26 litshi pppd[20163]: Hangup (SIGHUP)
Jan 30 02:23:26 litshi pppd[20163]: Modem hangup
Jan 30 02:23:26 litshi pppd[20163]: Connect time 1.8 minutes.
Jan 30 02:23:26 litshi pppd[20163]: Sent 322096541 bytes, received 0 bytes.
Jan 30 02:23:26 litshi pppd[20163]: restoring old default route to eth0
[172.29.208.1]
Jan 30 02:23:26 litshi pppd[20163]: Script /etc/ppp/ip-down started (pid 20255)
Jan 30 02:23:26 litshi pppd[20163]: Connection terminated.
Jan 30 02:23:26 litshi pppd[20163]: Script /etc/ppp/ip-down finished (pid
20255), status = 0x0
Jan 30 02:23:26 litshi pppd[20163]: Exit.
--
To UNSUBSCRIBE, email to [EMAIL PROTECTED]
with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]
