[hylafax-users] waiting for v.21 carrier + Failure to train modems

[<hbfornies@xxxxxxxxxxx>]

 
Hi all,
 
I have the following configuration:
PSTN -> PRI Gateway -> Asterisk 1.4.20.1 in hostA -> Iaxmodem + Hylafax 4.4.4 in hostB
 
hostA and hostB are CentOS 5.0 and are in a LAN network.
 
I have an error when receiving fax:
- MODEM TIMEOUT: waiting for v.21 carrier
- RECV FAX: Failure to train modems
 
 
 
This is the config.ttyIAX1:
 

CountryCode:  34
LongDistancePrefix: 00
InternationalPrefix: 00
DialStringRules: etc/dialrules
ServerTracing:          0xFFF
SessionTracing:         0xFFF
RecvFileMode:  0600
LogFileMode:  0600
DeviceMode:  0600
RingsBeforeAnswer: 1
SpeakerVolume:  off
GettyArgs:  "-h %l dx_%s"
LocalIdentifier: "local"
TagLineFont:  etc/lutRS18.pcf
TagLineFormat:  "De %%l|%c|Pagina %%P de %%T"
PercentGoodLines: 80
MaxConsecutiveBadLines: 10
MaxRecvPages:           200
ModemType:  Class1  # use this to supply a hint
 
Class1AdaptRecvCmd: AT+FAR=1
Class1TMConnectDelay: 400  # counteract quick CONNECT response
Class1RMQueryCmd: "!24,48,72,96" # V.17 fast-train recv doesn't work well
ModemResetCmds:         AT+VCID=1       # enables CallID display

CallIDPattern:          "NMBR="
CallIDPattern:          "NAME="
CallIDPattern:  "ANID="
CallIDPattern:          "NDID="

 
 
And this is the hylafax log:
 
