[hylafax-users] Sending fax Problem

["Jorge Frater B." <jfrater@xxxxxxxxxx>]

Hi,

 

I´m using Elastix 1.6 with a Quintum BX for voice calls, that works fine. I configured the fax part and receiving works great also, BUT, no luck as in sending, here´s an example log file:

 

Apr 02 16:25:55.68: [26242]: SESSION BEGIN 000000023 5062822281384

Apr 02 16:25:55.68: [26242]: HylaFAX (tm) Version 4.3.3

Apr 02 16:25:55.68: [26242]: SEND FAX: JOB 17 DEST 822281384 COMMID 000000023 DEVICE '/dev/ttyIAX1' FROM '1601 <1601@xxxxxxxxxxxxx>' USER 1601

Apr 02 16:25:55.68: [26242]: STATE CHANGE: RUNNING -> SENDING

Apr 02 16:25:55.68: [26242]: <-- [12:AT+FCLASS=1\r]

Apr 02 16:25:55.68: [26242]: --> [2:OK]

Apr 02 16:25:55.68: [26242]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled

Apr 02 16:25:55.68: [26242]: DIAL 822281384

Apr 02 16:25:55.68: [26242]: <-- [14:ATDT822281384\r]

Apr 02 16:26:09.20: [26242]: --> [7:CONNECT]

Apr 02 16:26:10.54: [26242]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 11 09 01 BA 00 A5 46>

Apr 02 16:26:10.54: [26242]: --> [2:OK]

Apr 02 16:26:10.54: [26242]: REMOTE NSF "00 00 56 55 55 00 88 90 80 5D 00"

Apr 02 16:26:10.54: [26242]: NSF remote fax equipment: Brother Fax-560/770

Apr 02 16:26:10.54: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:11.25: [26242]: --> [7:CONNECT]

Apr 02 16:26:11.25: [26242]: --> HDLC<25:FF C0 02 EC 2C 9C CC AC EC 4C 4C 04 04 04 04 04 04 04 04 04 04 04 04 09 99>

Apr 02 16:26:11.25: [26242]: --> [2:OK]

Apr 02 16:26:11.25: [26242]: REMOTE CSI "22753947"

Apr 02 16:26:11.25: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:11.58: [26242]: --> [7:CONNECT]

Apr 02 16:26:11.58: [26242]: --> HDLC<11:FF C8 01 00 72 1D 21 01 88 2F 39>

Apr 02 16:26:11.64: [26242]: --> [2:OK]

Apr 02 16:26:11.64: [26242]: REMOTE best rate 9600 bit/s

Apr 02 16:26:11.64: [26242]: REMOTE max A4 page width (215 mm)

Apr 02 16:26:11.64: [26242]: REMOTE max unlimited page length

Apr 02 16:26:11.64: [26242]: REMOTE best vres 15.4 line/mm

Apr 02 16:26:11.64: [26242]: REMOTE format support: MH

Apr 02 16:26:11.64: [26242]: REMOTE supports T.30 Annex A, 256-byte ECM

Apr 02 16:26:11.64: [26242]: REMOTE best 20 ms, 10 ms/scanline

Apr 02 16:26:11.64: [26242]: USE 9600 bit/s

Apr 02 16:26:11.64: [26242]: USE error correction mode

Apr 02 16:26:11.64: [26242]: SEND file "docq/doc17.tif;f1"

Apr 02 16:26:11.64: [26242]: USE A4 page width (215 mm)

Apr 02 16:26:11.64: [26242]: USE unlimited page length

Apr 02 16:26:11.64: [26242]: USE 7.7 line/mm

Apr 02 16:26:11.64: [26242]: USE 1-D MH

Apr 02 16:26:11.64: [26242]: USE 0 ms/scanline

Apr 02 16:26:11.64: [26242]: SEND training at v.29 9600 bit/s

Apr 02 16:26:11.64: [26242]: <-- [9:AT+FRS=7\r]

Apr 02 16:26:11.70: [26242]: --> [2:OK]

Apr 02 16:26:11.70: [26242]: <-- [9:AT+FTH=3\r]

Apr 02 16:26:11.72: [26242]: --> [7:CONNECT]

Apr 02 16:26:11.72: [26242]: <-- HDLC<23:FF C0 C2 74 82 74 CA 04 C6 A6 2E 86 4E 62 04 CE 86 B6 A6 2E CE 96 CA>

