pppd problems
 

Hi,
I'm getting frustrated with my pppd problems. I call my organisation using pppd v2.4.1 (support for MS-CHAP and MS cbcp). I get the connection, and ifconfig reports ppp0. But I can't even ping the remote ip address shown by ifconfig for ppp0.
When I try 'ping -s 1000 remote-ip-address' - I see the Tx field of ifconfig rapidly increase, but the Rx changes by 2/3 bytes only. Any ideas?
I've attached the pppd log files, and ifconfig and route -n outputs below. Thanks in advance for your responses.

-Ramesh

ppp0 Link encap:Point-to-Point Protocol
inet addr:192.168.1.1 P-t-P:10.87.3.121 Mask:255.255.255.255
UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1500 Metric:1
RX packets:1637 errors:0 dropped:0 overruns:0 frame:0
TX packets:1893 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:3
RX bytes:11277 (11.0 Kb) TX bytes:48109 (46.9 Kb)

eth0 Link encap:Ethernet HWaddr 00:10:DC:77:9F:56
inet addr:192.168.1.1 Bcast:192.168.1.255 Mask:255.255.255.0
inet6 addr: fe80::210:dcff:fe77:9f56/10 Scope:Link
UP BROADCAST MULTICAST MTU:1500 Metric:1
RX packets:0 errors:0 dropped:0 overruns:0 frame:0
TX packets:6 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:100
RX bytes:0 (0.0 b) TX bytes:436 (436.0 b)
Interrupt:5 Base address:0xec00

lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
inet6 addr: ::1/128 Scope:Host
UP LOOPBACK RUNNING MTU:16436 Metric:1
RX packets:645 errors:0 dropped:0 overruns:0 frame:0
TX packets:645 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:52281 (51.0 Kb) TX bytes:52281 (51.0 Kb)


linux:/etc/ppp # route -n
Kernel IP routing table
Destination Gateway Genmask Flags Metric Ref Use Iface
10.87.3.121 0.0.0.0 255.255.255.255 UH 0 0 0 ppp0
192.168.1.0 0.0.0.0 255.255.255.0 U 0 0 0 eth0
0.0.0.0 10.87.3.121 0.0.0.0 UG 0 0 0 ppp0

