[hylafax-users] HELP: No response to EOP repeated 3 tries

[Tanel Kokk <tanel.kokk@xxxxxx>]

Hello

Problem: hylafax (v4.2.0) can't send fax to quite a lot fax machine.

Error: No response to EOP repeated 3 tries

However other fax-server we have currently in use - VSIFAX-3.5.1 - have 
no problems sending to these fax-machines.

Does somebody know what to do to solve this problem in hylafax?

Hylafax and VSIFAX have both US.Robotics modems with exactly the same 
firmware version:
----------------------------------
ati7
USRobotics Courier V.Everything Configuration Profile...

Product type           International External
Options                HST,V32bis,Terbo,VFC,V34+,x2,V90
Fax Options            Class 1,Class 2.0
Clock Freq             20.16Mhz
Flash ROM              512k
Ram                    64k

Supervisor date        12/02/98
DSP date               09/09/98

Supervisor rev         7.6.7
DSP rev                3.1.2
---------------------------------

hylafax modem conf:
---------------------------------
CountryCode:            372
AreaCode:               2
FAXNumber:              +1.999.555.1212
LongDistancePrefix:     0
InternationalPrefix:    00
DialStringRules:        etc/dialrules
ServerTracing:          1
SessionTracing:         11
RecvFileMode:           0600
LogFileMode:            0600
DeviceMode:             0600
RingsBeforeAnswer:      1
SpeakerVolume:          off
GettyArgs:              "-h %l dx_%s"
LocalIdentifier:        "XXX YYY ZZZ"
TagLineFont:            etc/lutRS18.pcf
TagLineFormat:          "From %%l|%c|Page %%P of %%T"
MaxRecvPages:           25

ModemType:              Class1
ModemRate:              19200
ModemFlowControl:       xonxoff
ModemSetupDTRCmd:       ATS13=1&D2
ModemSetupDCDCmd:       AT&C1
ModemNoFlowCmd:         AT&H0&I0&R1
ModemHardFlowCmd:       AT&H1&I0&R2
ModemSoftFlowCmd:       AT&H2&I2&R1
ModemResultCodesCmd:    ATQ0X4
ModemMfrQueryCmd:       !USR
ModemModelQueryCmd:     ATI3
ModemRevQueryCmd:       ATI7

Class1NFLOCmd:          AT&H0&I0&R1
Class1HFLOCmd:          AT&H1&I0&R2
Class1SFLOCmd:          ""
Class1ResponseWaitCmd:  AT+FRS=1

ModemSetupAACmd:        AT+FCLASS=0
ModemAnswerCmd:         AT+FCLASS=1A
Class2NRCmd:    AT+FNR=1,1,1,0

Class1EOPWaitCmd:       "<delay:110>"
---------------------------------

Two attachments added:
hylafax.log
vsifax.log




