I fail to setup callback (fwd)
Gert Doering (gert@greenie.muc.de)
Thu, 3 Apr 1997 17:36:43 +0200
Hi,
anybody? I don't have time to debug this right now. I assume a modem
problem, but can't say for sure.
gert
----- Forwarded message from Ph. Bourdeu d'Aguerre -----
*From aime.insa-tlse.fr!bda Thu Apr 3 16:54:04 1997
Sender: bda@aime.insa-tlse.fr
Message-ID: <3343C481.7159@aime.insa-tlse.fr>
Date: Thu, 03 Apr 1997 16:53:53 +0200
From: "Ph. Bourdeu d'Aguerre" <bda@aime.insa-tlse.fr>
Organization: Atelier Interuniversitaire de Micro-Electronique - Toulouse (FRANCE)
X-Mailer: Mozilla 3.01Gold (X11; I; SunOS 5.5 sun4u)
To: gert@greenie.muc.de
Subject: I fail to setup callback
Hi,
I am using mgetty on Solaris for FAX in, FAX out, ppp and remote login
for some months and I am very happy with it. I try now to setup
callback but encounter a problem : when callback occurs, connection
starts but I didn't have login prompt on remote. If I strike a login
name anyway, it is received by mgetty and I can see on the host a
hanging process "login name". The connection between the 2 modems
remains but nothing happens.
I use mgetty-1.1.1 on a SUN host with Solaris 2.5.
In an attachment, the logs from mgetty and callback which are mixed in
the same file. It's the state of the log file when connection hangs.
Thanks for your help,
--
___ Philippe BOURDEU d'AGUERRE
/\ | |\ /| AIME - Campus de l'INSA
____/__\|__|_\/_|________ Complexe Scientifique de Rangueil
/ | | | 31077 TOULOUSE CEDEX 04 - FRANCE
/ |\ | | http://www.aime.insa-tlse.fr/
/ | \| |____ Tel. +33 561 559 885 Fax +33 561 559 870
04/03 14:10:29 m/a select returned 1
04/03 14:10:29 m/a checking lockfiles, locking the line
04/03 14:10:29 m/a makelock(term/a) called
04/03 14:10:29 m/a get_lock_name(term/a) called
04/03 14:10:29 m/a -> ttyname /dev/term/a
04/03 14:10:29 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:10:29 m/a do_makelock: lock='/var/spool/locks/LK.032.029.000'
04/03 14:10:29 m/a lock made
04/03 14:10:29 m/a waiting for ``RING''
04/03 14:10:29 m/a got: [0d]
04/03 14:10:29 m/a CND: OK[0a]RING ** found **
04/03 14:10:29 m/a send: ATA[0d]
04/03 14:10:29 m/a waiting for ``CONNECT''
04/03 14:10:29 m/a got: [0d]
04/03 14:10:29 m/a CND: RING[0a]ATA[0d]
04/03 14:10:29 m/a CND: ATA[0d][0a]+FDM[0d]
04/03 14:10:39 m/a CND: +FDM[0a][0d][0a]CONNECT ** found **
04/03 14:10:39 m/a send:
04/03 14:10:39 m/a waiting for ``
''
04/03 14:10:39 m/a got: 14400/ARQ/V32/LAPM/V42BIS[0d]
04/03 14:10:39 m/a CND: CONNECT 14400/ARQ/V32/LAPM/V42BIS
04/03 14:10:39 m/a CND: found: 14400/ARQ/V32/LAPM/V42BIS[0a] ** found **
04/03 14:10:39 m/a waiting for line to clear (VTIME), read:
04/03 14:10:40 m/a looking for utmp entry... (my PID: 9334)
04/03 14:10:40 m/a utmp + wtmp entry made
04/03 14:10:40 m/a tio_set_flow_control( HARD )
04/03 14:10:40 m/a getlogname (no opts), read:callback[0d]
04/03 14:10:44 m/a input finished with '\r', setting ICRNL ONLCR
04/03 14:10:44 m/a tio_get_rs232_lines: status: RTS CTS DTR DCD
04/03 14:10:44 m/a login: use login config file /sys_aime/FAX/login.config
04/03 14:10:44 m/a match: user='callback', key=''
04/03 14:10:44 m/a match: user='callback', key=''
04/03 14:10:44 m/a match: user='callback', key='callback'*** hit!
04/03 14:10:44 m/a calling login: cmd='/sys_aime/FAX/bin/callback', argv[]='callback -x9'
04/03 14:10:44 ##### data dev=term/a, pid=9334, caller=none, conn='14400/ARQ/V32/LAPM/V42BIS', name='', cmd='/sys_aime/FAX/bin/callback', user='callback'
04/03 14:10:44 m/a setenv: 'CALLER_ID=none'
04/03 14:10:44 m/a setenv: 'CONNECT=14400/ARQ/V32/LAPM/V42BIS'
--
04/03 14:10:48 callback: experimental test release 1.1.1-Jan28
04/03 14:10:48 1 telephone numbers given:
04/03 14:10:48 #1: 9874
04/03 14:10:48 reading default configuration
04/03 14:10:48 reading /sys_aime/FAX/callback.config...
04/03 14:10:48 conf lib: read: 'dialout-devices /dev/term/a'
04/03 14:10:48 conf lib: read: 'speed 38400'
04/03 14:10:48 conf lib: read: 'modem-init "" ATZ OK'
04/03 14:10:48 key: 'dialout-devices', type=1, flags=3, data=/dev/term/a
04/03 14:10:48 key: 'dialout-devices', type=1, flags=4, data=(ignored)
04/03 14:10:48 key: 'delay', type=0, flags=1, data=20
04/03 14:10:48 key: 'delay-randomize', type=0, flags=1, data=10
04/03 14:10:48 key: 'retry-time', type=0, flags=1, data=30
04/03 14:10:48 key: 'max-time', type=0, flags=1, data=600
04/03 14:10:48 key: 'modem-init', type=2, flags=3, data= ATZ OK
04/03 14:10:48 key: 'speed', type=0, flags=3, data=38400
04/03 14:10:48 key: 'dial-prefix', type=1, flags=1, data=ATD
04/03 14:10:48 key: 'autobauding', type=3, flags=1, data=FALSE
04/03 14:10:48 key: 'prompt-waittime', type=0, flags=1, data=300
04/03 14:10:48 key: '', type=3, flags=1, data=FALSE
04/03 14:10:48 key: 'debug', type=0, flags=2, data=9
04/03 14:10:48 detaching from ctty...
04/03 14:10:49 delaying 6 seconds
--
04/03 14:10:50 m/a mgetty: experimental test release 1.1.1-Jan28
04/03 14:10:50 m/a check for lockfiles
04/03 14:10:50 m/a get_lock_name(term/a) called
04/03 14:10:50 m/a -> ttyname /dev/term/a
04/03 14:10:50 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:10:50 m/a checklock: no active process has lock, will remove
04/03 14:10:50 m/a locking the line
04/03 14:10:50 m/a makelock(term/a) called
04/03 14:10:50 m/a get_lock_name(term/a) called
04/03 14:10:50 m/a -> ttyname /dev/term/a
04/03 14:10:50 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:10:50 m/a do_makelock: lock='/var/spool/locks/LK.032.029.000'
04/03 14:10:50 m/a lock made
04/03 14:10:52 m/a tio_get_rs232_lines: status: RTS CTS DTR
04/03 14:10:52 m/a WARNING: DSR is off - modem turned off or bad cable?
04/03 14:10:52 m/a lowering DTR to reset Modem
04/03 14:10:55 m/a tss: set speed to 38400 (017)
04/03 14:10:55 m/a tio_set_flow_control( )
04/03 14:10:55 m/a waiting for line to clear (VTIME), read:
04/03 14:10:55 cbfd: search ttys '/dev/term/a'
04/03 14:10:55 cbfd: device: '/dev/term/a'
04/03 14:10:55 makelock(term/a) called
04/03 14:10:55 get_lock_name(term/a) called
04/03 14:10:55 -> ttyname /dev/term/a
04/03 14:10:55 lock file: /var/spool/locks/LK.032.029.000
04/03 14:10:55 do_makelock: lock='/var/spool/locks/LK.032.029.000'
04/03 14:10:55 lock not made: lock file exists
04/03 14:10:55 term/a: locked
04/03 14:10:55 delaying 30 seconds before next try
04/03 14:10:55 m/a send: ATZ[0d]
04/03 14:10:55 m/a waiting for ``OK''
04/03 14:10:55 m/a got: ATZ[0d]
04/03 14:10:55 m/a CND: ATZ[0d][0a]OK ** found **
04/03 14:10:55 m/a mdm_send: 'AT+FCLASS=2.0'
04/03 14:10:55 m/a got:[0d][0a]AT+FCLASS=2.0[0d]
04/03 14:10:55 m/a mdm_command: string 'AT+FCLASS=2.0'
04/03 14:10:55 m/a got:[0d][0a]OK[0d]
04/03 14:10:55 m/a mdm_command: string 'OK' -> OK
04/03 14:10:55 m/a mdm_send: 'AT+FAA=1;+FCR=1'
04/03 14:10:55 m/a got:[0a]AT+FAA=1;+FCR=1[0d]
04/03 14:10:55 m/a mdm_command: string 'AT+FAA=1;+FCR=1'
04/03 14:10:55 m/a got:[0d][0a]OK[0d]
04/03 14:10:55 m/a mdm_command: string 'OK' -> OK
04/03 14:10:55 m/a mdm_send: 'AT+FBO=1'
04/03 14:10:55 m/a got:[0a]AT+FBO=1[0d]
04/03 14:10:55 m/a mdm_command: string 'AT+FBO=1'
04/03 14:10:55 m/a got:[0d][0a]OK[0d]
04/03 14:10:55 m/a mdm_command: string 'OK' -> OK
04/03 14:10:55 m/a mdm_send: 'AT+FNR=1,1,1,0'
04/03 14:10:55 m/a got:[0a]AT+FNR=1,1,1,0[0d]
04/03 14:10:55 m/a mdm_command: string 'AT+FNR=1,1,1,0'
04/03 14:10:55 m/a got:[0d][0a]OK[0d]
04/03 14:10:56 m/a mdm_command: string 'OK' -> OK
04/03 14:10:56 m/a mdm_send: 'AT+FLI="AIME +33 561 559 873"'
04/03 14:10:56 m/a got:[0a]AT+FLI="AIME +33 561 559 873"[0d]
04/03 14:10:56 m/a mdm_command: string 'AT+FLI="AIME +33 561 559 873"'
04/03 14:10:56 m/a got:[0d][0a]OK[0d]
04/03 14:10:56 m/a mdm_command: string 'OK' -> OK
04/03 14:10:56 m/a mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
04/03 14:10:56 m/a got:[0a]AT+FCC=1,5,0,2,0,0,0,0[0d]
04/03 14:10:56 m/a mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
04/03 14:10:56 m/a got:[0d][0a]OK[0d]
04/03 14:10:56 m/a mdm_command: string 'OK' -> OK
04/03 14:10:56 m/a waiting for line to clear (VTIME), read:
04/03 14:10:56 m/a removing lock file
04/03 14:10:56 m/a waiting...
04/03 14:11:25 cbfd: device: '/dev/term/a'
04/03 14:11:25 makelock(term/a) called
04/03 14:11:25 get_lock_name(term/a) called
04/03 14:11:25 -> ttyname /dev/term/a
04/03 14:11:25 lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:25 do_makelock: lock='/var/spool/locks/LK.032.029.000'
04/03 14:11:25 lock made
04/03 14:11:25 find_mgetty: look in PID file /etc/mg-pid.term-a
04/03 14:11:25 PID for mgetty on line term/a: 9393
04/03 14:11:25 tss: set speed to 38400 (017)
04/03 14:11:25 tio_set_flow_control( )
04/03 14:11:25 m/a reading specific data for port 'term/a'
04/03 14:11:25 m/a reading /sys_aime/FAX/callback.config...
04/03 14:11:25 m/a conf lib: read: 'dialout-devices /dev/term/a'
04/03 14:11:25 m/a conf lib: read: 'speed 38400'
04/03 14:11:25 m/a conf lib: read: 'modem-init "" ATZ OK'
04/03 14:11:25 m/a key: 'dialout-devices', type=1, flags=4, data=(ignored)
04/03 14:11:25 m/a key: 'delay', type=0, flags=1, data=20
04/03 14:11:25 m/a key: 'delay-randomize', type=0, flags=1, data=10
04/03 14:11:25 m/a key: 'retry-time', type=0, flags=1, data=30
04/03 14:11:25 m/a key: 'max-time', type=0, flags=1, data=600
04/03 14:11:25 m/a key: 'modem-init', type=2, flags=3, data= ATZ OK
04/03 14:11:25 m/a key: 'speed', type=0, flags=3, data=38400
04/03 14:11:25 m/a key: 'dial-prefix', type=1, flags=1, data=ATD
04/03 14:11:25 m/a key: 'autobauding', type=3, flags=1, data=FALSE
04/03 14:11:25 m/a key: 'prompt-waittime', type=0, flags=1, data=300
04/03 14:11:25 m/a key: '', type=3, flags=1, data=FALSE
04/03 14:11:25 m/a key: 'debug', type=0, flags=2, data=9
04/03 14:11:25 m/a initializing modem...
04/03 14:11:25 m/a send: ATZ[0d]
04/03 14:11:25 m/a waiting for ``OK''
04/03 14:11:25 m/a got: ATZ[0d][0d][0a]OK ** found **
04/03 14:11:25 m/a dialing 9874
...
04/03 14:11:25 m/a select returned 1
04/03 14:11:25 m/a checking lockfiles, locking the line
04/03 14:11:25 m/a makelock(term/a) called
04/03 14:11:25 m/a get_lock_name(term/a) called
04/03 14:11:25 m/a -> ttyname /dev/term/a
04/03 14:11:25 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:25 m/a do_makelock: lock='/var/spool/locks/LK.032.029.000'
04/03 14:11:25 m/a lock not made: lock file exists
04/03 14:11:25 m/a mdm_send: 'ATD9874
'
04/03 14:11:25 m/a got:[0d][0a]ATD9874[0a]
04/03 14:11:25 m/a dialup: got 'ATD9874'
04/03 14:11:25 m/a got:[0d]
04/03 14:11:25 m/a lock file exists (dialout)!
04/03 14:11:25 m/a get_lock_name(term/a) called
04/03 14:11:25 m/a -> ttyname /dev/term/a
04/03 14:11:25 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:25 m/a lockfile found, pid=9392
04/03 14:11:25 m/a looking for utmp entry... (my PID: 9393)
04/03 14:11:25 m/a utmp + wtmp entry made
04/03 14:11:25 m/a get_lock_name(term/a) called
04/03 14:11:25 m/a -> ttyname /dev/term/a
04/03 14:11:25 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:25 m/a lockfile found, pid=9392
04/03 14:11:35 m/a get_lock_name(term/a) called
04/03 14:11:35 m/a -> ttyname /dev/term/a
04/03 14:11:35 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:35 m/a lockfile found, pid=9392
04/03 14:11:45 m/a get_lock_name(term/a) called
04/03 14:11:45 m/a -> ttyname /dev/term/a
04/03 14:11:45 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:45 m/a lockfile found, pid=9392[0d][0a]CONNECT 14400/ARQ/V32/LAPM/V42BIS[0d]
04/03 14:11:53 m/a dialup: got 'CONNECT 14400/ARQ/V32/LAPM/V42BIS'
04/03 14:11:53 m/a got CONNECT, success!
04/03 14:11:53 m/a Got callback signal from pid=9392!
04/03 14:11:53 m/a tio_set_flow_control( HARD )
04/03 14:11:53 m/a stealing lock file from pid=9392
04/03 14:11:53 m/a steal_lock(term/a) called
04/03 14:11:53 m/a get_lock_name(term/a) called
04/03 14:11:53 m/a -> ttyname /dev/term/a
04/03 14:11:53 m/a lock file: /var/spool/locks/LK.032.029.000
04/03 14:11:53 m/a waiting for line to clear (VTIME), read:
04/03 14:11:53 m/a got ACK signal from mgetty
04/03 14:11:53 ##### callback: success, device=/dev/term/a, mgetty=9393
04/03 14:11:54 m/a looking for utmp entry... (my PID: 9393)
04/03 14:11:54 m/a tio_set_flow_control( HARD )
04/03 14:11:54 m/a getlogname (no opts), read:bda[0d]
04/03 14:12:05 m/a input finished with '\r', setting ICRNL ONLCR
04/03 14:12:05 m/a tio_get_rs232_lines: status: RTS CTS DTR DCD
04/03 14:12:05 m/a login: use login config file /sys_aime/FAX/login.config
04/03 14:12:05 m/a match: user='bda', key=''
04/03 14:12:05 m/a match: user='bda', key=''
04/03 14:12:05 m/a match: user='bda', key='callback'
04/03 14:12:05 m/a match: user='bda', key='bda'*** hit!
04/03 14:12:05 m/a calling login: cmd='/bin/login', argv[]='login bda'
04/03 14:12:05 ##### data dev=term/a, pid=9393, caller=none, conn='', name='', cmd='/bin/login', user='bda'
04/03 14:12:05 m/a setenv: 'CALLER_ID=none'
04/03 14:12:05 m/a setenv: 'CONNECT='
----- End of forwarded message from Ph. Bourdeu d'Aguerre -----
--
USENET is *not* the non-clickable part of WWW!
//www.muc.de/~gert/
Gert Doering - Munich, Germany gert@greenie.muc.de
fax: +49-89-3545980 gert.doering@physik.tu-muenchen.de
.