[hylafax-users] High Error Rate

[Albert Jumaquio <albert@xxxxxxxxxxxx>]

We've been using Hylafax 4.2.2 for quite a while now and just recently errors on sending and receiving faxes has increased. It may be because of the increase of usage of the asterisk VOIP network where the fax modem is connected to for that's the only change that happened. Can i determine this through the error logs? Mostly it's a communication failure during phase (x). Here's an example of a sending failure

Nov 15 09:44:11.25: [25509]: SESSION BEGIN 000004999 16503178763450
Nov 15 09:44:11.25: [25509]: HylaFAX (tm) Version 4.2.2
Nov 15 09:44:11.25: [25509]: SEND FAX: JOB 1314 DEST 317-876-3450 COMMID 000004999 DEVICE '/dev/ttyds06'
Nov 15 09:44:11.25: [25509]: <-- [10:AT+FBOR=0\r]
Nov 15 09:44:11.25: [25509]: --> [2:OK]
Nov 15 09:44:11.25: [25509]: <-- [13:AT+FPHCTO=30\r]
Nov 15 09:44:11.25: [25509]: --> [5:ERROR]
Nov 15 09:44:11.25: [25509]: MODEM Command error
Nov 15 09:44:11.25: [25509]: <-- [24:AT+FDCC=1,D,2,2,0,2,0,0\r]
Nov 15 09:44:11.25: [25509]: --> [2:OK]
Nov 15 09:44:11.25: [25509]: DIAL 3178763450
Nov 15 09:44:11.25: [25509]: <-- [22:ATS0=255;DT3178763450\r]
Nov 15 09:44:30.70: [25509]: --> [5:+FCON]
Nov 15 09:44:30.70: [25509]: --> [28:+FCSI:"INTUITY..FAX        "]
Nov 15 09:44:30.70: [25509]: REMOTE CSI "INTUITY..FAX"
Nov 15 09:44:30.70: [25509]: --> [22:+FDIS: 1,3,0,2,0,0,0,0]
Nov 15 09:44:30.70: [25509]: --> [2:OK]
Nov 15 09:44:30.70: [25509]: REMOTE best rate 9600 bit/s
Nov 15 09:44:30.70: [25509]: REMOTE max A4 page width (215 mm)
Nov 15 09:44:30.70: [25509]: REMOTE max unlimited page length
Nov 15 09:44:30.70: [25509]: REMOTE best vres 7.7 line/mm
Nov 15 09:44:30.70: [25509]: REMOTE best format 1-D MH
Nov 15 09:44:30.70: [25509]: REMOTE best 0 ms/scanline
Nov 15 09:44:30.70: [25509]: USE 9600 bit/s
Nov 15 09:44:30.70: [25509]: SEND file "docq/doc2382.ps;31"
Nov 15 09:44:30.70: [25509]: USE A4 page width (215 mm)
Nov 15 09:44:30.70: [25509]: USE unlimited page length
Nov 15 09:44:30.70: [25509]: USE 7.7 line/mm
Nov 15 09:44:30.70: [25509]: USE 1-D MH
Nov 15 09:44:30.70: [25509]: USE 0 ms/scanline
Nov 15 09:44:30.70: [25509]: <-- [24:AT+FDIS=1,3,0,2,0,0,0,0\r]
Nov 15 09:44:30.70: [25509]: --> [2:OK]
Nov 15 09:44:30.70: [25509]: <-- [7:AT+FDT\r]
Nov 15 09:44:36.06: [25509]: --> [22:+FDCS: 1,3,0,2,0,0,0,0]
Nov 15 09:44:36.27: [25509]: --> [7:CONNECT]
Nov 15 09:44:36.27: [25509]: SEND wait for XON
Nov 15 09:44:36.29: [25509]: --> [1:]
Nov 15 09:44:36.29: [25509]: SEND begin page
Nov 15 09:44:38.35: [25509]: SENT 45518 bytes of data
Nov 15 09:44:38.35: [25509]: SEND end page
Nov 15 09:45:14.18: [25509]: --> [2:OK]
Nov 15 09:45:14.18: [25509]: SEND send EOP (no more pages or documents)
Nov 15 09:45:14.18: [25509]: <-- [9:AT+FET=2\r]
Nov 15 09:45:18.91: [25509]: --> [8:+FPTS: 2]
Nov 15 09:45:18.91: [25509]: --> [8:+FHNG: 0]
Nov 15 09:45:18.91: [25509]: REMOTE HANGUP: Normal and proper end of connection (code 0)
Nov 15 09:45:18.91: [25509]: SEND recv RTN (retrain negative)
Nov 15 09:45:18.91: [25509]: SEND: Communication failure during Phase B/C (modem protocol botch); Giving up after 3 attempts to send same page "docq/doc2382.ps;31", dirnum 0
Nov 15 09:45:18.91: [25509]: <-- [5:ATH0\r]
Nov 15 09:45:18.91: [25509]: --> [2:OK]
Nov 15 09:45:18.91: [25509]: SESSION END

