flexfax: comms problem in france

[Geoffrey Tolchin <gbt@adeptus.com>]

hi,

i have a reproducible problem sending to certain fax machines (i.e., the
problem does not appear when i send the same document to other fax
machines).  the fax is sent from a faxmodem in france to a machine in
france.  the remote fax machine reports an error when hylafax sends
either an mps (more pages, same doc) or eop (no more pages or
documents).  it seems to me that tiffinfo reports (see attachment) the
image created by sendfax is within the parameters of the receiving fax
machine.  comparisons between the trace of the session which fails and
traces of those which succeed indicate only one major differences -- the
called machine renogotiates the scan mode from 20ms/10ms-fine to 20ms
(see attached trace).

i am at a loss as to what further measures to take.

things i have tried:
 -  reformatting the document
 -  setting ecm to 'no' during send (but see note, below)
 -  explicitly setting paper format to a4 (default in config file is a4)
 -  breaking the (multipage) document into individual pages
 -  explicitly setting the baud rate
 -  explicitly setting the mode

Note regarding ecm:
  it appears that setting ecm to 'no' is impossible due to a bug in
sendfax:
  500 'JPARM USEECM 0': Syntax error, invalid boolean value, use YES or
NO.
however, the session trace ('+fcs:...') seems to show that ecm is
actually disabled...


platform:	pentium 200 pc running bsd/os 2.0, 48mb ram
modem:		zyxel 2864i. output from ati1:
			Elite 2864I DSS1:  V 2.09
			Internal fax/modem:  V 1.16
			1605
software:	hylafax 4.0


any clues would be appreciated.


thanks,
geoff tolchin

-- 
Geoff Tolchin             Solutions En Ligne   
gbt@solutions.fr            13 rue Reaumur     
import Std.Disclaimer.*;  75003 Paris, France
TIFF Directory at offset 0x8
  Subfile Type: multi-page document (2 = 0x2)
  Image Width: 1728 Image Length: 1146
  Resolution: 204, 98 pixels/inch
  Bits/Sample: 1
  Compression Scheme: CCITT Group 3
  Photometric Interpretation: min-is-white
  FillOrder: msb-to-lsb
  Orientation: row 0 top, col 0 lhs
  Samples/Pixel: 1
  Rows/Strip: (infinite)
  Planar Configuration: single image plane
  Group 3 Options: (0 = 0x0)
  Fax Data: clean (0 = 0x0)
Mar 17 08:08:40.12: [10431]: SESSION BEGIN 00000095 xxxx
Mar 17 08:08:40.12: [10431]: SEND FAX: JOB 62 DEST xxxx COMMID 00000095
Mar 17 08:08:40.12: [10431]: MODEM set DTR OFF
Mar 17 08:08:40.12: [10431]: DELAY 2600 ms
Mar 17 08:08:42.73: [10431]: MODEM set DTR ON
Mar 17 08:08:42.73: [10431]: MODEM set baud rate: 115200 baud, input flow RTS/CTS, output flow RTS/CTS
Mar 17 08:08:42.73: [10431]: MODEM flush i/o
Mar 17 08:08:42.73: [10431]: <-- [15:ATE0V1Q0S0=0H0\r]
Mar 17 08:08:42.73: [10431]: --> [2:OK]
Mar 17 08:08:42.73: [10431]: <-- [12:ATS8=2S7=60\r]
Mar 17 08:08:42.74: [10431]: --> [2:OK]
Mar 17 08:08:42.74: [10431]: <-- [14:AT+FCLASS=2.0\r]
Mar 17 08:08:42.76: [10431]: --> [2:OK]
Mar 17 08:08:42.76: [10431]: <-- [9:AT+FLO=2\r]
Mar 17 08:08:42.77: [10431]: --> [2:OK]
Mar 17 08:08:42.77: [10431]: <-- [9:AT+FPP=0\r]
Mar 17 08:08:42.78: [10431]: --> [2:OK]
Mar 17 08:08:42.78: [10431]: <-- [9:AT+FBO=0\r]
Mar 17 08:08:42.79: [10431]: --> [2:OK]
Mar 17 08:08:42.79: [10431]: <-- [10:AT+FCT=30\r]
Mar 17 08:08:42.80: [10431]: --> [2:OK]
Mar 17 08:08:42.80: [10431]: <-- [15:AT+FNR=1,1,1,1\r]
Mar 17 08:08:42.81: [10431]: --> [2:OK]
Mar 17 08:08:42.81: [10431]: <-- [9:AT+FIE=0\r]
Mar 17 08:08:42.82: [10431]: --> [2:OK]
Mar 17 08:08:42.82: [10431]: <-- [9:AT+FBU=1\r]
Mar 17 08:08:42.83: [10431]: --> [2:OK]
Mar 17 08:08:42.83: [10431]: <-- [23:AT+FCC=1,5,2,2,1,0,0,0\r]
Mar 17 08:08:42.84: [10431]: --> [2:OK]
Mar 17 08:08:42.84: [10431]: <-- [7:ATM1L3\r]
Mar 17 08:08:42.85: [10431]: --> [2:OK]
Mar 17 08:08:42.85: [10431]: STATE CHANGE: RUNNING -> SENDING
Mar 17 08:08:42.85: [10431]: MODEM input buffering enabled
	[ telno globbing omitted ]
