mgetty - failed in mg_init_data

Dan (NOSPAMan3951@iname.com)
Mon, 22 Feb 1999 20:20:05 GMT


A little background. I have been using Linux for about two years now.
Until recently I had been using Slackware but switched over to Redhat
5.2.  The hardware setup is the same. I think the mgetty release was
1.0.0

I use the mgetty+sendfax package (mgetty-sendfax-1.1.14-5) with three
different modems COM 2, 3, 4
The modem I am having trouble with is on COM 4 and it is used for
outside dial up access and fax transmissions. Ever since I switched to
Redhat, my system has been loosing access to this modem. (As an added
note, I have two systems with this problem.)
If I use minicom to access the modem (after loss) the only thing it
does is echo characters. The modem will not respond to any commands.
When I loose access to the modem the only way I know to make it work
again is rebooting the system.
The problem seems to happen randomly and only seems to happen after
someone hangs up from dial up access. Sometimes works for days
sometimes for only hours.
I don't have a printer configured so I don't think it is an interrupt
conflict.

As I am at a loss as to what this problem is, does anybody know how to
fix this?

Thanks,
Dan
--
If you choose to respond via email, remove the word NOSPAM from my
address.



These are the facts about my setup.

Modem Type;
USRobotics Sportster Vi 33600 Faxmodem with its jumper settings set to
non-pnp COM4 IRQ 7

/proc/interrupts
7:     432088 + serial

/proc/ioports
02e8-02ef : serial(set)

/etc/inittab
s4:12345:respawn:/sbin/mgetty -x9 -s 57600 ttyS3

/etc/rc.d/rc.serial
${SETSERIAL} /dev/ttyS3 port 0x2E8 irq 7

/var/log/mgetty.log.ttyS3

02/22 13:31:23 yS3  waiting...
02/22 13:43:59 yS3    select returned 1
02/22 13:43:59 yS3   checking lockfiles, locking the line
02/22 13:43:59 yS3   makelock(ttyS3) called
02/22 13:43:59 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
02/22 13:43:59 yS3   lock made
02/22 13:43:59 yS3  waiting for ``RING''
02/22 13:43:59 yS3   got: [0d]
02/22 13:43:59 yS3    CND: OK[0a]RING ** found **
02/22 13:43:59 yS3    CND: check no: 'none'
02/22 13:43:59 yS3  send: ATA[0d]
02/22 13:43:59 yS3  waiting for ``CONNECT''
02/22 13:43:59 yS3   got: [0d]
02/22 13:43:59 yS3    CND: RING[0a]ATA[0d]
02/22 13:44:00 yS3    CND: ATA[0d][0a]+FCO[0d]
02/22 13:44:11 yS3    CND: +FCO
02/22 13:44:11 yS3  found action string: ``+FCO_''
02/22 13:44:11 yS3  start fax receiver...
02/22 13:44:11 yS3   fax receiver: entry
02/22 13:44:11 yS3  fax_wait_for(OK)
02/22 13:44:11 yS3    got:[0a][0a][0d][0d][0a]+FTI:"
xxxxxxxxxx"[0d]
02/22 13:44:12 yS3   fax_wait_for: string '+FTI:"
xxxxxxxxxx"'
02/22 13:44:12 yS3  fax_id: '+FTI:"          xxxxxxxxxx"'
02/22 13:44:12 yS3    got:[0a][0d][0a]+FCS:1,3,0,2,0,0,0,3[0d]
02/22 13:44:13 yS3   fax_wait_for: string '+FCS:1,3,0,2,0,0,0,3'
02/22 13:44:13 yS3  transmission par.: '+FCS:1,3,0,2,0,0,0,3'
02/22 13:44:13 yS3    got:[0a][0d][0a]OK[0d]
02/22 13:44:13 yS3   fax_wait_for: string 'OK'** found **
02/22 13:44:13 yS3    looking for utmp entry... (my PID: 2626)
02/22 13:44:13 yS3   utmp + wtmp entry made
02/22 13:44:13 yS3   tio_set_flow_control( HARD XON_IN )
02/22 13:44:13 yS3  mdm_send: 'AT+FLO=2'
02/22 13:44:13 yS3    got:[0a]AT+FLO=2[0d]
02/22 13:44:13 yS3   mdm_command: string 'AT+FLO=2'
02/22 13:44:13 yS3    got:[0d][0a]OK[0d]
02/22 13:44:13 yS3   mdm_command: string 'OK' -> OK
02/22 13:44:13 yS3  fax_send: 'AT+FDR'
02/22 13:44:13 yS3  fax_wait_for(CONNECT)
02/22 13:44:13 yS3    got:[0a]AT+FDR[0d]
02/22 13:44:13 yS3   fax_wait_for: string 'AT+FDR'
02/22 13:44:13 yS3    got:[0d][0a]+FCS:1,3,0,2,0,0,0,3[0d]
02/22 13:44:15 yS3   fax_wait_for: string '+FCS:1,3,0,2,0,0,0,3'
02/22 13:44:15 yS3  transmission par.: '+FCS:1,3,0,2,0,0,0,3'
02/22 13:44:15 yS3    got:[0a][0d][0a]CONNECT[0d]
02/22 13:44:16 yS3   fax_wait_for: string 'CONNECT'** found **
02/22 13:44:16 yS3   sending DC2
02/22 13:44:16 yS3   fax_get_page_data: wait for EOL, got:
[0a]DDD![ff][ff][ff][00]
02/22 13:44:17 yS3  fax_get_page_data: receiving
/var/spool/fax/incoming/ff6d1a56fS3-_-xxxxxxxxxx_.01...
02/22 13:45:47 yS3  timeout...
02/22 13:45:47 yS3  mdm_read_byte: read returned -1: Interrupted
system call
02/22 13:45:47 yS3  fax_get_page_data: cannot read from port (1)!:
Interrupted system call
02/22 13:45:47 yS3  fax_get_page_data: page end, bytes received: 87920
02/22 13:45:47 yS3  fax_get_page_data: aborting receive, timeout!
02/22 13:45:47 yS3   fax receiver: hangup & end
02/22 13:45:47 yS3   fax_notify_mail: sending mail to: root
02/22 13:45:48 yS3   notify: '/etc/mgetty+sendfax/new_fax -1 '_
xxxxxxxxxx_' 1  /var/spool/fax/incoming/ff6d1a56fS3-_-xxxxxxxxxx_.01
>/dev/console 2>&1 </dev/null'
02/22 13:45:48 ##### fax dev=ttyS3, pid=2626, caller='none', name='',
id='_          xxxxxxxxxx_', +FHNG=-01, pages=1, time=00:01:48


