well, i have red hat 6.0 with mgetty-1.1.14-8, ppp-2.3.7-2, callback
4.24, icmake 6.22.
My problem is that the win9x client connects to the ppp server on
linux and the server calls back. The line is not dropped by the client
and the linux ppp successfully logins and gives the bash prompt. i can
see through the "w" command on my linux server that i am connected on
ttyS0. BUt the problem is that PPP never starts although it is set to
AutoPPP. The client just hungs up at verifying username & password.
below are the logs.
appreciate your help.
--------------------------------------------------------
callback.log
Fri Nov 26 15:16:56 1999
cb V 4.24: Requesting default ttyline 'ttyS0'
Fri Nov 26 15:16:56 1999
cb V 4.24: 'cb init ...' started
Fri Nov 26 15:16:56 1999
cb V 4.24: Line ttyS0 enabled: /etc/nologin.ttyS0 removed
Fri Nov 26 15:16:56 1999
cb V 4.24: Line ttyS1 enabled: /etc/nologin.ttyS1 removed
Fri Nov 26 15:16:56 1999
cb V 4.24: New '/etc/mgetty+sendfax/mgetty.config' written
Fri Nov 26 15:16:56 1999
cb V 4.24: New '/etc/mgetty+sendfax/mgetty.config' written
Fri Nov 26 15:16:56 1999
cb V 4.24: No modem process(es) killed
Fri Nov 26 15:19:22 1999
cblogin V 4.24: 'cblogin test' started
Fri Nov 26 15:19:22 1999
cblogin V 4.24: starting first_login()
Fri Nov 26 15:19:22 1999
cblogin V 4.24: Destination office: ok
Fri Nov 26 15:19:23 1999
cblogin V 4.24: setcallback(0 (= test), 0 (= office))
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: 'port ttyS0'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: 'port ttyS0'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: ' modem-type data'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: ' modem-type data'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: ' rings 1'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: ' rings 1'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: ' login-prompt This is modem port \L of
@\r\n\nPlease login:\040'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: ' login-prompt This is modem port \L of
@\r\n\nPlease login:\040'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: ' init-chat "" ATZ OK ATD$PHONE'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: ' init-chat "" ATZ OK ATD9999457'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->in: ' answer-chat CONNECT'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: ->out: ' answer-chat CONNECT'
Fri Nov 26 15:19:23 1999
cblogin V 4.24: make_active() copy to /conf/callback/active.ttyS0
completed
Fri Nov 26 15:19:23 1999
cblogin V 4.24: New '/etc/mgetty+sendfax/mgetty.config' written
Fri Nov 26 15:19:23 1999
cblogin V 4.24: statefile /conf/callback/state.ttyS0: write state 3
for test (uid: 0)
Fri Nov 26 15:19:23 1999
cbmgetty V 4.24: 'cbmgetty ttyS0' started
Fri Nov 26 15:19:23 1999
cbmgetty V 4.24: Age of '/conf/callback/active.ttyS0': 0 secs
Fri Nov 26 15:19:23 1999
cbmgetty V 4.24: Callback state on line ttyS0 : 3 for test
Fri Nov 26 15:19:23 1999
cbmgetty V 4.24: statefile /conf/callback/state.ttyS0: write state 2
for test (uid: 0)
Fri Nov 26 15:19:23 1999
cbmgetty V 4.24: Executing /usr/local/sbin/modem.ttyS0 from cbmgetty
Fri Nov 26 15:19:41 1999
cblogin V 4.24: 'cblogin test' started
Fri Nov 26 15:19:41 1999
cblogin V 4.24: Age of '/conf/callback/active.ttyS0': 18 secs
Fri Nov 26 15:19:41 1999
cblogin V 4.24: starting exec_login()
Fri Nov 26 15:19:41 1999
cblogin V 4.24: New '/etc/mgetty+sendfax/mgetty.config' written
Fri Nov 26 15:19:41 1999
cblogin V 4.24: Executing '/bin/login' for 'test'
Fri Nov 26 15:20:24 1999
cbmgetty V 4.24: 'cbmgetty ttyS0' started
Fri Nov 26 15:20:24 1999
cbmgetty V 4.24: Standing by for incoming calls on line ttyS0
Fri Nov 26 15:20:24 1999
cbmgetty V 4.24: Executing /usr/local/sbin/modem.ttyS0 from cbmgetty
------------------------------------------------------
mgetyy.log.ttyS0
11/26 15:19:07 yS0 checking lockfiles, locking the line
11/26 15:19:07 yS0 makelock(ttyS0) called
11/26 15:19:07 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
11/26 15:19:07 yS0 lock made
11/26 15:19:07 yS0 waiting for ``RING''
11/26 15:19:07 yS0 got: [0d][0a]RING ** found **
11/26 15:19:07 yS0 send: ATA[0d]
11/26 15:19:07 yS0 waiting for ``CONNECT''
11/26 15:19:07 yS0 got:
[0d][0a]ATA[0d][0d][0a]+FDM[0d][0a][0d][0a]CONNECT ** found **
11/26 15:19:21 yS0 send:
11/26 15:19:21 yS0 waiting for ``_''
11/26 15:19:21 yS0 got: 33600/ARQ/V34/LAPM/V42BIS[0d][0a] ** found
**
11/26 15:19:21 yS0 waiting for line to clear (VTIME), read:
11/26 15:19:21 yS0 utmp + wtmp entry made
11/26 15:19:21 yS0 tio_set_flow_control( HARD )
11/26 15:19:21 yS0 print welcome banner (/etc/issue)
11/26 15:19:21 yS0 getlogname (AUTO_PPP), read:[0d]
11/26 15:19:22 yS0 input finished with '\r', setting ICRNL ONLCR
11/26 15:19:22 yS0 tio_set_flow_control( HARD )
11/26 15:19:22 yS0 print welcome banner (/etc/issue)
11/26 15:19:22 yS0 getlogname (AUTO_PPP), read:test[0d]
11/26 15:19:22 yS0 input finished with '\r', setting ICRNL ONLCR
11/26 15:19:22 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
11/26 15:19:22 yS0 match: user='test', key=''
11/26 15:19:22 yS0 match: user='test', key='/FIDO/'
11/26 15:19:22 yS0 match: user='test', key=''
11/26 15:19:22 yS0 match: user='test', key='/AutoPPP/'
11/26 15:19:22 yS0 match: user='test', key=''
11/26 15:19:22 yS0 match: user='test', key='*'*** hit!
11/26 15:19:22 yS0 calling login: cmd='/conf/callback/cblogin',
argv[]='cblogin test'
11/26 15:19:22 ##### data dev=ttyS0, pid=21707, caller='none',
conn='33600/ARQ/V34/LAPM/V42BIS', name='',
cmd='/conf/callback/cblogin', user='test'
11/26 15:19:22 yS0 setenv: 'CALLER_ID=none'
11/26 15:19:22 yS0 setenv: 'CONNECT=33600/ARQ/V34/LAPM/V42BIS'
--
11/26 15:19:23 yS0 mgetty: experimental test release 1.1.14-Apr02
11/26 15:19:23 yS0 check for lockfiles
11/26 15:19:23 yS0 locking the line
11/26 15:19:23 yS0 lowering DTR to reset Modem
11/26 15:19:24 yS0 send: ATZ[0d]
11/26 15:19:24 yS0 waiting for ``OK'' ** found **
11/26 15:19:24 yS0 send: ATD9999457[0d]
11/26 15:19:25 yS0 waiting...
11/26 15:19:39 yS0 waiting for ``RING''
11/26 15:19:39 yS0 found action string: ``CONNECT''
11/26 15:19:40 ##### data dev=ttyS0, pid=27423, caller='none',
conn='', name='', cmd='/conf/callback/cblogin', user='test'
--
11/26 15:20:24 yS0 mgetty: experimental test release 1.1.14-Apr02
11/26 15:20:24 yS0 check for lockfiles
11/26 15:20:24 yS0 checklock: no active process has lock, will
remove
11/26 15:20:24 yS0 locking the line
11/26 15:20:24 yS0 makelock(ttyS0) called
11/26 15:20:24 yS0 do_makelock: lock='/var/lock/LCK..ttyS0'
11/26 15:20:24 yS0 lock made
11/26 15:20:25 yS0 tio_get_rs232_lines: status: RTS CTS DSR DTR
11/26 15:20:25 yS0 lowering DTR to reset Modem
11/26 15:20:25 yS0 tss: set speed to 38400 (017)
11/26 15:20:25 yS0 tio_set_flow_control( HARD )
11/26 15:20:25 yS0 waiting for line to clear (VTIME), read:
11/26 15:20:25 yS0 send: ATZ[0d]
11/26 15:20:25 yS0 waiting for ``OK''
11/26 15:20:25 yS0 got: ATZ[0d][0d][0a]OK ** found **
11/26 15:20:25 yS0 mdm_send: 'ATI'
11/26 15:20:25 yS0 mdm_gis: string 1: '5601'
11/26 15:20:26 yS0 mdm_identify: string '5601'
11/26 15:20:26 yS0 USR Courier/Sportster 56k detected
11/26 15:20:26 yS0 mdm_send: 'ATI3'
11/26 15:20:26 yS0 mdm_gis: string 1: 'U.S. Robotics Sportster 56000
Voice V4.1'
11/26 15:20:26 yS0 additional info: 'U.S. Robotics Sportster 56000
Voice V4.1'
11/26 15:20:26 yS0 mdm_send: 'AT+FCLASS=2.0'
11/26 15:20:26 yS0 mdm_command: string 'AT+FCLASS=2.0'
11/26 15:20:26 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:26 yS0 mdm_send: 'AT+FAA=1;+FCR=1'
11/26 15:20:26 yS0 mdm_command: string 'AT+FAA=1;+FCR=1'
11/26 15:20:26 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:26 yS0 mdm_send: 'AT+FBO=1'
11/26 15:20:26 yS0 mdm_command: string 'AT+FBO=1'
11/26 15:20:26 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:26 yS0 mdm_send: 'AT+FNR=1,1,1,0'
11/26 15:20:26 yS0 mdm_command: string 'AT+FNR=1,1,1,0'
11/26 15:20:27 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:27 yS0 mdm_send: 'AT+FLI="+31 50 363 6686"'
11/26 15:20:27 yS0 mdm_command: string 'AT+FLI="+31 50 363 6686"'
11/26 15:20:27 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:27 yS0 mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
11/26 15:20:27 yS0 mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
11/26 15:20:27 yS0 mdm_command: string 'OK' -> OK
11/26 15:20:27 yS0 waiting for line to clear (VTIME), read:
11/26 15:20:27 yS0 removing lock file
11/26 15:20:27 yS0 waiting...
--------------------------------------------
messages
Nov 26 15:16:56 linux init: Re-reading inittab
Nov 26 15:19:22 linux modem.ttyS0[21707]: data dev=ttyS0, pid=21707,
caller='none', conn='33600/ARQ/V34/LAPM/V42BIS', name='',
cmd='/conf/callback/cblogin', user='test'
Nov 26 15:19:40 linux modem.ttyS0[27423]: data dev=ttyS0, pid=27423,
caller='none', conn='', name='', cmd='/conf/callback/cblogin',
user='test'
Nov 26 15:19:41 linux PAM_pwdb[27423]: (login) session opened for user
test by (uid=0)
-
To unsubscribe from this list: send the line "unsubscribe linux-ppp" in
the body of a message to [EMAIL PROTECTED]