Apr 02 16:26:11.72: [26242]: <-- data [23]

Apr 02 16:26:11.72: [26242]: <-- data [2]

Apr 02 16:26:13.29: [26242]: --> [7:CONNECT]

Apr 02 16:26:13.29: [26242]: <-- HDLC<7:FF C8 C1 00 62 1F 20>

Apr 02 16:26:13.29: [26242]: <-- data [7]

Apr 02 16:26:13.29: [26242]: <-- data [2]

Apr 02 16:26:13.72: [26242]: --> [2:OK]

Apr 02 16:26:13.72: [26242]: <-- [9:AT+FTS=7\r]

Apr 02 16:26:13.82: [26242]: --> [2:OK]

Apr 02 16:26:13.82: [26242]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Apr 02 16:26:13.82: [26242]: <-- [10:AT+FTM=96\r]

Apr 02 16:26:13.84: [26242]: --> [7:CONNECT]

Apr 02 16:26:13.84: [26242]: DELAY 800 ms

Apr 02 16:26:14.64: [26242]: <-- data [1024]

Apr 02 16:26:14.64: [26242]: <-- data [776]

Apr 02 16:26:14.64: [26242]: <-- data [2]

Apr 02 16:26:16.22: [26242]: --> [2:OK]

Apr 02 16:26:16.22: [26242]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Apr 02 16:26:16.22: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:19.32: [26242]: --> [0:]

Apr 02 16:26:19.32: [26242]: MODEM <Empty line>

Apr 02 16:26:19.32: [26242]: MODEM TIMEOUT: waiting for v.21 carrier

Apr 02 16:26:19.32: [26242]: <-- data [1]

Apr 02 16:26:19.33: [26242]: --> [2:OK]

Apr 02 16:26:19.33: [26242]: DELAY 1500 ms

Apr 02 16:26:20.83: [26242]: SEND training at v.29 7200 bit/s

Apr 02 16:26:20.83: [26242]: <-- [9:AT+FRS=7\r]

Apr 02 16:26:22.93: [26242]: --> [2:OK]

Apr 02 16:26:22.93: [26242]: <-- [9:AT+FTH=3\r]

Apr 02 16:26:22.95: [26242]: --> [7:CONNECT]

Apr 02 16:26:22.95: [26242]: <-- HDLC<23:FF C0 C2 74 82 74 CA 04 C6 A6 2E 86 4E 62 04 CE 86 B6 A6 2E CE 96 CA>

Apr 02 16:26:22.95: [26242]: <-- data [23]

Apr 02 16:26:22.95: [26242]: <-- data [2]

Apr 02 16:26:24.54: [26242]: --> [7:CONNECT]

Apr 02 16:26:24.54: [26242]: <-- HDLC<7:FF C8 C1 00 72 1F 20>

Apr 02 16:26:24.54: [26242]: <-- data [7]

Apr 02 16:26:24.54: [26242]: <-- data [2]

Apr 02 16:26:24.96: [26242]: --> [2:OK]

Apr 02 16:26:24.96: [26242]: <-- [9:AT+FTS=7\r]

Apr 02 16:26:25.06: [26242]: --> [2:OK]

Apr 02 16:26:25.06: [26242]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Apr 02 16:26:25.06: [26242]: <-- [10:AT+FTM=72\r]

Apr 02 16:26:25.08: [26242]: --> [7:CONNECT]

Apr 02 16:26:25.08: [26242]: DELAY 800 ms

Apr 02 16:26:25.88: [26242]: <-- data [1024]

Apr 02 16:26:25.88: [26242]: <-- data [326]

Apr 02 16:26:25.88: [26242]: <-- data [2]

Apr 02 16:26:27.50: [26242]: --> [2:OK]

Apr 02 16:26:27.50: [26242]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Apr 02 16:26:27.50: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:30.60: [26242]: --> [0:]

Apr 02 16:26:30.60: [26242]: MODEM <Empty line>

Apr 02 16:26:30.60: [26242]: MODEM TIMEOUT: waiting for v.21 carrier

Apr 02 16:26:30.60: [26242]: <-- data [1]

Apr 02 16:26:30.60: [26242]: --> [2:OK]

Apr 02 16:26:30.60: [26242]: DELAY 1500 ms

Apr 02 16:26:32.10: [26242]: SEND training at v.27ter 4800 bit/s