02/22 13:45:49 yS3  mdm_send: 'ATH0'
02/22 13:45:49 yS3    got:ATH0[0d]
02/22 13:45:49 yS3   mdm_command: string 'ATH0'
02/22 13:45:49 yS3    got:
02/22 13:45:59 yS3  Warning: got alarm signal!
02/22 13:45:59 yS3  mdm_read_byte: read returned -1: Interrupted
system call
02/22 13:45:59 yS3  mdm_get_line: cannot read byte, return:
Interrupted system call -> ERROR
02/22 13:45:59 yS3   removing lock file
--
02/22 13:45:59 yS3  mgetty: experimental test release 1.1.14-Apr02
02/22 13:45:59 yS3   mgetty.c compiled at Oct 12 1998, 20:53:55
02/22 13:45:59 yS3   user id: 0, parent pid: 1
02/22 13:45:59 yS3   reading configuration data for port 'ttyS3'
02/22 13:45:59 yS3   reading /etc/mgetty+sendfax/mgetty.config...
02/22 13:45:59 yS3   conf lib: read: 'debug 4'
02/22 13:45:59 yS3   conf lib: read: 'fax-id 49 89 xxxxxxxxxx'
02/22 13:45:59 yS3   conf lib: read: 'speed 15200'
02/22 13:45:59 yS3   conf lib: read: 'port ttyS3'
02/22 13:45:59 yS3   section: port ttyS3, **found**
02/22 13:45:59 yS3   conf lib: read: 'speed 57600'
02/22 13:45:59 yS3   conf lib: read: 'switchbd 0'
02/22 13:45:59 yS3   key: 'speed', type=0, flags=2, data=57600
02/22 13:45:59 yS3   key: 'switchbd', type=0, flags=3, data=0
02/22 13:45:59 yS3   key: 'direct', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'blocking', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'port-owner', type=1, flags=1, data=uucp
02/22 13:45:59 yS3   key: 'port-group', type=1, flags=1, data=uucp
02/22 13:45:59 yS3   key: 'port-mode', type=0, flags=1, data=432
02/22 13:45:59 yS3   key: 'toggle-dtr', type=3, flags=1, data=TRUE
02/22 13:45:59 yS3   key: 'toggle-dtr-waittime', type=0, flags=1,
data=500
02/22 13:45:59 yS3   key: 'data-only', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'fax-only', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'modem-type', type=1, flags=1, data=auto
02/22 13:45:59 yS3   key: 'modem-quirks', type=0, flags=0,
data=(empty)
02/22 13:45:59 yS3   key: 'init-chat', type=2, flags=1, data=
\dATQ0V1H0 OK ATS0=0Q0&D3&C1 OK 
02/22 13:45:59 yS3   key: 'force-init-chat', type=2, flags=1, data=
\d
\d\d\d+++\d\d\d
\dATQ0V1H0 OK 
02/22 13:45:59 yS3   key: 'modem-check-time', type=0, flags=1,
data=3600
02/22 13:45:59 yS3   key: 'rings', type=0, flags=1, data=1
02/22 13:45:59 yS3   key: 'get-cnd-chat', type=2, flags=0,
data=(empty)
02/22 13:45:59 yS3   key: 'answer-chat', type=2, flags=1, data= ATA
CONNECT \c 
 