Feb 8 18:38:55 linux pppd[1636]: pppd 2.4.1 started by kram, uid 0
Feb 8 18:38:55 linux pppd[1636]: Perms of /dev/modem are ok, no 'mesg n' neccesary.
Feb 8 18:38:56 linux chat[1637]: timeout set to 60 seconds
Feb 8 18:38:56 linux chat[1637]: abort on (BUSY)
Feb 8 18:38:56 linux chat[1637]: abort on (NO DIALTONE)
Feb 8 18:38:56 linux chat[1637]: abort on (ERROR)
Feb 8 18:38:56 linux chat[1637]: abort on (NO CARRIER)
Feb 8 18:38:56 linux chat[1637]: send (+++ATZ^M)
Feb 8 18:38:57 linux chat[1637]: expect (OK)
Feb 8 18:38:57 linux chat[1637]: +++ATZ^M^M
Feb 8 18:38:57 linux chat[1637]: OK
Feb 8 18:38:57 linux chat[1637]: -- got it
Feb 8 18:38:57 linux chat[1637]: send (ATE1Q0&C1&S0DT0521597010^M)
Feb 8 18:38:58 linux chat[1637]: expect (CONNECT)
Feb 8 18:38:58 linux chat[1637]: ^M
Feb 8 18:39:28 linux chat[1637]: ATE1Q0&C1&S0DT0521597010^M^M
Feb 8 18:39:28 linux chat[1637]: CONNECT
Feb 8 18:39:28 linux chat[1637]: -- got it
Feb 8 18:39:28 linux chat[1637]: send (^M)
Feb 8 18:39:28 linux pppd[1636]: Serial connection established.
Feb 8 18:39:28 linux pppd[1636]: using channel 7
Feb 8 18:39:28 linux pppd[1636]: Using interface ppp0
Feb 8 18:39:28 linux pppd[1636]: Connect: ppp0 <--> /dev/modem
Feb 8 18:39:29 linux pppd[1636]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7441df3a> <pcomp> <accomp>]
Feb 8 18:39:29 linux pppd[1636]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap 81> <magic 0x8d35fb0> <pcomp> <accomp> <callback CBCP> <mrru 1614> <endpoint [local:ec.76.c9.b8.b3.ef.4f.ef.a5.94.ea.82.88.e2.a8.1c.00.00.00.00]> < 17 04 00 37>]
Feb 8 18:39:29 linux pppd[1636]: sent [LCP ConfRej id=0x0 <mrru 1614> < 17 04 00 37>]
Feb 8 18:39:29 linux pppd[1636]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x7441df3a> <pcomp> <accomp>]
Feb 8 18:39:30 linux pppd[1636]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap 81> <magic 0x8d35fb0> <pcomp> <accomp> <callback CBCP> <endpoint [local:ec.76.c9.b8.b3.ef.4f.ef.a5.94.ea.82.88.e2.a8.1c.00.00.00.00]>]
Feb 8 18:39:30 linux pppd[1636]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <auth chap 81> <magic 0x8d35fb0> <pcomp> <accomp> <callback CBCP> <endpoint [local:ec.76.c9.b8.b3.ef.4f.ef.a5.94.ea.82.88.e2.a8.1c.00.00.00.00]>]
Feb 8 18:39:30 linux pppd[1636]: sent [LCP EchoReq id=0x0 magic=0x7441df3a]
Feb 8 18:39:30 linux pppd[1636]: cbcp_lowerup
Feb 8 18:39:30 linux pppd[1636]: want: 12
Feb 8 18:39:30 linux pppd[1636]: phone no: 5343985
Feb 8 18:39:30 linux pppd[1636]: rcvd [CHAP Challenge id=0x0 <7ea35618acaa2f5cca6008291977c706>, name = "NTSERVER3"]
Feb 8 18:39:30 linux pppd[1636]: sent [CHAP Response id=0x0 <d35e5e6d9f101b4faaa8a627498ae90e0000000000000000e3fb2e27c5e80de174d6df69ebafbcbfcb2b2cb95937c8be00> , name = "ramesh"]
Feb 8 18:39:30 linux pppd[1636]: rcvd [LCP EchoRep id=0x0 magic=0x8d35fb0]
Feb 8 18:39:30 linux pppd[1636]: rcvd [CHAP Success id=0x0 "S=19643F04264CF7C3EA8BF3C2AF7C97BB5F5796E2"]
Feb 8 18:39:30 linux pppd[1636]: Remote message: S=19643F04264CF7C3EA8BF3C2AF7C97BB5F5796E2
Feb 8 18:39:30 linux pppd[1636]: cbcp_open
Feb 8 18:39:30 linux pppd[1636]: cbcp_req CONF_USER
Feb 8 18:39:30 linux pppd[1636]: sent [CBCP Request id=0x1 < UserDefined delay = 5 number = 534398>] 35 33 34 33 39 38
Feb 8 18:39:30 linux pppd[1636]: rcvd [CBCP Request id=0x1 < AdminDefined delay = 0>]
Feb 8 18:39:30 linux pppd[1636]: length: 3
Feb 8 18:39:30 linux pppd[1636]: Callback: user admin defined
Feb 8 18:39:30 linux pppd[1636]: cbcp_resp cb_type=8
Feb 8 18:39:30 linux pppd[1636]: cbcp_resp CONF_ADMIN
Feb 8 18:39:30 linux pppd[1636]: sent [CBCP Response id=0x1 < AdminDefined delay = 5>]
Feb 8 18:39:30 linux pppd[1636]: rcvd [CBCP Ack id=0x1 < AdminDefined delay = 5>]
Feb 8 18:39:30 linux pppd[1636]: cbcp_lowerdown
Feb 8 18:39:30 linux pppd[1636]: sent [LCP TermReq id=0x2 "Call me back, please"]
Feb 8 18:39:30 linux pppd[1636]: rcvd [LCP TermAck id=0x2 "Call me back, please"]
Feb 8 18:39:30 linux pppd[1636]: Connection terminated.
Feb 8 18:39:31 linux pppd[1636]: Exit.
Feb 8 18:39:31 linux pppd[1666]: pppd 2.4.1 started by kram, uid 0
Feb 8 18:39:32 linux chat[1667]: timeout set to 120 seconds
Feb 8 18:39:32 linux chat[1667]: abort on (NO CARRIER)
Feb 8 18:39:32 linux chat[1667]: abort on (BUSY)
Feb 8 18:39:32 linux chat[1667]: abort on (NO DIALTONE)
Feb 8 18:39:32 linux chat[1667]: abort on (ERROR)
Feb 8 18:39:32 linux chat[1667]: send (+++ATZ^M)
Feb 8 18:39:32 linux chat[1667]: expect (OK)
Feb 8 18:39:33 linux chat[1667]: +++ATZ^M^M
Feb 8 18:39:33 linux chat[1667]: OK
Feb 8 18:39:33 linux chat[1667]: -- got it
Feb 8 18:39:33 linux chat[1667]: send (ATE1Q0&C1&S0^M)
Feb 8 18:39:33 linux chat[1667]: expect (RING)
Feb 8 18:39:33 linux chat[1667]: ^M
Feb 8 18:39:34 linux chat[1667]: ATE1Q0&C1&S0^M^M
Feb 8 18:39:34 linux chat[1667]: OK^M
Feb 8 18:39:38 linux chat[1667]: ^M
Feb 8 18:39:38 linux chat[1667]: RING
Feb 8 18:39:38 linux chat[1667]: -- got it
Feb 8 18:39:38 linux chat[1667]: send (ATA^M)
Feb 8 18:39:38 linux chat[1667]: expect (CONNECT)
Feb 8 18:39:38 linux chat[1667]: ^M
Feb 8 18:40:00 linux chat[1667]: ATA^M^M
Feb 8 18:40:00 linux chat[1667]: CONNECT
Feb 8 18:40:00 linux chat[1667]: -- got it
Feb 8 18:40:00 linux chat[1667]: send (^M)
Feb 8 18:40:00 linux pppd[1666]: Serial connection established.
Feb 8 18:40:00 linux pppd[1666]: Using interface ppp0
Feb 8 18:40:00 linux pppd[1666]: Connect: ppp0 <--> /dev/modem
Feb 8 18:40:01 linux pppd[1666]: Remote message: S=D1560BA4F52F2F79E3D3FB5877F86D163A8241F4
Feb 8 18:40:02 linux pppd[1666]: local IP address 192.168.1.1
Feb 8 18:40:02 linux pppd[1666]: remote IP address 10.87.3.121
Feb 8 18:40:02 linux pppd[1666]: Script /etc/ppp/ip-up finished (pid 1682), status = 0x0

Feb 8 19:27:13 linux pppd[1666]: Script /etc/ppp/ip-down finished (pid 2080), status = 0x0
Feb 8 19:27:13 linux pppd[1666]: Connection terminated.
Feb 8 19:27:13 linux pppd[1666]: Connect time 47.3 minutes.
Feb 8 19:27:13 linux pppd[1666]: Sent 48209 bytes, received 11377 bytes.
Feb 8 19:27:14 linux pppd[1666]: Exit.