ppp very slow in one direction?

To: debian-user@lists.debian.org
Subject: ppp very slow in one direction?
From: dlr@postbox.ius.cs.cmu.edu
Date: Thu, 22 Apr 99 12:13:07 EDT
Message-id: <"a-W2pC.A.A4C.9q0H3"@murphy>
In-reply-to: <[🔎] 19990422005725.A22906@muskrat> (message from Marsh Ray on Thu, 22 Apr 1999 00:57:25 -0400)
Please forgive yet another ppp question.

I'm stumped by this though, and I'm hoping that someone else has
experienced the same problem.

Summary:  My ppp dialup starts up OK.  Everything appears to be
  working fine, except that transfers from the ISP to my machine are
  really slow (~200 bytes/sec).  Transfers in the other direction are
  40x faster (~8000 bytes/sec).

I'm using slink, with pppd version 2.3.5 .  I set things up using
pppconfig, and I'm starting my connection with pon.

I've screwed around with my ppp options as instructed in the PPP FAQ
(eg asyncmap) and the PPP HowTo, to no effect.  Does this sound
familiar to anyone?

More details are appended below.  Thanks for reading this far,
though.
David LaRose
dlr@cs.cmu.edu

----------More Details---------
My machine is a Dell XPS-R400.  I'm using a Zoom 56k "dual mode"
external modem on /dev/ttyS0) to dial up the modem pool at my
university, and the connection usually establishes with a negotiated
speed between 44kbps and 50kbps.  If I watch /var/log/ppp.log using
tail -f, I notice that there are long pauses between consecutive
entries, but I'm too new to know if this is significant (see logs
below).

Several colleagues claim to access this dialup pool from their redhat
5.2 machines with no problem.  I had some initial difficulties getting
PAP to work, so I used the "chat" option in pppconfig to build a chat
script.

I've appended extracts from what I think are the relevant files below.
As instructed in other documentation, I've deleted phone numbers,
userids, etc.  The excerpt from /var/log/ppp.log is pretty long, but I
didn't want to delete any important clues.  I'm wondering especially
about the line which says
> Apr 20 22:38:55 yancey pppd[1011]: Unsupported protocol (0x8029) received

Anyhow thanks again, and thanks for any help.
David LaRose
dlr@cs.cmu.edu

----------Relevant Files-----------

/etc/ppp/peers/provider:
  # This file was generated by pppconfig.  You can edit the following lines
  # but please do not delete lines or the change the comments or you will
  # confuse pppconfig.
  noauth         #pppconfig_noauth
  connect "/usr/sbin/chat -v -t 60 -f /etc/chatscripts/provider"  #pppconfig_connect
  debug          #pppconfig_debug
  /dev/ttyS0       #pppconfig_dev
  115200      #pppconfig_speed
  defaultroute         #pppconfig_route
  noipdefault      #pppconfig_ipdefault
  user [...] #pppconfig_user
  # End of pppconfig controlled lines.  You can add lines below here without
  # confusing pppconfig
  #
  # The following options added by dlr have no obvious affect on 
  # connection performance
  # noproxyarp
  # asyncmap 0
  lock
  modem
  crtscts
  noipx

