Errors with sendfax in 'normal' mode only?

Steeve (steeve@eps.mcgill.ca)
Tue, 22 Sep 1998 22:51:36 +0200
Messages sorted by:[ date ][ thread ][ subject ][ author ]

Hi,

I have been experimenting with my crappy USR faxmodem and it
seems that I am able to fax in 'fine' mode but the send
ALWAYS fails in 'normal' mode.

To test this I did,

cat textfile | pbmtext -font cour25.pbm | pbm2g3 > ff1.g3
cat textfile | pbmtext -font cour25n.pbm | pbm2g3 > fn1.g3

The resulting files are both viewable with viewfax 2.4

I then did,

sendfax 4680 ff1.g3
sendfax -n 4680 fn1.g3

The fine mode fax works EVERY time.  The normal mode fax
fails EVERY time.  The output from the log file near the
failure is,

09/22 16:25:26 yS3  sendfax: honouring DCD (carrier) drops now
09/22 16:25:26 yS3   fax_send_page("fn1.g3") started...
09/22 16:25:26 yS3   tio_set_flow_control( HARD )
09/22 16:25:26 yS3  fax_send: 'AT+FDT'
09/22 16:25:26 yS3  fax_wait_for(CONNECT)
09/22 16:25:26 yS3   fax_wait_for: string 'AT+FDT'
09/22 16:25:26 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:26 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:32 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:32 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:39 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:39 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:47 yS3   fax_wait_for: string '+FHS:25'
09/22 16:25:47 yS3  connection hangup: '+FHS:25'
09/22 16:25:47 yS3   (DCS sent three times without response)** found **
09/22 16:25:47 yS3  AT+FDT -> some error (25), abort fax send!
09/22 16:25:47 ##### failed transmitting fn1.g3: phone="4680", +FHS:25, dev=ttyS3, time=34s, acct="" 

The complete logs for the fine and normal send are included below.

Any help would be appreciated!  I am using mgetty+sendfax 1.1.18

steeve

