callback mit mgetty

Thomas Mohaupt (ThoMo@gmx.de)
Fri, 07 May 1999 16:15:34 +0200


Hallo,
ich versuche seit einiger Zeit einen ppp-Server fuer callback
einzurichten.
Bekomme aber nur immer folgende Meldungen (s.u.) Bei mir zu Hause (dahin
soll zurueckgerufen werden) kommt nichts an, also kein Klingeln, nichts.
Das Modem am Server ist an einer Anlage, deshalb die "0".

Ansonsten: 
SuSe 5.3
Kernel 2.0.35
PPP Dial-In klappt (fast) ohne Probleme (Fast, weil manchmal haengt so
fuer 10-20 sek. die Leitung und nichts geht rueber, danach ist wieder
alles ok.)

Hier die Meldungen:

/var/log/mgetty.callback
04/26 23:05:58  detaching from ctty...
04/26 23:06:00   delaying 27 seconds
04/26 23:06:27   cbfd: search ttys '/dev/ttyS1'
04/26 23:06:27   cbfd: device: '/dev/ttyS1'
04/26 23:06:27   makelock(ttyS1) called
04/26 23:06:27   do_makelock: lock='/var/lock/LCK..ttyS1'
04/26 23:06:27   lock made
04/26 23:06:27   find_mgetty: look in PID file /var/run/mg-pid.ttyS1
04/26 23:06:27  PID for mgetty on line ttyS1: 8252
04/26 23:06:27   tss: set speed to 38400 (017)
04/26 23:06:27   tio_set_flow_control( HARD )
04/26 23:06:27 yS1   reading specific data for port 'ttyS1'
04/26 23:06:27 yS1   reading /etc/mgetty+sendfax/callback.config...
04/26 23:06:27 yS1   conf lib: read: 'retry-time 30'
04/26 23:06:27 yS1   conf lib: read: 'max-time 90'
04/26 23:06:27 yS1   conf lib: read: 'debug 9'
04/26 23:06:27 yS1   conf lib: read: 'modem-init "" ATQ0V1H&C0X3 OK
AT+FCLASS=0 OK'
04/26 23:06:27 yS1   key: 'dialout-devices', type=1, flags=4,
data=(ignored)
04/26 23:06:27 yS1   key: 'delay', type=0, flags=1, data=20
04/26 23:06:27 yS1   key: 'delay-randomize', type=0, flags=1, data=10
04/26 23:06:27 yS1   key: 'retry-time', type=0, flags=3, data=30
04/26 23:06:27 yS1   key: 'max-time', type=0, flags=3, data=90
04/26 23:06:27 yS1   key: 'modem-init', type=2, flags=3, data=
ATQ0V1H&C0X3 OK AT+FCLASS=0 OK 
04/26 23:06:27 yS1   key: 'speed', type=0, flags=1, data=38400
04/26 23:06:27 yS1   key: 'dial-prefix', type=1, flags=1, data=ATD
04/26 23:06:27 yS1   key: 'autobauding', type=3, flags=1, data=FALSE
04/26 23:06:27 yS1   key: 'prompt-waittime', type=0, flags=1, data=300
04/26 23:06:27 yS1   key: '', type=3, flags=1, data=FALSE
04/26 23:06:27 yS1   key: 'debug', type=0, flags=3, data=9
04/26 23:06:27 yS1  initializing modem...
04/26 23:06:27 yS1  send: ATQ0V1H&C0X3[0d]
04/26 23:06:27 yS1  waiting for ``OK''
04/26 23:06:27 yS1   got: [0d][0a]OK ** found **
04/26 23:06:27 yS1  send: AT+FCLASS=0[0d]
04/26 23:06:27 yS1  waiting for ``OK''
04/26 23:06:27 yS1   got: [0d][0a][0d][0a]OK ** found **
04/26 23:06:27 yS1  dialing 0112233...
04/26 23:06:27 yS1  mdm_send: 'ATD0112233'
04/26 23:06:27 yS1    got:[0d][0a][0d][0a]NO CARRIER[0d]
04/26 23:07:40 yS1  dialup: got 'NO CARRIER'
04/26 23:07:40 yS1  dialup attempt failed, try next number
04/26 23:08:10 yS1  time ran out, giving up
04/26 23:08:10 yS1  can't dial any of the given numbers, exiting: Bad
file number

