PPP problems

PPP problems

Post by Louis A. Dun » Sun, 14 May 1995 04:00:00



Hi,

    I'm having problems getting ppp V2.1.2c to work on a Linux system with
    kernel V1.1.91.

    It dials in okay, but fails on the connect.  I've included the log below,
    but here's what I think is happening.  My end sends the

        "send [LCP ConfReq id=0x1..."

    and my service provider responds with a

        "rcvd [LCP ConfReq id=0x1..."

    but I see that the AUTHTYPE is rejected (REJ), and in the ConfRej
    message is says "<auth upap>", which leads me to suspect that there
    is some confusion about PAP.  I've asked a few friends that use the
    same provider, and no one seems to need PAP.

    Does anyone know what's going on with this?

Thanks in advance...
Louis
lo...@misery.vip.best.com

May  8 13:18:33 misery pppd[206]: pppd 2.1.2 started by louis, uid 0
May  8 13:18:33 misery pppd[206]: Connecting with <../chat/chat -v "" atds=1 Wel
come "" ogin: misery word: xxxx>
May  8 13:18:35 misery chat[207]: send (atds=1^M)
May  8 13:18:35 misery chat[207]: expect (Welcome)
May  8 13:18:37 misery chat[207]: ^M
May  8 13:18:57 misery chat[207]: 14155227500Welcome -- got it
May  8 13:18:57 misery chat[207]: send (^M)
May  8 13:18:57 misery chat[207]: expect (ogin:)
May  8 13:18:57 misery chat[207]:  to BEST Internet Communications, Inc(pm01.sf1
 s19)^M
May  8 13:18:57 misery chat[207]: ^M
May  8 13:18:57 misery last message repeated 2 times
May  8 13:18:57 misery chat[207]: Type shell or your SLIP/PPP login: -- got it
May  8 13:18:57 misery chat[207]: send (misery^M)
May  8 13:18:57 misery chat[207]: expect (word:)
May  8 13:18:57 misery chat[207]:  ^M
May  8 13:18:58 misery chat[207]: Type shell or your SLIP/PPP login: sery^M
May  8 13:18:58 misery chat[207]: Password: -- got it
May  8 13:18:58 misery chat[207]: send (xxxx^M)
May  8 13:18:58 misery kernel: cua1: 1 input overrun(s)
May  8 13:18:58 misery pppd[206]: Connected...
May  8 13:18:59 misery pppd[206]: set kernel debugging level to 0
May  8 13:18:59 misery pppd[206]: Using interface ppp0
May  8 13:18:59 misery kernel: ppp: channel ppp0 mtu = 1500, mru = 1500
May  8 13:18:59 misery kernel: ppp: channel ppp0 open
May  8 13:18:59 misery pppd[206]: Connect: ppp0 <--> /dev/modem
May  8 13:18:59 misery pppd[206]: set_xaccm: 00000000 00000000 00000000 60000000

May  8 13:18:59 misery pppd[206]: send_config: mtu = 1500
May  8 13:18:59 misery pppd[206]: send_config: asyncmap = ffffffff
May  8 13:18:59 misery pppd[206]: send_config: flags = f000000
May  8 13:18:59 misery pppd[206]: recv_config: mru = 1500
May  8 13:18:59 misery pppd[206]: recv_config: asyncmap = 0
May  8 13:18:59 misery pppd[206]: recv_config: flags = f000010
May  8 13:18:59 misery pppd[206]: sent [LCP ConfReq id=0x1 <mru 1500> <magic 0xb
36c243b> <pcomp> <accomp>]
May  8 13:18:59 misery pppd[206]: fsm_sdata(LCP): Sent code 1, id 1.
May  8 13:18:59 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:18:59 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:18:59 misery pppd[206]: LCP: sending Configure-Request, id 1
May  8 13:18:59 misery pppd[206]: IO signal received
May  8 13:18:59 misery pppd[206]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic
 0x7479df22> <pcomp> <accomp> <auth upap>] 2c d4
