Hmm. Still having fax receive problems

Brax Sisco (xenex@bsd1.se-tel.com)
Fri, 06 Aug 1999 21:54:15 -0400


This is a multi-part message in MIME format.

--------------613341352E6
Content-Type: text/plain; charset=us-ascii
Content-Transfer-Encoding: 7bit

I thought that the 3com/U.S.Robotics had fixed all my fax receive
problems (other than at 9600 baud) but it is still not working so well.
If someone could take a look at my files/logs and maybe offer some help,
it would be most appreciated.  Thanks...

mgetty.config
--------------------------------------------------------------
debug 5
rings 1

fax-id 626-9523

speed 38400
cnd-program /etc/mgetty+sendfax/cprogram

port ttyS3
speed 38400
toggle-dtr no
init-chat "" \d\d\d+++\d\d\dAT&F1&C1&D2&K3 OK ATQ0E1V1H0 OK AT&H3&I2 OK
ATS0=0S41=1S7=90 OK
issue-file /etc/issue.net
term vt100
modem-type c2.0
data-only no
fax-only no
--------------------------------------------------------------------

mgetty.log.ttyS3
--------------------------------------------------------------------
Attached (LONG)

--------------613341352E6
Content-Type: text/plain; charset=us-ascii; name="mgetty.log.ttyS3"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline; filename="mgetty.log.ttyS3"

08/05 18:32:27 yS3  waiting...
08/05 18:32:27 yS3   checking lockfiles, locking the line
08/05 18:32:27 yS3   makelock(ttyS3) called
08/05 18:32:27 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
08/05 18:32:27 yS3   lock made
08/05 18:32:27 yS3  wfr: waiting for ``RING''
08/05 18:32:27 yS3   got: [0a][0d][0a]RING B[0d]
08/05 18:32:27 yS3   wfr: rc=0, drn=2
08/05 18:32:27 yS3   CND: program "/etc/mgetty+sendfax/cprogram ttyS3 'none' '' 2 '' >/dev/console 2>&1 </dev/null"
08/05 18:32:27 yS3   CND: rc=0x0
08/05 18:32:27 yS3  send: ATA[0d]
08/05 18:32:27 yS3  waiting for ``CONNECT''
08/05 18:32:27 yS3   got: ATA[0d][0d][0a]+FCO[0d]
08/05 18:32:35 yS3  found action string: ``+FCO_''
08/05 18:32:35 yS3  start fax receiver...
08/05 18:32:35 yS3   fax receiver: entry
08/05 18:32:35 yS3  fax_wait_for(OK)
08/05 18:32:37 yS3   fax_wait_for: string '+FTI:"         714 7124014"'
08/05 18:32:37 yS3  fax_id: '+FTI:"         714 7124014"'
08/05 18:32:37 yS3   fax_wait_for: string '+FCS:0,5,0,2,0,0,0,0'
08/05 18:32:37 yS3  transmission par.: '+FCS:0,5,0,2,0,0,0,0'
08/05 18:32:37 yS3   fax_wait_for: string 'OK'** found **
08/05 18:32:37 yS3   utmp + wtmp entry made
08/05 18:32:37 yS3   tio_set_flow_control( HARD XON_IN )
08/05 18:32:37 yS3  mdm_send: 'AT+FLO=2'
08/05 18:32:37 yS3   mdm_command: string 'AT+FLO=2'
08/05 18:32:37 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:37 yS3  fax_send: 'AT+FDR'
08/05 18:32:37 yS3  fax_wait_for(CONNECT)
08/05 18:32:37 yS3   fax_wait_for: string 'AT+FDR'
08/05 18:32:44 yS3   fax_wait_for: string '+FHS:73'
08/05 18:32:44 yS3  connection hangup: '+FHS:73'
08/05 18:32:44 yS3   (T.30 T2 timeout, expected page not received)** found **
08/05 18:32:44 yS3  fax_get_pages: cannot start page receive
08/05 18:32:44 yS3   fax receiver: hangup & end
08/05 18:32:44 yS3   fax_notify_mail: sending mail to: root
08/05 18:32:44 yS3  fax_notify_mail: mailer exit status: 32256_
08/05 18:32:44 yS3   notify: '/etc/mgetty+sendfax/new_fax 73 '         714 7124014' 0  >/dev/console 2>&1 </dev/null'
08/05 18:32:44 ##### fax dev=ttyS3, pid=5303, caller='none', name='', id='         714 7124014', +FHNG=073, pages=0/0, time=00:00:17