Oct 28 09:05:40.68: [ 5791]: SESSION BEGIN 000000067 xxxxxxxxxxxx
Oct 28 09:05:40.68: [ 5791]: HylaFAX (tm) Version 4.4.4
Oct 28 09:05:40.68: [ 5791]: CallID: "xxxxxxxxx" "<NONE>" "xxxxxxxxx"
Oct 28 09:05:40.68: [ 5791]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Oct 28 09:05:40.68: [ 5791]: <-- [4:ATA\r]
Oct 28 09:05:44.40: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:44.40: [ 5791]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyIAX1'
Oct 28 09:05:44.40: [ 5791]: STATE CHANGE: ANSWERING -> RECEIVING
Oct 28 09:05:44.40: [ 5791]: RECV FAX: begin
Oct 28 09:05:44.40: [ 5791]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 2C 74 2C>
Oct 28 09:05:44.40: [ 5791]: <-- data [32]
Oct 28 09:05:44.40: [ 5791]: <-- data [2]
Oct 28 09:05:45.32: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:45.32: [ 5791]: <-- HDLC<23:FF C0 02 A6 F6 6E 6E F2 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Oct 28 09:05:45.32: [ 5791]: <-- data [23]
Oct 28 09:05:45.32: [ 5791]: <-- data [2]
Oct 28 09:05:46.08: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:46.08: [ 5791]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Oct 28 09:05:46.08: [ 5791]: <-- data [13]
Oct 28 09:05:46.08: [ 5791]: <-- data [2]
Oct 28 09:05:46.66: [ 5791]: --> [2:OK]
Oct 28 09:05:46.66: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:05:53.66: [ 5791]: --> [0:]
Oct 28 09:05:53.66: [ 5791]: MODEM <Empty line>
Oct 28 09:05:53.66: [ 5791]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 28 09:05:53.66: [ 5791]: <-- data [1]
Oct 28 09:05:53.68: [ 5791]: --> [2:OK]
Oct 28 09:05:53.68: [ 5791]: <-- [9:AT+FRS=7\r]
Oct 28 09:05:53.70: [ 5791]: --> [2:OK]
Oct 28 09:05:53.70: [ 5791]: <-- [9:AT+FTH=3\r]
Oct 28 09:05:53.72: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:53.72: [ 5791]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 2C 74 2C>
Oct 28 09:05:53.72: [ 5791]: <-- data [32]
Oct 28 09:05:53.72: [ 5791]: <-- data [2]
Oct 28 09:05:55.50: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:55.50: [ 5791]: <-- HDLC<23:FF C0 02 A6 F6 6E 6E F2 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Oct 28 09:05:55.50: [ 5791]: <-- data [23]
Oct 28 09:05:55.50: [ 5791]: <-- data [2]
Oct 28 09:05:56.24: [ 5791]: --> [7:CONNECT]
Oct 28 09:05:56.24: [ 5791]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Oct 28 09:05:56.24: [ 5791]: <-- data [13]
Oct 28 09:05:56.24: [ 5791]: <-- data [2]
Oct 28 09:05:56.82: [ 5791]: --> [2:OK]
Oct 28 09:05:56.82: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:03.82: [ 5791]: --> [0:]
Oct 28 09:06:03.82: [ 5791]: MODEM <Empty line>
Oct 28 09:06:03.82: [ 5791]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 28 09:06:03.82: [ 5791]: <-- data [1]
Oct 28 09:06:03.84: [ 5791]: --> [2:OK]
Oct 28 09:06:03.84: [ 5791]: <-- [9:AT+FRS=7\r]
Oct 28 09:06:03.86: [ 5791]: --> [2:OK]
Oct 28 09:06:03.86: [ 5791]: <-- [9:AT+FTH=3\r]
Oct 28 09:06:03.86: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:03.86: [ 5791]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 2C 74 2C>
Oct 28 09:06:03.86: [ 5791]: <-- data [32]
Oct 28 09:06:03.86: [ 5791]: <-- data [2]
Oct 28 09:06:05.64: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:05.64: [ 5791]: <-- HDLC<23:FF C0 02 A6 F6 6E 6E F2 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Oct 28 09:06:05.64: [ 5791]: <-- data [23]
Oct 28 09:06:05.64: [ 5791]: <-- data [2]
Oct 28 09:06:06.38: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:06.38: [ 5791]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Oct 28 09:06:06.38: [ 5791]: <-- data [13]
Oct 28 09:06:06.38: [ 5791]: <-- data [2]
Oct 28 09:06:06.96: [ 5791]: --> [2:OK]
Oct 28 09:06:06.96: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:13.96: [ 5791]: --> [0:]
Oct 28 09:06:13.96: [ 5791]: MODEM <Empty line>
Oct 28 09:06:13.96: [ 5791]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 28 09:06:13.96: [ 5791]: <-- data [1]
Oct 28 09:06:13.98: [ 5791]: --> [2:OK]
Oct 28 09:06:13.98: [ 5791]: <-- [9:AT+FRS=7\r]
Oct 28 09:06:14.00: [ 5791]: --> [2:OK]
Oct 28 09:06:14.00: [ 5791]: <-- [9:AT+FTH=3\r]
Oct 28 09:06:14.02: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:14.02: [ 5791]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 2C 74 2C>
Oct 28 09:06:14.02: [ 5791]: <-- data [32]
Oct 28 09:06:14.02: [ 5791]: <-- data [2]
Oct 28 09:06:15.80: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:15.80: [ 5791]: <-- HDLC<23:FF C0 02 A6 F6 6E 6E F2 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Oct 28 09:06:15.80: [ 5791]: <-- data [23]
Oct 28 09:06:15.80: [ 5791]: <-- data [2]
Oct 28 09:06:16.54: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:16.54: [ 5791]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18>
Oct 28 09:06:16.54: [ 5791]: <-- data [13]
Oct 28 09:06:16.54: [ 5791]: <-- data [2]
Oct 28 09:06:17.12: [ 5791]: --> [2:OK]
Oct 28 09:06:17.12: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:17.92: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:19.54: [ 5791]: --> HDLC<25:FF C0 C2 AC 6C 9C 1C 4C AC 6C EC 9C 04 04 04 04 04 04 04 04 04 04 04 A5 0D>
Oct 28 09:06:19.54: [ 5791]: --> [2:OK]
Oct 28 09:06:19.54: [ 5791]: REMOTE TSI "xxxxxxxxx"
Oct 28 09:06:19.54: [ 5791]: RECV recv TSI (sender id)
Oct 28 09:06:19.54: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:19.98: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:19.98: [ 5791]: --> HDLC<15:FF C8 C1 00 60 1F 23 01 01 00 01 01 00 8C 11>
Oct 28 09:06:19.98: [ 5791]: --> [5:ERROR]
Oct 28 09:06:19.98: [ 5791]: MODEM Command error
Oct 28 09:06:19.98: [ 5791]: FCS error
Oct 28 09:06:19.98: [ 5791]: <-- [10:AT+FRS=21\r]
Oct 28 09:06:22.06: [ 5791]: --> [2:OK]
Oct 28 09:06:22.06: [ 5791]: <-- [9:AT+FTH=3\r]
Oct 28 09:06:22.08: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:22.08: [ 5791]: <-- HDLC<3:FF C8 58>
Oct 28 09:06:22.08: [ 5791]: <-- data [3]
Oct 28 09:06:22.08: [ 5791]: <-- data [2]
Oct 28 09:06:23.14: [ 5791]: --> [2:OK]
Oct 28 09:06:23.14: [ 5791]: RECV send CRP (command repeat)
Oct 28 09:06:23.14: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:23.64: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:25.28: [ 5791]: --> HDLC<25:FF C0 C2 AC 6C 9C 1C 4C AC 6C EC 9C 04 04 04 04 04 04 04 04 04 04 04 A5 0D>
Oct 28 09:06:25.28: [ 5791]: --> [2:OK]
Oct 28 09:06:25.28: [ 5791]: REMOTE TSI "xxxxxxxxx"
Oct 28 09:06:25.28: [ 5791]: RECV recv TSI (sender id)
Oct 28 09:06:25.28: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:25.72: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:25.72: [ 5791]: --> HDLC<15:FF C8 C1 00 60 1F 23 01 01 01 01 01 00 8C 11>
Oct 28 09:06:25.76: [ 5791]: --> [2:OK]
Oct 28 09:06:25.76: [ 5791]: REMOTE wants 9600 bit/s
Oct 28 09:06:25.76: [ 5791]: REMOTE wants A4 page width (215 mm)
Oct 28 09:06:25.76: [ 5791]: REMOTE wants unlimited page length
Oct 28 09:06:25.76: [ 5791]: REMOTE wants 3.85 line/mm
Oct 28 09:06:25.76: [ 5791]: REMOTE wants 2-D MMR
Oct 28 09:06:25.76: [ 5791]: REMOTE wants T.30 Annex A, 256-byte ECM
Oct 28 09:06:25.76: [ 5791]: RECV recv DCS (command signal)
Oct 28 09:06:25.76: [ 5791]: RECV training at v.29 9600 bit/s
Oct 28 09:06:25.76: [ 5791]: MODEM set XON/XOFF/DRAIN: input ignored, output generated
Oct 28 09:06:25.76: [ 5791]: <-- [10:AT+FRM=96\r]
Oct 28 09:06:30.26: [ 5791]: --> [0:]
Oct 28 09:06:30.26: [ 5791]: MODEM <Empty line>
Oct 28 09:06:30.26: [ 5791]: MODEM TIMEOUT: receiving TCF
Oct 28 09:06:30.26: [ 5791]: <-- data [1]
Oct 28 09:06:30.28: [ 5791]: --> [2:OK]
Oct 28 09:06:30.28: [ 5791]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Oct 28 09:06:30.28: [ 5791]: <-- [9:AT+FRS=7\r]
Oct 28 09:06:30.30: [ 5791]: --> [2:OK]
Oct 28 09:06:30.30: [ 5791]: <-- [9:AT+FTH=3\r]
Oct 28 09:06:30.32: [ 5791]: --> [7:CONNECT]
Oct 28 09:06:30.32: [ 5791]: <-- HDLC<3:FF C8 22>
Oct 28 09:06:30.32: [ 5791]: <-- data [3]
Oct 28 09:06:30.32: [ 5791]: <-- data [2]
Oct 28 09:06:31.40: [ 5791]: --> [2:OK]
Oct 28 09:06:31.40: [ 5791]: TRAINING failed
Oct 28 09:06:31.40: [ 5791]: <-- [9:AT+FRH=3\r]
Oct 28 09:06:38.40: [ 5791]: --> [0:]
Oct 28 09:06:38.40: [ 5791]: MODEM <Empty line>
Oct 28 09:06:38.40: [ 5791]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 28 09:06:38.40: [ 5791]: <-- data [1]
Oct 28 09:06:38.42: [ 5791]: --> [2:]
Oct 28 09:06:38.42: [ 5791]: --> [2:OK]
Oct 28 09:06:38.42: [ 5791]: RECV FAX: Failure to train modems
Oct 28 09:06:38.42: [ 5791]: RECV FAX: end
Oct 28 09:06:38.42: [ 5791]: Failure to train modems
Oct 28 09:06:38.42: [ 5791]: SESSION END
 
 

---

La cartera, las gafas. ¿te falta algo? Ahora llévate Messenger en tu móvil