May  8 13:18:59 misery pppd[206]: fsm_rconfreq(LCP): Rcvd id 1.
May  8 13:18:59 misery pppd[206]: lcp_reqci: rcvd ASYNCMAP
May  8 13:18:59 misery pppd[206]: (0)
May  8 13:18:59 misery pppd[206]:  (ACK)
May  8 13:18:59 misery pppd[206]: lcp_reqci: rcvd MAGICNUMBER
May  8 13:18:59 misery pppd[206]: (7479df22)
May  8 13:18:59 misery pppd[206]:  (ACK)
May  8 13:18:59 misery pppd[206]: lcp_reqci: rcvd PCOMPRESSION
May  8 13:18:59 misery pppd[206]:  (ACK)
May  8 13:18:59 misery pppd[206]: lcp_reqci: rcvd ACCOMPRESSION
May  8 13:18:59 misery pppd[206]:  (ACK)
May  8 13:18:59 misery pppd[206]: lcp_reqci: rcvd AUTHTYPE
May  8 13:18:59 misery pppd[206]:  (REJ)
May  8 13:18:59 misery pppd[206]: lcp_reqci: returning CONFREJ.
May  8 13:18:59 misery pppd[206]: sent [LCP ConfRej id=0x1 <auth upap>]
May  8 13:18:59 misery pppd[206]: fsm_sdata(LCP): Sent code 4, id 1.
May  8 13:19:02 misery pppd[206]: Alarm
May  8 13:19:02 misery pppd[206]: sent [LCP ConfReq id=0x1 <mru 1500> <magic 0xb
36c243b> <pcomp> <accomp>]
May  8 13:19:02 misery pppd[206]: fsm_sdata(LCP): Sent code 1, id 1.
May  8 13:19:02 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:19:02 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:02 misery pppd[206]: LCP: sending Configure-Request, id 1
May  8 13:19:02 misery pppd[206]: IO signal received
May  8 13:19:02 misery pppd[206]: rcvd [LCP ConfAck id=0x1 <mru 1500> <magic 0xb
36c243b> <pcomp> <accomp>] 7e 49
May  8 13:19:02 misery pppd[206]: fsm_rconfack(LCP): Rcvd id 1.
May  8 13:19:03 misery pppd[206]: IO signal received
May  8 13:19:03 misery pppd[206]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0> <auth
chap 05> <magic 0x7479df22> <pcomp> <accomp>] 87 23
May  8 13:19:03 misery pppd[206]: fsm_rconfreq(LCP): Rcvd id 3.
May  8 13:19:03 misery pppd[206]: lcp_reqci: rcvd ASYNCMAP
May  8 13:19:03 misery pppd[206]: (0)
May  8 13:19:03 misery pppd[206]:  (ACK)
May  8 13:19:03 misery pppd[206]: lcp_reqci: rcvd AUTHTYPE
May  8 13:19:03 misery pppd[206]:  (REJ)
May  8 13:19:03 misery pppd[206]: lcp_reqci: rcvd MAGICNUMBER
May  8 13:19:03 misery pppd[206]: (7479df22)
May  8 13:19:03 misery pppd[206]:  (ACK)
May  8 13:19:03 misery pppd[206]: lcp_reqci: rcvd PCOMPRESSION
May  8 13:19:03 misery pppd[206]:  (ACK)
May  8 13:19:03 misery pppd[206]: lcp_reqci: rcvd ACCOMPRESSION
May  8 13:19:03 misery pppd[206]:  (ACK)
May  8 13:19:03 misery pppd[206]: lcp_reqci: returning CONFREJ.
May  8 13:19:03 misery pppd[206]: sent [LCP ConfRej id=0x3 <auth chap 05>]
May  8 13:19:03 misery pppd[206]: fsm_sdata(LCP): Sent code 4, id 3.
May  8 13:19:05 misery pppd[206]: Alarm
May  8 13:19:05 misery pppd[206]: sent [LCP ConfReq id=0xff <mru 1500> <magic 0x
b36c243b> <pcomp> <accomp>]
May  8 13:19:05 misery pppd[206]: fsm_sdata(LCP): Sent code 1, id 255.
May  8 13:19:05 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:19:05 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:05 misery pppd[206]: LCP: sending Configure-Request, id 255
May  8 13:19:06 misery pppd[206]: IO signal received
May  8 13:19:06 misery pppd[206]: rcvd [LCP ConfReq id=0x5 <asyncmap 0x0> <magic
 0x7479df22> <pcomp> <accomp>] a8 8c
May  8 13:19:06 misery pppd[206]: fsm_rconfreq(LCP): Rcvd id 5.
May  8 13:19:06 misery pppd[206]: lcp_reqci: rcvd ASYNCMAP
May  8 13:19:06 misery pppd[206]: (0)
May  8 13:19:06 misery pppd[206]:  (ACK)
May  8 13:19:06 misery pppd[206]: lcp_reqci: rcvd MAGICNUMBER
May  8 13:19:06 misery pppd[206]: (7479df22)
May  8 13:19:06 misery pppd[206]:  (ACK)
May  8 13:19:06 misery pppd[206]: lcp_reqci: rcvd PCOMPRESSION
May  8 13:19:06 misery pppd[206]:  (ACK)
May  8 13:19:06 misery pppd[206]: lcp_reqci: rcvd ACCOMPRESSION
May  8 13:19:06 misery pppd[206]:  (ACK)
May  8 13:19:06 misery pppd[206]: lcp_reqci: returning CONFACK.
May  8 13:19:06 misery pppd[206]: sent [LCP ConfAck id=0x5 <asyncmap 0x0> <magic
 0x7479df22> <pcomp> <accomp>]
