[hylafax-users] Some users don't get all scan lines

[Michael Evans <michael@togawasmith.com>]

Hi,

I installed HylaFAX on a SuSE 7.3 box, with a Zoom V.90 Model 2920 modem 
("our best," according to Zoom). I'm using WHFC to send M$ Word 
documents. A fax machine here in the office (Brother Intellifax 2800) 
receives and prints the documents perfectly, but my HP psc 950 
All-In-One at home prints garbled faxes. Specifically, the first inch or 
so under the tag line always prints perfectly, but then there will be a 
random pattern of missing scan lines, resulting in a chopped-up image 
that is vertically shorter than it should be.  Each time I try to send a 
fax, HylaFAX sends it 3 times, and it gets printed 3 times - each with a 
different pattern of missing data. After the 1st and 2nd transmission of 
the data, there are "Retrain Negative" errors.  Here's the log:

Aug 21 11:02:28.64: [15606]: SESSION BEGIN 00000038 16267961336
Aug 21 11:02:28.64: [15606]: HylaFAX (tm) Version 4.1
Aug 21 11:02:28.64: [15606]: SEND FAX: JOB 29 DEST 7961336 COMMID 00000038
Aug 21 11:02:28.64: [15606]: DEVICE '/dev/ttyS4'
Aug 21 11:02:28.64: [15606]: DELAY 75 ms
Aug 21 11:02:28.72: [15606]: DELAY 2600 ms
Aug 21 11:02:31.32: [15606]: <-- [4:ATZ\r]
Aug 21 11:02:31.48: [15606]: --> [3:ATZ]
Aug 21 11:02:31.48: [15606]: --> [2:OK]
Aug 21 11:02:31.48: [15606]: <-- [5:ATE0\r]
Aug 21 11:02:31.49: [15606]: --> [4:ATE0]
Aug 21 11:02:31.49: [15606]: --> [2:OK]
Aug 21 11:02:31.49: [15606]: <-- [5:ATV1\r]
Aug 21 11:02:31.50: [15606]: --> [2:OK]
Aug 21 11:02:31.50: [15606]: <-- [5:ATQ0\r]
Aug 21 11:02:31.51: [15606]: --> [2:OK]
Aug 21 11:02:31.51: [15606]: <-- [7:ATS0=0\r]
Aug 21 11:02:31.52: [15606]: --> [2:OK]
Aug 21 11:02:31.52: [15606]: <-- [7:ATS8=2\r]
Aug 21 11:02:31.53: [15606]: --> [2:OK]
Aug 21 11:02:31.53: [15606]: <-- [8:ATS7=60\r]
Aug 21 11:02:31.54: [15606]: --> [2:OK]
Aug 21 11:02:31.54: [15606]: <-- [6:AT&K3\r]
Aug 21 11:02:31.55: [15606]: --> [2:OK]
Aug 21 11:02:31.55: [15606]: <-- [6:AT&D3\r]
Aug 21 11:02:31.56: [15606]: --> [2:OK]
Aug 21 11:02:31.56: [15606]: <-- [12:AT+FCLASS=1\r]
Aug 21 11:02:31.57: [15606]: --> [2:OK]
Aug 21 11:02:31.57: [15606]: <-- [5:ATM0\r]
Aug 21 11:02:31.58: [15606]: --> [2:OK]
Aug 21 11:02:31.58: [15606]: <-- [12:AT+FCLASS=1\r]
Aug 21 11:02:31.69: [15606]: --> [2:OK]
Aug 21 11:02:31.69: [15606]: DIAL 7961336
Aug 21 11:02:31.69: [15606]: <-- [12:ATDT7961336\r]
Aug 21 11:03:06.98: [15606]: --> [7:CONNECT]
Aug 21 11:03:08.36: [15606]: --> [2:OK]
Aug 21 11:03:08.36: [15606]: REMOTE NSF "AD 00 36 A0 00 00 00 00 38"
Aug 21 11:03:08.36: [15606]: NSF remote fax equipment: HP
Aug 21 11:03:08.36: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:08.37: [15606]: --> [7:CONNECT]
Aug 21 11:03:09.06: [15606]: --> [2:OK]
Aug 21 11:03:09.06: [15606]: REMOTE CSI "626 796 1336"
Aug 21 11:03:09.06: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:09.07: [15606]: --> [7:CONNECT]
Aug 21 11:03:09.50: [15606]: --> [2:OK]
Aug 21 11:03:09.50: [15606]: REMOTE best rate 14400 bit/s
Aug 21 11:03:09.50: [15606]: REMOTE max page width 1728 pixels in 215 mm
Aug 21 11:03:09.50: [15606]: REMOTE max unlimited page length
Aug 21 11:03:09.50: [15606]: REMOTE best vres 7.7 line/mm
Aug 21 11:03:09.50: [15606]: REMOTE best format 2-D MMR
Aug 21 11:03:09.50: [15606]: REMOTE supports T.30 Annex A, ECM
Aug 21 11:03:09.50: [15606]: REMOTE best 0 ms/scanline
Aug 21 11:03:09.50: [15606]: USE 14400 bit/s
Aug 21 11:03:09.50: [15606]: USE 0 ms/scanline
Aug 21 11:03:09.50: [15606]: SEND file "docq/doc29.ps;31"
Aug 21 11:03:09.50: [15606]: USE page width 1728 pixels in 215 mm
Aug 21 11:03:09.50: [15606]: USE unlimited page length
Aug 21 11:03:09.50: [15606]: USE 7.7 line/mm
Aug 21 11:03:09.50: [15606]: USE 1-D MR
Aug 21 11:03:09.50: [15606]: SEND training at v.17 14400 bit/s
Aug 21 11:03:09.50: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:03:10.36: [15606]: --> [7:CONNECT]
Aug 21 11:03:10.36: [15606]: <-- data [23]
Aug 21 11:03:10.36: [15606]: <-- data [2]
Aug 21 11:03:10.37: [15606]: --> [7:CONNECT]
Aug 21 11:03:10.37: [15606]: <-- data [6]
Aug 21 11:03:10.37: [15606]: <-- data [2]
Aug 21 11:03:11.46: [15606]: --> [2:OK]
Aug 21 11:03:11.46: [15606]: DELAY 75 ms
Aug 21 11:03:11.54: [15606]: <-- [11:AT+FTM=145\r]
Aug 21 11:03:11.55: [15606]: --> [7:CONNECT]
Aug 21 11:03:11.55: [15606]: <-- data [1024]
Aug 21 11:03:11.55: [15606]: <-- data [1024]
Aug 21 11:03:11.55: [15606]: <-- data [652]
Aug 21 11:03:11.55: [15606]: <-- data [2]
Aug 21 11:03:14.68: [15606]: --> [2:OK]
Aug 21 11:03:14.68: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:14.80: [15606]: --> [7:CONNECT]
Aug 21 11:03:16.07: [15606]: --> [2:OK]
Aug 21 11:03:16.07: [15606]: DELAY 1500 ms
Aug 21 11:03:17.57: [15606]: SEND training at v.17 12000 bit/s
Aug 21 11:03:17.57: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:03:18.43: [15606]: --> [7:CONNECT]
Aug 21 11:03:18.43: [15606]: <-- data [23]
Aug 21 11:03:18.43: [15606]: <-- data [2]
Aug 21 11:03:18.44: [15606]: --> [7:CONNECT]
Aug 21 11:03:18.44: [15606]: <-- data [6]
Aug 21 11:03:18.44: [15606]: <-- data [2]
Aug 21 11:03:19.53: [15606]: --> [2:OK]
Aug 21 11:03:19.53: [15606]: DELAY 75 ms
Aug 21 11:03:19.61: [15606]: <-- [11:AT+FTM=121\r]
Aug 21 11:03:19.62: [15606]: --> [7:CONNECT]
Aug 21 11:03:19.62: [15606]: <-- data [1024]
Aug 21 11:03:19.62: [15606]: <-- data [1024]
Aug 21 11:03:19.62: [15606]: <-- data [202]
Aug 21 11:03:19.62: [15606]: <-- data [2]
Aug 21 11:03:22.76: [15606]: --> [2:OK]
Aug 21 11:03:22.76: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:22.87: [15606]: --> [7:CONNECT]
Aug 21 11:03:24.14: [15606]: --> [2:OK]
Aug 21 11:03:24.14: [15606]: DELAY 1500 ms
Aug 21 11:03:25.64: [15606]: SEND training at v.17 9600 bit/s
Aug 21 11:03:25.64: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:03:26.50: [15606]: --> [7:CONNECT]
Aug 21 11:03:26.50: [15606]: <-- data [23]
Aug 21 11:03:26.50: [15606]: <-- data [2]
Aug 21 11:03:26.51: [15606]: --> [7:CONNECT]
Aug 21 11:03:26.51: [15606]: <-- data [6]
Aug 21 11:03:26.51: [15606]: <-- data [2]
Aug 21 11:03:27.60: [15606]: --> [2:OK]
Aug 21 11:03:27.60: [15606]: DELAY 75 ms
Aug 21 11:03:27.68: [15606]: <-- [10:AT+FTM=97\r]
Aug 21 11:03:27.69: [15606]: --> [7:CONNECT]
Aug 21 11:03:27.69: [15606]: <-- data [1024]
Aug 21 11:03:27.69: [15606]: <-- data [776]
Aug 21 11:03:27.69: [15606]: <-- data [2]
Aug 21 11:03:30.83: [15606]: --> [2:OK]
Aug 21 11:03:30.83: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:30.95: [15606]: --> [7:CONNECT]
Aug 21 11:03:32.22: [15606]: --> [2:OK]
Aug 21 11:03:32.22: [15606]: DELAY 1500 ms
Aug 21 11:03:33.72: [15606]: SEND training at v.17 7200 bit/s
Aug 21 11:03:33.72: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:03:34.58: [15606]: --> [7:CONNECT]
Aug 21 11:03:34.58: [15606]: <-- data [23]
Aug 21 11:03:34.58: [15606]: <-- data [2]
Aug 21 11:03:34.59: [15606]: --> [7:CONNECT]
Aug 21 11:03:34.59: [15606]: <-- data [6]
Aug 21 11:03:34.59: [15606]: <-- data [2]
Aug 21 11:03:35.68: [15606]: --> [2:OK]
Aug 21 11:03:35.68: [15606]: DELAY 75 ms
Aug 21 11:03:35.76: [15606]: <-- [10:AT+FTM=73\r]
Aug 21 11:03:35.77: [15606]: --> [7:CONNECT]
Aug 21 11:03:35.77: [15606]: <-- data [1024]
Aug 21 11:03:35.77: [15606]: <-- data [326]
Aug 21 11:03:35.77: [15606]: <-- data [2]
Aug 21 11:03:38.92: [15606]: --> [2:OK]
Aug 21 11:03:38.92: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:03:39.04: [15606]: --> [7:CONNECT]
Aug 21 11:03:40.31: [15606]: --> [2:OK]
Aug 21 11:03:40.31: [15606]: TRAINING succeeded
Aug 21 11:03:40.31: [15606]: DELAY 75 ms
Aug 21 11:03:40.39: [15606]: <-- [10:AT+FTM=74\r]
Aug 21 11:03:40.40: [15606]: --> [7:CONNECT]
Aug 21 11:03:40.40: [15606]: SEND begin page
Aug 21 11:03:40.40: [15606]: <-- data [1027]

