[hylafax-users] Some faxes received at recipient blank

[Daren Russell <darenr@xxxxxxxxxxxxxxxxx>]

Hi,

We've been using Hylafax with great success for a few years now,
recently (well, about 6 months ago) adding Avantfax to the mix.

Over the last week, though, we have been having issues when sending
faxes which are being received but completely blank.  If I log onto the
server and re-send the doc???.ps using sendfax (although, it was to a
different number where I can see the final result) the fax is received ok.

Here is the output of the log file for the fax which came out blank:

Apr 27 14:32:00.93: [14585]: SESSION BEGIN 000000692 441159xx03xx
Apr 27 14:32:00.93: [14585]: HylaFAX (tm) Version 6.0.4
Apr 27 14:32:00.93: [14585]: SEND FAX: JOB 255 DEST 01159xx03xx COMMID
000000692 DEVICE '/dev/cuau0' FROM 'Alf Stacey <email.removed>' USER alfs
Apr 27 14:32:00.93: [14585]: <-- [14:AT+FCLASS=1.0\r]
Apr 27 14:32:00.95: [14585]: --> [2:OK]
Apr 27 14:32:00.95: [14585]: DIAL 01159xx03xx
Apr 27 14:32:00.95: [14585]: <-- [16:ATDT01159xx03xx\r]
Apr 27 14:32:15.52: [14585]: --> [7:CONNECT]
Apr 27 14:32:16.83: [14585]: --> [2:OK]
Apr 27 14:32:16.83: [14585]: REMOTE NSF "86 00 16 00 00 A4 01 C8 40 E0
03 18 80 00 00"
Apr 27 14:32:16.83: [14585]: NSF remote fax equipment: Samsung M545 6800
Apr 27 14:32:16.83: [14585]: <-- [9:AT+FRH=3\r]
Apr 27 14:32:16.85: [14585]: --> [7:CONNECT]
Apr 27 14:32:17.55: [14585]: --> [2:OK]
Apr 27 14:32:17.55: [14585]: REMOTE CSI "01159xx03xx"
Apr 27 14:32:17.55: [14585]: <-- [9:AT+FRH=3\r]
Apr 27 14:32:17.57: [14585]: --> [7:CONNECT]
Apr 27 14:32:17.92: [14585]: --> [2:OK]
Apr 27 14:32:17.92: [14585]: REMOTE best rate 14400 bit/s
Apr 27 14:32:17.92: [14585]: REMOTE max A4 page width (215 mm)
Apr 27 14:32:17.92: [14585]: REMOTE max unlimited page length
Apr 27 14:32:17.92: [14585]: REMOTE best vres 300 x 300 dpi
Apr 27 14:32:17.92: [14585]: REMOTE format support: MH, MR, MMR
Apr 27 14:32:17.92: [14585]: REMOTE supports T.30 Annex A, 256-byte ECM
Apr 27 14:32:17.92: [14585]: REMOTE best 10 ms/scanline
Apr 27 14:32:17.92: [14585]: USE 14400 bit/s
Apr 27 14:32:17.92: [14585]: USE error correction mode
Apr 27 14:32:17.92: [14585]: SEND file "docq/doc254.ps;c0"
Apr 27 14:32:17.92: [14585]: USE A4 page width (215 mm)
Apr 27 14:32:17.92: [14585]: USE unlimited page length
Apr 27 14:32:17.92: [14585]: USE 3.85 line/mm
Apr 27 14:32:17.92: [14585]: USE 2-D MMR
Apr 27 14:32:17.92: [14585]: USE 0 ms/scanline
Apr 27 14:32:17.92: [14585]: SEND training at v.17 14400 bit/s
Apr 27 14:32:17.92: [14585]: <-- [9:AT+FRS=7\r]
Apr 27 14:32:18.04: [14585]: --> [2:OK]
Apr 27 14:32:18.04: [14585]: <-- [9:AT+FTH=3\r]
Apr 27 14:32:18.09: [14585]: --> [7:CONNECT]
Apr 27 14:32:18.09: [14585]: <-- data [23]
Apr 27 14:32:18.09: [14585]: <-- data [2]
Apr 27 14:32:19.88: [14585]: --> [7:CONNECT]
Apr 27 14:32:19.88: [14585]: <-- data [7]
Apr 27 14:32:19.88: [14585]: <-- data [2]
Apr 27 14:32:20.34: [14585]: --> [2:OK]
Apr 27 14:32:20.34: [14585]: <-- [9:AT+FTS=7\r]
Apr 27 14:32:20.42: [14585]: --> [2:OK]
Apr 27 14:32:20.42: [14585]: <-- [11:AT+FTM=145\r]
Apr 27 14:32:20.45: [14585]: --> [7:CONNECT]
Apr 27 14:32:20.45: [14585]: <-- data [1024]
Apr 27 14:32:20.45: [14585]: <-- data [1024]
Apr 27 14:32:20.45: [14585]: <-- data [652]
Apr 27 14:32:20.45: [14585]: <-- data [2]
Apr 27 14:32:23.56: [14585]: --> [2:OK]
Apr 27 14:32:23.56: [14585]: <-- [9:AT+FRH=3\r]
Apr 27 14:32:24.12: [14585]: --> [7:CONNECT]
Apr 27 14:32:25.03: [14585]: --> [2:OK]
Apr 27 14:32:25.03: [14585]: TRAINING succeeded
Apr 27 14:32:25.03: [14585]: SEND begin page
Apr 27 14:32:25.03: [14585]: SEND send frame number 0
Apr 27 14:32:25.03: [14585]: SEND send frame number 1
Apr 27 14:32:25.03: [14585]: <-- [9:AT+FRS=7\r]
Apr 27 14:32:25.15: [14585]: --> [2:OK]
Apr 27 14:32:25.15: [14585]: <-- [11:AT+FTM=146\r]
Apr 27 14:32:25.18: [14585]: --> [7:CONNECT]
Apr 27 14:32:25.18: [14585]: <-- data [921]
Apr 27 14:32:25.18: [14585]: <-- data [2]
Apr 27 14:32:26.05: [14585]: --> [2:OK]
Apr 27 14:32:26.05: [14585]: <-- [9:AT+FTS=9\r]
Apr 27 14:32:26.16: [14585]: --> [2:OK]
Apr 27 14:32:26.16: [14585]: <-- [9:AT+FTH=3\r]
Apr 27 14:32:26.21: [14585]: --> [7:CONNECT]
Apr 27 14:32:26.21: [14585]: <-- data [7]
Apr 27 14:32:26.21: [14585]: <-- data [2]
Apr 27 14:32:27.63: [14585]: --> [2:OK]
Apr 27 14:32:27.63: [14585]: SEND send PPS (partial page signal)
Apr 27 14:32:27.63: [14585]: SEND send EOP (no more pages or documents)
Apr 27 14:32:27.63: [14585]: <-- [9:AT+FRH=3\r]
Apr 27 14:32:28.07: [14585]: --> [7:CONNECT]
Apr 27 14:32:28.98: [14585]: --> [2:OK]
Apr 27 14:32:28.98: [14585]: SEND recv MCF (message confirmation)
Apr 27 14:32:28.98: [14585]: <-- [9:AT+FRS=7\r]
Apr 27 14:32:29.13: [14585]: --> [2:OK]
Apr 27 14:32:29.13: [14585]: SEND end page
Apr 27 14:32:29.13: [14585]: SEND FAX (000000692): FROM email.removed TO
01159xx03xx (page 1 of 1[1,1,0,0]  sent in 0:00:12)
Apr 27 14:32:29.13: [14585]: SEND FAX (000000692): FROM email.removed TO
01159xx03xx (docq/doc254.ps;c0 sent in 0:00:12)
Apr 27 14:32:29.13: [14585]: <-- [9:AT+FTH=3\r]
Apr 27 14:32:29.18: [14585]: --> [7:CONNECT]
Apr 27 14:32:29.18: [14585]: <-- data [3]
Apr 27 14:32:29.18: [14585]: <-- data [2]
Apr 27 14:32:30.49: [14585]: --> [2:OK]
Apr 27 14:32:31.49: [14585]: <-- [5:ATH0\r]
Apr 27 14:32:33.03: [14585]: --> [2:OK]
Apr 27 14:32:33.03: [14585]: SESSION END