Apr 02 16:26:32.10: [26242]: <-- [9:AT+FRS=7\r]

Apr 02 16:26:34.19: [26242]: --> [2:OK]

Apr 02 16:26:34.19: [26242]: <-- [9:AT+FTH=3\r]

Apr 02 16:26:34.21: [26242]: --> [7:CONNECT]

Apr 02 16:26:34.21: [26242]: <-- HDLC<23:FF C0 C2 74 82 74 CA 04 C6 A6 2E 86 4E 62 04 CE 86 B6 A6 2E CE 96 CA>

Apr 02 16:26:34.21: [26242]: <-- data [23]

Apr 02 16:26:34.21: [26242]: <-- data [2]

Apr 02 16:26:35.83: [26242]: --> [7:CONNECT]

Apr 02 16:26:35.83: [26242]: <-- HDLC<7:FF C8 C1 00 52 1F 20>

Apr 02 16:26:35.83: [26242]: <-- data [7]

Apr 02 16:26:35.83: [26242]: <-- data [2]

Apr 02 16:26:36.25: [26242]: --> [2:OK]

Apr 02 16:26:36.25: [26242]: <-- [9:AT+FTS=7\r]

Apr 02 16:26:36.35: [26242]: --> [2:OK]

Apr 02 16:26:36.35: [26242]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Apr 02 16:26:36.35: [26242]: <-- [10:AT+FTM=48\r]

Apr 02 16:26:36.38: [26242]: --> [7:CONNECT]

Apr 02 16:26:36.38: [26242]: DELAY 800 ms

Apr 02 16:26:37.18: [26242]: <-- data [900]

Apr 02 16:26:37.18: [26242]: <-- data [2]

Apr 02 16:26:38.81: [26242]: --> [2:OK]

Apr 02 16:26:38.81: [26242]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Apr 02 16:26:38.81: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:41.92: [26242]: --> [0:]

Apr 02 16:26:41.92: [26242]: MODEM <Empty line>

Apr 02 16:26:41.92: [26242]: MODEM TIMEOUT: waiting for v.21 carrier

Apr 02 16:26:41.92: [26242]: <-- data [1]

Apr 02 16:26:41.93: [26242]: --> [2:OK]

Apr 02 16:26:41.93: [26242]: DELAY 1500 ms

Apr 02 16:26:43.43: [26242]: SEND training at v.27ter fallback mode 2400 bit/s

Apr 02 16:26:43.43: [26242]: <-- [9:AT+FRS=7\r]

Apr 02 16:26:45.45: [26242]: --> [2:OK]

Apr 02 16:26:45.45: [26242]: <-- [9:AT+FTH=3\r]

Apr 02 16:26:45.47: [26242]: --> [7:CONNECT]

Apr 02 16:26:45.47: [26242]: <-- HDLC<23:FF C0 C2 74 82 74 CA 04 C6 A6 2E 86 4E 62 04 CE 86 B6 A6 2E CE 96 CA>

Apr 02 16:26:45.47: [26242]: <-- data [23]

Apr 02 16:26:45.47: [26242]: <-- data [2]

Apr 02 16:26:47.10: [26242]: --> [7:CONNECT]

Apr 02 16:26:47.10: [26242]: <-- HDLC<7:FF C8 C1 00 42 1F 20>

Apr 02 16:26:47.10: [26242]: <-- data [7]

Apr 02 16:26:47.10: [26242]: <-- data [2]

Apr 02 16:26:47.53: [26242]: --> [2:OK]

Apr 02 16:26:47.53: [26242]: <-- [9:AT+FTS=7\r]

Apr 02 16:26:47.64: [26242]: --> [2:OK]

Apr 02 16:26:47.64: [26242]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Apr 02 16:26:47.64: [26242]: <-- [10:AT+FTM=24\r]

Apr 02 16:26:47.66: [26242]: --> [7:CONNECT]

Apr 02 16:26:47.66: [26242]: DELAY 800 ms

Apr 02 16:26:48.46: [26242]: <-- data [450]

Apr 02 16:26:48.46: [26242]: <-- data [2]

Apr 02 16:26:50.34: [26242]: --> [2:OK]

Apr 02 16:26:50.34: [26242]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Apr 02 16:26:50.34: [26242]: <-- [9:AT+FRH=3\r]

Apr 02 16:26:53.44: [26242]: --> [0:]

Apr 02 16:26:53.44: [26242]: MODEM <Empty line>