...(36 lines of data)...

Aug 21 11:04:15.51: [15606]: <-- data [177]
Aug 21 11:04:15.83: [15606]: SENT 38065 bytes of data
Aug 21 11:04:15.83: [15606]: SEND 1D RTC
Aug 21 11:04:15.83: [15606]: <-- data [29]
Aug 21 11:04:15.83: [15606]: <-- data [2]
Aug 21 11:04:15.83: [15606]: SEND end page
Aug 21 11:04:23.12: [15606]: --> [2:OK]
Aug 21 11:04:23.12: [15606]: DELAY 200 ms
Aug 21 11:04:23.32: [15606]: SEND send EOP (no more pages or documents)
Aug 21 11:04:23.32: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:04:24.19: [15606]: --> [7:CONNECT]
Aug 21 11:04:24.19: [15606]: <-- data [3]
Aug 21 11:04:24.19: [15606]: <-- data [2]
Aug 21 11:04:24.50: [15606]: --> [2:OK]
Aug 21 11:04:24.50: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:04:24.61: [15606]: --> [7:CONNECT]
Aug 21 11:04:25.87: [15606]: --> [2:OK]
Aug 21 11:04:25.87: [15606]: SEND recv RTN (retrain negative)
Aug 21 11:04:25.87: [15606]: SEND training at v.27ter 4800 bit/s
Aug 21 11:04:25.87: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:04:26.73: [15606]: --> [7:CONNECT]
Aug 21 11:04:26.73: [15606]: <-- data [23]
Aug 21 11:04:26.73: [15606]: <-- data [2]
Aug 21 11:04:26.74: [15606]: --> [7:CONNECT]
Aug 21 11:04:26.74: [15606]: <-- data [6]
Aug 21 11:04:26.74: [15606]: <-- data [2]
Aug 21 11:04:27.83: [15606]: --> [2:OK]
Aug 21 11:04:27.83: [15606]: DELAY 75 ms
Aug 21 11:04:27.91: [15606]: <-- [10:AT+FTM=48\r]
Aug 21 11:04:28.19: [15606]: --> [7:CONNECT]
Aug 21 11:04:28.19: [15606]: <-- data [900]
Aug 21 11:04:28.19: [15606]: <-- data [2]
Aug 21 11:04:30.69: [15606]: --> [2:OK]
Aug 21 11:04:30.69: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:04:30.82: [15606]: --> [7:CONNECT]
Aug 21 11:04:32.09: [15606]: --> [2:OK]
Aug 21 11:04:32.09: [15606]: TRAINING succeeded
Aug 21 11:04:32.09: [15606]: DELAY 75 ms
Aug 21 11:04:32.17: [15606]: <-- [10:AT+FTM=48\r]
Aug 21 11:04:32.19: [15606]: --> [7:CONNECT]
Aug 21 11:04:32.19: [15606]: SEND begin page
Aug 21 11:04:32.19: [15606]: <-- data [1027]