Mar 17 08:08:42.87: [10431]: <-- [14:AT+FCLASS=2.0\r]
Mar 17 08:08:42.98: [10431]: --> [2:OK]
Mar 17 08:08:42.98: [10431]: <-- [9:AT+FLO=2\r]
Mar 17 08:08:43.09: [10431]: --> [2:OK]
Mar 17 08:08:43.09: [10431]: <-- [9:AT+FPP=0\r]
Mar 17 08:08:43.20: [10431]: --> [2:OK]
Mar 17 08:08:43.20: [10431]: <-- [9:AT+FBO=0\r]
Mar 17 08:08:43.30: [10431]: --> [2:OK]
Mar 17 08:08:43.30: [10431]: <-- [10:AT+FCT=30\r]
Mar 17 08:08:43.41: [10431]: --> [2:OK]
Mar 17 08:08:43.41: [10431]: <-- [15:AT+FNR=1,1,1,1\r]
Mar 17 08:08:43.52: [10431]: --> [2:OK]
Mar 17 08:08:43.52: [10431]: <-- [9:AT+FIE=0\r]
Mar 17 08:08:43.62: [10431]: --> [2:OK]
Mar 17 08:08:43.62: [10431]: <-- [9:AT+FBU=1\r]
Mar 17 08:08:43.73: [10431]: --> [2:OK]
Mar 17 08:08:43.73: [10431]: <-- [23:AT+FCC=1,5,2,2,1,0,0,0\r]
Mar 17 08:08:43.84: [10431]: --> [2:OK]
Mar 17 08:08:43.84: [10431]: <-- [28:AT+FLI="SOLUTIONS EN LIGNE"\r]
Mar 17 08:08:43.94: [10431]: --> [2:OK]
Mar 17 08:08:43.96: [10431]: DIAL xxx
Mar 17 08:08:43.96: [10431]: <-- [15:ATDMxxx\r]
Mar 17 08:09:04.08: [10431]: --> [4:+FCO]
Mar 17 08:09:04.09: [10431]: --> [66:+FNF:  xxxxxx ]
	[ REMOTE NSF omitted ]