===== fflog =====
--
09/22 16:21:55  sendfax: experimental test release 1.1.18-Sep11
09/22 16:21:55   sendfax.c compiled at Sep 19 1998, 17:20:46
09/22 16:21:55  sending fax to 4680
09/22 16:21:55  checking ff1.g3
09/22 16:21:55   makelock(ttyS3) called
09/22 16:21:55   do_makelock: lock='/var/lock/LCK..ttyS3'
09/22 16:21:55   lock made
09/22 16:21:55   tss: set speed to 38400 (017)
09/22 16:21:55   tio_set_flow_control( HARD )
09/22 16:21:55 yS3   fax_open_device succeeded, ttyS3 -> 4
09/22 16:21:55 yS3   reading specific data for port 'ttyS3'
09/22 16:21:55 yS3   reading /usr/local/etc/mgetty+sendfax/sendfax.config...
09/22 16:21:55 yS3   conf lib: read: 'verbose y'
09/22 16:21:55 yS3   conf lib: read: 'debug 5'
09/22 16:21:55 yS3   conf lib: read: 'fax-devices ttyS3'
09/22 16:21:55 yS3   conf lib: read: 'fax-id 5143984952'
09/22 16:21:55 yS3   conf lib: read: 'max-tries 3'
09/22 16:21:55 yS3   conf lib: read: 'max-tries-continue y'
09/22 16:21:55 yS3   conf lib: read: 'port ttyS3'
09/22 16:21:55 yS3   section: port ttyS3, **found**
09/22 16:21:55 yS3   conf lib: read: 'modem-init ATZ~M0'
09/22 16:21:55 yS3   conf lib: read: 'modem-handshake AT&H3&I2&R2S7=90'
09/22 16:21:55 yS3   conf lib: read: 'ignore-carrier n'
09/22 16:21:55 yS3   conf lib: read: 'modem-type auto'
09/22 16:21:55 yS3   key: 'fax-devices', type=1, flags=4, data=(ignored)
09/22 16:21:55 yS3   key: 'modem-init', type=1, flags=3, data=ATZ~M0
09/22 16:21:55 yS3   key: 'modem-handshake', type=1, flags=3, data=AT&H3&I2&R2S7=90
09/22 16:21:55 yS3   key: 'modem-type', type=1, flags=3, data=auto
09/22 16:21:55 yS3   key: 'modem-quirks', type=0, flags=0, data=(empty)
09/22 16:21:55 yS3   key: 'max-tries', type=0, flags=3, data=3
09/22 16:21:55 yS3   key: 'max-tries-continue', type=3, flags=3, data=TRUE
09/22 16:21:55 yS3   key: 'speed', type=0, flags=1, data=38400
09/22 16:21:55 yS3   key: 'switchbd', type=0, flags=1, data=0
09/22 16:21:55 yS3   key: 'open-delay', type=0, flags=0, data=(empty)
09/22 16:21:55 yS3   key: 'ignore-carrier', type=3, flags=3, data=FALSE
09/22 16:21:55 yS3   key: 'dial-prefix', type=1, flags=1, data=ATD
09/22 16:21:55 yS3   key: 'fax-id', type=1, flags=3, data=5143984952
09/22 16:21:55 yS3   key: 'poll-dir', type=1, flags=1, data=.
09/22 16:21:55 yS3   key: 'normal-res', type=3, flags=1, data=FALSE
09/22 16:21:55 yS3   key: 'fax-min-speed', type=0, flags=1, data=0
09/22 16:21:55 yS3   key: 'fax-max-speed', type=0, flags=1, data=14400
09/22 16:21:55 yS3   key: 'debug', type=0, flags=3, data=5
09/22 16:21:55 yS3   key: 'verbose', type=3, flags=3, data=TRUE
09/22 16:21:55 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:21:55 yS3   key: 'page-header', type=1, flags=0, data=(empty)
09/22 16:21:55 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:21:55 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:21:55 yS3   key: '', type=1, flags=1, data=
09/22 16:21:55 yS3   tss: set speed to 38400 (017)
09/22 16:21:55 yS3  mdm_send: 'ATV1Q0'
09/22 16:21:55 yS3   mdm_command: string 'ATV1Q0'
09/22 16:21:55 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:55 yS3  fax_send: 'ATZ~M0'
09/22 16:21:55 yS3  fax_wait_for(OK)
09/22 16:21:55 yS3   fax_wait_for: string 'ATZ~M0'
09/22 16:21:55 yS3   fax_wait_for: string 'OK'** found **
09/22 16:21:55 yS3  mdm_send: 'ATI'
09/22 16:21:55 yS3   mdm_gis: string 1: '5601'
09/22 16:21:55 yS3   mdm_identify: string '5601'
09/22 16:21:55 yS3  USR Courier/Sportster 56k detected
09/22 16:21:55 yS3  mdm_send: 'ATI3'
09/22 16:21:55 yS3   mdm_gis: string 1: 'U.S. Robotics 56K FAX INT V4.9.1'
09/22 16:21:55 yS3  additional info: 'U.S. Robotics 56K FAX INT V4.9.1'
09/22 16:21:56 yS3  mdm_send: 'AT+FCLASS=2.0'
09/22 16:21:56 yS3   mdm_command: string 'AT+FCLASS=2.0'
09/22 16:21:56 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:56 yS3  mdm_send: 'AT+FLI="5143984952"'
09/22 16:21:56 yS3   mdm_command: string 'AT+FLI="5143984952"'
09/22 16:21:56 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:56 yS3  mdm_send: 'AT+FCC=1,5,0,2,0,0,0,0'
09/22 16:21:56 yS3   mdm_command: string 'AT+FCC=1,5,0,2,0,0,0,0'
09/22 16:21:56 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:56 yS3  mdm_send: 'AT+FBO=0'
09/22 16:21:56 yS3   mdm_command: string 'AT+FBO=0'
09/22 16:21:56 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:56 yS3  mdm_send: 'AT+FNR=1,1,1,0'
09/22 16:21:56 yS3   mdm_command: string 'AT+FNR=1,1,1,0'
09/22 16:21:56 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:56 yS3  mdm_send: 'AT+FLO=2'
09/22 16:21:57 yS3   mdm_command: string 'AT+FLO=2'
09/22 16:21:57 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:57 yS3  mdm_send: 'AT&H3&I2&R2S7=90'
09/22 16:21:57 yS3   mdm_command: string 'AT&H3&I2&R2S7=90'
09/22 16:21:57 yS3   mdm_command: string 'OK' -> OK
09/22 16:21:57 yS3  fax_send: 'ATD4680'
09/22 16:21:57 yS3  fax_wait_for(OK)
09/22 16:21:57 yS3   fax_wait_for: string 'ATD4680'
09/22 16:22:06 yS3   fax_wait_for: string '+FCO'
09/22 16:22:09 yS3   fax_wait_for: string '+FCI:"          5143984680"'
09/22 16:22:09 yS3  fax_id: '+FCI:"          5143984680"'
09/22 16:22:09 yS3   fax_wait_for: string '+FIS:1,3,0,2,1,0,0,2'
09/22 16:22:09 yS3   fax_wait_for: string 'OK'** found **
09/22 16:22:09 yS3  sendfax: honouring DCD (carrier) drops now
09/22 16:22:09 yS3   fax_send_page("ff1.g3") started...
09/22 16:22:09 yS3   tio_set_flow_control( HARD )
09/22 16:22:09 yS3  fax_send: 'AT+FDT'
09/22 16:22:09 yS3  fax_wait_for(CONNECT)
09/22 16:22:09 yS3   fax_wait_for: string 'AT+FDT'
09/22 16:22:09 yS3   fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
09/22 16:22:09 yS3  transmission par.: '+FCS:1,3,0,2,0,0,0,0'
09/22 16:22:15 yS3   fax_wait_for: string 'CONNECT'** found **
09/22 16:22:15 yS3   tio_set_flow_control( HARD XON_OUT )
09/22 16:22:15 yS3  sending ff1.g3...
09/22 16:22:29 yS3  page complete, 22684 bytes sent
09/22 16:22:29 yS3  sending DLE '.'
09/22 16:22:38 yS3  connection hangup: '+FHS:00'
09/22 16:22:38 yS3   (Normal and proper end of connection)
09/22 16:22:38 yS3  got response: 'OK'
09/22 16:22:39 yS3  fax_send: 'AT+FCLASS=0'
09/22 16:22:39 yS3   removing lock file
09/22 16:22:39 ##### success, phone="4680", dev=ttyS3, time=42s, pages=1(+0), bytes=22684, acct=""