...(36 lines of data)...

Aug 21 11:05:25.24: [15606]: <-- data [177]
Aug 21 11:05:25.72: [15606]: SENT 38065 bytes of data
Aug 21 11:05:25.72: [15606]: SEND 1D RTC
Aug 21 11:05:25.72: [15606]: <-- data [29]
Aug 21 11:05:25.72: [15606]: <-- data [2]
Aug 21 11:05:25.72: [15606]: SEND end page
Aug 21 11:05:36.65: [15606]: --> [2:OK]
Aug 21 11:05:36.65: [15606]: DELAY 200 ms
Aug 21 11:05:36.85: [15606]: SEND send EOP (no more pages or documents)
Aug 21 11:05:36.85: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:05:37.71: [15606]: --> [7:CONNECT]
Aug 21 11:05:37.71: [15606]: <-- data [3]
Aug 21 11:05:37.71: [15606]: <-- data [2]
Aug 21 11:05:38.03: [15606]: --> [2:OK]
Aug 21 11:05:38.03: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:05:38.14: [15606]: --> [7:CONNECT]
Aug 21 11:05:39.40: [15606]: --> [2:OK]
Aug 21 11:05:39.40: [15606]: SEND recv RTN (retrain negative)
Aug 21 11:05:39.40: [15606]: SEND training at v.27ter fallback mode 2400 
bit/s
Aug 21 11:05:39.40: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:05:40.26: [15606]: --> [7:CONNECT]
Aug 21 11:05:40.26: [15606]: <-- data [23]
Aug 21 11:05:40.26: [15606]: <-- data [2]
Aug 21 11:05:40.27: [15606]: --> [7:CONNECT]
Aug 21 11:05:40.27: [15606]: <-- data [6]
Aug 21 11:05:40.27: [15606]: <-- data [2]
Aug 21 11:05:41.36: [15606]: --> [2:OK]
Aug 21 11:05:41.36: [15606]: DELAY 75 ms
Aug 21 11:05:41.44: [15606]: <-- [10:AT+FTM=24\r]
Aug 21 11:05:41.45: [15606]: --> [7:CONNECT]
Aug 21 11:05:41.45: [15606]: <-- data [450]
Aug 21 11:05:41.45: [15606]: <-- data [2]
Aug 21 11:05:44.25: [15606]: --> [2:OK]
Aug 21 11:05:44.25: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:05:44.39: [15606]: --> [7:CONNECT]
Aug 21 11:05:45.66: [15606]: --> [2:OK]
Aug 21 11:05:45.66: [15606]: TRAINING succeeded
Aug 21 11:05:45.66: [15606]: DELAY 75 ms
Aug 21 11:05:45.74: [15606]: <-- [10:AT+FTM=24\r]
Aug 21 11:05:45.75: [15606]: --> [7:CONNECT]
Aug 21 11:05:45.75: [15606]: SEND begin page
Aug 21 11:05:45.75: [15606]: <-- data [1027]