08/05 18:32:46 yS3  mdm_send: 'ATH0'
08/05 18:32:46 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:46 yS3   removing lock file
--
08/05 18:32:46 yS3  mgetty: experimental test release 1.1.20-Jan17
08/05 18:32:46 yS3  check for lockfiles
08/05 18:32:46 yS3   checklock: stat failed, no file
08/05 18:32:46 yS3  locking the line
08/05 18:32:46 yS3   makelock(ttyS3) called
08/05 18:32:46 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
08/05 18:32:46 yS3   lock made
08/05 18:32:46 yS3   tio_get_rs232_lines: status: RTS CTS DSR DTR
08/05 18:32:46 yS3   tss: set speed to 38400 (017)
08/05 18:32:46 yS3   tio_set_flow_control( HARD )
08/05 18:32:46 yS3   waiting for line to clear (VTIME), read: 
08/05 18:32:47 yS3  send: \d\d\d+++\d\d\dAT&F1&C1&D2&K3[0d]
08/05 18:32:50 yS3  waiting for ``OK''
08/05 18:32:50 yS3   got: +++AT&F1&C1&D2&K3[0d][0d][0a]OK ** found **
08/05 18:32:50 yS3  send: ATQ0E1V1H0[0d]
08/05 18:32:50 yS3  waiting for ``OK''
08/05 18:32:50 yS3   got: [0d][0a]ATQ0E1V1H0[0d][0d][0a]OK ** found **
08/05 18:32:50 yS3  send: AT&H3&I2[0d]
08/05 18:32:50 yS3  waiting for ``OK''
08/05 18:32:50 yS3   got: [0d][0a]AT&H3&I2[0d][0d][0a]OK ** found **
08/05 18:32:50 yS3  send: ATS0=0S41=1S7=90[0d]
08/05 18:32:50 yS3  waiting for ``OK''
08/05 18:32:50 yS3   got: [0d][0a]ATS0=0S41=1S7=90[0d][0d][0a]OK ** found **
08/05 18:32:50 yS3  mdm_send: 'AT+FCLASS=2.0'
08/05 18:32:50 yS3   mdm_command: string 'AT+FCLASS=2.0'
08/05 18:32:51 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:51 yS3  mdm_send: 'AT+FAA=1;+FCR=1'
08/05 18:32:51 yS3   mdm_command: string 'AT+FAA=1;+FCR=1'
08/05 18:32:51 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:51 yS3  mdm_send: 'AT+FBO=1'
08/05 18:32:51 yS3   mdm_command: string 'AT+FBO=1'
08/05 18:32:51 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:51 yS3  mdm_send: 'AT+FNR=1,1,1,0'
08/05 18:32:51 yS3   mdm_command: string 'AT+FNR=1,1,1,0'
08/05 18:32:51 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:51 yS3  mdm_send: 'AT+FLI="626-9559"'
08/05 18:32:51 yS3   mdm_command: string 'AT+FLI="626-9559"'
08/05 18:32:51 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:51 yS3  mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
08/05 18:32:51 yS3   mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
08/05 18:32:52 yS3   mdm_command: string 'OK' -> OK
08/05 18:32:52 yS3   waiting for line to clear (VTIME), read: 
08/05 18:32:52 yS3   removing lock file
08/05 18:32:52 yS3  waiting...
08/05 18:33:51 yS3   checking lockfiles, locking the line
08/05 18:33:51 yS3   makelock(ttyS3) called
08/05 18:33:51 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
08/05 18:33:51 yS3   lock made
08/05 18:33:51 yS3  wfr: waiting for ``RING''
08/05 18:33:51 yS3   got: [0a][0d][0a]RING B[0d]
08/05 18:33:51 yS3   wfr: rc=0, drn=2
08/05 18:33:51 yS3   CND: program "/etc/mgetty+sendfax/cprogram ttyS3 'none' '' 2 '' >/dev/console 2>&1 </dev/null"
08/05 18:33:51 yS3   CND: rc=0x0
08/05 18:33:51 yS3  send: ATA[0d]
08/05 18:33:51 yS3  waiting for ``CONNECT''
08/05 18:33:51 yS3   got: ATA[0d][0d][0a]+FCO[0d]
08/05 18:33:59 yS3  found action string: ``+FCO_''
08/05 18:33:59 yS3  start fax receiver...
08/05 18:33:59 yS3   fax receiver: entry
08/05 18:33:59 yS3  fax_wait_for(OK)
08/05 18:33:59 yS3   fax_wait_for: string 'ERROR'
08/05 18:33:59 yS3  ABORTING: line='ERROR'
08/05 18:33:59 yS3   utmp + wtmp entry made
08/05 18:33:59 yS3   tio_set_flow_control( HARD XON_IN )
08/05 18:34:00 yS3  mdm_send: 'AT+FLO=2'
08/05 18:34:00 yS3   mdm_command: string 'AT+FLO=2'
08/05 18:34:00 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:00 yS3  fax_get_pages: no pages to receive
08/05 18:34:00 yS3   fax receiver: hangup & end
08/05 18:34:00 yS3   fax_notify_mail: sending mail to: root
08/05 18:34:00 yS3  fax_notify_mail: mailer exit status: 32256_
08/05 18:34:00 yS3   notify: '/etc/mgetty+sendfax/new_fax -4 '' 0  >/dev/console 2>&1 </dev/null'
08/05 18:34:00 ##### fax dev=ttyS3, pid=5409, caller='none', name='', id='', +FHNG=-04, pages=0/0, time=00:00:09


