Help. I got big troubles but I don't know what they are. I've included the log
file from pppd -debug and my connect scripts. Do you need any more info.
Thanks in advance for your help.
Log From PPPD:
Feb 20 14:46:55 eleocharis pppd[153]: pppd 2.1.2 started by root, uid 0
Feb 20 14:46:55 eleocharis pppd[153]: Connecting with <chat -v -f
$PPP/chat.scr>
Feb 20 14:46:56 eleocharis chat[154]: abort on (ERROR)
Feb 20 14:46:56 eleocharis chat[154]: abort on (BUSY)
Feb 20 14:46:56 eleocharis chat[154]: abort on (NO CARRIER)
Feb 20 14:46:56 eleocharis chat[154]: send (ATZ^M)
Feb 20 14:46:56 eleocharis chat[154]: expect (OK)
Feb 20 14:46:57 eleocharis chat[154]: ATZ^M^M
Feb 20 14:46:57 eleocharis chat[154]: OK -- got it
Feb 20 14:46:57 eleocharis chat[154]: send (ATDT*70,xxxxxxxxx^M)
Feb 20 14:46:58 eleocharis chat[154]: expect (CONNECT)
Feb 20 14:46:58 eleocharis chat[154]: ^M
Feb 20 14:47:19 eleocharis chat[154]: ATDT*70,xxxxxxxxxxxxxx^M^M
Feb 20 14:47:19 eleocharis chat[154]: CARRIER 9600^M
Feb 20 14:47:19 eleocharis chat[154]: ^M
Feb 20 14:47:19 eleocharis chat[154]: PROTOCOL: LAP-M^M
Feb 20 14:47:19 eleocharis chat[154]: ^M
Feb 20 14:47:19 eleocharis chat[154]: COMPRESSION: V.42BIS^M
Feb 20 14:47:19 eleocharis chat[154]: ^M
Feb 20 14:47:19 eleocharis chat[154]: CONNECT -- got it
Feb 20 14:47:19 eleocharis chat[154]: send (^M)
Feb 20 14:47:19 eleocharis chat[154]: expect (ogin:)
Feb 20 14:47:19 eleocharis chat[154]: 9600/ARQ^M
Feb 20 14:47:22 eleocharis chat[154]: Welcome to Internet Access...
1098:107^M
Feb 20 14:47:22 eleocharis chat[154]: ^M
Feb 20 14:47:22 eleocharis chat[154]: Need Help? Call 800- .^M
Feb 20 14:47:22 eleocharis chat[154]: ^M
Feb 20 14:47:22 eleocharis chat[154]: login: -- got it
Feb 20 14:47:22 eleocharis chat[154]: send (ssword:xxxxxxxxxx^M)
Feb 20 14:47:22 eleocharis pppd[153]: Connected...
Feb 20 14:47:23 eleocharis pppd[153]: Using interface ppp0
Feb 20 14:47:23 eleocharis pppd[153]: Connect: ppp0 <--> /dev/modem
Feb 20 14:47:23 eleocharis pppd[153]: set_xaccm: 00000000 00000000 00000000
60000000
Feb 20 14:47:23 eleocharis pppd[153]: send_config: mtu = 1500
Feb 20 14:47:23 eleocharis pppd[153]: send_config: asyncmap = ffffffff
Feb 20 14:47:23 eleocharis pppd[153]: send_config: flags = 0
Feb 20 14:47:23 eleocharis pppd[153]: recv_config: mru = 1500
Feb 20 14:47:23 eleocharis pppd[153]: recv_config: asyncmap = 0
Feb 20 14:47:23 eleocharis pppd[153]: recv_config: flags = 10
Feb 20 14:47:23 eleocharis pppd[153]: sent [LCP ConfReq id=0x1 <mru 1500>
<magic 0x312d9315> <pcomp> <accomp>]
Feb 20 14:47:23 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 1, id 1.
Feb 20 14:47:23 eleocharis pppd[153]: Timeout 2194:10920 in 3 seconds.
Feb 20 14:47:23 eleocharis pppd[153]: Setting itimer for 3 seconds in timeout.
Feb 20 14:47:23 eleocharis pppd[153]: LCP: sending Configure-Request, id 1
Feb 20 14:47:26 eleocharis pppd[153]: Alarm
Feb 20 14:47:26 eleocharis pppd[153]: sent [LCP ConfReq id=0x1 <mru 1500>
<magic 0x312d9315> <pcomp> <accomp>]
Feb 20 14:47:26 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 1, id 1.
Feb 20 14:47:26 eleocharis pppd[153]: Timeout 2194:10920 in 3 seconds.
Feb 20 14:47:26 eleocharis pppd[153]: Setting itimer for 3 seconds in timeout.
Feb 20 14:47:26 eleocharis pppd[153]: LCP: sending Configure-Request, id 1
Feb 20 14:47:26 eleocharis pppd[153]: IO signal received
Feb 20 14:47:26 eleocharis pppd[153]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x964ef7ba> <pcomp> <accomp> <auth upap>] bb b6
Feb 20 14:47:26 eleocharis pppd[153]: fsm_rconfreq(LCP): Rcvd id 1.
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: rcvd ASYNCMAP
Feb 20 14:47:26 eleocharis pppd[153]: (0)
Feb 20 14:47:26 eleocharis pppd[153]: (ACK)
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: rcvd MAGICNUMBER
Feb 20 14:47:26 eleocharis pppd[153]: (964ef7ba)
Feb 20 14:47:26 eleocharis pppd[153]: (ACK)
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: rcvd PCOMPRESSION
Feb 20 14:47:26 eleocharis pppd[153]: (ACK)
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: rcvd ACCOMPRESSION
Feb 20 14:47:26 eleocharis pppd[153]: (ACK)
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: rcvd AUTHTYPE
Feb 20 14:47:26 eleocharis pppd[153]: (REJ)
Feb 20 14:47:26 eleocharis pppd[153]: lcp_reqci: returning CONFREJ.
Feb 20 14:47:26 eleocharis pppd[153]: sent [LCP ConfRej id=0x1 <auth upap>]
Feb 20 14:47:26 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 4, id 1.
Feb 20 14:47:27 eleocharis pppd[153]: IO signal received
Feb 20 14:47:27 eleocharis pppd[153]: rcvd [LCP ConfReq id=0x2 <asyncmap 0x0>
<auth chap 05> <magic 0x964ef7ba> <pcomp> <accomp>] 10 ba
Feb 20 14:47:27 eleocharis pppd[153]: fsm_rconfreq(LCP): Rcvd id 2.
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd ASYNCMAP
Feb 20 14:47:27 eleocharis pppd[153]: (0)
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd AUTHTYPE
Feb 20 14:47:27 eleocharis pppd[153]: (REJ)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd MAGICNUMBER
Feb 20 14:47:27 eleocharis pppd[153]: (964ef7ba)
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd PCOMPRESSION
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd ACCOMPRESSION
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: returning CONFREJ.
Feb 20 14:47:27 eleocharis pppd[153]: sent [LCP ConfRej id=0x2 <auth chap 05>]
Feb 20 14:47:27 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 4, id 2.
Feb 20 14:47:27 eleocharis pppd[153]: IO signal received
Feb 20 14:47:27 eleocharis pppd[153]: rcvd [LCP ConfReq id=0x3 <asyncmap 0x0>
<magic 0x964ef7ba> <pcomp> <accomp>] fe 3d
Feb 20 14:47:27 eleocharis pppd[153]: fsm_rconfreq(LCP): Rcvd id 3.
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd ASYNCMAP
Feb 20 14:47:27 eleocharis pppd[153]: (0)
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd MAGICNUMBER
Feb 20 14:47:27 eleocharis pppd[153]: (964ef7ba)
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd PCOMPRESSION
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: rcvd ACCOMPRESSION
Feb 20 14:47:27 eleocharis pppd[153]: (ACK)
Feb 20 14:47:27 eleocharis pppd[153]: lcp_reqci: returning CONFACK.
Feb 20 14:47:27 eleocharis pppd[153]: sent [LCP ConfAck id=0x3 <asyncmap 0x0>
<magic 0x964ef7ba> <pcomp> <accomp>]
Feb 20 14:47:27 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 2, id 3.
Feb 20 14:47:29 eleocharis pppd[153]: Alarm
Feb 20 14:47:29 eleocharis pppd[153]: sent [LCP ConfReq id=0x1 <mru 1500>
<magic 0x312d9315> <pcomp> <accomp>]
Feb 20 14:47:29 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 1, id 1.
Feb 20 14:47:29 eleocharis pppd[153]: Timeout 2194:10920 in 3 seconds.
Feb 20 14:47:29 eleocharis pppd[153]: Setting itimer for 3 seconds in timeout.
Feb 20 14:47:29 eleocharis pppd[153]: LCP: sending Configure-Request, id 1
Feb 20 14:47:29 eleocharis pppd[153]: IO signal received
Feb 20 14:47:29 eleocharis pppd[153]: rcvd [LCP ConfAck id=0x1 <mru 1500>
<magic 0x312d9315> <pcomp> <accomp>] aa 0e
Feb 20 14:47:29 eleocharis pppd[153]: fsm_rconfack(LCP): Rcvd id 1.
Feb 20 14:47:29 eleocharis pppd[153]: Untimeout 2194:10920.
Feb 20 14:47:29 eleocharis pppd[153]: Setting itimer for 0 seconds in
untimeout.
Feb 20 14:47:29 eleocharis pppd[153]: send_config: mtu = 1500
Feb 20 14:47:29 eleocharis pppd[153]: send_config: asyncmap = 0
Feb 20 14:47:29 eleocharis pppd[153]: send_config: flags = f000013
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: mru = 1500
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: asyncmap = 0
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: flags = f000003
Feb 20 14:47:29 eleocharis pppd[153]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>
<compress VJ 0f 01>]
Feb 20 14:47:29 eleocharis pppd[153]: fsm_sdata(IPCP): Sent code 1, id 1.
Feb 20 14:47:29 eleocharis pppd[153]: Timeout 2194:114f0 in 3 seconds.
Feb 20 14:47:29 eleocharis pppd[153]: Setting itimer for 3 seconds in timeout.
Feb 20 14:47:29 eleocharis pppd[153]: IPCP: sending Configure-Request, id 1
Feb 20 14:47:29 eleocharis pppd[153]: IO signal received
Feb 20 14:47:29 eleocharis pppd[153]: rcvd [LCP TermReq id=0x4] 80 fe
Feb 20 14:47:29 eleocharis pppd[153]: fsm_rtermreq(LCP): Rcvd id 4.
Feb 20 14:47:29 eleocharis pppd[153]: LCP terminated at peer's request
Feb 20 14:47:29 eleocharis pppd[153]: Untimeout 2194:114f0.
Feb 20 14:47:29 eleocharis pppd[153]: Setting itimer for 0 seconds in
untimeout.
Feb 20 14:47:29 eleocharis pppd[153]: send_config: mtu = 1500
Feb 20 14:47:29 eleocharis pppd[153]: send_config: asyncmap = ffffffff
Feb 20 14:47:29 eleocharis pppd[153]: send_config: flags = f000000
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: mru = 1500
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: asyncmap = 0
Feb 20 14:47:29 eleocharis pppd[153]: recv_config: flags = f000010
Feb 20 14:47:29 eleocharis pppd[153]: Timeout 2194:10920 in 3 seconds.
Feb 20 14:47:29 eleocharis pppd[153]: Setting itimer for 3 seconds in timeout.
Feb 20 14:47:29 eleocharis pppd[153]: sent [LCP TermAck id=0x4]
Feb 20 14:47:29 eleocharis pppd[153]: fsm_sdata(LCP): Sent code 6, id 4.
Feb 20 14:47:32 eleocharis pppd[153]: Alarm
Feb 20 14:47:32 eleocharis pppd[153]: Connection terminated.
Feb 20 14:47:32 eleocharis pppd[153]: Exit.
PPPD script:
pppd /dev/modem 115200 connect 'chat -v -f $PPP/chat.scr' -detach debug
crtscts modem defaultroute noipdefault
Chat script:
ABORT ERROR ABORT BUSY ABORT "NO CARRIER" "" ATZ OK ATDT*70,xxxxxxxxxx CONNECT
"" ogin:--ogin:sands ssword:xxxxxxxxxx