...(36 lines of data)...

Aug 21 11:07:31.19: [15606]: <-- data [177]
Aug 21 11:07:32.14: [15606]: SENT 38065 bytes of data
Aug 21 11:07:32.14: [15606]: SEND 1D RTC
Aug 21 11:07:32.14: [15606]: <-- data [29]
Aug 21 11:07:32.14: [15606]: <-- data [2]
Aug 21 11:07:32.14: [15606]: SEND end page
Aug 21 11:07:54.00: [15606]: --> [2:OK]
Aug 21 11:07:54.00: [15606]: DELAY 200 ms
Aug 21 11:07:54.20: [15606]: SEND send EOP (no more pages or documents)
Aug 21 11:07:54.20: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:07:55.06: [15606]: --> [7:CONNECT]
Aug 21 11:07:55.06: [15606]: <-- data [3]
Aug 21 11:07:55.06: [15606]: <-- data [2]
Aug 21 11:07:55.38: [15606]: --> [2:OK]
Aug 21 11:07:55.38: [15606]: <-- [9:AT+FRH=3\r]
Aug 21 11:07:55.48: [15606]: --> [7:CONNECT]
Aug 21 11:07:56.74: [15606]: --> [2:OK]
Aug 21 11:07:56.74: [15606]: SEND recv MCF (message confirmation)
Aug 21 11:07:56.74: [15606]: SEND FAX (00000038): FROM 
michael@togawasmith.com T
O 7961336 (page 1 of 1 sent in 4:47)
Aug 21 11:07:56.74: [15606]: SEND FAX (00000038): FROM 
michael@togawasmith.com T
O 7961336 (docq/doc29.ps;31 sent in 4:47)
Aug 21 11:07:56.74: [15606]: <-- [9:AT+FTH=3\r]
Aug 21 11:07:57.60: [15606]: --> [7:CONNECT]
Aug 21 11:07:57.60: [15606]: <-- data [3]
Aug 21 11:07:57.60: [15606]: <-- data [2]
Aug 21 11:07:57.93: [15606]: --> [2:OK]
Aug 21 11:07:57.93: [15606]: <-- [5:ATH0\r]
Aug 21 11:07:59.00: [15606]: --> [2:OK]
Aug 21 11:07:59.00: [15606]: SESSION END