Apr 02 16:26:53.44: [26242]: MODEM TIMEOUT: waiting for v.21 carrier

Apr 02 16:26:53.44: [26242]: <-- data [1]

Apr 02 16:26:53.45: [26242]: --> [2:OK]

Apr 02 16:26:53.45: [26242]: DELAY 1500 ms

Apr 02 16:26:54.95: [26242]: TRAINING failed

Apr 02 16:26:54.95: [26242]: <-- [9:AT+FTH=3\r]

Apr 02 16:26:54.95: [26242]: --> [7:CONNECT]

Apr 02 16:26:54.95: [26242]: <-- HDLC<3:FF C8 DF>

Apr 02 16:26:54.95: [26242]: <-- data [3]

Apr 02 16:26:54.95: [26242]: <-- data [2]

Apr 02 16:26:56.12: [26242]: --> [2:OK]

Apr 02 16:26:56.12: [26242]: MODEM input buffering enabled

Apr 02 16:26:56.12: [26242]: SEND FAILED: JOB 17 DEST 822281384 ERR Failure to train remote modem at 2400 bps or minimum speed; too many attempts to dial

Apr 02 16:26:56.12: [26242]: <-- [5:ATH0\r]

Apr 02 16:26:56.24: [26242]: --> [2:OK]

Apr 02 16:26:56.24: [26242]: MODEM set DTR OFF

Apr 02 16:26:56.24: [26242]: MODEM set baud rate: 0 baud (flow control unchanged)

Apr 02 16:26:56.24: [26242]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)

Apr 02 16:26:56.24: [26242]: SESSION END

 

This is my config:

 

[root@elastix ~]# cat /etc/hylafax/etc/config.ttyIAX1

CountryCode:            506

AreaCode:               2

FAXNumber:              22753947

LongDistancePrefix:     1

InternationalPrefix:    011

DialStringRules:        etc/dialrules

ServerTracing:          0xFFF

SessionTracing:         0xFFF

RecvFileMode:           0600

LogFileMode:            0600

DeviceMode:             0600

RingsBeforeAnswer:      1

SpeakerVolume:          off

GettyArgs:              "-h %l dx_%s"

LocalIdentifier:        "Sistemas Fratec S.A."

TagLineFont:            etc/lutRS18.pcf

TagLineFormat:          "From %%l|%c|Page %%P of %%T"

MaxRecvPages:           200

#

#

# Modem-related stuff: should reflect modem command interface

# and hardware connection/cabling (e.g. flow control).

#

ModemType:              Class1          # use this to supply a hint

 

#

# Enabling this will use the hfaxd-protocol to set Caller*ID

#

#ModemSetOriginCmd:     AT+VSID="%s","%d"

 

#

# If "glare" during initialization becomes a problem then take

# the modem off-hook during initialization, and then place it

# back on-hook when done.

#

#ModemResetCmds:        "ATH1\nAT+VCID=1"       # enables CallID display

#ModemReadyCmds:        ATH0

 

 

Class1AdaptRecvCmd:     AT+FAR=1

Class1TMConnectDelay:   800            # counteract quick CONNECT response

 

# Class1RMQueryCmd:       "!24,48,72,96"  # enable this to disable V.17

 

# Cambios

 

# Class1ResponseWaitCmd:  AT+FRS=1        # wait after sending TCF for response

# Class1RMQueryCmd:       "!24,48,72,96"  # enable this to disable V.17

# Class1FrameOverhead: 2

 

#

# You'll likely want Caller*ID display (also displays DID) enabled.

#

# ModemResetCmds:         AT+VCID=1       # enables CallID display

 

#

# If you are "missing" Caller*ID data on some calls (but not all)

# and if you do not have adequate glare protection you may want to

# not answer based on RINGs, but rather enable the CallIDAnswerLength

# for NDID, disable AT+VCID=1 and do this:

#

#RingsBeforeAnswer: 0

#ModemRingResponse: AT+VRID=1

 

CallIDPattern:          "NMBR="

CallIDPattern:          "NAME="

CallIDPattern:          "ANID="

CallIDPattern:          "NDID="

#CallIDAnswerLength:    4

# Uncomment these if you really want them, but you probably don't.

#CallIDPattern:          "DATE="

#CallIDPattern:          "TIME="

FaxRcvdCmd:              bin/faxrcvd.php

UseJobTSI:               true