Mar 17 08:09:04.09: [10431]: --> [26:+FCI:       xxxxxx ]
Mar 17 08:09:04.09: [10431]: REMOTE CSI "xxxxxx"
Mar 17 08:09:04.19: [10431]: --> [20:+FIS:1,3,0,2,1,1,0,4]
Mar 17 08:09:04.19: [10431]: --> [2:OK]
Mar 17 08:09:04.19: [10431]: REMOTE best rate 9600 bit/s
Mar 17 08:09:04.19: [10431]: REMOTE max page width 1728 pixels in 215 mm
Mar 17 08:09:04.19: [10431]: REMOTE max unlimited page length 
Mar 17 08:09:04.19: [10431]: REMOTE best vres 7.7 line/mm
Mar 17 08:09:04.19: [10431]: REMOTE best format 2-D MR
Mar 17 08:09:04.19: [10431]: REMOTE supports T.30 Annex A, ECM
Mar 17 08:09:04.19: [10431]: REMOTE best 20 ms, 10 ms/scanline
Mar 17 08:09:04.19: [10431]: USE 9600 bit/s
Mar 17 08:09:04.19: [10431]: USE 20 ms, 10 ms/scanline
Mar 17 08:09:04.19: [10431]: SEND file "docq/doc87.ps;40"
Mar 17 08:09:04.19: [10431]: USE page width 1728 pixels in 215 mm
Mar 17 08:09:04.19: [10431]: USE unlimited page length 
Mar 17 08:09:04.19: [10431]: USE 3.85 line/mm
Mar 17 08:09:04.19: [10431]: USE 1-D MR
Mar 17 08:09:04.19: [10431]: <-- [23:AT+FIS=0,3,0,2,0,0,0,4\r]
Mar 17 08:09:04.29: [10431]: --> [2:OK]
Mar 17 08:09:04.29: [10431]: <-- [7:AT+FDT\r]
Mar 17 08:09:10.35: [10431]: --> [20:+FCS:0,3,0,2,0,0,0,5]
Mar 17 08:09:10.88: [10431]: --> [7:CONNECT]
Mar 17 08:09:10.88: [10431]: SEND begin page
Mar 17 08:09:10.88: [10431]: <-- data [1031]
Mar 17 08:09:10.88: [10431]: <-- data [1027]
Mar 17 08:09:10.88: [10431]: <-- data [1025]
Mar 17 08:09:10.88: [10431]: <-- data [1027]
Mar 17 08:09:11.42: [10431]: <-- data [1025]
Mar 17 08:09:11.42: [10431]: <-- data [1030]
Mar 17 08:09:11.42: [10431]: <-- data [1027]
Mar 17 08:09:11.42: [10431]: <-- data [1025]
Mar 17 08:09:13.70: [10431]: <-- data [1026]
Mar 17 08:09:13.70: [10431]: <-- data [1027]
Mar 17 08:09:13.70: [10431]: <-- data [1025]
Mar 17 08:09:13.70: [10431]: <-- data [1026]
Mar 17 08:09:17.60: [10431]: <-- data [1026]
Mar 17 08:09:17.60: [10431]: <-- data [1025]
Mar 17 08:09:17.60: [10431]: <-- data [1024]
Mar 17 08:09:17.60: [10431]: <-- data [1024]
Mar 17 08:09:21.91: [10431]: <-- data [1025]
Mar 17 08:09:21.91: [10431]: <-- data [1025]
Mar 17 08:09:21.91: [10431]: <-- data [1026]
Mar 17 08:09:27.65: [10431]: <-- data [1024]
Mar 17 08:09:27.65: [10431]: <-- data [1026]
Mar 17 08:09:27.65: [10431]: <-- data [1026]
Mar 17 08:09:27.65: [10431]: <-- data [1026]
Mar 17 08:09:31.59: [10431]: <-- data [1025]
Mar 17 08:09:31.59: [10431]: <-- data [1027]
Mar 17 08:09:31.59: [10431]: <-- data [1030]
Mar 17 08:09:31.59: [10431]: <-- data [623]
Mar 17 08:09:35.90: [10431]: SENT 27247 bytes of data
Mar 17 08:09:35.90: [10431]: SEND 1D RTC
Mar 17 08:09:35.90: [10431]: <-- data [9]
Mar 17 08:09:35.90: [10431]: SEND end page
Mar 17 08:09:35.90: [10431]: SEND send EOP (no more pages or documents)
Mar 17 08:09:35.90: [10431]: <-- data [2]
Mar 17 08:09:49.42: [10431]: --> [5:ERROR]
Mar 17 08:09:49.42: [10431]: SEND recv RTN (retrain negative)
Mar 17 08:09:49.42: [10431]: <-- [7:AT+FDT\r]
Mar 17 08:09:55.48: [10431]: --> [20:+FCS:0,3,0,2,0,0,0,5]
Mar 17 08:09:56.01: [10431]: --> [7:CONNECT]
Mar 17 08:09:56.01: [10431]: SEND begin page
Mar 17 08:09:56.01: [10431]: <-- data [1031]
Mar 17 08:09:56.01: [10431]: <-- data [1027]
Mar 17 08:09:56.01: [10431]: <-- data [1025]
Mar 17 08:09:56.01: [10431]: <-- data [1027]
Mar 17 08:09:56.55: [10431]: <-- data [1025]
Mar 17 08:09:56.55: [10431]: <-- data [1030]
Mar 17 08:09:56.55: [10431]: <-- data [1027]
Mar 17 08:09:56.55: [10431]: <-- data [1025]
Mar 17 08:09:58.83: [10431]: <-- data [1026]
Mar 17 08:09:58.83: [10431]: <-- data [1027]
Mar 17 08:09:58.83: [10431]: <-- data [1025]
Mar 17 08:09:58.84: [10431]: <-- data [1026]
Mar 17 08:10:02.73: [10431]: <-- data [1026]
Mar 17 08:10:02.73: [10431]: <-- data [1025]
Mar 17 08:10:02.73: [10431]: <-- data [1024]
Mar 17 08:10:02.73: [10431]: <-- data [1024]
Mar 17 08:10:07.04: [10431]: <-- data [1025]
Mar 17 08:10:07.04: [10431]: <-- data [1025]
Mar 17 08:10:07.04: [10431]: <-- data [1026]
Mar 17 08:10:12.78: [10431]: <-- data [1024]
Mar 17 08:10:12.78: [10431]: <-- data [1026]
Mar 17 08:10:12.78: [10431]: <-- data [1026]
Mar 17 08:10:12.78: [10431]: <-- data [1026]
Mar 17 08:10:16.72: [10431]: <-- data [1025]
Mar 17 08:10:16.72: [10431]: <-- data [1027]
Mar 17 08:10:16.73: [10431]: <-- data [1030]
Mar 17 08:10:16.73: [10431]: <-- data [623]
Mar 17 08:10:21.03: [10431]: SENT 27247 bytes of data
Mar 17 08:10:21.03: [10431]: SEND 1D RTC
Mar 17 08:10:21.03: [10431]: <-- data [9]
Mar 17 08:10:21.03: [10431]: SEND end page
Mar 17 08:10:21.03: [10431]: SEND send EOP (no more pages or documents)
Mar 17 08:10:21.03: [10431]: <-- data [2]
Mar 17 08:10:34.49: [10431]: --> [5:ERROR]
Mar 17 08:10:34.49: [10431]: SEND recv RTN (retrain negative)
Mar 17 08:10:34.49: [10431]: <-- [7:AT+FDT\r]
Mar 17 08:10:40.55: [10431]: --> [20:+FCS:0,3,0,2,0,0,0,5]
Mar 17 08:10:41.08: [10431]: --> [7:CONNECT]
Mar 17 08:10:41.08: [10431]: SEND begin page
Mar 17 08:10:41.08: [10431]: <-- data [1031]
Mar 17 08:10:41.08: [10431]: <-- data [1027]
Mar 17 08:10:41.08: [10431]: <-- data [1025]
Mar 17 08:10:41.08: [10431]: <-- data [1027]
Mar 17 08:10:41.62: [10431]: <-- data [1025]
Mar 17 08:10:41.62: [10431]: <-- data [1030]
Mar 17 08:10:41.62: [10431]: <-- data [1027]
Mar 17 08:10:41.62: [10431]: <-- data [1025]
Mar 17 08:10:43.90: [10431]: <-- data [1026]
Mar 17 08:10:43.90: [10431]: <-- data [1027]
Mar 17 08:10:43.90: [10431]: <-- data [1025]
Mar 17 08:10:43.90: [10431]: <-- data [1026]
Mar 17 08:10:47.80: [10431]: <-- data [1026]
Mar 17 08:10:47.80: [10431]: <-- data [1025]
Mar 17 08:10:47.80: [10431]: <-- data [1024]
Mar 17 08:10:47.80: [10431]: <-- data [1024]
Mar 17 08:10:52.11: [10431]: <-- data [1025]
Mar 17 08:10:52.11: [10431]: <-- data [1025]
Mar 17 08:10:52.11: [10431]: <-- data [1026]
Mar 17 08:10:57.85: [10431]: <-- data [1024]
Mar 17 08:10:57.85: [10431]: <-- data [1026]
Mar 17 08:10:57.85: [10431]: <-- data [1026]
Mar 17 08:10:57.85: [10431]: <-- data [1026]
Mar 17 08:11:01.79: [10431]: <-- data [1025]
Mar 17 08:11:01.79: [10431]: <-- data [1027]
Mar 17 08:11:01.79: [10431]: <-- data [1030]
Mar 17 08:11:01.80: [10431]: <-- data [623]
Mar 17 08:11:06.10: [10431]: SENT 27247 bytes of data
Mar 17 08:11:06.10: [10431]: SEND 1D RTC
Mar 17 08:11:06.10: [10431]: <-- data [9]
Mar 17 08:11:06.10: [10431]: SEND end page
Mar 17 08:11:06.10: [10431]: SEND send EOP (no more pages or documents)
Mar 17 08:11:06.10: [10431]: <-- data [2]
Mar 17 08:11:19.56: [10431]: --> [5:ERROR]
Mar 17 08:11:19.56: [10431]: SEND recv RTN (retrain negative)
Mar 17 08:11:19.56: [10431]: <-- [7:AT+FKS\r]
Mar 17 08:11:20.99: [10431]: --> [7:+FHS:02]
Mar 17 08:11:20.99: [10431]: REMOTE HANGUP: Call aborted,  from +FK or <CAN> (code 2)
Mar 17 08:11:20.99: [10431]: --> [5:ERROR]
Mar 17 08:11:20.99: [10431]: MODEM Command error
Mar 17 08:11:20.99: [10431]: <-- [5:ATH0\r]
Mar 17 08:11:21.18: [10431]: --> [10:NO CARRIER]
Mar 17 08:11:21.18: [10431]: MODEM No carrier
Mar 17 08:11:21.18: [10431]: MODEM set DTR OFF
Mar 17 08:11:21.18: [10431]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Mar 17 08:11:21.20: [10431]: SESSION END