May  8 13:19:06 misery pppd[206]: fsm_sdata(LCP): Sent code 2, id 5.
May  8 13:19:08 misery pppd[206]: Alarm
May  8 13:19:08 misery pppd[206]: sent [LCP ConfReq id=0xff <mru 1500> <magic 0x
b36c243b> <pcomp> <accomp>]
May  8 13:19:08 misery pppd[206]: fsm_sdata(LCP): Sent code 1, id 255.
May  8 13:19:08 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:19:08 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:08 misery pppd[206]: LCP: sending Configure-Request, id 255
May  8 13:19:08 misery pppd[206]: IO signal received
May  8 13:19:08 misery pppd[206]: rcvd [LCP ConfAck id=0xff <mru 1500> <magic 0x
b36c243b> <pcomp> <accomp>] 8b d4
May  8 13:19:08 misery pppd[206]: fsm_rconfack(LCP): Rcvd id 255.
May  8 13:19:08 misery pppd[206]: Untimeout 2194:10920.
May  8 13:19:08 misery pppd[206]: Setting itimer for 0 seconds in untimeout.
May  8 13:19:08 misery pppd[206]: send_config: mtu = 1500
May  8 13:19:08 misery pppd[206]: send_config: asyncmap = 0
May  8 13:19:08 misery pppd[206]: send_config: flags = f000013
May  8 13:19:08 misery pppd[206]: recv_config: mru = 1500
May  8 13:19:08 misery pppd[206]: recv_config: asyncmap = 0
May  8 13:19:08 misery pppd[206]: recv_config: flags = f000003
May  8 13:19:08 misery pppd[206]: sent [IPCP ConfReq id=0x1 <addr 204.156.141.33

> <compress VJ 0f 01>]

May  8 13:19:08 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 1.
May  8 13:19:08 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:08 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:08 misery pppd[206]: IPCP: sending Configure-Request, id 1
May  8 13:19:09 misery pppd[206]: IO signal received
May  8 13:19:09 misery pppd[206]: rcvd [LCP ConfReq id=0x7 <asyncmap 0x0> <magic
 0x7479df22> <pcomp> <accomp>] 2b a2
May  8 13:19:09 misery pppd[206]: fsm_rconfreq(LCP): Rcvd id 7.
May  8 13:19:09 misery pppd[206]: Untimeout 2194:114f0.
May  8 13:19:09 misery pppd[206]: Setting itimer for 0 seconds in untimeout.
May  8 13:19:09 misery pppd[206]: send_config: mtu = 1500
May  8 13:19:09 misery pppd[206]: send_config: asyncmap = ffffffff
May  8 13:19:09 misery pppd[206]: send_config: flags = f000000
May  8 13:19:09 misery pppd[206]: recv_config: mru = 1500
May  8 13:19:09 misery pppd[206]: recv_config: asyncmap = 0
May  8 13:19:09 misery pppd[206]: recv_config: flags = f000010
May  8 13:19:09 misery pppd[206]: sent [LCP ConfReq id=0x2 <mru 1500> <magic 0x5
4d5b4e> <pcomp> <accomp>]
May  8 13:19:09 misery pppd[206]: fsm_sdata(LCP): Sent code 1, id 2.
May  8 13:19:09 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:19:09 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:09 misery pppd[206]: LCP: sending Configure-Request, id 2
May  8 13:19:09 misery pppd[206]: lcp_reqci: rcvd ASYNCMAP
May  8 13:19:09 misery pppd[206]: (0)
May  8 13:19:09 misery pppd[206]:  (ACK)
May  8 13:19:09 misery pppd[206]: lcp_reqci: rcvd MAGICNUMBER
May  8 13:19:09 misery pppd[206]: (7479df22)
May  8 13:19:09 misery pppd[206]:  (ACK)
May  8 13:19:09 misery pppd[206]: lcp_reqci: rcvd PCOMPRESSION
May  8 13:19:09 misery pppd[206]:  (ACK)
May  8 13:19:09 misery pppd[206]: lcp_reqci: rcvd ACCOMPRESSION
May  8 13:19:09 misery pppd[206]:  (ACK)
May  8 13:19:09 misery pppd[206]: lcp_reqci: returning CONFACK.
May  8 13:19:09 misery pppd[206]: sent [LCP ConfAck id=0x7 <asyncmap 0x0> <magic
 0x7479df22> <pcomp> <accomp>]
