LCP log of problems with PPP and 2.1.109 (was: PPP trashed in 2.1.109)

Bruce A. Locke (blocke@lizard.org)
Sat, 18 Jul 1998 06:55:31 -0400 (EDT)

Someone suggested that I post what LCP stuff pppd spits out in the log files
so that someone here might be able to track down the bug that prevents me
from connecting with ppp under 2.1.109.

The following is the log file entries when I try to connect using 2.1.109:

Jul 18 06:40:03 capsize pppd[459]: pppd 2.3.5 started by root, uid 0
Jul 18 06:40:30 capsize pppd[459]: Serial connection established.
Jul 18 06:40:31 capsize pppd[459]: Using interface ppp0
Jul 18 06:40:31 capsize pppd[459]: Connect: ppp0 <--> /dev/ttyS2
Jul 18 06:40:31 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:33 capsize pppd[459]: rcvd [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:33 capsize pppd[459]: sent [LCP ConfAck id=0x2 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:34 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:36 capsize pppd[459]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:36 capsize pppd[459]: sent [LCP ConfAck id=0x3 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:37 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:39 capsize pppd[459]: rcvd [LCP ConfReq id=0x4 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:39 capsize pppd[459]: sent [LCP ConfAck id=0x4 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:40 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:42 capsize pppd[459]: rcvd [LCP ConfReq id=0x5 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:42 capsize pppd[459]: sent [LCP ConfAck id=0x5 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:43 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:45 capsize pppd[459]: rcvd [LCP ConfReq id=0x6 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:45 capsize pppd[459]: sent [LCP ConfAck id=0x6 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:46 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:48 capsize pppd[459]: rcvd [LCP ConfReq id=0x7 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:48 capsize pppd[459]: sent [LCP ConfAck id=0x7 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:49 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:51 capsize pppd[459]: rcvd [LCP ConfReq id=0x8 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:51 capsize pppd[459]: sent [LCP ConfAck id=0x8 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:52 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:54 capsize pppd[459]: rcvd [LCP ConfReq id=0x9 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:54 capsize pppd[459]: sent [LCP ConfAck id=0x9 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:55 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:40:57 capsize pppd[459]: rcvd [LCP ConfReq id=0xa <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:57 capsize pppd[459]: sent [LCP ConfAck id=0xa <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:40:58 capsize pppd[459]: sent [LCP ConfReq id=0x1 <magic 0xffff8081> <pcomp> <accomp>]
Jul 18 06:41:00 capsize pppd[459]: rcvd [LCP ConfReq id=0xb <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:00 capsize pppd[459]: sent [LCP ConfAck id=0xb <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:01 capsize pppd[459]: LCP: timeout sending Config-Requests
Jul 18 06:41:03 capsize pppd[459]: rcvd [LCP ConfReq id=0xc <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:03 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:03 capsize pppd[459]: sent [LCP ConfAck id=0xc <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:06 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:06 capsize pppd[459]: rcvd [LCP ConfReq id=0xd <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:06 capsize pppd[459]: sent [LCP ConfAck id=0xd <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:09 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:09 capsize pppd[459]: rcvd [LCP ConfReq id=0xe <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:09 capsize pppd[459]: sent [LCP ConfAck id=0xe <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:12 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:12 capsize pppd[459]: rcvd [LCP ConfReq id=0xf <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:12 capsize pppd[459]: sent [LCP ConfAck id=0xf <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:15 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:15 capsize pppd[459]: rcvd [LCP ConfReq id=0x10 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:15 capsize pppd[459]: sent [LCP ConfAck id=0x10 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:18 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:18 capsize pppd[459]: rcvd [LCP ConfReq id=0x11 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:18 capsize pppd[459]: sent [LCP ConfAck id=0x11 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:21 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:21 capsize pppd[459]: rcvd [LCP ConfReq id=0x12 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:21 capsize pppd[459]: sent [LCP ConfAck id=0x12 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:24 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:24 capsize pppd[459]: rcvd [LCP ConfReq id=0x13 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:24 capsize pppd[459]: sent [LCP ConfAck id=0x13 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:27 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:27 capsize pppd[459]: rcvd [LCP ConfReq id=0x14 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:27 capsize pppd[459]: sent [LCP ConfAck id=0x14 <asyncmap 0x0> <magic 0x10ccf11a> <pcomp> <accomp>]
Jul 18 06:41:30 capsize pppd[459]: sent [LCP ConfReq id=0x2 <magic 0x2cfa> <pcomp> <accomp>]
Jul 18 06:41:31 capsize pppd[459]: Hangup (SIGHUP)
Jul 18 06:41:31 capsize pppd[459]: Modem hangup
Jul 18 06:41:31 capsize pppd[459]: Connection terminated.
Jul 18 06:41:32 capsize pppd[459]: Exit.

>From what I can tell it doesn't even get a change to send the userid and
password.

And here is the same exact pppd command under the 2.1.106 kernel:

Jul 18 06:46:41 capsize pppd[361]: pppd 2.3.5 started by root, uid 0
Jul 18 06:47:06 capsize pppd[361]: Serial connection established.
Jul 18 06:47:07 capsize pppd[361]: Using interface ppp0
Jul 18 06:47:07 capsize pppd[361]: Connect: ppp0 <--> /dev/ttyS2
Jul 18 06:47:07 capsize pppd[361]: sent [LCP ConfReq id=0x1 <magic 0xffff812e> <pcomp> <accomp>]
Jul 18 06:47:10 capsize pppd[361]: sent [LCP ConfReq id=0x1 <magic 0xffff812e> <pcomp> <accomp>]
Jul 18 06:47:10 capsize pppd[361]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x7725a0e9> <pcomp> <accomp> <auth pap>]
Jul 18 06:47:10 capsize pppd[361]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x7725a0e9> <pcomp> <accomp> <auth pap>]
Jul 18 06:47:13 capsize pppd[361]: sent [LCP ConfReq id=0x1 <magic 0xffff812e> <pcomp> <accomp>]
Jul 18 06:47:13 capsize pppd[361]: rcvd [LCP ConfAck id=0x1 <magic 0xffff812e> <pcomp> <accomp>]
Jul 18 06:47:13 capsize pppd[361]: sent [PAP AuthReq id=0x1 user="myuserid" password="mypassword"]
Jul 18 06:47:14 capsize pppd[361]: rcvd [PAP AuthAck id=0x1 "Login Succeeded"]
Jul 18 06:47:14 capsize pppd[361]: Remote message: Login Succeeded
Jul 18 06:47:14 capsize pppd[361]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Jul 18 06:47:14 capsize modprobe: can't locate module ppp-compress-21
Jul 18 06:47:14 capsize modprobe: can't locate module ppp-compress-26
Jul 18 06:47:14 capsize modprobe: can't locate module ppp-compress-24
Jul 18 06:47:14 capsize pppd[361]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f 00> <addr 209.203.129.1>]
Jul 18 06:47:14 capsize pppd[361]: sent [IPCP ConfAck id=0x1 <compress VJ 0f 00> <addr 209.203.129.1>]
Jul 18 06:47:14 capsize pppd[361]: rcvd [IPCP ConfNak id=0x1 <addr 209.203.144.15>]
Jul 18 06:47:14 capsize pppd[361]: sent [IPCP ConfReq id=0x2 <addr 209.203.144.15> <compress VJ 0f 01>]
Jul 18 06:47:14 capsize pppd[361]: rcvd [IPCP ConfAck id=0x2 <addr 209.203.144.15> <compress VJ 0f 01>]
Jul 18 06:47:14 capsize pppd[361]: local IP address 209.203.144.15
Jul 18 06:47:14 capsize pppd[361]: remote IP address 209.203.129.1

Both looked pretty much the same every time I tried it. Can anyone shed
some light on this problem?

----------------------------------------------------------------------
Bruce A. Locke blocke@lizard.org
http://www.lizard.org/blocke/
http://www.lizard.org/dragonlinux

-
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@vger.rutgers.edu
Please read the FAQ at http://www.altern.org/andrebalsa/doc/lkml-faq.html