Tanel Kokk
veebr 16 15:10:53.38: [23774]: SESSION BEGIN 000000295 372XXXXXXXX
veebr 16 15:10:53.38: [23774]: HylaFAX (tm) Version 4.2.0
veebr 16 15:10:53.38: [23774]: SEND FAX: JOB 178 DEST XXXXXXXX COMMID 000000295 DEVICE '/dev/ttyUSB0'
veebr 16 15:10:53.38: [23774]: <-- [12:AT+FCLASS=1\r]
veebr 16 15:10:53.52: [23774]: --> [2:OK]
veebr 16 15:10:53.53: [23774]: DIAL XXXXXXXX
veebr 16 15:10:53.53: [23774]: <-- [13:ATDTXXXXXXXX\r]
veebr 16 15:11:11.82: [23774]: --> [7:CONNECT]
veebr 16 15:11:11.82: [23774]: --> [2:OK]
veebr 16 15:11:11.82: [23774]: REMOTE NSF "86 80 10 50 00 00 08 70 04 04 04 04 04 04 04 04 04 04 04 04 04 04 82 82"
veebr 16 15:11:11.82: [23774]: NSF remote fax equipment: unknown 
veebr 16 15:11:11.82: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:11.87: [23774]: --> [7:CONNECT]
veebr 16 15:11:12.45: [23774]: --> [2:OK]
veebr 16 15:11:12.45: [23774]: REMOTE CSI ""
veebr 16 15:11:12.45: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:12.50: [23774]: --> [7:CONNECT]
veebr 16 15:11:12.75: [23774]: --> [2:OK]
veebr 16 15:11:12.75: [23774]: REMOTE best rate 9600 bit/s
veebr 16 15:11:12.75: [23774]: REMOTE max A4 page width (215 mm)
veebr 16 15:11:12.75: [23774]: REMOTE max unlimited page length
veebr 16 15:11:12.75: [23774]: REMOTE best vres 7.7 line/mm
veebr 16 15:11:12.75: [23774]: REMOTE best format 2-D MR
veebr 16 15:11:12.75: [23774]: REMOTE best 20 ms, 10 ms/scanline
veebr 16 15:11:12.75: [23774]: USE 9600 bit/s
veebr 16 15:11:12.75: [23774]: USE 20 ms, 10 ms/scanline
veebr 16 15:11:12.75: [23774]: SEND file "docq/doc196.ps;01"
veebr 16 15:11:12.75: [23774]: USE A4 page width (215 mm)
veebr 16 15:11:12.75: [23774]: USE unlimited page length
veebr 16 15:11:12.75: [23774]: USE 7.7 line/mm
veebr 16 15:11:12.75: [23774]: USE 2-D MR
veebr 16 15:11:12.75: [23774]: SEND training at v.29 9600 bit/s
veebr 16 15:11:12.75: [23774]: <-- [9:AT+FTH=3\r]
veebr 16 15:11:12.86: [23774]: --> [7:CONNECT]
veebr 16 15:11:12.86: [23774]: <-- data [23]
veebr 16 15:11:12.86: [23774]: <-- data [2]
veebr 16 15:11:14.60: [23774]: --> [7:CONNECT]
veebr 16 15:11:14.60: [23774]: <-- data [6]
veebr 16 15:11:14.60: [23774]: <-- data [2]
veebr 16 15:11:15.31: [23774]: --> [2:OK]
veebr 16 15:11:15.31: [23774]: <-- [9:AT+FTS=7\r]
veebr 16 15:11:15.43: [23774]: --> [2:OK]
veebr 16 15:11:15.43: [23774]: <-- [10:AT+FTM=96\r]
veebr 16 15:11:15.70: [23774]: --> [7:CONNECT]
veebr 16 15:11:15.70: [23774]: <-- data [1024]
veebr 16 15:11:16.06: [23774]: <-- data [776]
veebr 16 15:11:16.49: [23774]: <-- data [2]
veebr 16 15:11:17.27: [23774]: --> [2:OK]
veebr 16 15:11:17.27: [23774]: <-- [9:AT+FRS=1\r]
veebr 16 15:11:17.34: [23774]: --> [2:OK]
veebr 16 15:11:17.34: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:18.42: [23774]: --> [7:CONNECT]
veebr 16 15:11:18.56: [23774]: --> [2:OK]
veebr 16 15:11:18.56: [23774]: TRAINING succeeded
veebr 16 15:11:18.56: [23774]: DELAY 200 ms
veebr 16 15:11:18.76: [23774]: <-- [10:AT+FTM=96\r]
veebr 16 15:11:19.02: [23774]: --> [7:CONNECT]
veebr 16 15:11:19.02: [23774]: SEND begin page
veebr 16 15:11:19.02: [23774]: Reading MH-compressed image file
veebr 16 15:11:19.02: [23774]: <-- data [1032]
veebr 16 15:11:19.42: [23774]: <-- data [1024]
veebr 16 15:11:19.92: [23774]: <-- data [1024]
veebr 16 15:11:20.45: [23774]: <-- data [1031]
veebr 16 15:11:21.02: [23774]: <-- data [1033]
veebr 16 15:11:21.56: [23774]: <-- data [1026]
veebr 16 15:11:22.09: [23774]: <-- data [1035]
veebr 16 15:11:22.62: [23774]: <-- data [1027]
veebr 16 15:11:23.16: [23774]: <-- data [1035]
veebr 16 15:11:23.70: [23774]: <-- data [1031]
veebr 16 15:11:24.23: [23774]: <-- data [315]
veebr 16 15:11:24.37: [23774]: SENT 10552 bytes of data
veebr 16 15:11:24.37: [23774]: SEND 2D RTC
veebr 16 15:11:24.37: [23774]: <-- data [30]
veebr 16 15:11:24.40: [23774]: <-- data [2]
veebr 16 15:11:24.42: [23774]: SEND end page
veebr 16 15:11:24.42: [23774]: --> [2:OK]
veebr 16 15:11:24.42: [23774]: DELAY 1100 ms
veebr 16 15:11:25.52: [23774]: SEND send EOP (no more pages or documents)
veebr 16 15:11:25.52: [23774]: <-- [9:AT+FTH=3\r]
veebr 16 15:11:25.64: [23774]: --> [7:CONNECT]
veebr 16 15:11:25.64: [23774]: <-- data [3]
veebr 16 15:11:25.64: [23774]: <-- data [2]
veebr 16 15:11:26.85: [23774]: --> [2:OK]
veebr 16 15:11:26.85: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:29.95: [23774]: --> [0:]
veebr 16 15:11:29.95: [23774]: MODEM <Empty line>
veebr 16 15:11:29.95: [23774]: <-- data [1]
veebr 16 15:11:29.96: [23774]: --> [2:OK]
veebr 16 15:11:29.96: [23774]: SEND send EOP (no more pages or documents)
veebr 16 15:11:29.96: [23774]: <-- [9:AT+FTH=3\r]
veebr 16 15:11:30.07: [23774]: --> [7:CONNECT]
veebr 16 15:11:30.07: [23774]: <-- data [3]
veebr 16 15:11:30.07: [23774]: <-- data [2]
veebr 16 15:11:31.28: [23774]: --> [2:OK]
veebr 16 15:11:31.28: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:34.38: [23774]: --> [0:]
veebr 16 15:11:34.38: [23774]: MODEM <Empty line>
veebr 16 15:11:34.38: [23774]: <-- data [1]
veebr 16 15:11:34.39: [23774]: --> [2:OK]
veebr 16 15:11:34.39: [23774]: SEND send EOP (no more pages or documents)
veebr 16 15:11:34.39: [23774]: <-- [9:AT+FTH=3\r]
veebr 16 15:11:34.50: [23774]: --> [7:CONNECT]
veebr 16 15:11:34.50: [23774]: <-- data [3]
veebr 16 15:11:34.50: [23774]: <-- data [2]
veebr 16 15:11:35.71: [23774]: --> [2:OK]
veebr 16 15:11:35.71: [23774]: <-- [9:AT+FRH=3\r]
veebr 16 15:11:38.81: [23774]: --> [0:]
veebr 16 15:11:38.81: [23774]: MODEM <Empty line>
veebr 16 15:11:38.81: [23774]: <-- data [1]
veebr 16 15:11:38.82: [23774]: --> [2:OK]
veebr 16 15:11:38.82: [23774]: No response to EOP repeated 3 tries
veebr 16 15:11:38.82: [23774]: <-- [9:AT+FTH=3\r]
veebr 16 15:11:38.93: [23774]: --> [7:CONNECT]
veebr 16 15:11:38.93: [23774]: <-- data [3]
veebr 16 15:11:38.93: [23774]: <-- data [2]
veebr 16 15:11:40.15: [23774]: --> [2:OK]
veebr 16 15:11:40.15: [23774]: <-- [5:ATH0\r]
veebr 16 15:11:40.57: [23774]: --> [2:OK]
veebr 16 15:11:40.57: [23774]: SESSION END

