Subject: Need help with PPP LCP ProtRej message
To: None <netbsd-help@netbsd.org>
From: Brian Stark <bstark@siemens-psc.com>
List: netbsd-help
Date: 12/30/1998 17:29:33
Help!

My PPP connection to my ISP (US West) has been working just fine until Dec. 
25th when my ISP upgraded their modems from 56k to v.90. Since then, I keep 
getting a "LCP ProtRej" message and IPCP negotiation never completes. The
system that I am using is running NetBSD/i386 version 1.3.3 and PPP 2.3.5.My 
system also has Windows 95 installed, and that system does not have any 
problems connecting to my ISP. Both operating systems use the same modem,
so I do not beleive there are any modem related issues here. Calls to the
tech-support dept of US West were extremely unhelpful as I was unable to 
locate anyone  knowledgeable about PPP protocol negotiations and of
course, US West does not support "UNIX" systems. Hopefully someone here
can help me out.

I should probably mention that I was running 1.3.2 with PPP 2.3.5 without
problems and that for the few days that I was running 1.3.3 with PPP
2.3.35 before the 25th, I did not have problems either.

On the NetBSD system, this is what I see when calling my ISP:
 
Dec 29 13:50:28 callisto pppd[8250]: Connect: ppp0 <--> /dev/tty02
Dec 29 13:50:28 callisto pppd[8250]: sent [LCP ConfReq id=0x1 <magic 0xc641dac6> <pcomp> <accomp>]
Dec 29 13:50:28 callisto pppd[8250]: rcvd [LCP ConfReq id=0x6 <asyncmap 0xa0000> <magic 0xfbcf440b> <pcomp
> <accomp>]
Dec 29 13:50:28 callisto pppd[8250]: sent [LCP ConfAck id=0x6 <asyncmap 0xa0000> <magic 0xfbcf440b> <pcomp
> <accomp>]
Dec 29 13:50:28 callisto pppd[8250]: rcvd [LCP ConfAck id=0x1 <magic 0xc641dac6> <pcomp> <accomp>]
Dec 29 13:50:28 callisto pppd[8250]: sent [IPCP ConfReq id=0x1 <addr 192.168.2.1>]
Dec 29 13:50:28 callisto pppd[8250]: rcvd [LCP ProtRej id=0x7 80 21 01 01 00 0a 03 06 c0 a8 02 01]
Dec 29 13:53:36 callisto pppd[8250]: Hangup (SIGHUP)  
Dec 29 13:53:36 callisto pppd[8250]: sent [LCP TermReq id=0x2 "User request"]
Dec 29 13:53:36 callisto pppd[8250]: rcvd [LCP TermAck id=0x2]
Dec 29 13:53:36 callisto pppd[8250]: Connection terminated.

After these messages appear, there are no more messages from my system 
or the  remote system and the modem connection remains established until I
instruct pppd to drop the connection (that is the source of the "Hangup"
message).

If you convert the last 4 hex codes of the LCP ProtRej message to decimal 
you will see that they match the IP address used when my system sent a
IPCP ConfReq message. However, I am really confused as to why the remote 
system is responding with a LCP ProtRej message. I know that there can
sometimes be problems with the asyncmap setting, and I have tried different
settings for that that (asyncmap 0, asyncmap 0xfffff, asyncmap 0xa0000, 
default-asyncmap) and all to no avail. 

It might be helpful to know the options for my PPP connection, and they are: 

nodefaultroute
idle 900
lock
crtscts
modem
debug
kdebug 4
refuse-pap
refuse-chap
nobsdcomp
nodeflate
novj
novjccomp
nopredictor1
tty02 115200
user bstark
ipcp-accept-local
ipcp-accept-remote
connect /etc/ppp/scripts/connect_uswest
welcome /etc/ppp/scripts/welcome
disconnect /etc/ppp/scripts/disconnect

As a comparison, I have included the messages that Windows 95 records in the 
ppplog.txt file. The following shows a successful connection to my ISP:

12-29-1998 14:33:39.64 - Remote access driver log opened.
12-29-1998 14:33:39.64 - Installable CP VxD SPAP     is loaded
12-29-1998 14:33:39.64 - Server type is  PPP (Point to Point Protocol).
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol 80fd (CCP) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Protocol not bound - skipping control protocol 803f (NBFCP).
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol 8021 (IPCP) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Protocol not bound - skipping control protocol 802b (IPXCP).
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol c029 (CallbackCP) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol c027 (no description) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol c023 (PAP) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol c223 (CHAP) to control protocol chain.
12-29-1998 14:33:39.64 - FSA : Adding Control Protocol c021 (LCP) to control protocol chain.
12-29-1998 14:33:39.64 - LCP : Callback negotiation enabled.
12-29-1998 14:33:39.64 - LCP : Layer started.
12-29-1998 14:33:40.98 - LCP : Received and accepted ACCM of a0000.
12-29-1998 14:33:40.98 - LCP : Received and accepted authentication protocol c023 (PAP).
12-29-1998 14:33:40.98 - LCP : Received and accepted magic number fbf2ae1b.
12-29-1998 14:33:40.98 - LCP : Received and accepted protocol field compression option.
12-29-1998 14:33:40.98 - LCP : Received and accepted address+control field compression option.
12-29-1998 14:33:41.91 - LCP : Received and accepted ACCM of a0000.
12-29-1998 14:33:41.91 - LCP : Received and accepted authentication protocol c023 (PAP).
12-29-1998 14:33:41.91 - LCP : Received and accepted magic number fbf2ae1b.
12-29-1998 14:33:41.91 - LCP : Received and accepted protocol field compression option.
12-29-1998 14:33:41.91 - LCP : Received and accepted address+control field compression option.
12-29-1998 14:33:42.94 - LCP : Received and accepted ACCM of a0000.
12-29-1998 14:33:42.94 - LCP : Received and accepted authentication protocol c023 (PAP).
12-29-1998 14:33:42.94 - LCP : Received and accepted magic number fbf2ae1b.
12-29-1998 14:33:42.94 - LCP : Received and accepted protocol field compression option.
12-29-1998 14:33:42.94 - LCP : Received and accepted address+control field compression option.
12-29-1998 14:33:42.94 - LCP : Received configure reject for callback control protocol option.
12-29-1998 14:33:43.25 - LCP : Layer up.
12-29-1998 14:33:43.25 - PAP : Layer started.
12-29-1998 14:33:46.22 - PAP : Login was successful.
12-29-1998 14:33:46.22 - PAP : Layer up.
12-29-1998 14:33:46.22 - IPCP : Layer started.
12-29-1998 14:33:46.22 - IPCP : IP address is 0.
12-29-1998 14:33:46.22 - CCP : Layer started.
12-29-1998 14:33:46.53 - IPCP : Received and accepted IP address of cfe18c2d.
12-29-1998 14:33:46.84 - IPCP : Changing IP address from 0 to cfe19a7c.
12-29-1998 14:33:46.84 - IPCP : Accepting primary DNS cc935001.
12-29-1998 14:33:46.84 - IPCP : Accepting backup DNS cc935005.
12-29-1998 14:33:47.15 - IPCP : Layer up.
12-29-1998 14:33:47.15 - CCP : Layer up.
12-29-1998 14:33:47.15 - FSA : Last control protocol is up.

So, does anyone have any ideas as to what I or pppd are doing wrong?

Thanks,

Brian

-------------------------------------------------------------------------
| Brian Stark                       | Internet : bstark@siemens-psc.com |
| Siemens PT&D, LLC                 | Voice    : +1 612 536-4697        |
| Power Systems Control Division    | Fax      : +1 612 536-4919        |
| 7225 Northland Drive, Brooklyn Park, Minnesota 55428   USA            | 
-------------------------------------------------------------------------