May  8 13:19:09 misery pppd[206]: fsm_sdata(LCP): Sent code 2, id 7.
May  8 13:19:09 misery pppd[206]: IO signal received
May  8 13:19:09 misery pppd[206]: rcvd [LCP ConfAck id=0x2 <mru 1500> <magic 0x5
4d5b4e> <pcomp> <accomp>] cd 75
May  8 13:19:09 misery pppd[206]: fsm_rconfack(LCP): Rcvd id 2.
May  8 13:19:09 misery pppd[206]: Untimeout 2194:10920.
May  8 13:19:09 misery pppd[206]: Setting itimer for 0 seconds in untimeout.
May  8 13:19:09 misery pppd[206]: send_config: mtu = 1500
May  8 13:19:09 misery pppd[206]: send_config: asyncmap = 0
May  8 13:19:09 misery pppd[206]: send_config: flags = f000013
May  8 13:19:09 misery pppd[206]: recv_config: mru = 1500
May  8 13:19:09 misery pppd[206]: recv_config: asyncmap = 0
May  8 13:19:09 misery pppd[206]: recv_config: flags = f000003
May  8 13:19:09 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:09 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:09 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:09 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:09 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:12 misery pppd[206]: Alarm
May  8 13:19:12 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:12 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:12 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:12 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:12 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:15 misery pppd[206]: Alarm
May  8 13:19:15 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:15 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:15 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:15 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:15 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:18 misery pppd[206]: Alarm
May  8 13:19:18 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:18 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:18 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:18 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:18 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:21 misery pppd[206]: Alarm
May  8 13:19:21 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:21 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:21 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:21 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:21 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:24 misery pppd[206]: Alarm
May  8 13:19:24 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:24 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:24 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:24 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:24 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:27 misery pppd[206]: Alarm
May  8 13:19:27 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:27 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:27 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:27 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:27 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:30 misery pppd[206]: Alarm
May  8 13:19:30 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:30 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:30 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:30 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:30 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:33 misery pppd[206]: Alarm
May  8 13:19:33 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:33 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:33 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:33 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:33 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:36 misery pppd[206]: Alarm
May  8 13:19:36 misery pppd[206]: sent [IPCP ConfReq id=0x2 <addr 204.156.141.33
> <compress VJ 0f 01>]

May  8 13:19:36 misery pppd[206]: fsm_sdata(IPCP): Sent code 1, id 2.
May  8 13:19:36 misery pppd[206]: Timeout 2194:114f0 in 3 seconds.
May  8 13:19:36 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:19:36 misery pppd[206]: IPCP: sending Configure-Request, id 2
May  8 13:19:39 misery pppd[206]: Alarm
May  8 13:19:39 misery pppd[206]: IPCP: timeout sending Config-Requests
May  8 13:28:44 misery pppd[206]: Interrupt received: terminating link
May  8 13:28:44 misery pppd[206]: send_config: mtu = 1500
May  8 13:28:44 misery pppd[206]: send_config: asyncmap = ffffffff
May  8 13:28:44 misery pppd[206]: send_config: flags = f000000
May  8 13:28:44 misery pppd[206]: recv_config: mru = 1500
May  8 13:28:44 misery pppd[206]: recv_config: asyncmap = 0
May  8 13:28:44 misery pppd[206]: recv_config: flags = f000010
May  8 13:28:44 misery pppd[206]: sent [LCP TermReq id=0x3]
May  8 13:28:44 misery pppd[206]: fsm_sdata(LCP): Sent code 5, id 3.
May  8 13:28:44 misery pppd[206]: Timeout 2194:10920 in 3 seconds.
May  8 13:28:44 misery pppd[206]: Setting itimer for 3 seconds in timeout.
May  8 13:28:44 misery pppd[206]: IO signal received
May  8 13:28:44 misery pppd[206]: rcvd [LCP TermAck id=0x8] ee 7e
May  8 13:28:44 misery pppd[206]: fsm_rtermack(LCP).
May  8 13:28:44 misery pppd[206]: Connection terminated.
May  8 13:28:44 misery pppd[206]: set kernel debugging level to 2
May  8 13:28:44 misery pppd[206]: Exit.
May  8 13:28:44 misery kernel: ppp_ioctl: set debug level 2, netpacket 0
May  8 13:28:44 misery kernel: ppp: channel ppp0 closing.
May  8 13:29:23 misery kernel: cua1: 1 input overrun(s)
May  8 13:34:08 misery kernel: cua1: 1 input overrun(s)
May  8 13:34:16 misery kernel: cua1: 3 input overrun(s)

--
    Louis Dunne                                         what have I become
    lo...@misery.vip.best.com                              my sweetest friend

--
    Louis Dunne                                         what have I become
    lo...@misery.vip.best.com                              my sweetest friend

--
    Louis Dunne                                         what have I become
    lo...@misery.vip.best.com                              my sweetest friend