And the log file for the same postscript file which completed (although
to a different number - I'll need to try this to the same number if we
need to send again - if the user lets me know in time ;-) )

Apr 27 16:35:45.97: [16994]: SESSION BEGIN 000000699 44137xxxxx92
Apr 27 16:35:45.97: [16994]: HylaFAX (tm) Version 6.0.4
Apr 27 16:35:45.97: [16994]: SEND FAX: JOB 257 DEST 0137xxxxx92 COMMID
000000699 DEVICE '/dev/cuau0' FROM 'Charlie Root <email.removed>' USER
darenr
Apr 27 16:35:45.97: [16994]: <-- [14:AT+FCLASS=1.0\r]
Apr 27 16:35:45.98: [16994]: --> [2:OK]
Apr 27 16:35:45.98: [16994]: DIAL 01372450592
Apr 27 16:35:45.98: [16994]: <-- [16:ATDT0137xxxxx92\r]
Apr 27 16:36:05.22: [16994]: --> [7:CONNECT]
Apr 27 16:36:06.62: [16994]: --> [2:OK]
Apr 27 16:36:06.62: [16994]: REMOTE CSI "+44 137x xxxx92"
Apr 27 16:36:06.62: [16994]: <-- [9:AT+FRH=3\r]
Apr 27 16:36:06.63: [16994]: --> [7:CONNECT]
Apr 27 16:36:06.99: [16994]: --> [2:OK]
Apr 27 16:36:06.99: [16994]: REMOTE best rate 14400 bit/s
Apr 27 16:36:06.99: [16994]: REMOTE max A4 page width (215 mm)
Apr 27 16:36:06.99: [16994]: REMOTE max unlimited page length
Apr 27 16:36:06.99: [16994]: REMOTE best vres 7.7 line/mm
Apr 27 16:36:06.99: [16994]: REMOTE format support: MH
Apr 27 16:36:06.99: [16994]: REMOTE best 0 ms/scanline
Apr 27 16:36:06.99: [16994]: USE 14400 bit/s
Apr 27 16:36:06.99: [16994]: SEND file "docq/doc256.ps;00"
Apr 27 16:36:06.99: [16994]: USE A4 page width (215 mm)
Apr 27 16:36:06.99: [16994]: USE unlimited page length
Apr 27 16:36:06.99: [16994]: USE 3.85 line/mm
Apr 27 16:36:06.99: [16994]: USE 1-D MH
Apr 27 16:36:06.99: [16994]: USE 0 ms/scanline
Apr 27 16:36:06.99: [16994]: SEND training at v.17 14400 bit/s
Apr 27 16:36:06.99: [16994]: <-- [9:AT+FRS=7\r]
Apr 27 16:36:07.11: [16994]: --> [2:OK]
Apr 27 16:36:07.11: [16994]: <-- [9:AT+FTH=3\r]
Apr 27 16:36:07.15: [16994]: --> [7:CONNECT]
Apr 27 16:36:07.15: [16994]: <-- data [23]
Apr 27 16:36:07.15: [16994]: <-- data [2]
Apr 27 16:36:08.94: [16994]: --> [7:CONNECT]
Apr 27 16:36:08.94: [16994]: <-- data [6]
Apr 27 16:36:08.94: [16994]: <-- data [2]
Apr 27 16:36:09.38: [16994]: --> [2:OK]
Apr 27 16:36:09.38: [16994]: <-- [9:AT+FTS=7\r]
Apr 27 16:36:09.47: [16994]: --> [2:OK]
Apr 27 16:36:09.47: [16994]: <-- [11:AT+FTM=145\r]
Apr 27 16:36:09.50: [16994]: --> [7:CONNECT]
Apr 27 16:36:09.50: [16994]: <-- data [1024]
Apr 27 16:36:09.50: [16994]: <-- data [1024]
Apr 27 16:36:09.50: [16994]: <-- data [652]
Apr 27 16:36:09.50: [16994]: <-- data [2]
Apr 27 16:36:12.61: [16994]: --> [2:OK]
Apr 27 16:36:12.61: [16994]: <-- [9:AT+FRH=3\r]
Apr 27 16:36:13.14: [16994]: --> [7:CONNECT]
Apr 27 16:36:14.00: [16994]: --> [2:OK]
Apr 27 16:36:14.00: [16994]: TRAINING succeeded
Apr 27 16:36:14.00: [16994]: <-- [9:AT+FRS=7\r]
Apr 27 16:36:14.19: [16994]: --> [2:OK]
Apr 27 16:36:14.19: [16994]: <-- [11:AT+FTM=146\r]
Apr 27 16:36:14.22: [16994]: --> [7:CONNECT]
Apr 27 16:36:14.22: [16994]: SEND begin page
Apr 27 16:36:14.22: [16994]: <-- data [1027]
Apr 27 16:36:14.22: [16994]: <-- data [1027]
Apr 27 16:36:14.22: [16994]: <-- data [1026]
Apr 27 16:36:14.22: [16994]: <-- data [1034]
Apr 27 16:36:16.15: [16994]: <-- data [1025]
Apr 27 16:36:16.15: [16994]: <-- data [1028]
Apr 27 16:36:16.15: [16994]: <-- data [1033]
Apr 27 16:36:16.15: [16994]: <-- data [1034]
Apr 27 16:36:18.08: [16994]: <-- data [1029]
Apr 27 16:36:18.08: [16994]: <-- data [1026]
Apr 27 16:36:18.08: [16994]: <-- data [1026]
Apr 27 16:36:18.08: [16994]: <-- data [1027]
Apr 27 16:36:20.02: [16994]: <-- data [1025]
Apr 27 16:36:20.02: [16994]: <-- data [1024]
Apr 27 16:36:20.02: [16994]: <-- data [1024]
Apr 27 16:36:21.95: [16994]: <-- data [1024]
Apr 27 16:36:21.95: [16994]: <-- data [1029]
Apr 27 16:36:21.95: [16994]: <-- data [1027]
Apr 27 16:36:21.95: [16994]: <-- data [1024]
Apr 27 16:36:23.89: [16994]: <-- data [1029]
Apr 27 16:36:23.89: [16994]: <-- data [1026]
Apr 27 16:36:23.89: [16994]: <-- data [41]
Apr 27 16:36:23.89: [16994]: SENT 21545 bytes of data
Apr 27 16:36:23.89: [16994]: SEND 1D RTC
Apr 27 16:36:23.89: [16994]: <-- data [29]
Apr 27 16:36:23.89: [16994]: <-- data [2]
Apr 27 16:36:23.89: [16994]: SEND end page
Apr 27 16:36:26.57: [16994]: --> [2:OK]
Apr 27 16:36:26.57: [16994]: <-- [9:AT+FTS=9\r]
Apr 27 16:36:26.68: [16994]: --> [2:OK]
Apr 27 16:36:26.68: [16994]: SEND send EOP (no more pages or documents)
Apr 27 16:36:26.68: [16994]: <-- [9:AT+FTH=3\r]
Apr 27 16:36:26.73: [16994]: --> [7:CONNECT]
Apr 27 16:36:26.73: [16994]: <-- data [3]
Apr 27 16:36:26.73: [16994]: <-- data [2]
Apr 27 16:36:28.04: [16994]: --> [2:OK]
Apr 27 16:36:28.04: [16994]: <-- [9:AT+FRH=3\r]
Apr 27 16:36:28.50: [16994]: --> [7:CONNECT]
Apr 27 16:36:29.40: [16994]: --> [2:OK]
Apr 27 16:36:29.40: [16994]: SEND recv MCF (message confirmation)
Apr 27 16:36:29.40: [16994]: SEND FAX (000000699): FROM email.removed TO
0137xxxxx92 (page 1 of 1[1,1,0,0]  sent in 0:00:23)
Apr 27 16:36:29.40: [16994]: <-- [9:AT+FRS=7\r]
Apr 27 16:36:29.59: [16994]: --> [2:OK]
Apr 27 16:36:29.59: [16994]: SEND FAX (000000699): FROM email.removed TO
0137xxxxx92 (docq/doc256.ps;00 sent in 0:00:23)
Apr 27 16:36:29.59: [16994]: <-- [9:AT+FTH=3\r]
Apr 27 16:36:29.64: [16994]: --> [7:CONNECT]
Apr 27 16:36:29.64: [16994]: <-- data [3]
Apr 27 16:36:29.64: [16994]: <-- data [2]
Apr 27 16:36:30.95: [16994]: --> [2:OK]
Apr 27 16:36:31.95: [16994]: <-- [5:ATH0\r]
Apr 27 16:36:33.49: [16994]: --> [2:OK]
Apr 27 16:36:33.49: [16994]: SESSION END

I also confirmed that both the prepared documents involved ended up
exactly the same, so there were no differences in the final docxxx.ps
document.

Any ideas what's going on here?   The same form was also faxed by
somebody else from their workstation client (both workstations are
running WHFC) although again to a number we have the ability to view the
outcome, and it also went through fine.

Thanks and apologies for the long email.

Daren


____________________ 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@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*