Can't send a two page fax. Why?

Mike Elliott (mre@emerald.compata.com)
Sun, 28 Sep 1997 22:19:15 -0700
Messages sorted by:[ date ][ thread ][ subject ][ author ]
Sirs:

I seem to have reached the point of impasse with sendfax. I cannot
get it to send a multiple page fax. So far, to every machine I've
attempted to send a two page fax, I always fail after the first page.
Apparently the first page is sent properly, but the negotiation
between the two machines fails at my end when preparing to send the
second page. This has manifested itself on at least 6 different
receiving fax machines and I've never had one successful multi-page
transmission. 

If I manually use sendfax to send each page as a separate one page fax
the pages seem to transmit and terminate properly -- it's just when I
try to send the two page pair on the same call that I have trouble.

I have compiled sendfax from new sources, but the 1996 version I had
from a RedHat package exhibited exactly the same behavior. I have
compiled it with the USRobotics variable #defined as well as both
combinations of:

 #define FAXREC_FLOW	FLOW_SOFT
 #define FAXSEND_FLOW	FLOW_SOFT

and

 #define FAXREC_FLOW	FLOW_HARD | FLOW_SOFT
 #define FAXSEND_FLOW	FLOW_HARD | FLOW_SOFT

ATI3 for this faxmodem reports: Sportster 33600/Fax V3.12

I have no apparent problem in receiving multiple page faxes, as I have
requested faxback service from Sun Microsystems and received multiple
page responses on several occasions. 

If anyone can help, I'd be greatly appreciative. Here is what is
displayed on the command line when faxrunq (followed by sendfax for
the second page) is run:

Command line:
 /home/mre/xfer/mgetty-1.1.9
 emerald$ faxrunq
 processing F000026.3072/JOB...
 /usr/sbin/sendfax -v 10333-1-310-323-9433 f1.g3 f2.g3 
 Trying fax device '/dev/modem'... OK.
 Dialing 10333-1-310-323-9433... OK.
 sending 'f1.g3'...

 /usr/sbin/sendfax: FAILED to transmit 'f1.g3'.
 Transmission error: +FHNG:-5 (Unexpected hangup / read() error / write() error (int.))
 command exited with status 12

 /home/mre/xfer/mgetty-1.1.9
 emerald$ sendfax 10333-1-310-323-9433 /var/spool/fax/outgoing/F000026.3072/f2.g3 
 Trying fax device '/dev/modem'... OK.
 Dialing 10333-1-310-323-9433... OK.
 sending '/var/spool/fax/outgoing/F000026.3072/f2.g3'...

 /home/mre/xfer/mgetty-1.1.9
 emerald$ 

and here's the log files reflecting these attempts:

/var/spool/Faxlog:
 --
 09/27 12:01:48 sendfax: experimental test release 1.1.9-Aug17
 09/27 12:01:48 sendfax.c compiled at Sep 27 1997, 11:46:10
 09/27 12:01:48 sending fax to 10333-1-310-323-9433
 09/27 12:01:48 checking f1.g3
 09/27 12:01:48 checking f2.g3
 09/27 12:01:48 makelock(modem) called
 09/27 12:01:48 do_makelock: lock='/var/lock/LCK..modem'
 09/27 12:01:48 lock made
 09/27 12:01:48 tss: set speed to 38400 (017)
 09/27 12:01:48 tio_set_flow_control( )
 09/27 12:01:48 dem fax_open_device succeeded, modem -> 4
 09/27 12:01:48 dem reading specific data for port 'modem'
 09/27 12:01:48 dem reading /etc/mgetty+sendfax/sendfax.config...
 09/27 12:01:48 dem conf lib: read: 'verbose y'
 09/27 12:01:48 dem conf lib: read: 'debug 5'
 09/27 12:01:48 dem conf lib: read: 'fax-devices modem'
 09/27 12:01:48 dem conf lib: read: 'fax-id 714/536-6224'
 09/27 12:01:48 dem conf lib: read: 'dial-prefix ATM1DT'
 09/27 12:01:48 dem conf lib: read: 'max-tries 3'
 09/27 12:01:48 dem conf lib: read: 'max-tries-continue y'
 09/27 12:01:48 dem conf lib: read: 'port ttyS1'
 09/27 12:01:48 dem section: port ttyS1, ignore
 09/27 12:01:48 dem conf lib: read: 'modem-type cls2'
 09/27 12:01:48 dem key: 'fax-devices', type=1, flags=4, data=(ignored)
 09/27 12:01:48 dem key: 'modem-init', type=1, flags=0, data=(empty)
 09/27 12:01:48 dem key: 'modem-handshake', type=1, flags=0, data=(empty)
 09/27 12:01:48 dem key: 'modem-type', type=1, flags=1, data=auto
 09/27 12:01:48 dem key: 'max-tries', type=0, flags=3, data=3
 09/27 12:01:48 dem key: 'max-tries-continue', type=3, flags=3, data=TRUE
 09/27 12:01:48 dem key: 'speed', type=0, flags=1, data=38400
 09/27 12:01:48 dem key: 'switchbd', type=0, flags=1, data=0
 09/27 12:01:48 dem key: 'ignore-carrier', type=3, flags=1, data=FALSE
 09/27 12:01:48 dem key: 'dial-prefix', type=1, flags=3, data=ATM1DT
 09/27 12:01:48 dem key: 'fax-id', type=1, flags=3, data=714/536-6224
 09/27 12:01:48 dem key: 'poll-dir', type=1, flags=1, data=.
 09/27 12:01:48 dem key: 'normal-res', type=3, flags=1, data=FALSE
 09/27 12:01:48 dem key: 'debug', type=0, flags=3, data=5
 09/27 12:01:48 dem key: 'verbose', type=3, flags=2, data=TRUE
 09/27 12:01:48 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:01:48 dem key: 'page-header', type=1, flags=0, data=(empty)
 09/27 12:01:48 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:01:48 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:01:48 dem key: '', type=1, flags=1, data=
 09/27 12:01:48 dem tss: set speed to 38400 (017)
 09/27 12:01:48 dem fax_send: 'ATV1Q0'
 09/27 12:01:48 dem fax_wait_for(OK)
 09/27 12:01:48 dem fax_wait_for: string 'ATV1Q0'
 09/27 12:01:48 dem fax_wait_for: string 'OK'** found **
 09/27 12:01:48 dem mdm_send: 'ATI'
 09/27 12:01:48 dem mdm_gis: string 1: '3362'
 09/27 12:01:49 dem mdm_identify: string '3362'
 09/27 12:01:49 dem unknown numerical modem id 3362
 09/27 12:01:49 dem mdm_send: 'AT+FCLASS=2.0'
 09/27 12:01:49 dem mdm_command: string 'AT+FCLASS=2.0'
 09/27 12:01:49 dem mdm_command: string 'OK' -> OK
 09/27 12:01:49 dem mdm_send: 'AT+FLI="714/536-6224"'
 09/27 12:01:49 dem mdm_command: string 'AT+FLI="714/536-6224"'
 09/27 12:01:49 dem mdm_command: string 'OK' -> OK
 09/27 12:01:49 dem mdm_send: 'AT+FCC=1'
 09/27 12:01:49 dem mdm_command: string 'AT+FCC=1'
 09/27 12:01:49 dem mdm_command: string 'OK' -> OK
 09/27 12:01:49 dem mdm_send: 'AT+FBO=0'
 09/27 12:01:49 dem mdm_command: string 'AT+FBO=0'
 09/27 12:01:49 dem mdm_command: string 'OK' -> OK
 09/27 12:01:49 dem mdm_send: 'AT+FNR=1,1,1,0'
 09/27 12:01:49 dem mdm_command: string 'AT+FNR=1,1,1,0'
 09/27 12:01:50 dem mdm_command: string 'OK' -> OK
 09/27 12:01:50 dem mdm_send: 'AT+FLO=1'
 09/27 12:01:50 dem mdm_command: string 'AT+FLO=1'
 09/27 12:01:50 dem mdm_command: string 'OK' -> OK
 09/27 12:01:50 dem fax_send: 'ATM1DT10333-1-310-323-9433'
 09/27 12:01:50 dem fax_wait_for(OK)
 09/27 12:01:50 dem fax_wait_for: string 'ATM1DT10333-1-310-323-9433'
 09/27 12:02:09 dem fax_wait_for: string '+FCO'
 09/27 12:02:12 dem fax_wait_for: string '+FCI:"    "'
 09/27 12:02:12 dem fax_id: '+FCI:"    "'
 09/27 12:02:12 dem fax_wait_for: string '+FIS:1,3,0,2,1,0,0,4'
 09/27 12:02:12 dem fax_wait_for: string 'OK'** found **
 09/27 12:02:12 dem sendfax: honouring DCD (carrier) drops now
 09/27 12:02:12 dem fax_send_page("f1.g3") started...
 09/27 12:02:12 dem tio_set_flow_control( )
 09/27 12:02:12 dem fax_send: 'AT+FDT'
 09/27 12:02:12 dem fax_wait_for(CONNECT)
 09/27 12:02:12 dem fax_wait_for: string 'AT+FDT'
 09/27 12:02:12 dem fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
 09/27 12:02:12 dem transmission par.: '+FCS:1,3,0,2,0,0,0,0'
 09/27 12:02:18 dem fax_wait_for: string 'CONNECT'** found **
 09/27 12:02:18 dem tio_set_flow_control( XON_OUT )
 09/27 12:02:18 dem sending f1.g3...
 09/27 12:02:40 dem page complete, 33077 bytes sent
 09/27 12:02:40 dem sending DLE ','
 09/27 12:02:48 dem mdm_read_byte: read returned 0: Unknown error
 09/27 12:02:48 dem mdm_get_line: cannot read byte, return: Unknown error
 09/27 12:02:48 ##### failed transmitting f1.g3: phone="10333-1-310-323-9433", +FHS:-5, dev=modem, time=58s, acct=""

 09/27 12:02:48 dem fax_send: 'AT+FCLASS=0'
 09/27 12:02:48 dem fax_send: cannot write: I/O error
 09/27 12:02:49 dem tio: cannot flush queue: I/O error
 09/27 12:02:49 dem removing lock file
 --
 09/27 12:07:04 sendfax: experimental test release 1.1.9-Aug17
 09/27 12:07:04 sendfax.c compiled at Sep 27 1997, 11:46:10
 09/27 12:07:04 sending fax to 10333-1-310-323-9433
 09/27 12:07:04 checking /var/spool/fax/outgoing/F000026.3072/f2.g3
 09/27 12:07:04 makelock(modem) called
 09/27 12:07:04 do_makelock: lock='/var/lock/LCK..modem'
 09/27 12:07:04 lock made
 09/27 12:07:04 tss: set speed to 38400 (017)
 09/27 12:07:04 tio_set_flow_control( )
 09/27 12:07:04 dem fax_open_device succeeded, modem -> 4
 09/27 12:07:04 dem reading specific data for port 'modem'
 09/27 12:07:04 dem reading /etc/mgetty+sendfax/sendfax.config...
 09/27 12:07:04 dem conf lib: read: 'verbose y'
 09/27 12:07:04 dem conf lib: read: 'debug 5'
 09/27 12:07:04 dem conf lib: read: 'fax-devices modem'
 09/27 12:07:04 dem conf lib: read: 'fax-id 714/536-6224'
 09/27 12:07:04 dem conf lib: read: 'dial-prefix ATM1DT'
 09/27 12:07:04 dem conf lib: read: 'max-tries 3'
 09/27 12:07:04 dem conf lib: read: 'max-tries-continue y'
 09/27 12:07:04 dem conf lib: read: 'port ttyS1'
 09/27 12:07:04 dem section: port ttyS1, ignore
 09/27 12:07:04 dem conf lib: read: 'modem-type cls2'
 09/27 12:07:04 dem key: 'fax-devices', type=1, flags=4, data=(ignored)
 09/27 12:07:04 dem key: 'modem-init', type=1, flags=0, data=(empty)
 09/27 12:07:04 dem key: 'modem-handshake', type=1, flags=0, data=(empty)
 09/27 12:07:04 dem key: 'modem-type', type=1, flags=1, data=auto
 09/27 12:07:04 dem key: 'max-tries', type=0, flags=3, data=3
 09/27 12:07:04 dem key: 'max-tries-continue', type=3, flags=3, data=TRUE
 09/27 12:07:04 dem key: 'speed', type=0, flags=1, data=38400
 09/27 12:07:04 dem key: 'switchbd', type=0, flags=1, data=0
 09/27 12:07:04 dem key: 'ignore-carrier', type=3, flags=1, data=FALSE
 09/27 12:07:04 dem key: 'dial-prefix', type=1, flags=3, data=ATM1DT
 09/27 12:07:04 dem key: 'fax-id', type=1, flags=3, data=714/536-6224
 09/27 12:07:04 dem key: 'poll-dir', type=1, flags=1, data=.
 09/27 12:07:04 dem key: 'normal-res', type=3, flags=1, data=FALSE
 09/27 12:07:04 dem key: 'debug', type=0, flags=3, data=5
 09/27 12:07:04 dem key: 'verbose', type=3, flags=3, data=TRUE
 09/27 12:07:04 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:07:04 dem key: 'page-header', type=1, flags=0, data=(empty)
 09/27 12:07:04 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:07:04 dem key: '', type=3, flags=1, data=FALSE
 09/27 12:07:04 dem key: '', type=1, flags=1, data=
 09/27 12:07:04 dem tss: set speed to 38400 (017)
 09/27 12:07:04 dem fax_send: 'ATV1Q0'
 09/27 12:07:04 dem fax_wait_for(OK)
 09/27 12:07:04 dem fax_wait_for: string 'ATV1Q0'
 09/27 12:07:04 dem fax_wait_for: string 'OK'** found **
 09/27 12:07:04 dem mdm_send: 'ATI'
 09/27 12:07:04 dem mdm_gis: string 1: '3362'
 09/27 12:07:05 dem mdm_identify: string '3362'
 09/27 12:07:05 dem unknown numerical modem id 3362
 09/27 12:07:05 dem mdm_send: 'AT+FCLASS=2.0'
 09/27 12:07:05 dem mdm_command: string 'AT+FCLASS=2.0'
 09/27 12:07:05 dem mdm_command: string 'OK' -> OK
 09/27 12:07:05 dem mdm_send: 'AT+FLI="714/536-6224"'
 09/27 12:07:05 dem mdm_command: string 'AT+FLI="714/536-6224"'
 09/27 12:07:05 dem mdm_command: string 'OK' -> OK
 09/27 12:07:05 dem mdm_send: 'AT+FCC=1'
 09/27 12:07:05 dem mdm_command: string 'AT+FCC=1'
 09/27 12:07:05 dem mdm_command: string 'OK' -> OK
 09/27 12:07:05 dem mdm_send: 'AT+FBO=0'
 09/27 12:07:05 dem mdm_command: string 'AT+FBO=0'
 09/27 12:07:05 dem mdm_command: string 'OK' -> OK
 09/27 12:07:05 dem mdm_send: 'AT+FNR=1,1,1,0'
 09/27 12:07:05 dem mdm_command: string 'AT+FNR=1,1,1,0'
 09/27 12:07:05 dem mdm_command: string 'OK' -> OK
 09/27 12:07:06 dem mdm_send: 'AT+FLO=1'
 09/27 12:07:06 dem mdm_command: string 'AT+FLO=1'
 09/27 12:07:06 dem mdm_command: string 'OK' -> OK
 09/27 12:07:06 dem fax_send: 'ATM1DT10333-1-310-323-9433'
 09/27 12:07:06 dem fax_wait_for(OK)
 09/27 12:07:06 dem fax_wait_for: string 'ATM1DT10333-1-310-323-9433'
 09/27 12:07:24 dem fax_wait_for: string '+FCO'
 09/27 12:07:27 dem fax_wait_for: string '+FCI:"    "'
 09/27 12:07:27 dem fax_id: '+FCI:"    "'
 09/27 12:07:27 dem fax_wait_for: string '+FIS:1,3,0,2,1,0,0,4'
 09/27 12:07:27 dem fax_wait_for: string 'OK'** found **
 09/27 12:07:27 dem sendfax: honouring DCD (carrier) drops now
 09/27 12:07:27 dem fax_send_page("/var/spool/fax/outgoing/F000026.3072/f2.g3") started...
 09/27 12:07:27 dem tio_set_flow_control( )
 09/27 12:07:27 dem fax_send: 'AT+FDT'
 09/27 12:07:27 dem fax_wait_for(CONNECT)
 09/27 12:07:27 dem fax_wait_for: string 'AT+FDT'
 09/27 12:07:27 dem fax_wait_for: string '+FCS:1,3,0,2,0,0,0,0'
 09/27 12:07:27 dem transmission par.: '+FCS:1,3,0,2,0,0,0,0'
 09/27 12:07:33 dem fax_wait_for: string 'CONNECT'** found **
 09/27 12:07:33 dem tio_set_flow_control( XON_OUT )
 09/27 12:07:33 dem sending /var/spool/fax/outgoing/F000026.3072/f2.g3...
 09/27 12:08:30 dem page complete, 74564 bytes sent
 09/27 12:08:30 dem sending DLE '.'
 09/27 12:08:39 dem connection hangup: '+FHS:00'
 09/27 12:08:39 dem (Normal and proper end of connection)
 09/27 12:08:39 dem got response: 'OK'
 09/27 12:08:39 dem fax_send: 'AT+FCLASS=0'
 09/27 12:08:40 dem removing lock file
 09/27 12:08:40 ##### success, phone="10333-1-310-323-9433", dev=modem, time=94s, pages=1(+0), bytes=74564, acct=""

.