/var/log/messages
Apr 26 23:05:58 eiche mgetty[8250]: data dev=ttyS1, pid=8250,
caller=none, conn='28800/REL', name='', cmd='/usr/sbin/callback',
user='callback'
Apr 26 23:06:43 eiche kernel: PPP: ppp line discipline successfully
unregistered

/var/log/mgetty.ttyS1
04/26 23:05:27 yS1  mgetty: experimental test release 1.1.8-Jul27
04/26 23:05:27 yS1  check for lockfiles
04/26 23:05:27 yS1  locking the line
04/26 23:05:27 yS1  lowering DTR to reset Modem
04/26 23:05:28 yS1  send: \dATQ0V1H0[0d]
04/26 23:05:28 yS1  waiting for ``OK'' ** found **
04/26 23:05:28 yS1  send: ATS0=0Q0&D3&C1[0d]
04/26 23:05:28 yS1  waiting for ``OK'' ** found **
04/26 23:05:28 yS1  mdm_send: 'ATI'
04/26 23:05:28 yS1  Generic Rockwell modem (33600)
04/26 23:05:28 yS1  mdm_send: 'ATI3'
04/26 23:05:29 yS1  mdm_send: 'ATI4'
04/26 23:05:29 yS1  additional info: ''
04/26 23:05:29 yS1  mdm_send: 'AT+FCLASS=2' -> OK
04/26 23:05:29 yS1  mdm_send: 'AT+FCLASS=0' -> OK
04/26 23:05:29 yS1  mdm_send: 'AT+FAA=1;+FCR=1' -> OK
04/26 23:05:29 yS1  mdm_send: 'AT+FBOR=0' -> OK
04/26 23:05:29 yS1  mdm_send: 'AT+FLID="49 89 xxxxxxxx"' -> OK
04/26 23:05:29 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0' -> OK
04/26 23:05:29 yS1  waiting...
04/26 23:05:43 yS1  waiting for ``RING'' ** found **
04/26 23:05:43 yS1  send: ATA[0d]
04/26 23:05:43 yS1  waiting for ``CONNECT'' ** found **
04/26 23:05:57 yS1  send: 
04/26 23:05:57 yS1  waiting for ``_'' ** found **
04/26 23:05:58 ##### data dev=ttyS1, pid=8250, caller=none,
conn='28800/REL', name='', cmd='/usr/sbin/callback', user='callback'