/var/log/ppp.log:
  Apr 20 22:38:05 yancey pppd[1011]: pppd 2.3.5 started by root, uid 0
  Apr 20 22:38:06 yancey chat[1012]: abort on (BUSY)
  Apr 20 22:38:06 yancey chat[1012]: abort on (NO CARRIER)
  Apr 20 22:38:06 yancey chat[1012]: abort on (VOICE)
  Apr 20 22:38:06 yancey chat[1012]: abort on (NO DIALTONE)
  Apr 20 22:38:06 yancey chat[1012]: abort on (NO ANSWER)
  Apr 20 22:38:06 yancey chat[1012]: send (ATZ^M)
  Apr 20 22:38:06 yancey chat[1012]: expect (OK)
  Apr 20 22:38:07 yancey chat[1012]: ATZ^M^M
  Apr 20 22:38:07 yancey chat[1012]: OK
  Apr 20 22:38:07 yancey chat[1012]:  -- got it 
  Apr 20 22:38:07 yancey chat[1012]: send (ATDT[Phone number]^M)
  Apr 20 22:38:08 yancey chat[1012]: expect (CONNECT)
  Apr 20 22:38:08 yancey chat[1012]: ^M
  Apr 20 22:38:44 yancey chat[1012]: ATDT[Phone number]^M^M
  Apr 20 22:38:44 yancey chat[1012]: CONNECT
  Apr 20 22:38:44 yancey chat[1012]:  -- got it 
  Apr 20 22:38:44 yancey chat[1012]: send (^M)
  Apr 20 22:38:44 yancey chat[1012]: expect (sername:)
  Apr 20 22:38:44 yancey chat[1012]:  50666 V42bis^M
  Apr 20 22:38:45 yancey chat[1012]: ^M
  Apr 20 22:38:45 yancey chat[1012]: SCS Cisco 2511 Access Server [...]
  Apr 20 22:38:45 yancey chat[1012]: Type "?" or "help" for information on available commands.^M
  Apr 20 22:38:45 yancey chat[1012]: ^M
  Apr 20 22:38:45 yancey chat[1012]: ^M
  Apr 20 22:38:45 yancey chat[1012]: User Access Verification^M
  Apr 20 22:38:45 yancey chat[1012]: ^M
  Apr 20 22:38:45 yancey chat[1012]: Username:
  Apr 20 22:38:45 yancey chat[1012]:  -- got it 
  Apr 20 22:38:45 yancey chat[1012]: send (??????)
  Apr 20 22:38:46 yancey chat[1012]: expect (ssword:)
  Apr 20 22:38:46 yancey chat[1012]:  [username]^M
  Apr 20 22:38:46 yancey chat[1012]: Password:
  Apr 20 22:38:46 yancey chat[1012]:  -- got it 
  Apr 20 22:38:46 yancey chat[1012]: send (??????)
  Apr 20 22:38:46 yancey chat[1012]: expect (>)
  Apr 20 22:38:47 yancey chat[1012]:  ^M
  Apr 20 22:38:47 yancey chat[1012]: TS8>
  Apr 20 22:38:47 yancey chat[1012]:  -- got it 
  Apr 20 22:38:47 yancey chat[1012]: send (ppp default^M)
  Apr 20 22:38:47 yancey chat[1012]: send (\d)
  Apr 20 22:38:48 yancey pppd[1011]: Serial connection established.
  Apr 20 22:38:49 yancey pppd[1011]: Using interface ppp0
  Apr 20 22:38:49 yancey pppd[1011]: Connect: ppp0 <--> /dev/ttyS0
  Apr 20 22:38:49 yancey pppd[1011]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x3f517617> <pcomp> <accomp>]
  Apr 20 22:38:49 yancey pppd[1011]: rcvd [LCP TermAck id=0x1]
  Apr 20 22:38:50 yancey pppd[1011]: rcvd [LCP ConfReq id=0xd <asyncmap 0xa0000> <magic 0x5d497a48> <pcomp> <accomp>]
  Apr 20 22:38:50 yancey pppd[1011]: sent [LCP ConfAck id=0xd <asyncmap 0xa0000> <magic 0x5d497a48> <pcomp> <accomp>]
  Apr 20 22:38:52 yancey pppd[1011]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x3f517617> <pcomp> <accomp>]
  Apr 20 22:38:52 yancey pppd[1011]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x3f517617> <pcomp> <accomp>]
  Apr 20 22:38:52 yancey pppd[1011]: sent [LCP EchoReq id=0x0 magic=0x3f517617]
  Apr 20 22:38:52 yancey pppd[1011]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
  Apr 20 22:38:52 yancey pppd[1011]: rcvd [LCP EchoRep id=0x0 magic=0x5d497a48]
  Apr 20 22:38:52 yancey pppd[1011]: rcvd [IPCP ConfNak id=0x1 <addr 128.2.188.56>]
  Apr 20 22:38:52 yancey pppd[1011]: sent [IPCP ConfReq id=0x2 <addr 128.2.188.56> <compress VJ 0f 01>]
  Apr 20 22:38:53 yancey pppd[1011]: rcvd [IPCP ConfAck id=0x2 <addr 128.2.188.56> <compress VJ 0f 01>]
  Apr 20 22:38:55 yancey pppd[1011]: rcvd [IPCP ConfReq id=0x8 <compress VJ 0f 00> <addr 128.2.254.152>]
  Apr 20 22:38:55 yancey pppd[1011]: sent [IPCP ConfAck id=0x8 <compress VJ 0f 00> <addr 128.2.254.152>]
  Apr 20 22:38:55 yancey pppd[1011]: Cannot determine ethernet address for proxy ARP
  Apr 20 22:38:55 yancey pppd[1011]: local  IP address 128.2.188.56
  Apr 20 22:38:55 yancey pppd[1011]: remote IP address 128.2.254.152
  Apr 20 22:38:55 yancey pppd[1011]: rcvd [proto=0x8029] 01 08 00 1e 06 0b 00 01 00 00 00 2a 54 53 38 07 09 53 43 53 5f 50 50 50 08 06 00 04 0b c7
  Apr 20 22:38:55 yancey pppd[1011]: Unsupported protocol (0x8029) received
  Apr 20 22:38:55 yancey pppd[1011]: sent [LCP ProtRej id=0x2 80 29 01 08 00 1e 06 0b 00 01 00 00 00 2a 54 53 38 07 09 53 43 53 5f 50 50 50 08 06 00 04 0b c7]
  Apr 20 22:39:01 yancey pppd[1011]: rcvd [LCP EchoReq id=0x1 magic=0x5d497a48 54 53 38 07]
  Apr 20 22:39:01 yancey pppd[1011]: sent [LCP EchoRep id=0x1 magic=0x3f517617 00 01 00 00]
  Apr 20 22:39:22 yancey pppd[1011]: sent [LCP EchoReq id=0x1 magic=0x3f517617]
  Apr 20 22:39:22 yancey pppd[1011]: rcvd [LCP EchoRep id=0x1 magic=0x5d497a48]
  Apr 20 22:39:52 yancey pppd[1011]: sent [LCP EchoReq id=0x2 magic=0x3f517617]
  Apr 20 22:39:52 yancey pppd[1011]: rcvd [LCP EchoRep id=0x2 magic=0x5d497a48]
  Apr 20 22:40:02 yancey pppd[1011]: rcvd [LCP EchoReq id=0x2 magic=0x5d497a48 54 53 38 07]
  Apr 20 22:40:02 yancey pppd[1011]: sent [LCP EchoRep id=0x2 magic=0x3f517617 00 01 00 00]
  Apr 20 22:40:22 yancey pppd[1011]: sent [LCP EchoReq id=0x3 magic=0x3f517617]
  Apr 20 22:40:23 yancey pppd[1011]: rcvd [LCP EchoRep id=0x3 magic=0x5d497a48]
  Apr 20 22:40:52 yancey pppd[1011]: sent [LCP EchoReq id=0x4 magic=0x3f517617]
  Apr 20 22:40:53 yancey pppd[1011]: rcvd [LCP EchoRep id=0x4 magic=0x5d497a48]
  Apr 20 22:41:03 yancey pppd[1011]: rcvd [LCP EchoReq id=0x3 magic=0x5d497a48 54 53 38 07]
  Apr 20 22:41:03 yancey pppd[1011]: sent [LCP EchoRep id=0x3 magic=0x3f517617 00 01 00 00]
  Apr 20 22:41:22 yancey pppd[1011]: sent [LCP EchoReq id=0x5 magic=0x3f517617]
  Apr 20 22:41:23 yancey pppd[1011]: rcvd [LCP EchoRep id=0x5 magic=0x5d497a48]
  Apr 20 22:41:52 yancey pppd[1011]: sent [LCP EchoReq id=0x6 magic=0x3f517617]
  Apr 20 22:41:53 yancey pppd[1011]: rcvd [LCP EchoRep id=0x6 magic=0x5d497a48]
  Apr 20 22:42:04 yancey pppd[1011]: rcvd [LCP EchoReq id=0x4 magic=0x5d497a48 54 53 38 07]
  Apr 20 22:42:04 yancey pppd[1011]: sent [LCP EchoRep id=0x4 magic=0x3f517617 00 01 00 00]
  Apr 20 22:42:07 yancey pppd[1011]: Terminating on signal 15.
  Apr 20 22:42:07 yancey pppd[1011]: sent [LCP TermReq id=0x3 "User request"]
  Apr 20 22:42:08 yancey pppd[1011]: rcvd [LCP TermAck id=0x3]
  Apr 20 22:42:08 yancey pppd[1011]: Connection terminated.
  Apr 20 22:42:09 yancey pppd[1011]: Exit.
Reply to:
References:
- Re: Disk geommetry, was Re: Kernel Upgrade: Why?
  - From: Marsh Ray <marsh-deblists@rayfamily.org>
Prev by Date: Re: building pcmcia modules for 2.2.6
Next by Date: Re: Linux nis and shadow passwords, non Linux clients
Previous by thread: Re: Disk geommetry, was Re: Kernel Upgrade: Why?
Next by thread: OPTi 925 (PnP)
Index(es):
- Date
- Thread