08/05 18:34:01 yS3  mdm_send: 'ATH0'
08/05 18:34:01 yS3   mdm_command: string 'ATH0'
08/05 18:34:01 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:01 yS3   removing lock file
--
08/05 18:34:01 yS3  mgetty: experimental test release 1.1.20-Jan17
08/05 18:34:01 yS3  check for lockfiles
08/05 18:34:01 yS3   checklock: stat failed, no file
08/05 18:34:01 yS3  locking the line
08/05 18:34:01 yS3   makelock(ttyS3) called
08/05 18:34:01 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
08/05 18:34:01 yS3   lock made
08/05 18:34:02 yS3   tio_get_rs232_lines: status: RTS CTS DSR DTR
08/05 18:34:02 yS3   tss: set speed to 38400 (017)
08/05 18:34:02 yS3   tio_set_flow_control( HARD )
08/05 18:34:02 yS3   waiting for line to clear (VTIME), read: 
08/05 18:34:02 yS3  send: \d\d\d+++\d\d\dAT&F1&C1&D2&K3[0d]
08/05 18:34:05 yS3  waiting for ``OK''
08/05 18:34:05 yS3   got: +++AT&F1&C1&D2&K3[0d][0d][0a]OK ** found **
08/05 18:34:05 yS3  send: ATQ0E1V1H0[0d]
08/05 18:34:05 yS3  waiting for ``OK''
08/05 18:34:05 yS3   got: [0d][0a]ATQ0E1V1H0[0d][0d][0a]OK ** found **
08/05 18:34:06 yS3  send: AT&H3&I2[0d]
08/05 18:34:06 yS3  waiting for ``OK''
08/05 18:34:06 yS3   got: [0d][0a]AT&H3&I2[0d][0d][0a]OK ** found **
08/05 18:34:06 yS3  send: ATS0=0S41=1S7=90[0d]
08/05 18:34:06 yS3  waiting for ``OK''
08/05 18:34:06 yS3   got: [0d][0a]ATS0=0S41=1S7=90[0d][0d][0a]OK ** found **
08/05 18:34:06 yS3  mdm_send: 'AT+FCLASS=2.0'
08/05 18:34:06 yS3   mdm_command: string 'AT+FCLASS=2.0'
08/05 18:34:06 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:06 yS3  mdm_send: 'AT+FAA=1;+FCR=1'
08/05 18:34:06 yS3   mdm_command: string 'AT+FAA=1;+FCR=1'
08/05 18:34:06 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:06 yS3  mdm_send: 'AT+FBO=1'
08/05 18:34:06 yS3   mdm_command: string 'AT+FBO=1'
08/05 18:34:06 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:06 yS3  mdm_send: 'AT+FNR=1,1,1,0'
08/05 18:34:07 yS3   mdm_command: string 'AT+FNR=1,1,1,0'
08/05 18:34:07 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:07 yS3  mdm_send: 'AT+FLI="626-9559"'
08/05 18:34:07 yS3   mdm_command: string 'AT+FLI="626-9559"'
08/05 18:34:07 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:07 yS3  mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
08/05 18:34:07 yS3   mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
08/05 18:34:07 yS3   mdm_command: string 'OK' -> OK
08/05 18:34:07 yS3   waiting for line to clear (VTIME), read: 
08/05 18:34:07 yS3   removing lock file
08/05 18:34:07 yS3  waiting...
08/05 18:35:15 yS3   checking lockfiles, locking the line
08/05 18:35:15 yS3   makelock(ttyS3) called
08/05 18:35:15 yS3   do_makelock: lock='/var/lock/LCK..ttyS3'
08/05 18:35:15 yS3   lock made
08/05 18:35:15 yS3  wfr: waiting for ``RING''
08/05 18:35:15 yS3   got: [0a][0d][0a]RING B[0d]
08/05 18:35:15 yS3   wfr: rc=0, drn=2
08/05 18:35:15 yS3   CND: program "/etc/mgetty+sendfax/cprogram ttyS3 'none' '' 2 '' >/dev/console 2>&1 </dev/null"
08/05 18:35:15 yS3   CND: rc=0x0
08/05 18:35:15 yS3  send: ATA[0d]
08/05 18:35:15 yS3  waiting for ``CONNECT''
08/05 18:35:15 yS3   got: ATA[0d][0d][0a]+FCO[0d]
08/05 18:35:23 yS3  found action string: ``+FCO_''
08/05 18:35:23 yS3  start fax receiver...
08/05 18:35:23 yS3   fax receiver: entry
08/05 18:35:23 yS3  fax_wait_for(OK)
08/05 18:35:25 yS3   fax_wait_for: string '+FTI:"714 712 4016        "'
08/05 18:35:25 yS3  fax_id: '+FTI:"714 712 4016        "'
08/05 18:35:25 yS3   fax_wait_for: string '+FCS:0,5,0,2,0,0,0,0'
08/05 18:35:25 yS3  transmission par.: '+FCS:0,5,0,2,0,0,0,0'
08/05 18:35:25 yS3   fax_wait_for: string 'OK'** found **
08/05 18:35:25 yS3   utmp + wtmp entry made
08/05 18:35:25 yS3   tio_set_flow_control( HARD XON_IN )
08/05 18:35:25 yS3  mdm_send: 'AT+FLO=2'
08/05 18:35:25 yS3   mdm_command: string 'AT+FLO=2'
08/05 18:35:26 yS3   mdm_command: string 'OK' -> OK
08/05 18:35:26 yS3  fax_send: 'AT+FDR'
08/05 18:35:26 yS3  fax_wait_for(CONNECT)
08/05 18:35:26 yS3   fax_wait_for: string 'AT+FDR'
08/05 18:35:31 yS3   fax_wait_for: string '+FTI:"714 712 4016        "'
08/05 18:35:31 yS3  fax_id: '+FTI:"714 712 4016        "'
08/05 18:35:31 yS3   fax_wait_for: string '+FCS:0,4,0,2,0,0,0,0'
08/05 18:35:31 yS3  transmission par.: '+FCS:0,4,0,2,0,0,0,0'
08/05 18:35:37 yS3   fax_wait_for: string 'CONNECT'** found **
08/05 18:35:37 yS3   sending DC2
08/05 18:35:37 yS3   fax_get_page_data: wait for EOL, got: [0a][ff][ff][ff][ff][ff][ff][ff][ff][80][0a]l[d4][00]
08/05 18:35:37 yS3  fax_get_page_data: receiving /var/spool/fax/incoming/fn7aa11a3S3-714-712-4016.01...
08/05 18:35:52 yS3  fax_get_page_data: page end, bytes received: 22137
08/05 18:35:52 yS3  fax_wait_for(OK)
08/05 18:35:52 yS3   fax_wait_for: string '+FPS:1,1102,0,0,0'
08/05 18:35:52 yS3  page status: +FPS:1,1102,0,0,0
08/05 18:35:52 yS3   4354 lines received, 0 lines bad, 0 bytes lost
08/05 18:35:53 yS3   fax_wait_for: string '+FET:0'
08/05 18:35:53 yS3   fax_wait_for: string 'OK'** found **
08/05 18:35:53 yS3  fax_send: 'AT+FDR'
08/05 18:35:53 yS3  fax_wait_for(CONNECT)
08/05 18:35:53 yS3   fax_wait_for: string 'AT+FDR'
08/05 18:35:55 yS3   fax_wait_for: string 'CONECT'
08/05 18:35:56 yS3   fax_wait_for: string 'lSfSfSfSfSfSfSfv Rs"bNs#HA-L 0G{Cj LC9L'7&Ee`vbn}`=>/`j`zsW~c|<]@ub> vn/oQ})mnSodoi>#ObD2)y}l,"'}}I1AF#'V{8S1dK}[;'Ncb/+LN~7~^SGWL';g"QN,~vOO;o`zw|}oL}{)]Y>O?>drY/LvSV{%'} S"9(|!#29^NGQOGi)1G#L#;9dISf'
08/05 18:35:56 yS3   fax_wait_for: string 'SfSfSfSfSfSfSfSfSfSfSfSfSfSfSf[)SfSfSfSfSfSfSfSfSfSfSf[!:[7lnd-RtZ,Zbe2@5V`Ys'
08/05 18:35:56 yS3   fax_wait_for: string ',YSK,Z'p6StY@Cx8CYtC]NH'
08/05 18:35:56 yS3   fax_wait_for: string 'Ykh;tY7Rh;tYbA"TX1XYzYh'
08/05 18:35:56 yS3   fax_wait_for: string 'Yme`f'
08/05 18:35:56 yS3   fax_wait_for: string 'XYXf* 3@h;tYkPh@h'

And on and on like this for a LONG time...


--------------613341352E6--