--
04/26 23:06:00 yS1  mgetty: experimental test release 1.1.8-Jul27
04/26 23:06:00 yS1  check for lockfiles
04/26 23:06:00 yS1  locking the line
04/26 23:06:00 yS1  lowering DTR to reset Modem
04/26 23:06:01 yS1  send: \dATQ0V1H0[0d]
04/26 23:06:01 yS1  waiting for ``OK'' ** found **
04/26 23:06:01 yS1  send: ATS0=0Q0&D3&C1[0d]
04/26 23:06:01 yS1  waiting for ``OK'' ** found **
04/26 23:06:01 yS1  mdm_send: 'ATI'
04/26 23:06:01 yS1  Generic Rockwell modem (33600)
04/26 23:06:01 yS1  mdm_send: 'ATI3'
04/26 23:06:02 yS1  mdm_send: 'ATI4'
04/26 23:06:02 yS1  additional info: ''
04/26 23:06:02 yS1  mdm_send: 'AT+FCLASS=2' -> OK
04/26 23:06:02 yS1  mdm_send: 'AT+FCLASS=0' -> OK
04/26 23:06:02 yS1  mdm_send: 'AT+FAA=1;+FCR=1' -> OK
04/26 23:06:02 yS1  mdm_send: 'AT+FBOR=0' -> OK
04/26 23:06:02 yS1  mdm_send: 'AT+FLID="49 89 xxxxxxxx"' -> OK
04/26 23:06:02 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0' -> OK
04/26 23:06:02 yS1  waiting...
04/26 23:06:27 yS1  lock not made: lock file exists (pid=8251)
--
04/26 23:08:22 yS1  mgetty: experimental test release 1.1.8-Jul27
04/26 23:08:22 yS1  check for lockfiles
04/26 23:08:22 yS1  locking the line
04/26 23:08:22 yS1  lowering DTR to reset Modem
04/26 23:08:23 yS1  send: \dATQ0V1H0[0d]
04/26 23:08:23 yS1  waiting for ``OK'' ** found **
04/26 23:08:23 yS1  send: ATS0=0Q0&D3&C1[0d]
04/26 23:08:23 yS1  waiting for ``OK'' ** found **
04/26 23:08:23 yS1  mdm_send: 'ATI'
04/26 23:08:23 yS1  Generic Rockwell modem (33600)
04/26 23:08:24 yS1  mdm_send: 'ATI3'
04/26 23:08:24 yS1  mdm_send: 'ATI4'
04/26 23:08:24 yS1  additional info: ''
04/26 23:08:24 yS1  mdm_send: 'AT+FCLASS=2' -> OK
04/26 23:08:24 yS1  mdm_send: 'AT+FCLASS=0' -> OK
04/26 23:08:24 yS1  mdm_send: 'AT+FAA=1;+FCR=1' -> OK
04/26 23:08:24 yS1  mdm_send: 'AT+FBOR=0' -> OK
04/26 23:08:24 yS1  mdm_send: 'AT+FLID="49 89 xxxxxxxx"' -> OK
04/26 23:08:24 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0' -> OK
04/26 23:08:24 yS1  waiting...
04/27 00:08:24 yS1  checking if modem is still alive
04/27 00:08:24 yS1  mdm_send: 'AT' -> OK
04/27 00:08:25 yS1  waiting...
....
05/06 16:12:33 yS1  mdm_send: 'AT' -> OK
05/06 16:12:34 yS1  waiting...
05/06 16:18:08 yS1  waiting for ``RING''
05/06 16:18:18 yS1  timeout in chat script, waiting for `RING'
05/06 16:18:18 yS1  huh? Junk on the line?
--
05/06 16:18:18 yS1  mgetty: experimental test release 1.1.8-Jul27
05/06 16:18:18 yS1  check for lockfiles
05/06 16:18:18 yS1  locking the line
05/06 16:18:18 yS1  lowering DTR to reset Modem
05/06 16:18:19 yS1  send: \dATQ0V1H0[0d]
05/06 16:18:19 yS1  waiting for ``OK'' ** found **
05/06 16:18:20 yS1  send: ATS0=0Q0&D3&C1[0d]
05/06 16:18:20 yS1  waiting for ``OK'' ** found **
05/06 16:18:20 yS1  mdm_send: 'ATI'
05/06 16:18:20 yS1  Generic Rockwell modem (33600)
05/06 16:18:20 yS1  mdm_send: 'ATI3'
05/06 16:18:20 yS1  mdm_send: 'ATI4'
05/06 16:18:20 yS1  additional info: ''
05/06 16:18:20 yS1  mdm_send: 'AT+FCLASS=2' -> OK
05/06 16:18:20 yS1  mdm_send: 'AT+FCLASS=0' -> OK
05/06 16:18:20 yS1  mdm_send: 'AT+FAA=1;+FCR=1' -> OK
05/06 16:18:20 yS1  mdm_send: 'AT+FBOR=0' -> OK
05/06 16:18:20 yS1  mdm_send: 'AT+FLID="49 89 xxxxxxxx"' -> OK
05/06 16:18:20 yS1  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0' -> OK
05/06 16:18:21 yS1  waiting...
05/06 16:18:31 ##### failed dev=ttyS1, pid=4902, got signal 15, exiting

Der letzte Abschnitt wiederholt sich (haengt aber wahrscheinlich mit
restart zusammen ?)

Irgendeine Idee? Waere sehr schoen.

Danke.

--ThoMo