Here's what I've tried to fix the problem:

- I had first installed the SuSE rpm binary, but I noticed that there 
was a newer version of the source (4.1.7), so I downloaded the source 
and installed it.
- faxaddmodem doesn't specifically recognize the Zoom 2920, so it uses 
the generic "class1" config file. However, I noticed in 4.1.7's /config 
directory several "lucent" files, one of which lists the Zoom 2920 
model. (Per Zoom's docs, it uses the "Lucent Venus" chipset.) I copied / 
overwrote those entries in the "lucent" config file to the active config 
file.
- I tried switching between rtscts and xonxoff in the config file.
- I tried adding "Use2D:  no" to the HylaFAX general configuration in 
/etc/config.
- The "0ms/scan line" entries in the log file looked fishy, since the 
log file for the Brother fax that receives correctly shows "10ms/scan 
line." I tried changing the "minScanlineTime" entry in the /info file to 
"10ms," but HylaFAX appears to have ignored this.
- The fax and modem here in the office are on separate, direct phone 
lines apart from the PBX system we use for voice calls. I tried 
switching the phone lines between the fax and modem.
- I tried printing my document out on paper, and then sending it to my 
HP machine at home using the Brother fax machine in the conventional 
way. The HP received and printed it correctly. Therefore, I don't think 
noisy phone lines can be blamed.

None of the above measures has resoved the problem.

Any ideas what I can do to get the Zoom modem and HylaFAX to send faxes 
as well as the Brother fax machine? Thanks,

Michael Evans



____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@hylafax.org.*