02/16 16.06.16: JOB     :   380.180: processing request 3614 at 2005/02/16 16:06:16
02/16 16.06.16: DIAL    :   380.180: Requested: XXXXXXXX
02/16 16.06.16: DIAL    :   380.180: Converted: XXXXXXXX
02/16 16.06.16: TERMIO  :     0.000: ---> "ATS0=0^M"
02/16 16.06.16: EVENT   :     0.180: <--- "OK"
02/16 16.06.16: TERMIO  :     0.180: ---> "AT&C0^M"
02/16 16.06.16: EVENT   :     0.360: <--- "OK"
02/16 16.06.16: TERMIO  :     0.360: ---> "AT&D0^M"
02/16 16.06.16: EVENT   :     0.540: <--- "OK"
02/16 16.06.16: TERMIO  :     0.540: ---> "AT+FCLASS=2.0^M"
02/16 16.06.17: EVENT   :     0.600: <--- "OK"
02/16 16.06.17: TERMIO  :     0.600: ---> "AT+FBO=0^M"
02/16 16.06.17: EVENT   :     0.660: <--- "OK"
02/16 16.06.17: TERMIO  :     0.660: ---> "AT+FNR=1,1,1,0^M"
02/16 16.06.17: EVENT   :     0.720: <--- "OK"
02/16 16.06.17: TERMIO  :     0.720: ---> "ATX4^M"
02/16 16.06.17: EVENT   :     0.900: <--- "OK"
02/16 16.06.17: TERMIO  :     0.900: ---> "AT&H0^M"
02/16 16.06.17: EVENT   :     1.080: <--- "OK"
02/16 16.06.17: TERMIO  :     1.080: ---> "AT&I2^M"
02/16 16.06.17: EVENT   :     1.260: <--- "OK"
02/16 16.06.17: TERMIO  :     1.260: ---> "AT+FLO=1^M"
02/16 16.06.17: EVENT   :     1.320: <--- "OK"
02/16 16.06.17: TERMIO  :     1.320: ---> "AT+FFC=0^M"
02/16 16.06.17: EVENT   :     1.380: <--- "OK"
02/16 16.06.17: TERMIO  :     1.380: ---> "AT+FCR=0^M"
02/16 16.06.17: EVENT   :     1.440: <--- "OK"
02/16 16.06.17: TERMIO  :     1.440: ---> "ATS6=3^M"
02/16 16.06.18: EVENT   :     1.620: <--- "OK"
02/16 16.06.18: TERMIO  :     1.620: ---> "ATS7=60^M"
02/16 16.06.18: EVENT   :     1.800: <--- "OK"
02/16 16.06.18: TERMIO  :     1.800: ---> "AT+FLI="YYYYYYYY"^M"
02/16 16.06.18: EVENT   :     1.860: <--- "OK"
02/16 16.06.18: TERMIO  :     1.860: ---> "AT+FCC=1,5,0,2,0,0,0,0^M"
02/16 16.06.18: EVENT   :     1.920: <--- "OK"
02/16 16.06.18: TERMIO  :     1.920: ---> "ATDT XXXXXXXX^M"
02/16 16.06.34: EVENT   :    18.331: <--- "+FCO"
02/16 16.06.37: EVENT   :    20.841: <--- "+FCI:"                    ""
02/16 16.06.37: EVENT   :    21.131: <--- "+FIS:1,3,0,2,1,0,0,4"
02/16 16.06.37: EVENT   :    21.241: <--- "OK"
02/16 16.06.37: T.30    :    21.241: enter phase C for page 1 with VR=FINE BR=14400
02/16 16.06.37: TERMIO  :    21.241: ---> "AT+FIS=1,5,^M"
02/16 16.06.37: EVENT   :    21.301: <--- "OK"
02/16 16.06.37: TERMIO  :    21.301: ---> "AT+FDT^M"
02/16 16.06.37: EVENT   :    21.371: <--- "+FCS:1,3,0,2,0,0,0,5"
02/16 16.06.43: EVENT   :    27.311: <--- "CONNECT"
02/16 16.06.43: T.30    :    27.311: CONNECTed in phase C
02/16 16.06.55: T.30    :    39.082: transmitted 14611 T.4 bytes
02/16 16.06.55: SEND    :    39.082: sending <RTC><DLE><EOP> to leave phase C
02/16 16.07.39: EVENT   :    82.564: <--- "+FHS:00"
02/16 16.07.39: EVENT   :    82.564: <--- "OK"
02/16 16.07.39: T.30    :    82.564: now out of phase C