[CLUE-Tech] mgetty and ppp troubles on linux dialin server

[daniel]

Hi Jim,

I got to do some major testing after I got the new modem all set up.  The
modems can CONNECT perfectly now. Hopefully the last snag that I am running
into is that PAP authentication does not seem to succeed.

Here is what I see from the client using minicom to dial into the server:
---
ATZ
OK
atdt 3034840000
CONNECT 24000

PROTOCOL:LAPM

ns1 login: carol
Looking for secret in /etc/ppp/pap-secrets for client (null) server ns1
Got client carol
Got server *
Got secret *
Got auth_addr *
Got client *~b???^\^F^F|FE}xD||E}~Y{EE~ilg&^\CCGzE}|Dd||AYy1}g&H|zE}|Dd|1AYy~}g&H|
zE}|Dd|1AYy~}g>^\^F^F|FE}xD||E}~Y{

~hlg6^\CC|zE}|Dt||AYy1}g&H|zE}|DD|1AYy}g6^\^F^F}xDt||A}~Yy1}g6^\CC|zE}xDt||A}~Yy1}
NO CARRIER 
---

It takes 30 seconds to drop to NO CARRIER after login. No ppp0 interface is
set up.
(Side questions: does that "client (null)" just mean no hostname is known for
the client? Should the "Got secret *" line actually print the password instead
of a *?)
Here is the messages log from the server:
---
Apr 22 15:57:59 ns1 mgetty[22833]: data dev=ttyS0, pid=22833, caller='none',
conn='38400', name='', cmd='/usr/sbin/pppd', user='/AutoPPP/'
Apr 22 15:58:00 ns1 pppd[22833]: pppd 2.4.1 started by root, uid 0
Apr 22 15:58:00 ns1 pppd[22833]: Using interface ppp0
Apr 22 15:58:00 ns1 pppd[22833]: Connect: ppp0 <--> /dev/tts/0
Apr 22 15:58:30 ns1 pppd[22833]: LCP: timeout sending Config-Requests 
Apr 22 15:58:30 ns1 pppd[22833]: Connection terminated.
Apr 22 15:58:30 ns1 pppd[22833]: Exit.
---

The server's mgetty.log.ttyS0 file shows:
---
04/22 15:54:26 yS0  send: ATA[0d]
04/22 15:54:26 yS0  waiting for ``CONNECT''
04/22 15:54:26 yS0   got: ATA[0d]
04/22 15:54:26 yS0    CND: OKATA[0d][0a]CONNECT ** found **
04/22 15:54:47 yS0  send: 
04/22 15:54:47 yS0  waiting for ``_''
04/22 15:54:47 yS0   got:  38400[0d]
04/22 15:54:47 yS0    CND: CONNECT 38400
04/22 15:54:47 yS0    CND: found: 38400[0a] ** found **
04/22 15:54:47 yS0   waiting for line to clear (VTIME), read: 
04/22 15:54:47 yS0    looking for utmp entry... (my PID: 21987)
04/22 15:54:47 yS0   utmp + wtmp entry made
04/22 15:54:48 yS0   tio_set_flow_control( HARD )
04/22 15:54:48 yS0   print welcome banner (/etc/issue)
04/22 15:54:48 yS0   getlogname (AUTO_PPP), read:~[ff]}#[c0]!
04/22 15:54:48 yS0   input finished with '\r', setting ICRNL ONLCR
04/22 15:54:48 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
04/22 15:54:48 yS0    login: use login config file
/etc/mgetty+sendfax/login.config
04/22 15:54:48 yS0   match: user='/AutoPPP/', key=''
04/22 15:54:48 yS0   match: user='/AutoPPP/', key=''
04/22 15:54:48 yS0   match: user='/AutoPPP/', key='/AutoPPP/'*** hit!
04/22 15:54:48 yS0   calling login: cmd='/usr/sbin/pppd', argv[]='pppd file
/etc/ppp/options.server'
04/22 15:54:48 yS0   setenv: 'CALLER_ID=none'
04/22 15:54:48 yS0   setenv: 'CONNECT=38400'
04/22 15:54:48 yS0   setenv: 'DEVICE=ttyS0'
04/22 15:54:48 ##### data dev=ttyS0, pid=21987, caller='none', conn='38400',
name='', cmd='/usr/sbin/pppd', user='/AutoPPP/'
04/22 15:55:18 yS0  mgetty: experimental test release 1.1.30-Dec16
04/22 15:55:18 yS0  check for lockfiles
04/22 15:55:18 yS0   checklock: no active process has lock, will remove
04/22 15:55:18 yS0  locking the line
04/22 15:55:18 yS0   makelock(ttyS0) called
04/22 15:55:18 yS0   do_makelock: lock='/var/lock/LCK..ttyS0'
04/22 15:55:18 yS0   lock made
04/22 15:55:19 yS0   tio_get_rs232_lines: status: RTS CTS DSR DTR
04/22 15:55:19 yS0  lowering DTR to reset Modem
04/22 15:55:19 yS0   tss: set speed to 38400 (017)
04/22 15:55:19 yS0   tio_set_flow_control( HARD )
04/22 15:55:19 yS0   waiting for line to clear (VTIME), read: [0a][0a]NO
CARRIER[0a][0a]
04/22 15:55:20 yS0  send: \dATQ0V1H0[0d]
04/22 15:55:20 yS0  waiting for ``OK''
04/22 15:55:20 yS0   got: ATQ0V1H0[0d]
04/22 15:55:20 yS0    CND: ATQ0V1H0[0d][0a]OK ** found **
04/22 15:55:20 yS0  send: ATS0=0Q0&D3&C1[0d]
04/22 15:55:20 yS0  waiting for ``OK''
04/22 15:55:20 yS0   got: [0d]
04/22 15:55:20 yS0    CND: OK[0a]ATS0=0Q0&D3&C1[0d]
04/22 15:55:20 yS0    CND: ATS0=0Q0&D3&C1[0d][0a]OK ** found **
04/22 15:55:20 yS0   waiting for line to clear (VTIME), read: [0d][0a]
04/22 15:55:21 yS0   removing lock file
04/22 15:55:21 yS0  waiting...
---

This is the server's login.config file:
---
/AutoPPP/ - - /usr/sbin/pppd file /etc/ppp/options.server
*       -       -       /bin/login @
#*      -       ppp     /usr/sbin/pppd file /etc/ppp/options.server
---
At one point I tried commenting out the "/bin/login" line and adding the last
line (which is now commented out) "* - ppp /usr/sbin/pppd file
/etc/ppp/options.server" when I was continually being logged into the command
line. The change caused pppd to be started upon login, though the same 30
seconds of modem barf and NO CARRIER resulted.

Here is the server's pap-secrets file entry:
#client  server  secret      ip addresses
carol          *        "testing"          *

Here is the client's pap-secrets file entry:
#client  server  secret      ip addresses
carol          *        "testing"          *

Should I have the "s around the password?  And does including/excluding the
last * for "ip addresses" make much difference if I would like to be able to
dial in from any ip address?
(auto-generated entries do not seem to put the last * I noticed)
I want to let you know how thankful I am for your efforts so far; I know this
has dragged out a little.  Thanks for the offer concerning the modems in
Canada, though I am afraid the shipping charges may outweigh my need for them :)

Thanks again,
-Daniel Stickney