And a receiving failure

Nov 15 08:13:31.54: [ 1233]: SESSION BEGIN 000004943 16503507101
Nov 15 08:13:31.54: [ 1233]: HylaFAX (tm) Version 4.2.2
Nov 15 08:13:31.54: [ 1233]: <-- [11:ATS0=255;A\r]
Nov 15 08:13:31.54: [ 1233]: --> [16:CID: 02152712093]
Nov 15 08:13:31.54: [ 1233]: --> [9:DAD: 7268]
Nov 15 08:13:31.54: [ 1233]: --> [4:RING]
Nov 15 08:13:44.05: [ 1233]: --> [5:+FCON]
Nov 15 08:13:44.05: [ 1233]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyds01'
Nov 15 08:13:44.05: [ 1233]: RECV FAX: begin
Nov 15 08:13:44.05: [ 1233]: --> [26:+FTSI:        215 271 2093]
Nov 15 08:13:44.05: [ 1233]: REMOTE TSI "215 271 2093"
Nov 15 08:13:44.05: [ 1233]: --> [22:+FDCS: 1,1,0,2,0,2,0,0]
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 4800 bit/s
Nov 15 08:13:44.05: [ 1233]: REMOTE wants A4 page width (215 mm)
Nov 15 08:13:44.05: [ 1233]: REMOTE wants unlimited page length
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 7.7 line/mm
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 1-D MH
Nov 15 08:13:44.05: [ 1233]: REMOTE wants T.30 Annex C, half duplex ECM
Nov 15 08:13:44.05: [ 1233]: --> [2:OK]
Nov 15 08:13:44.05: [ 1233]: <-- [7:AT+FDR\r]
Nov 15 08:13:44.05: [ 1233]: --> [5:+FCFR]
Nov 15 08:13:44.05: [ 1233]: --> [22:+FDCS: 1,1,0,2,0,2,0,0]
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 4800 bit/s
Nov 15 08:13:44.05: [ 1233]: REMOTE wants A4 page width (215 mm)
Nov 15 08:13:44.05: [ 1233]: REMOTE wants unlimited page length
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 7.7 line/mm
Nov 15 08:13:44.05: [ 1233]: REMOTE wants 1-D MH
Nov 15 08:13:44.05: [ 1233]: REMOTE wants T.30 Annex C, half duplex ECM
Nov 15 08:13:44.05: [ 1233]: --> [7:CONNECT]
Nov 15 08:13:44.05: [ 1233]: RECV: begin page
Nov 15 08:13:44.05: [ 1233]: RECV: send trigger 021
Nov 15 08:13:44.05: [ 1233]: Copy quality checking performed by host
Nov 15 08:14:20.61: [ 1233]: --> [10:+FPTS: 2,0]
Nov 15 08:14:20.61: [ 1233]: --> [7:+FET: 2]
Nov 15 08:14:20.61: [ 1233]: RECV recv EOP (no more pages or documents)
Nov 15 08:14:20.61: [ 1233]: --> [2:OK]
Nov 15 08:14:20.61: [ 1233]: RECV send RTN (retrain negative)
Nov 15 08:14:20.61: [ 1233]: <-- [10:AT+FPTS=2\r]
Nov 15 08:14:20.61: [ 1233]: --> [2:OK]
Nov 15 08:14:20.61: [ 1233]: <-- [7:AT+FDR\r]
Nov 15 08:14:20.61: [ 1233]: --> [10:+FHNG: 100]
Nov 15 08:14:20.61: [ 1233]: REMOTE HANGUP: Unspecified Phase D error (code 100)
Nov 15 08:14:20.61: [ 1233]: RECV keeping unconfirmed page
Nov 15 08:14:20.61: [ 1233]: RECV FAX (000004943): from 215 271 2093, page 1 in 0:36, INF, 7.7 line/mm, 1-D MH, 4800 bit/s
Nov 15 08:14:20.62: [ 1233]: RECV FAX (000004943): recvq/fax000000196.tif from 215 271 2093, route to <unspecified>, 1 pages in 0:36
Nov 15 08:14:20.62: [ 1233]: RECV FAX: Unspecified Phase D error
Nov 15 08:14:20.62: [ 1233]: RECV FAX (000004943): session with 215 271 2093 terminated abnormally: Unspecified Phase D error
Nov 15 08:14:20.62: [ 1233]: RECV FAX: bin/faxrcvd "recvq/fax000000196.tif" "ttyds01" "000004943" "Unspecified Phase D error" "02152712093" "7268"
Nov 15 08:14:20.62: [ 1233]: RECV FAX: end
Nov 15 08:14:20.62: [ 1233]: SESSION END

--
Albert F. Jumaquio

IT HELPDESK TECHNICIAN
   
Tel:       650-287-2318
Fax:      650-523-8637
email:   albert@xxxxxxxxxxxx
YM ID:  albert_fj