02/22 13:45:59 yS3   key: 'answer-chat-timeout', type=0, flags=1,
data=80
02/22 13:45:59 yS3   key: 'autobauding', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'ringback', type=3, flags=1, data=FALSE
02/22 13:45:59 yS3   key: 'ringback-time', type=0, flags=1, data=30
02/22 13:45:59 yS3   key: 'ignore-carrier', type=3, flags=1,
data=FALSE
02/22 13:45:59 yS3   key: 'issue-file', type=1, flags=1,
data=/etc/issue
02/22 13:45:59 yS3   key: 'prompt-waittime', type=0, flags=1, data=500
02/22 13:45:59 yS3   key: 'login-prompt', type=1, flags=1, data=@
login: 
02/22 13:45:59 yS3   key: 'login-time', type=0, flags=1, data=240
02/22 13:45:59 yS3   key: 'fido-send-emsi', type=3, flags=1, data=TRUE
02/22 13:45:59 yS3   key: 'fax-id', type=1, flags=3, data=49 89
xxxxxxxxxx
02/22 13:45:59 yS3   key: 'fax-server-file', type=1, flags=0,
data=(empty)
02/22 13:45:59 yS3   key: 'diskspace', type=0, flags=1, data=1024
02/22 13:45:59 yS3   key: 'notify', type=1, flags=1, data=root
02/22 13:45:59 yS3   key: 'fax-owner', type=1, flags=1, data=uucp
02/22 13:45:59 yS3   key: 'fax-group', type=1, flags=0, data=(empty)
02/22 13:45:59 yS3   key: 'fax-mode', type=0, flags=1, data=432
02/22 13:45:59 yS3   key: 'debug', type=0, flags=2, data=9
02/22 13:45:59 yS3   key: 'statistics-chat', type=2, flags=0,
data=(empty)
02/22 13:45:59 yS3   key: 'statistics-file', type=1, flags=0,
data=(empty)
02/22 13:45:59 yS3   key: 'gettydefs', type=1, flags=1, data=n
02/22 13:45:59 yS3   key: 'term', type=1, flags=0, data=(empty)
02/22 13:45:59 yS3  check for lockfiles
02/22 13:45:59 yS3   checklock: stat failed, no file
02/22 13:45:59 yS3  locking the line
02/22 13:45:59 yS3   makelock(ttyS3) called
02/22 13:45:59 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
02/22 13:45:59 yS3   lock made
02/22 13:46:00 yS3   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
02/22 13:46:00 yS3  WARNING: DCD line still active, check modem
settings (AT&Dx)
02/22 13:46:00 yS3  lowering DTR to reset Modem
02/22 13:46:00 yS3   tss: set speed to 57600 (10001)
02/22 13:46:00 yS3   tio_set_flow_control( HARD )
02/22 13:46:00 yS3   waiting for line to clear (VTIME), read: 
02/22 13:46:01 yS3  send: \dATQ0V1H0[0d]
02/22 13:46:01 yS3  waiting for ``OK''
02/22 13:46:01 yS3   got: ATQ0V1H0[0d]
02/22 13:46:01 yS3    CND: ATQ0V1H0
02/22 13:46:21 yS3  timeout in chat script, waiting for `OK'
02/22 13:46:21 yS3  init chat timed out, trying force-init-chat
02/22 13:46:21 yS3  send: \d[10][03]\d\d\d+++\d\d\d[0d]\dATQ0V1H0[0d]
02/22 13:46:25 yS3  waiting for ``OK''
02/22 13:46:25 yS3   got: [10][03]+++[0d]
02/22 13:46:25 yS3    CND: __+++ATQ0V1H0[0d]
02/22 13:46:25 yS3    CND: ATQ0V1H0
02/22 13:46:45 yS3  timeout in chat script, waiting for `OK'
02/22 13:46:45 yS3  init chat failed, exiting...: Interrupted system
call
02/22 13:46:45 ##### failed in mg_init_data, dev=ttyS3, pid=2869

02/22 13:46:45 yS3   removing lock file