LCP timout
Hi. I'm back in my Debian now and am using mutt. Feels so good!
Now on to the problem. I'm trying to dial-in to our Cisco 3640 RAS from home
and I'm not able to do it. Yesterday, I did some tracing of what the Cisco box
is sending me and what my Debian box is sending to the Cisco box. What I found
out is that my box would send an LCP request but never got any response from
the Cisco box. I fiddled around my ppp setup and was able to get a response
and also able to login to the Cisco box but I wasn't able to do it again.
I spent the whole day trying to find out why and I think I need opinions from
others. Here's part of my /var/log/syslog:
Oct 13 13:12:55 localhost chat[617]: send (\d)
Oct 13 13:12:56 localhost pppd[616]: Serial connection established.
Oct 13 13:12:56 localhost pppd[616]: Using interface ppp0
Oct 13 13:12:56 localhost pppd[616]: Connect: ppp0 <--> /dev/ttyS0
Oct 13 13:12:57 localhost pppd[616]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xecc0be02> <pcomp> <accomp>]
Oct 13 13:12:58 localhost pppd[616]: rcvd [LCP ConfReq id=0x22 <asyncmap 0xa0000> <auth chap MD5> <magic 0x150e206f> <pcomp> <accomp>]
Oct 13 13:12:58 localhost pppd[616]: sent [LCP ConfNak id=0x22 <auth pap>]
Oct 13 13:12:58 localhost pppd[616]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xecc0be02> <pcomp> <accomp>]
Oct 13 13:12:58 localhost pppd[616]: rcvd [LCP ConfReq id=0x23 <asyncmap 0xa0000> <auth pap> <magic 0x150e206f> <pcomp> <accomp>]
Oct 13 13:12:58 localhost pppd[616]: sent [LCP ConfAck id=0x23 <asyncmap 0xa0000> <auth pap> <magic 0x150e206f> <pcomp> <accomp>]
Oct 13 13:12:58 localhost pppd[616]: sent [LCP EchoReq id=0x0 magic=0xecc0be02]
Oct 13 13:12:58 localhost pppd[616]: sent [PAP AuthReq id=0x1 user="storm.AL-TAYER" password=<hidden>]
Oct 13 13:12:58 localhost pppd[616]: rcvd [LCP EchoRep id=0x0 magic=0x150e206f]
Oct 13 13:12:58 localhost pppd[616]: rcvd [PAP AuthNak id=0x1 "Authentication failure"]
Oct 13 13:12:58 localhost pppd[616]: Remote message: Authentication failure
Oct 13 13:12:58 localhost pppd[616]: PAP authentication failed
Oct 13 13:12:58 localhost pppd[616]: sent [LCP TermReq id=0x2 "Failed to authenticate ourselves to peer"]
Oct 13 13:12:58 localhost pppd[616]: rcvd [LCP TermReq id=0x24]
Oct 13 13:12:58 localhost pppd[616]: sent [LCP TermAck id=0x24]
Oct 13 13:12:58 localhost pppd[616]: Hangup (SIGHUP)
Oct 13 13:12:58 localhost pppd[616]: Modem hangup
Oct 13 13:12:58 localhost pppd[616]: Connection terminated.
Oct 13 13:12:59 localhost pppd[616]: Exit.
The above message log is the message I'm still getting.
Below is the first time I was able to login successfully. It never happened
again. Even if I use PAP or CHAP (Cisco is configured for PAP).
Oct 13 13:28:35 localhost chat[650]: send (\d)
Oct 13 13:28:36 localhost pppd[649]: Serial connection established.
Oct 13 13:28:36 localhost pppd[649]: Using interface ppp0
Oct 13 13:28:36 localhost pppd[649]: Connect: ppp0 <--> /dev/ttyS0
Oct 13 13:28:37 localhost pppd[649]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x81208269> <pcomp> <accomp>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [LCP ConfReq id=0x20 <asyncmap 0xa0000> <auth chap MD5> <magic 0x151c7811> <pcomp> <accomp>]
Oct 13 13:28:38 localhost pppd[649]: sent [LCP ConfAck id=0x20 <asyncmap 0xa0000> <auth chap MD5> <magic 0x151c7811> <pcomp> <accomp>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x81208269> <pcomp> <accomp>]
Oct 13 13:28:38 localhost pppd[649]: sent [LCP EchoReq id=0x0 magic=0x81208269]
Oct 13 13:28:38 localhost pppd[649]: rcvd [CHAP Challenge id=0xa <e6206942d91da08439c556054997efb0>, name = "ALTAYER-RAS"]
Oct 13 13:28:38 localhost pppd[649]: sent [CHAP Response id=0xa <48b201dca4707c1f0d230e0062d733e3>, name = "rino"]
Oct 13 13:28:38 localhost pppd[649]: rcvd [LCP EchoRep id=0x0 magic=0x151c7811]
Oct 13 13:28:38 localhost pppd[649]: rcvd [CHAP Success id=0xa ""]
Oct 13 13:28:38 localhost pppd[649]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <compress VJ 0f 01>]
Oct 13 13:28:38 localhost kernel: PPP BSD Compression module registered
Oct 13 13:28:38 localhost kernel: PPP Deflate Compression module registered
Oct 13 13:28:38 localhost pppd[649]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15> <bsd v1 15>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [IPCP ConfReq id=0xf <addr 10.1.1.4>]
Oct 13 13:28:38 localhost pppd[649]: sent [IPCP ConfAck id=0xf <addr 10.1.1.4>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [proto=0x8207] 01 09 00 04
Oct 13 13:28:38 localhost pppd[649]: Unsupported protocol 0x8207 received
Oct 13 13:28:38 localhost pppd[649]: sent [LCP ProtRej id=0x2 82 07 01 09 00 04]
Oct 13 13:28:38 localhost pppd[649]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Oct 13 13:28:38 localhost pppd[649]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [LCP ProtRej id=0x21 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
Oct 13 13:28:38 localhost pppd[649]: rcvd [IPCP ConfNak id=0x2 <addr 10.1.5.7>]
Oct 13 13:28:38 localhost pppd[649]: sent [IPCP ConfReq id=0x3 <addr 10.1.5.7>]
Oct 13 13:28:38 localhost pppd[649]: rcvd [IPCP ConfAck id=0x3 <addr 10.1.5.7>]
Oct 13 13:28:38 localhost pppd[649]: Cannot determine ethernet address for proxy ARP
Oct 13 13:28:38 localhost pppd[649]: local IP address 10.1.5.7
Oct 13 13:28:38 localhost pppd[649]: remote IP address 10.1.1.4
Oct 13 13:28:38 localhost pppd[649]: Script /etc/ppp/ip-up started (pid 654)
Oct 13 13:28:40 localhost pppd[649]: Script /etc/ppp/ip-up finished (pid 654), status = 0x0
Oct 13 13:29:08 localhost pppd[649]: sent [LCP EchoReq id=0x1 magic=0x81208269]
Oct 13 13:29:08 localhost pppd[649]: rcvd [LCP EchoRep id=0x1 magic=0x151c7811]
Oct 13 13:29:38 localhost pppd[649]: sent [LCP EchoReq id=0x2 magic=0x81208269]
Oct 13 13:29:38 localhost pppd[649]: rcvd [LCP EchoRep id=0x2 magic=0x151c7811]
Oct 13 13:30:01 localhost /USR/SBIN/CRON[674]: (root) CMD (if [ -x /usr/bin/mrtg ]; then /usr/bin/mrtg /etc/mrtg.cfg; fi)
Oct 13 13:30:08 localhost pppd[649]: sent [LCP EchoReq id=0x3 magic=0x81208269]
Oct 13 13:30:08 localhost pppd[649]: rcvd [LCP EchoRep id=0x3 magic=0x151c7811]
Oct 13 13:30:38 localhost pppd[649]: sent [LCP EchoReq id=0x4 magic=0x81208269]
Oct 13 13:30:38 localhost pppd[649]: rcvd [LCP EchoRep id=0x4 magic=0x151c7811]
Oct 13 13:31:08 localhost pppd[649]: sent [LCP EchoReq id=0x5 magic=0x81208269]
Oct 13 13:31:08 localhost pppd[649]: rcvd [LCP EchoRep id=0x5 magic=0x151c7811]
Oct 13 13:31:38 localhost pppd[649]: sent [LCP EchoReq id=0x6 magic=0x81208269]
Oct 13 13:31:38 localhost pppd[649]: rcvd [LCP EchoRep id=0x6 magic=0x151c7811]
Oct 13 13:32:07 localhost pppd[649]: Terminating on signal 15.
Oct 13 13:32:07 localhost pppd[649]: Script /etc/ppp/ip-down started (pid 682)
Oct 13 13:32:07 localhost pppd[649]: sent [LCP TermReq id=0x3 "User request"]
Oct 13 13:32:07 localhost pppd[649]: Script /etc/ppp/ip-down finished (pid 682), status = 0x0
Oct 13 13:32:08 localhost pppd[649]: rcvd [LCP TermAck id=0x3]
Oct 13 13:32:08 localhost pppd[649]: Connection terminated.
Oct 13 13:32:08 localhost pppd[649]: Connect time 3.6 minutes.
Oct 13 13:32:08 localhost pppd[649]: Sent 516 bytes, received 555 bytes.
Oct 13 13:32:08 localhost pppd[649]: Hangup (SIGHUP)
Oct 13 13:32:08 localhost pppd[649]: Exit.
Oct 13 13:35:01 localhost /USR/SBIN/CRON[696]: (root) CMD (if [ -x /usr/bin/mrtg ]; then /usr/bin/mrtg /etc/mrtg.cfg; fi)
Oct 13 13:38:01 localhost /USR/SBIN/CRON[706]: (mail) CMD ( if [ -x /usr/sbin/exim -a -f /etc/exim.conf ]; then /usr/sbin/exim -q >/dev/null 2>&1; fi)
Oct 13 13:39:14 localhost pppd[709]: pppd 2.3.11 started by root, uid 0
Oct 13 13:39:15 localhost chat[710]: abort on (BUSY)
Oct 13 13:39:15 localhost chat[710]: abort on (NO CARRIER)
Oct 13 13:39:15 localhost chat[710]: abort on (VOICE)
Oct 13 13:39:15 localhost chat[710]: abort on (NO DIALTONE)
Oct 13 13:39:15 localhost chat[710]: send (ATDT<put^M)
Oct 13 13:39:15 localhost chat[710]: expect (phone)
Oct 13 13:39:18 localhost pppd[709]: Terminating on signal 15.
Oct 13 13:39:18 localhost pppd[709]: Connect script failed
Oct 13 13:39:18 localhost chat[710]: SIGTERM
Oct 13 13:39:19 localhost pppd[709]: Exit.
Oct 13 13:39:28 localhost pppd[718]: pppd 2.3.11 started by root, uid 0
Oct 13 13:39:29 localhost chat[719]: abort on (BUSY)
Oct 13 13:39:29 localhost chat[719]: abort on (NO CARRIER)
Oct 13 13:39:29 localhost chat[719]: abort on (VOICE)
Oct 13 13:39:29 localhost chat[719]: abort on (NO DIALTONE)
Oct 13 13:39:29 localhost chat[719]: abort on (NO DIAL TONE)
Oct 13 13:39:29 localhost chat[719]: abort on (NO ANSWER)
Oct 13 13:39:29 localhost chat[719]: send (ATZ^M)
Oct 13 13:39:29 localhost chat[719]: expect (OK)
Oct 13 13:39:29 localhost chat[719]: ATZ^M^M
Oct 13 13:39:29 localhost chat[719]: OK
Oct 13 13:39:29 localhost chat[719]: -- got it
Oct 13 13:39:29 localhost chat[719]: send (ATDT2869501^M)
Oct 13 13:39:29 localhost chat[719]: expect (CONNECT)
Oct 13 13:39:29 localhost chat[719]: ^M
Oct 13 13:40:01 localhost /USR/SBIN/CRON[722]: (root) CMD (if [ -x /usr/bin/mrtg ]; then /usr/bin/mrtg /etc/mrtg.cfg; fi)
Oct 13 13:40:06 localhost chat[719]: ATDT2869501^M^M
Oct 13 13:40:06 localhost chat[719]: CONNECT
Oct 13 13:40:06 localhost chat[719]: -- got it
Oct 13 13:40:06 localhost chat[719]: send (\d)
Oct 13 13:40:07 localhost pppd[718]: Serial connection established.
Oct 13 13:40:07 localhost pppd[718]: Using interface ppp0
Oct 13 13:40:07 localhost pppd[718]: Connect: ppp0 <--> /dev/ttyS0
Oct 13 13:40:08 localhost pppd[718]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x670630a8> <pcomp> <accomp>]
Oct 13 13:40:29 localhost last message repeated 7 times
Oct 13 13:40:30 localhost pppd[718]: Hangup (SIGHUP)
Oct 13 13:40:30 localhost pppd[718]: Modem hangup
Oct 13 13:40:30 localhost pppd[718]: Connection terminated.
Oct 13 13:40:31 localhost pppd[718]: Exit.
--
Who's watching the watchmen?
ICQ: 15096825
Reply to: