Conference turris::digital_unix

9781.0. "Can't dialout using pppd?" by CSC32::A_LICAUSE () Mon May 12 1997 09:48

I'm attempting to dial out to a local ISP from my dunix V4.0 system.  This 
    worked just fine until I made one change in the S0 register of the
    modem which allowed it to autoanswer on the first ring.  I then saved
    the parameters to servive a power cycle.  
    
    I have since changed the parameter back to 0 to disallow auto answer
    and now the dialout fails.  I can't understand what the log is telling
    me ( included below ).  
    
    I can establish a connetion to this same ISP using the same login and
    setup information from my NT system though it is a different modem.
    
    From the log below, can anyone help me understand what is going wrong
    here.  Can some one also recommend good reference on this subject for
    future debugs of ppp?
    
    Thanks,
    Al
    
    chatscript used to dialout:
    
    /usr/sbin/pppd connect '/usr/ucb/chat -v "" "atdt(phone#)" "CONNECT"
    "\r" "\}"' +ua /usr/users/licause/papuid /dev/ttyd0 115200 crtscts 
    -detach defaultroute passive -d
    exit 0
    
    papuid - contains usersid and pasword for ISP account.
    
    
    
    
    
May 12 08:25:24 licause pppd[1768]: pppd started by licause, uid 0
May 12 08:25:24 licause pppd[1768]: Connecting with </usr/ucb/chat -v "" "atdt9187101" "CONNECT" "\r" "\}" >
May 12 08:26:12 licause pppd[1768]: Serial connection established.
May 12 08:26:13 licause pppd[1768]: Using interface ppp0
May 12 08:26:13 licause pppd[1768]: Connect: ppp0 <--> /dev/ttyd0
May 12 08:26:13 licause pppd[1768]: sent [LCP ConfReq id=0x1 <mru 1500> <auth upap> <magic 0x5ba9f008> <pcomp> <accomp>]
May 12 08:26:13 licause pppd[1768]: fsm_sdata(LCP): Sent code 1, id 1.
May 12 08:26:13 licause pppd[1768]: Timeout 120008210:140007fb0 in 3 seconds.
May 12 08:26:13 licause pppd[1768]: LCP: sending Configure-Request, id 1
May 12 08:26:13 licause pppd[1768]: rcvd [LCP ConfAck id=0x1 <mru 1500> <auth upap> <magic 0x5ba9f008> <pcomp> <accomp>]
May 12 08:26:13 licause pppd[1768]: fsm_rconfack(LCP): Rcvd id 1.
May 12 08:26:14 licause pppd[1768]: rcvd [LCP ConfReq id=0x7e <asyncmap 0xa0000> <auth upap> <magic 0xa97b954c> <pcomp> <accomp>]
May 12 08:26:14 licause pppd[1768]: fsm_rconfreq(LCP): Rcvd id 126.
May 12 08:26:14 licause pppd[1768]: lcp_reqci: rcvd ASYNCMAP
May 12 08:26:14 licause pppd[1768]: (a0000)
May 12 08:26:14 licause pppd[1768]:  (ACK)
May 12 08:26:14 licause pppd[1768]: lcp_reqci: rcvd AUTHTYPE
May 12 08:26:14 licause pppd[1768]: (c023)
May 12 08:26:14 licause pppd[1768]:  (ACK)
May 12 08:26:14 licause pppd[1768]: lcp_reqci: rcvd MAGICNUMBER
May 12 08:26:14 licause pppd[1768]: (a97b954c)
May 12 08:26:14 licause pppd[1768]:  (ACK)
May 12 08:26:14 licause pppd[1768]: lcp_reqci: rcvd PCOMPRESSION
May 12 08:26:14 licause pppd[1768]:  (ACK)
May 12 08:26:14 licause pppd[1768]: lcp_reqci: rcvd ACCOMPRESSION
May 12 08:26:14 licause pppd[1768]:  (ACK)
May 12 08:26:14 licause pppd[1768]: lcp_reqci: returning CONFACK.
May 12 08:26:14 licause pppd[1768]: sent [LCP ConfAck id=0x7e <asyncmap 0xa0000> <auth upap> <magic 0xa97b954c> <pcomp> <accomp>]
May 12 08:26:14 licause pppd[1768]: fsm_sdata(LCP): Sent code 2, id 126.
May 12 08:26:14 licause pppd[1768]: Untimeout 120008210:140007fb0.
May 12 08:26:14 licause pppd[1768]: Timeout 120010138:140008218 in 30 seconds.
May 12 08:26:14 licause pppd[1768]: sent [PAP AuthReq id=0x1 user="user" password="password"]
May 12 08:26:14 licause pppd[1768]: upap_sauth: Sent id 1.
May 12 08:26:14 licause pppd[1768]: Timeout 120010098:140008218 in 3 seconds.
May 12 08:26:15 licause pppd[1768]: rcvd [PAP AuthAck id=0x1msg=""]
May 12 08:26:15 licause pppd[1768]: upap_rauthack: Rcvd id 1.
May 12 08:26:15 licause pppd[1768]: Remote message: 
May 12 08:26:44 licause pppd[1768]: Untimeout 120010138:140008218.
May 12 08:26:44 licause pppd[1768]: sent [LCP TermReq id=0x2]
May 12 08:26:44 licause pppd[1768]: fsm_sdata(LCP): Sent code 5, id 2.
May 12 08:26:44 licause pppd[1768]: Timeout 120008210:140007fb0 in 3 seconds.
May 12 08:26:44 licause pppd[1768]: rcvd [LCP TermAck id=0x2]
May 12 08:26:44 licause pppd[1768]: fsm_rtermack(LCP).
May 12 08:26:44 licause pppd[1768]: Untimeout 120008210:140007fb0.
May 12 08:26:44 licause pppd[1768]: Connection terminated.
May 12 08:26:44 licause pppd[1773]: slave died:  EOF on pipe.
May 12 08:26:45 licause pppd[1768]: Exit.

    Problem solved, but still curious about sources for debugging aides
    for PPP...
    
    Turns out in attempting to debug a customer problem I had configured
    /etc/ppp/options.  Once this file was either removed or all lines
    commented out, I was able to establish a full link to the ISP.
    

Copying this here from note 9801.0.

From:  NNTPD::"[email protected]" "Farrell Woods"
Topic: what else changed?

Somehing else probably changed...

That trace says to me that ppp never entered the network phase.  That is,
no IPCP config-requests were exchanged.  Also, your side appears to be asking
for authentication from the peer.  It hasn't been my experience that ISP's
will do two-way authentication.  Your peer may not have a secret that will
allow the ISP to authenticate itself to you.  pppd might be exiting because
of this (but if that's the case then it should have said something to that
effect.  The ppp code that I'm putting into Steel is better about this.)

What does your /etc/ppp/options file look like?

        -- Farrell


[Posted by WWW Notes gateway]