Subject: PPP trouble
To: None <netbsd-help@netbsd.org>
From: =?iso-8859-1?Q?Florian_St=F6hr?= <ich@florian-stoehr.de>
List: netbsd-help
Date: 08/16/2002 17:32:51
Hi,

i encounter serious strange problems connecting to my ISP under NetBSD 1.5.2
/ pppd 2.2.4

Let me first summarize what I've encountered:
- Modem/ISP both work, can dial with same modem, same cable under Win2k
without problems
- Init commands to modem taken from win2k-modemlog, they work definitely
(just reducing speaker volume).
- SAME PROBLEM under FreeBSD with pppd 2.2.5 (another machine!), so I assume
a configuration problem.

The problem is that after connection, modem hangs up (4 seconds). pppd
doesn't report a config error, chat script seems to work. But just when the
peer starts sending binary data (?), pppd disconnects.

Here's the /var/log/messages (debug, kdebug 4):

Aug 15 20:43:39 server pppd[649]: pppd 2.4.0 started by root, uid 0
Aug 15 20:43:41 server chat[652]: timeout set to 60 seconds
Aug 15 20:43:41 server chat[652]: abort on (BUSY)
Aug 15 20:43:41 server chat[652]: abort on (NO CARRIER)
Aug 15 20:43:41 server chat[652]: abort on (NO DIALTONE)
Aug 15 20:43:41 server chat[652]: abort on (ERROR)
Aug 15 20:43:41 server chat[652]: send (ATE0Q0V1^M)
Aug 15 20:43:41 server chat[652]: expect (OK)
Aug 15 20:43:41 server chat[652]: ATE0Q0V1^M^M
Aug 15 20:43:41 server chat[652]: OK
Aug 15 20:43:41 server chat[652]:  -- got it
Aug 15 20:43:41 server chat[652]: send (AT &F E1 V1 &A3 &B1 &D2 &S0 &C1
S0=0^M)
Aug 15 20:43:42 server chat[652]: expect (OK)
Aug 15 20:43:42 server chat[652]: ^M
Aug 15 20:43:42 server chat[652]: ^M
Aug 15 20:43:42 server chat[652]: OK
Aug 15 20:43:42 server chat[652]:  -- got it
Aug 15 20:43:42 server chat[652]: send
(ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4^M)
Aug 15 20:43:42 server chat[652]: expect (OK)
Aug 15 20:43:42 server chat[652]: ^M
Aug 15 20:43:43 server chat[652]: ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4^M^M
Aug 15 20:43:43 server chat[652]: OK
Aug 15 20:43:43 server chat[652]:  -- got it
Aug 15 20:43:43 server chat[652]: send (ATS0=0^M)
Aug 15 20:43:43 server chat[652]: expect (OK)
Aug 15 20:43:43 server chat[652]: ^M
Aug 15 20:43:43 server chat[652]: ATS0=0^M^M
Aug 15 20:43:43 server chat[652]: OK
Aug 15 20:43:43 server chat[652]:  -- got it
Aug 15 20:43:43 server chat[652]: send (ATE0Q0V1^M)
Aug 15 20:43:43 server chat[652]: expect (OK)
Aug 15 20:43:43 server chat[652]: ^M
Aug 15 20:43:43 server chat[652]: ATE0Q0V1^M^M
Aug 15 20:43:43 server chat[652]: OK
Aug 15 20:43:43 server chat[652]:  -- got it
Aug 15 20:43:43 server chat[652]: send (AT &F E1 V1 &A3 &B1 &D2 &S0 &C1
S0=0^M)
Aug 15 20:43:44 server chat[652]: expect (OK)
Aug 15 20:43:44 server chat[652]: ^M
Aug 15 20:43:44 server chat[652]: ^M
Aug 15 20:43:44 server chat[652]: OK
Aug 15 20:43:44 server chat[652]:  -- got it
Aug 15 20:43:44 server chat[652]: send
(ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4^M)
Aug 15 20:43:45 server chat[652]: expect (OK)
Aug 15 20:43:45 server chat[652]: ^M
Aug 15 20:43:45 server chat[652]: ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4^M^M
Aug 15 20:43:45 server chat[652]: OK
Aug 15 20:43:45 server chat[652]:  -- got it
Aug 15 20:43:45 server chat[652]: send (ATDT019231760^M)
Aug 15 20:43:45 server chat[652]: expect (CONNECT)
Aug 15 20:43:45 server chat[652]: ^M
Aug 15 20:44:11 server chat[652]: ATDT019231760^M^M
Aug 15 20:44:11 server chat[652]: CONNECT
Aug 15 20:44:11 server chat[652]:  -- got it
Aug 15 20:44:11 server chat[652]: send (^M)
Aug 15 20:44:11 server pppd[649]: Serial connection established.
Aug 15 20:44:11 server pppd[649]: Using interface ppp0
Aug 15 20:44:11 server pppd[649]: Connect: ppp0 <--> /dev/ttyp0
Aug 15 20:44:12 server /netbsd: ppp0 output:
ff03c0210101001202060000000005067906c6100802
Aug 15 20:44:12 server /netbsd: ppp0 output:
ff03c0210101001202060000000005067906c6100802
Aug 15 20:44:12 server /netbsd: ppp0 output: ff03c0210492000a0304c0230702
Aug 15 20:44:12 server /netbsd: ppp0 output: ff03c0210492000a0304c0230702
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049300090305c22305
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049300090305c22305
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049400080304c023
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049400080304c023
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049500090305c22305
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049500090305c22305
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049600080304c023
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c021049600080304c023
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c02106970004
Aug 15 20:44:13 server /netbsd: ppp0 output: ff03c02106970004
Aug 15 20:44:15 server /netbsd: ppp0 output:
ff03c0210101001202060000000005067906c6100802
Aug 15 20:44:15 server /netbsd: ppp0 output:
ff03c0210101001202060000000005067906c6100802
Aug 15 20:44:15 server pppd[649]: Modem hangup
Aug 15 20:44:15 server pppd[649]: Connection terminated.
Aug 15 20:44:17 server pppd[649]: Exit.

Strange ppp0 output section! And why "Connect ppp0  <--> /dev/ttyp0 ? What's
the 'p' ?

here are my config files (i invoke all with pppd call fscbc)

#/etc/ppp/options file:
/dev/tty01
lock
crtscts
57600
modem
defaultroute
noipdefault
record modemlog.txt
nopcomp
novj

#/etc/ppp/pap-secrets file (this is a call-by-call provider with
user-defined, free passwords)
"ventelo" * "internet"

#/etc/ppp/peers/fscbc file:
modem 19200 crtscts
lock
connect 'chat -v -f /etc/ppp/peers/fscbc.chat'
noauth
debug
kdebug 4

#/etc/ppp/peers/fscbc.chat file:
# /etc/ppp/peers/fscbc.chat
TIMEOUT 60
ABORT BUSY
ABORT 'NO CARRIER'
ABORT 'NO DIALTONE'
ABORT ERROR
'' ATE0Q0V1
OK 'AT &F E1 V1 &A3 &B1 &D2 &S0 &C1 S0=0'
OK ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4
OK ATS0=0
OK ATE0Q0V1
OK 'AT &F E1 V1 &A3 &B1 &D2 &S0 &C1 S0=0'
OK ATS7=60S19=0L0M1&M4&K1&H1&R2&I0B0X4
OK ATDT019231760
CONNECT ''


Please tell me your suggestions ... don't know where to search now, I played
around with this for HOURS now !!!

Thank you
Flo