AUTOPPP Problems..
Dirk Lattermann (dlatt@t-online.de)
Tue, 29 Sep 1998 19:48:17 +0200
Rudy Ruiz <rruiz@socal.sasco.com> wrote:
> Well it seems that I can dialin just fine...the modem answers and it
> runs the autoppp
> stuff, but my 95 box used to dialin just sits there and eventually times
> out. Anyone know
> why its timing out?
> Thanks
I just happen to experience exactly the same symptoms and was asking
if someone has a hint, didn't know if this was the right group though...
It doesn't seem to matter if pppd gets the ttyS1 argument or not in
login.config, the connection ppp0 <--> /dev/ttyS1 is made with or without
it. (is /etc/ppp/options.ttyS1 used in both cases?)
I didn't think of removing the 115200 in /etc/ppp/options.ttyS1, perhaps
this is the cause of the problems, but know my peer is unavailable and I
can't check right know.
I hope I collected all relevant files:
login.config:
AutoPPP/ - a_ppp /usr/sbin/pppd noauth ttyS1 kdebug 7 debug 192.168.0.9:192.168.0.10
/etc/ppp/options:
auth
lock
/etc/ppp/options.ttyS1:
115200 crtscts modem
part of the vgetty log file:
09/29 18:28:24 yS1 vgetty: answer mode was set directly
09/29 18:28:24 yS1 vgetty: answer mode is [data]
09/29 18:28:24 yS1 vgetty: trying data connection
09/29 18:28:24 yS1 voice command: 'AT#CLS=0' -> 'OK'
09/29 18:28:24 yS1 vgetty: AT#CLS=0
09/29 18:28:24 yS1 Rockwell: OK
09/29 18:28:25 yS1 voice command: 'AT' -> 'OK'
09/29 18:28:25 yS1 vgetty: AT
09/29 18:28:25 yS1 Rockwell: OK
09/29 18:28:25 yS1 voice command: 'AT+FAA=0' -> 'OK'
09/29 18:28:25 yS1 vgetty: AT+FAA=0
09/29 18:28:25 yS1 Rockwell: OK
09/29 18:28:25 yS1 vgetty: Restoring signal handlers
09/29 18:28:25 yS1 vgetty: No signal handlers were installed
09/29 18:28:25 yS1 send: ATA[0d]
09/29 18:28:25 yS1 waiting for ``CONNECT''
09/29 18:28:25 yS1 got: [0d]
09/29 18:28:39 yS1 CND: OK[0a]CONNECT ** found **
09/29 18:28:39 yS1 send:
09/29 18:28:39 yS1 waiting for ``_''
09/29 18:28:39 yS1 got: 38400/REL[0d]
09/29 18:28:39 yS1 CND: CONNECT 38400/REL
09/29 18:28:39 yS1 CND: found: 38400/REL[0a] ** found **
09/29 18:28:39 yS1 waiting for line to clear (VTIME), read: ~[ff]}#[c0]!}!}!} }7}"}&} }*} } }%}&} }1}6} }'}"}(}"}-}#}&[b8];~
09/29 18:28:39 yS1 looking for utmp entry... (my PID: 648)
09/29 18:28:39 yS1 utmp + wtmp entry made
09/29 18:28:40 yS1 tio_set_flow_control( HARD )
09/29 18:28:40 yS1 print welcome banner (/etc/issue)
09/29 18:28:40 yS1 getlogname (AUTO_PPP), read:~[ff]}#[c0]!
09/29 18:28:42 yS1 input finished with '\r', setting ICRNL ONLCR
09/29 18:28:42 yS1 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
09/29 18:28:42 yS1 login: use login config file /etc/mgetty+sendfax/login.config
09/29 18:28:42 yS1 match: user='/AutoPPP/', key=''
09/29 18:28:42 yS1 match: user='/AutoPPP/', key=''
09/29 18:28:42 yS1 match: user='/AutoPPP/', key='/AutoPPP/'*** hit!
09/29 18:28:42 yS1 login: utmp entry: a_ppp
09/29 18:28:42 yS1 looking for utmp entry... (my PID: 648)
09/29 18:28:42 yS1 utmp + wtmp entry made
09/29 18:28:42 yS1 calling login: cmd='/usr/sbin/pppd', argv[]='pppd noauth ttyS1 kdebug 7 debug 192.168.0.9:192.168.0.10'
09/29 18:28:42 ##### data dev=ttyS1, pid=648, caller='none', conn='38400/REL', name='', cmd='/usr/sbin/pppd', user='/AutoPPP/'
09/29 18:28:42 yS1 setenv: 'CALLER_ID=none'
09/29 18:28:42 yS1 setenv: 'CONNECT=38400/REL'
--
ppp log:
Sep 29 18:28:42 localhost pppd[648]: pppd 2.3.5 started by a_ppp, uid 0
Sep 29 18:28:42 localhost kernel: ppp_ioctl: set dbg flags to 70000
Sep 29 18:28:42 localhost kernel: ppp_ioctl: set flags to 70000
Sep 29 18:28:42 localhost kernel: ppp_tty_ioctl: set xasyncmap
Sep 29 18:28:42 localhost kernel: ppp_tty_ioctl: set xmit asyncmap ffffffff
Sep 29 18:28:42 localhost kernel: ppp_ioctl: set flags to 70000
Sep 29 18:28:42 localhost kernel: ppp_ioctl: set mru to 5dc
Sep 29 18:28:42 localhost kernel: ppp_tty_ioctl: set rcv asyncmap ffffffff
Sep 29 18:28:42 localhost kernel: ppp_ioctl: set flags to 70000
Sep 29 18:28:42 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:42 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:42 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:42 localhost kernel: 08 02 ..
Sep 29 18:28:42 localhost pppd[648]: Using interface ppp0
Sep 29 18:28:42 localhost pppd[648]: Connect: ppp0 <--> /dev/ttyS1
Sep 29 18:28:42 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:45 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:45 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:45 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:45 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:45 localhost kernel: 08 02 ..
Sep 29 18:28:48 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:48 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:48 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:48 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:48 localhost kernel: 08 02 ..
Sep 29 18:28:51 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:51 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:51 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:51 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:51 localhost kernel: 08 02 ..
Sep 29 18:28:54 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:54 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:54 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:54 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:54 localhost kernel: 08 02 ..
Sep 29 18:28:57 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:28:57 localhost kernel: ppp: write frame, count = 18
Sep 29 18:28:57 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:28:57 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:28:57 localhost kernel: 08 02 ..
Sep 29 18:29:00 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:29:00 localhost kernel: ppp: write frame, count = 18
Sep 29 18:29:00 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:29:00 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:29:00 localhost kernel: 08 02 ..
Sep 29 18:29:03 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:29:03 localhost kernel: ppp: write frame, count = 18
Sep 29 18:29:03 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:29:03 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:29:03 localhost kernel: 08 02 ..
Sep 29 18:29:06 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:29:06 localhost kernel: ppp: write frame, count = 18
Sep 29 18:29:06 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:29:06 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:29:06 localhost kernel: 08 02 ..
Sep 29 18:29:09 localhost kernel: ppp: write frame, count = 18
Sep 29 18:29:09 localhost kernel: FF 03 C0 21 01 01 00 0E ...!....
Sep 29 18:29:09 localhost kernel: 05 06 00 00 1B FF 07 02 ........
Sep 29 18:29:09 localhost kernel: 08 02 ..
Sep 29 18:29:09 localhost pppd[648]: sent [LCP ConfReq id=0x1 <magic 0x1bff> <pcomp> <accomp>]
Sep 29 18:29:12 localhost kernel: ppp: channel ppp0 closing.
Sep 29 18:29:12 localhost kernel: ppp0 released
Sep 29 18:29:12 localhost kernel: ppp0: ccp closed
Sep 29 18:29:12 localhost pppd[648]: LCP: timeout sending Config-Requests
Sep 29 18:29:12 localhost pppd[648]: Connection terminated.
Sep 29 18:29:12 localhost pppd[648]: Hangup (SIGHUP)
Sep 29 18:29:12 localhost pppd[648]: Exit.
--
Dirk Lattermann | Information is not knowledge | Beauty is not love
dlatt@t-online.de | Knowledge is not wisdom | Love is not music
Bonn, Germany | Wisdom is not truth | Music is THE BEST
PGP Key available | Truth is not beauty | (Frank Zappa)