===== fnlog =====
--
09/22 16:25:11  sendfax: experimental test release 1.1.18-Sep11
09/22 16:25:11   sendfax.c compiled at Sep 19 1998, 17:20:46
09/22 16:25:11  sending fax to 4680
09/22 16:25:11  checking fn1.g3
09/22 16:25:11   makelock(ttyS3) called
09/22 16:25:11   do_makelock: lock='/var/lock/LCK..ttyS3'
09/22 16:25:11   lock made
09/22 16:25:11   tss: set speed to 38400 (017)
09/22 16:25:11   tio_set_flow_control( HARD )
09/22 16:25:11 yS3   fax_open_device succeeded, ttyS3 -> 4
09/22 16:25:11 yS3   reading specific data for port 'ttyS3'
09/22 16:25:11 yS3   reading /usr/local/etc/mgetty+sendfax/sendfax.config...
09/22 16:25:11 yS3   conf lib: read: 'verbose y'
09/22 16:25:11 yS3   conf lib: read: 'debug 5'
09/22 16:25:11 yS3   conf lib: read: 'fax-devices ttyS3'
09/22 16:25:11 yS3   conf lib: read: 'fax-id 5143984952'
09/22 16:25:11 yS3   conf lib: read: 'max-tries 3'
09/22 16:25:11 yS3   conf lib: read: 'max-tries-continue y'
09/22 16:25:11 yS3   conf lib: read: 'port ttyS3'
09/22 16:25:11 yS3   section: port ttyS3, **found**
09/22 16:25:11 yS3   conf lib: read: 'modem-init ATZ~M0'
09/22 16:25:11 yS3   conf lib: read: 'modem-handshake AT&H3&I2&R2S7=90'
09/22 16:25:11 yS3   conf lib: read: 'ignore-carrier n'
09/22 16:25:11 yS3   conf lib: read: 'modem-type auto'
09/22 16:25:11 yS3   key: 'fax-devices', type=1, flags=4, data=(ignored)
09/22 16:25:11 yS3   key: 'modem-init', type=1, flags=3, data=ATZ~M0
09/22 16:25:11 yS3   key: 'modem-handshake', type=1, flags=3, data=AT&H3&I2&R2S7=90
09/22 16:25:11 yS3   key: 'modem-type', type=1, flags=3, data=auto
09/22 16:25:11 yS3   key: 'modem-quirks', type=0, flags=0, data=(empty)
09/22 16:25:11 yS3   key: 'max-tries', type=0, flags=3, data=3
09/22 16:25:11 yS3   key: 'max-tries-continue', type=3, flags=3, data=TRUE
09/22 16:25:11 yS3   key: 'speed', type=0, flags=1, data=38400
09/22 16:25:11 yS3   key: 'switchbd', type=0, flags=1, data=0
09/22 16:25:11 yS3   key: 'open-delay', type=0, flags=0, data=(empty)
09/22 16:25:11 yS3   key: 'ignore-carrier', type=3, flags=3, data=FALSE
09/22 16:25:11 yS3   key: 'dial-prefix', type=1, flags=1, data=ATD
09/22 16:25:11 yS3   key: 'fax-id', type=1, flags=3, data=5143984952
09/22 16:25:11 yS3   key: 'poll-dir', type=1, flags=1, data=.
09/22 16:25:11 yS3   key: 'normal-res', type=3, flags=2, data=TRUE
09/22 16:25:11 yS3   key: 'fax-min-speed', type=0, flags=1, data=0
09/22 16:25:11 yS3   key: 'fax-max-speed', type=0, flags=1, data=14400
09/22 16:25:11 yS3   key: 'debug', type=0, flags=3, data=5
09/22 16:25:11 yS3   key: 'verbose', type=3, flags=3, data=TRUE
09/22 16:25:11 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:25:11 yS3   key: 'page-header', type=1, flags=0, data=(empty)
09/22 16:25:11 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:25:11 yS3   key: '', type=3, flags=1, data=FALSE
09/22 16:25:11 yS3   key: '', type=1, flags=1, data=
09/22 16:25:11 yS3   tss: set speed to 38400 (017)
09/22 16:25:11 yS3  mdm_send: 'ATV1Q0'
09/22 16:25:11 yS3   mdm_command: string 'ATV1Q0'
09/22 16:25:11 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:11 yS3  fax_send: 'ATZ~M0'
09/22 16:25:11 yS3  fax_wait_for(OK)
09/22 16:25:11 yS3   fax_wait_for: string 'ATZ~M0'
09/22 16:25:12 yS3   fax_wait_for: string 'OK'** found **
09/22 16:25:12 yS3  mdm_send: 'ATI'
09/22 16:25:12 yS3   mdm_gis: string 1: '5601'
09/22 16:25:12 yS3   mdm_identify: string '5601'
09/22 16:25:12 yS3  USR Courier/Sportster 56k detected
09/22 16:25:12 yS3  mdm_send: 'ATI3'
09/22 16:25:12 yS3   mdm_gis: string 1: 'U.S. Robotics 56K FAX INT V4.9.1'
09/22 16:25:12 yS3  additional info: 'U.S. Robotics 56K FAX INT V4.9.1'
09/22 16:25:12 yS3  mdm_send: 'AT+FCLASS=2.0'
09/22 16:25:12 yS3   mdm_command: string 'AT+FCLASS=2.0'
09/22 16:25:12 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:12 yS3  mdm_send: 'AT+FLI="5143984952"'
09/22 16:25:12 yS3   mdm_command: string 'AT+FLI="5143984952"'
09/22 16:25:12 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:12 yS3  mdm_send: 'AT+FCC=0,5,0,2,0,0,0,0'
09/22 16:25:12 yS3   mdm_command: string 'AT+FCC=0,5,0,2,0,0,0,0'
09/22 16:25:13 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:13 yS3  mdm_send: 'AT+FBO=0'
09/22 16:25:13 yS3   mdm_command: string 'AT+FBO=0'
09/22 16:25:13 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:13 yS3  mdm_send: 'AT+FNR=1,1,1,0'
09/22 16:25:13 yS3   mdm_command: string 'AT+FNR=1,1,1,0'
09/22 16:25:13 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:13 yS3  mdm_send: 'AT+FLO=2'
09/22 16:25:13 yS3   mdm_command: string 'AT+FLO=2'
09/22 16:25:13 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:13 yS3  mdm_send: 'AT&H3&I2&R2S7=90'
09/22 16:25:13 yS3   mdm_command: string 'AT&H3&I2&R2S7=90'
09/22 16:25:13 yS3   mdm_command: string 'OK' -> OK
09/22 16:25:13 yS3  fax_send: 'ATD4680'
09/22 16:25:13 yS3  fax_wait_for(OK)
09/22 16:25:13 yS3   fax_wait_for: string 'ATD4680'
09/22 16:25:22 yS3   fax_wait_for: string '+FCO'
09/22 16:25:26 yS3   fax_wait_for: string '+FCI:"          5143984680"'
09/22 16:25:26 yS3  fax_id: '+FCI:"          5143984680"'
09/22 16:25:26 yS3   fax_wait_for: string '+FIS:1,3,0,2,1,0,0,2'
09/22 16:25:26 yS3   fax_wait_for: string 'OK'** found **
09/22 16:25:26 yS3  sendfax: honouring DCD (carrier) drops now
09/22 16:25:26 yS3   fax_send_page("fn1.g3") started...
09/22 16:25:26 yS3   tio_set_flow_control( HARD )
09/22 16:25:26 yS3  fax_send: 'AT+FDT'
09/22 16:25:26 yS3  fax_wait_for(CONNECT)
09/22 16:25:26 yS3   fax_wait_for: string 'AT+FDT'
09/22 16:25:26 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:26 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:32 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:32 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:39 yS3   fax_wait_for: string '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:39 yS3  transmission par.: '+FCS:0,3,0,2,0,0,0,0'
09/22 16:25:47 yS3   fax_wait_for: string '+FHS:25'
09/22 16:25:47 yS3  connection hangup: '+FHS:25'
09/22 16:25:47 yS3   (DCS sent three times without response)** found **
09/22 16:25:47 yS3  AT+FDT -> some error (25), abort fax send!
09/22 16:25:47 ##### failed transmitting fn1.g3: phone="4680", +FHS:25, dev=ttyS3, time=34s, acct=""

09/22 16:25:47 yS3  fax_send: 'AT+FCLASS=0'
09/22 16:25:47 yS3   removing lock file