Re: Multi faxing problems Hylafax+

On 3/13/19 3:49 PM, Lee Howard wrote:
> <snip>
> Let's talk about your fax line because your failure rates in Class 1
> seem to indicate that as being the most-likely culprit.  Where is the
> fax line coming from?  In particular, is it a VoIP line?  Is the line
> shared with some other device?  Does the line have call waiting enabled
> on it?  Does it have DSL service on it?
>
> Thanks,
>
> Lee.
>
Thanks again for your help. The following is an entire log of a typical 
failed session. I've looked at a lot of log files, and the errors are 
always the same.

I thought maybe they were receiving the fax and their modem was hanging 
up without replying "ok success I'm done", but in these cases, the 
recipient does not receive the fax. Their fax device is discarding the 
fax. This particular number is running at about a 10% failure rate, some 
are running as high as 30%. Interesting enough, some numbers we send to 
never fail, and a lot have a failure rate of less than 1%. I suspect 
there is a high correlation between the receiving device and the failure 
rate.

Mar 04 09:15:41.83: [19160]: SESSION BEGIN 000374545 19999999999
Mar 04 09:15:41.83: [19160]: HylaFAX (tm) Version 5.5.7
Mar 04 09:15:41.83: [19160]: SEND FAX: JOB 254405 DEST 1-999-999-9999 
COMMID 000374545 DEVICE '/dev/ttyS0' FROM 'daemon <daemon@faxserver>' 
USER user
Mar 04 09:15:41.83: [19160]: STATE CHANGE: RUNNING -> SENDING
Mar 04 09:15:41.83: [19160]: <-- [12:AT+FCLASS=1\r]
Mar 04 09:15:41.87: [19160]: --> [2:OK]
Mar 04 09:15:41.87: [19160]: MODEM set XON/XOFF/FLUSH: input ignored, 
output disabled
Mar 04 09:15:41.89: [19160]: DIAL 19999999999
Mar 04 09:15:41.89: [19160]: <-- [16:ATDT19999999999\r]
Mar 04 09:16:03.76: [19160]: --> [7:CONNECT]
Mar 04 09:16:04.18: [19160]: --> HDLC<16:FF C0 04 00 00 90 00 00 24 40 
50 08 80 0C C0 A6>
Mar 04 09:16:04.18: [19160]: --> [2:OK]
Mar 04 09:16:04.18: [19160]: REMOTE NSF "00 00 09 00 00 24 02 0A 10 01 30"
Mar 04 09:16:04.18: [19160]: NSF remote fax equipment: Xerox/Toshiba
Mar 04 09:16:04.18: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:04.30: [19160]: --> [7:CONNECT]
Mar 04 09:16:04.97: [19160]: --> HDLC<25:FF C0 02 9C 0C 1C 2C 04 2C 4C 
EC 04 0C 4C AC 04 04 04 04 04 04 04 04 28 F7>
Mar 04 09:16:04.97: [19160]: --> [2:OK]
Mar 04 09:16:04.97: [19160]: REMOTE CSI "999 999 9999"
Mar 04 09:16:04.97: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:05.08: [19160]: --> [7:CONNECT]
Mar 04 09:16:05.48: [19160]: --> HDLC<15:FF C8 01 04 77 19 23 01 EB C1 
01 01 04 00 47>
Mar 04 09:16:05.48: [19160]: --> [2:OK]
Mar 04 09:16:05.48: [19160]: REMOTE best rate 33600 bit/s
Mar 04 09:16:05.48: [19160]: REMOTE max A4 page width (215 mm)
Mar 04 09:16:05.48: [19160]: REMOTE max unlimited page length
Mar 04 09:16:05.48: [19160]: REMOTE best vres R16 x 15.4 line/mm
Mar 04 09:16:05.48: [19160]: REMOTE format support: MH, MR, MMR, JBIG
Mar 04 09:16:05.48: [19160]: REMOTE supports T.30 Annex A, 256-byte ECM
Mar 04 09:16:05.48: [19160]: REMOTE best 5 ms/scanline
Mar 04 09:16:05.48: [19160]: USE 14400 bit/s
Mar 04 09:16:05.48: [19160]: USE error correction mode
Mar 04 09:16:05.48: [19160]: SEND file "docq/doc254406.pdf;1de0"
Mar 04 09:16:05.48: [19160]: USE A4 page width (215 mm)
Mar 04 09:16:05.48: [19160]: USE unlimited page length
Mar 04 09:16:05.48: [19160]: USE 3.85 line/mm
Mar 04 09:16:05.48: [19160]: USE 2-D MMR
Mar 04 09:16:05.48: [19160]: USE 0 ms/scanline
Mar 04 09:16:05.48: [19160]: SEND training at v.17 14400 bit/s
Mar 04 09:16:05.48: [19160]: DELAY 70 ms
Mar 04 09:16:05.55: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:05.66: [19160]: --> [7:CONNECT]
Mar 04 09:16:05.66: [19160]: <-- HDLC<23:FF C0 C2 76 F6 5E 96 4E 82 04 
A6 16 2E 04 66 F6 04 A6 C6 96 66 66 F2>
Mar 04 09:16:05.66: [19160]: <-- data [23]
Mar 04 09:16:05.66: [19160]: <-- data [2]
Mar 04 09:16:07.40: [19160]: --> [7:CONNECT]
Mar 04 09:16:07.40: [19160]: <-- HDLC<7:FF C8 C1 00 44 1F 22>
Mar 04 09:16:07.40: [19160]: <-- data [7]
Mar 04 09:16:07.40: [19160]: <-- data [2]
Mar 04 09:16:08.14: [19160]: --> [2:OK]
Mar 04 09:16:08.14: [19160]: <-- [9:AT+FTS=7\r]
Mar 04 09:16:08.26: [19160]: --> [2:OK]
Mar 04 09:16:08.26: [19160]: MODEM set XON/XOFF/FLUSH: input 
interpreted, output disabled
Mar 04 09:16:08.26: [19160]: <-- [11:AT+FTM=145\r]
Mar 04 09:16:09.69: [19160]: --> [7:CONNECT]
Mar 04 09:16:09.69: [19160]: <-- data [1024]
Mar 04 09:16:09.69: [19160]: <-- data [1024]
Mar 04 09:16:09.69: [19160]: <-- data [652]
Mar 04 09:16:09.69: [19160]: <-- data [2]
Mar 04 09:16:11.26: [19160]: --> [2:OK]
Mar 04 09:16:11.26: [19160]: MODEM set XON/XOFF/DRAIN: input ignored, 
output disabled
Mar 04 09:16:11.26: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:13.25: [19160]: --> [7:CONNECT]
Mar 04 09:16:13.38: [19160]: --> HDLC<5:FF C8 22 67 DD>
Mar 04 09:16:13.38: [19160]: --> [2:OK]
Mar 04 09:16:13.38: [19160]: SEND training at v.17 12000 bit/s
Mar 04 09:16:13.38: [19160]: DELAY 70 ms
Mar 04 09:16:13.45: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:13.56: [19160]: --> [7:CONNECT]
Mar 04 09:16:13.56: [19160]: <-- HDLC<23:FF C0 C2 76 F6 5E 96 4E 82 04 
A6 16 2E 04 66 F6 04 A6 C6 96 66 66 F2>
Mar 04 09:16:13.56: [19160]: <-- data [23]
Mar 04 09:16:13.56: [19160]: <-- data [2]
Mar 04 09:16:15.30: [19160]: --> [7:CONNECT]
Mar 04 09:16:15.30: [19160]: <-- HDLC<7:FF C8 C1 00 54 1F 22>
Mar 04 09:16:15.30: [19160]: <-- data [7]
Mar 04 09:16:15.30: [19160]: <-- data [2]
Mar 04 09:16:16.04: [19160]: --> [2:OK]
Mar 04 09:16:16.04: [19160]: <-- [9:AT+FTS=7\r]
Mar 04 09:16:16.16: [19160]: --> [2:OK]
Mar 04 09:16:16.16: [19160]: MODEM set XON/XOFF/FLUSH: input 
interpreted, output disabled
Mar 04 09:16:16.16: [19160]: <-- [11:AT+FTM=121\r]
Mar 04 09:16:17.59: [19160]: --> [7:CONNECT]
Mar 04 09:16:17.59: [19160]: <-- data [1024]
Mar 04 09:16:17.59: [19160]: <-- data [1024]
Mar 04 09:16:17.59: [19160]: <-- data [202]
Mar 04 09:16:17.59: [19160]: <-- data [2]
Mar 04 09:16:19.16: [19160]: --> [2:OK]
Mar 04 09:16:19.16: [19160]: MODEM set XON/XOFF/DRAIN: input ignored, 
output disabled
Mar 04 09:16:19.16: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:21.18: [19160]: --> [7:CONNECT]
Mar 04 09:16:21.32: [19160]: --> HDLC<5:FF C8 21 57 BE>
Mar 04 09:16:21.32: [19160]: --> [2:OK]
Mar 04 09:16:21.32: [19160]: TRAINING succeeded
Mar 04 09:16:21.32: [19160]: SEND begin page
Mar 04 09:16:21.32: [19160]: SEND send frame number 0
Mar 04 09:16:21.32: [19160]: SEND send frame number 1
Mar 04 09:16:21.32: [19160]: SEND send frame number 2
Mar 04 09:16:21.32: [19160]: SEND send frame number 3
Mar 04 09:16:21.32: [19160]: SEND send frame number 4
Mar 04 09:16:21.32: [19160]: SEND send frame number 5
Mar 04 09:16:21.32: [19160]: SEND send frame number 6
Mar 04 09:16:21.32: [19160]: SEND send frame number 7
Mar 04 09:16:21.32: [19160]: SEND send frame number 8
Mar 04 09:16:21.32: [19160]: SEND send frame number 9
Mar 04 09:16:21.32: [19160]: SEND send frame number 10
Mar 04 09:16:21.32: [19160]: SEND send frame number 11
Mar 04 09:16:21.32: [19160]: SEND send frame number 12
Mar 04 09:16:21.32: [19160]: SEND send frame number 13
Mar 04 09:16:21.32: [19160]: SEND send frame number 14
Mar 04 09:16:21.32: [19160]: SEND send frame number 15
Mar 04 09:16:21.32: [19160]: SEND send frame number 16
Mar 04 09:16:21.32: [19160]: SEND send frame number 17
Mar 04 09:16:21.32: [19160]: SEND send frame number 18
Mar 04 09:16:21.33: [19160]: SEND send frame number 19
Mar 04 09:16:21.33: [19160]: SEND send frame number 20
Mar 04 09:16:21.33: [19160]: SEND send frame number 21
Mar 04 09:16:21.33: [19160]: SEND send frame number 22
Mar 04 09:16:21.33: [19160]: SEND send frame number 23
Mar 04 09:16:21.33: [19160]: SEND send frame number 24
Mar 04 09:16:21.33: [19160]: SEND send frame number 25
Mar 04 09:16:21.33: [19160]: SEND send frame number 26
Mar 04 09:16:21.33: [19160]: SEND send frame number 27
Mar 04 09:16:21.33: [19160]: SEND send frame number 28
Mar 04 09:16:21.33: [19160]: SEND send frame number 29
Mar 04 09:16:21.33: [19160]: SEND send frame number 30
Mar 04 09:16:21.33: [19160]: SEND send frame number 31
Mar 04 09:16:21.33: [19160]: SEND send frame number 32
Mar 04 09:16:21.33: [19160]: SEND send frame number 33
Mar 04 09:16:21.33: [19160]: SEND send frame number 34
Mar 04 09:16:21.33: [19160]: SEND send frame number 35
Mar 04 09:16:21.33: [19160]: SEND send frame number 36
Mar 04 09:16:21.33: [19160]: SEND send frame number 37
Mar 04 09:16:21.33: [19160]: SEND send frame number 38
Mar 04 09:16:21.33: [19160]: SEND send frame number 39
Mar 04 09:16:21.33: [19160]: SEND send frame number 40
Mar 04 09:16:21.33: [19160]: SEND send frame number 41
Mar 04 09:16:21.33: [19160]: SEND send frame number 42
Mar 04 09:16:21.33: [19160]: SEND send frame number 43
Mar 04 09:16:21.33: [19160]: SEND send frame number 44
Mar 04 09:16:21.33: [19160]: SEND send frame number 45
Mar 04 09:16:21.33: [19160]: SEND send frame number 46
Mar 04 09:16:21.33: [19160]: SEND send frame number 47
Mar 04 09:16:21.33: [19160]: SEND send frame number 48
Mar 04 09:16:21.33: [19160]: SEND send frame number 49
Mar 04 09:16:21.33: [19160]: SEND send frame number 50
Mar 04 09:16:21.33: [19160]: SEND send frame number 51
Mar 04 09:16:21.33: [19160]: SEND send frame number 52
Mar 04 09:16:21.33: [19160]: SEND send frame number 53
Mar 04 09:16:21.33: [19160]: SEND send frame number 54
Mar 04 09:16:21.33: [19160]: SEND send frame number 55
Mar 04 09:16:21.33: [19160]: SEND send frame number 56
Mar 04 09:16:21.33: [19160]: MODEM set XON/XOFF/FLUSH: input 
interpreted, output disabled
Mar 04 09:16:21.33: [19160]: DELAY 70 ms
Mar 04 09:16:21.40: [19160]: <-- [11:AT+FTM=122\r]
Mar 04 09:16:21.57: [19160]: --> [7:CONNECT]
Mar 04 09:16:21.57: [19160]: <-- data [1033]
Mar 04 09:16:21.57: [19160]: <-- data [1044]
Mar 04 09:16:21.57: [19160]: <-- data [1041]
Mar 04 09:16:21.57: [19160]: <-- data [1049]
Mar 04 09:16:23.56: [19160]: <-- data [1038]
Mar 04 09:16:23.56: [19160]: <-- data [1034]
Mar 04 09:16:23.56: [19160]: <-- data [1040]
Mar 04 09:16:23.56: [19160]: <-- data [1048]
Mar 04 09:16:25.56: [19160]: <-- data [1037]
Mar 04 09:16:25.56: [19160]: <-- data [1035]
Mar 04 09:16:25.56: [19160]: <-- data [1034]
Mar 04 09:16:25.56: [19160]: <-- data [1037]
Mar 04 09:16:28.42: [19160]: <-- data [1035]
Mar 04 09:16:28.42: [19160]: <-- data [1036]
Mar 04 09:16:28.42: [19160]: <-- data [1035]
Mar 04 09:16:30.41: [19160]: <-- data [195]
Mar 04 09:16:30.41: [19160]: <-- data [2]
Mar 04 09:16:32.01: [19160]: --> [2:OK]
Mar 04 09:16:32.01: [19160]: MODEM set XON/XOFF/DRAIN: input ignored, 
output disabled
Mar 04 09:16:32.01: [19160]: <-- [9:AT+FTS=9\r]
Mar 04 09:16:32.15: [19160]: --> [2:OK]
Mar 04 09:16:32.15: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:32.27: [19160]: --> [7:CONNECT]
Mar 04 09:16:32.27: [19160]: <-- HDLC<7:FF C8 FD F4 00 00 1C>
Mar 04 09:16:32.27: [19160]: <-- data [7]
Mar 04 09:16:32.27: [19160]: <-- data [2]
Mar 04 09:16:33.60: [19160]: --> [2:OK]
Mar 04 09:16:33.60: [19160]: SEND send PPS (partial page signal)
Mar 04 09:16:33.60: [19160]: SEND send EOP (no more pages or documents)
Mar 04 09:16:33.60: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:36.70: [19160]: --> [0:]
Mar 04 09:16:36.70: [19160]: MODEM <Empty line>
Mar 04 09:16:36.70: [19160]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 04 09:16:36.70: [19160]: <-- data [1]
Mar 04 09:16:36.70: [19160]: --> [2:OK]
Mar 04 09:16:36.70: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:36.81: [19160]: --> [7:CONNECT]
Mar 04 09:16:36.81: [19160]: <-- HDLC<7:FF C8 FD F4 00 00 1C>
Mar 04 09:16:36.81: [19160]: <-- data [7]
Mar 04 09:16:36.81: [19160]: <-- data [2]
Mar 04 09:16:38.14: [19160]: --> [2:OK]
Mar 04 09:16:38.14: [19160]: SEND send PPS (partial page signal)
Mar 04 09:16:38.14: [19160]: SEND send EOP (no more pages or documents)
Mar 04 09:16:38.14: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:39.96: [19160]: --> [7:CONNECT]
Mar 04 09:16:40.00: [19160]: --> [4:60 F6 10 03]
Mar 04 09:16:40.00: [19160]: --> [5:ERROR]
Mar 04 09:16:40.00: [19160]: MODEM Command error
Mar 04 09:16:40.00: [19160]: FCS error
Mar 04 09:16:40.00: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:40.11: [19160]: --> [7:CONNECT]
Mar 04 09:16:40.11: [19160]: <-- HDLC<7:FF C8 FD F4 00 00 1C>
Mar 04 09:16:40.11: [19160]: <-- data [7]
Mar 04 09:16:40.11: [19160]: <-- data [2]
Mar 04 09:16:41.44: [19160]: --> [2:OK]
Mar 04 09:16:41.44: [19160]: SEND send PPS (partial page signal)
Mar 04 09:16:41.44: [19160]: SEND send EOP (no more pages or documents)
Mar 04 09:16:41.44: [19160]: <-- [9:AT+FRH=3\r]
Mar 04 09:16:44.54: [19160]: --> [0:]
Mar 04 09:16:44.54: [19160]: MODEM <Empty line>
Mar 04 09:16:44.54: [19160]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 04 09:16:44.54: [19160]: <-- data [1]
Mar 04 09:16:44.54: [19160]: --> [2:OK]
Mar 04 09:16:44.54: [19160]: No response to PPS repeated 3 times. {E147}
Mar 04 09:16:44.54: [19160]: SEND end page
Mar 04 09:16:44.54: [19160]: SEND: No response to PPS repeated 3 times. 
{E147}; Giving up after 3 attempts to send same page 
"docq/doc254406.pdf;1de0", dirnum 0
Mar 04 09:16:44.54: [19160]: DELAY 70 ms
Mar 04 09:16:44.61: [19160]: <-- [9:AT+FTH=3\r]
Mar 04 09:16:44.72: [19160]: --> [7:CONNECT]
Mar 04 09:16:44.72: [19160]: <-- HDLC<3:FF C8 DF>
Mar 04 09:16:44.72: [19160]: <-- data [3]
Mar 04 09:16:44.72: [19160]: <-- data [2]
Mar 04 09:16:45.94: [19160]: --> [2:OK]
Mar 04 09:16:45.94: [19160]: MODEM input buffering enabled
Mar 04 09:16:46.94: [19160]: <-- [5:ATH0\r]
Mar 04 09:16:47.32: [19160]: --> [2:OK]
Mar 04 09:16:47.32: [19160]: MODEM set DTR OFF
Mar 04 09:16:47.32: [19160]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Mar 04 09:16:47.32: [19160]: SESSION END


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