[hylafax-users] TRAINING failed with Asterisk+iaxmodem+Hylafax

[Matias Rollan <cygar@xxxxxxxxxxxxxxxxx>]

Hola!

	I am not sure if this is the right list to write but I will give a try anyway.

	I am running on debian this:

ivr-virtual-101:/var/spool/hylafax/log# dpkg -l |grep hylafax       
ii  hylafax-client                  2:4.4.4-10.1               Flexible client/server fax software - client
ii  hylafax-server                  2:4.4.4-10.1               Flexible client/server fax software - server


ivr-virtual-101:/var/spool/hylafax/log# dpkg -l |grep iaxmodem
ii  iaxmodem                        1.1.1~dfsg-2               software modem with IAX2 connectivity

	I have configured iaxmodem and it is registered in the Asterisk box fine:

ivr-virtual-101:/var/spool/hylafax/log# asterisk  -r
Asterisk 1.4.26, Copyright (C) 1999 - 2008 Digium, Inc. and others.
Created by Mark Spencer <markster@xxxxxxxxxx>
Asterisk comes with ABSOLUTELY NO WARRANTY; type 'core show warranty' for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type 'core show license' for details.
=========================================================================
Connected to Asterisk 1.4.26 currently running on ivr-virtual-101 (pid = 28216)
ivr-virtual-101*CLI> iax2 show users
Username         Secret                Authen           Def.Context      A/C    Codec Pref
126              xxx		       000000000000003  fax-iax-in       Yes    Host 
125              xxx                   000000000000003  fax-iax-in       Yes    Host 
ivr-virtual-101*CLI> 

	I have a few DIDs that comes directly to a SS7 reachs a Cisco and then are forwarded to the Asterisk box using SIP.

	When I call with an externan fax machine connected to the PSTN in the Asterisk I can see how the call is accepted without problems and it is sent to the IAX device where Hylafax answers:

ivr-virtual-101*CLI> 
    -- Executing [011XXXXXXXX@entrantes:1] Answer("SIP/1.2.3.4-098ca078", "") in new stack
    -- Executing [011XXXXXXXX@entrantes:2] Dial("SIP/1.2.3.4-098ca078", "IAX2/125/011XXXXXXXX|15|r") in new stack
    -- Called 125/011XXXXXXXX
    -- Call accepted by 9.8.7.6 (format ulaw)
    -- Format for call is ulaw
    -- IAX2/125-15550 is ringing
    -- IAX2/125-15550 answered SIP/1.2.3.4-098ca078
ivr-virtual-101*CLI> 
 
1.2.3.4=cisco fake IP address
9.8.7.6=Asterisk fake IP address


Below I add the /var/spool/hylafax/log/CXXXXXXXXXXX, even if the error can be seen at the end I send it complete in case it helps:

Jul 14 18:21:56.18: [30325]: SESSION BEGIN 000000015 5411XXXXXXXX
Jul 14 18:21:56.18: [30325]: HylaFAX (tm) Version 4.4.4
Jul 14 18:21:56.18: [30325]: CallID: "11XXXXXXXX" "" "<NONE>" "011XXXXXXXX"
Jul 14 18:21:56.18: [30325]: <-- [4:ATA\r]
Jul 14 18:21:59.90: [30325]: --> [7:CONNECT]
Jul 14 18:21:59.90: [30325]: ANSWER: FAX CONNECTION  DEVICE '/dev/tty125'
Jul 14 18:21:59.90: [30325]: RECV FAX: begin
Jul 14 18:21:59.90: [30325]: <-- data [32]
Jul 14 18:21:59.90: [30325]: <-- data [2]
Jul 14 18:22:00.86: [30325]: --> [7:CONNECT]
Jul 14 18:22:00.86: [30325]: <-- data [23]
Jul 14 18:22:00.86: [30325]: <-- data [2]
Jul 14 18:22:01.60: [30325]: --> [7:CONNECT]
Jul 14 18:22:01.60: [30325]: <-- data [13]
Jul 14 18:22:01.60: [30325]: <-- data [2]
Jul 14 18:22:02.18: [30325]: --> [2:OK]
Jul 14 18:22:02.18: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:09.18: [30325]: --> [0:]
Jul 14 18:22:09.18: [30325]: MODEM <Empty line>
Jul 14 18:22:09.18: [30325]: <-- data [1]
Jul 14 18:22:09.20: [30325]: --> [2:OK]
Jul 14 18:22:09.20: [30325]: <-- [9:AT+FRS=7\r]
Jul 14 18:22:09.22: [30325]: --> [2:OK]
Jul 14 18:22:09.22: [30325]: <-- [9:AT+FTH=3\r]
Jul 14 18:22:09.24: [30325]: --> [7:CONNECT]
Jul 14 18:22:09.24: [30325]: <-- data [32]
Jul 14 18:22:09.24: [30325]: <-- data [2]
Jul 14 18:22:11.00: [30325]: --> [7:CONNECT]
Jul 14 18:22:11.00: [30325]: <-- data [23]
Jul 14 18:22:11.00: [30325]: <-- data [2]
Jul 14 18:22:11.74: [30325]: --> [7:CONNECT]
Jul 14 18:22:11.74: [30325]: <-- data [13]
Jul 14 18:22:11.74: [30325]: <-- data [2]
Jul 14 18:22:12.32: [30325]: --> [2:OK]
Jul 14 18:22:12.32: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:13.24: [30325]: --> [7:CONNECT]
Jul 14 18:22:14.74: [30325]: --> [5:ERROR]
Jul 14 18:22:14.74: [30325]: MODEM Command error
Jul 14 18:22:14.74: [30325]: FCS error
Jul 14 18:22:14.74: [30325]: <-- [10:AT+FRS=21\r]
Jul 14 18:22:17.34: [30325]: --> [2:OK]
Jul 14 18:22:17.34: [30325]: <-- [9:AT+FTH=3\r]
Jul 14 18:22:17.36: [30325]: --> [7:CONNECT]
Jul 14 18:22:17.36: [30325]: <-- data [3]
Jul 14 18:22:17.36: [30325]: <-- data [2]
Jul 14 18:22:18.44: [30325]: --> [2:OK]
Jul 14 18:22:18.44: [30325]: RECV send CRP (command repeat)
Jul 14 18:22:18.44: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:18.78: [30325]: --> [7:CONNECT]
Jul 14 18:22:20.36: [30325]: --> [2:OK]
Jul 14 18:22:20.36: [30325]: REMOTE TSI "+ 54  11  4371 4567"
Jul 14 18:22:20.36: [30325]: RECV recv TSI (sender id)
Jul 14 18:22:20.36: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:20.80: [30325]: --> [7:CONNECT]
Jul 14 18:22:11.74: [30325]: <-- data [13]
Jul 14 18:22:11.74: [30325]: <-- data [2]
Jul 14 18:22:12.32: [30325]: --> [2:OK]
Jul 14 18:22:12.32: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:13.24: [30325]: --> [7:CONNECT]
Jul 14 18:22:14.74: [30325]: --> [5:ERROR]
Jul 14 18:22:14.74: [30325]: MODEM Command error
Jul 14 18:22:14.74: [30325]: FCS error
Jul 14 18:22:14.74: [30325]: <-- [10:AT+FRS=21\r]
Jul 14 18:22:17.34: [30325]: --> [2:OK]
Jul 14 18:22:17.34: [30325]: <-- [9:AT+FTH=3\r]
Jul 14 18:22:17.36: [30325]: --> [7:CONNECT]
Jul 14 18:22:17.36: [30325]: <-- data [3]
Jul 14 18:22:17.36: [30325]: <-- data [2]
Jul 14 18:22:18.44: [30325]: --> [2:OK]
Jul 14 18:22:18.44: [30325]: RECV send CRP (command repeat)
Jul 14 18:22:18.44: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:18.78: [30325]: --> [7:CONNECT]
Jul 14 18:22:20.36: [30325]: --> [2:OK]
Jul 14 18:22:20.36: [30325]: REMOTE TSI "+ 54  11  4371 4567"
Jul 14 18:22:20.36: [30325]: RECV recv TSI (sender id)
Jul 14 18:22:20.36: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:20.80: [30325]: --> [7:CONNECT]
Jul 14 18:22:20.92: [30325]: --> [2:OK]
Jul 14 18:22:20.92: [30325]: REMOTE wants 9600 bit/s
Jul 14 18:22:20.92: [30325]: REMOTE wants A4 page width (215 mm)
Jul 14 18:22:20.92: [30325]: REMOTE wants unlimited page length
Jul 14 18:22:20.92: [30325]: REMOTE wants 7.7 line/mm
Jul 14 18:22:20.92: [30325]: REMOTE wants 2-D MMR
Jul 14 18:22:20.92: [30325]: REMOTE wants T.30 Annex A, 256-byte ECM
Jul 14 18:22:20.92: [30325]: RECV recv DCS (command signal)
Jul 14 18:22:20.92: [30325]: RECV training at v.29 9600 bit/s
Jul 14 18:22:20.92: [30325]: <-- [10:AT+FRM=96\r]
Jul 14 18:22:25.42: [30325]: --> [0:]
Jul 14 18:22:25.42: [30325]: MODEM <Empty line>
Jul 14 18:22:25.42: [30325]: <-- data [1]
Jul 14 18:22:25.44: [30325]: --> [2:OK]
Jul 14 18:22:25.44: [30325]: <-- [9:AT+FRS=7\r]
Jul 14 18:22:25.46: [30325]: --> [2:OK]
Jul 14 18:22:25.46: [30325]: <-- [9:AT+FTH=3\r]
Jul 14 18:22:25.48: [30325]: --> [7:CONNECT]
Jul 14 18:22:25.48: [30325]: <-- data [3]
Jul 14 18:22:25.48: [30325]: <-- data [2]
Jul 14 18:22:26.56: [30325]: --> [2:OK]
Jul 14 18:22:26.56: [30325]: TRAINING failed
Jul 14 18:22:26.56: [30325]: <-- [9:AT+FRH=3\r]
Jul 14 18:22:33.18: [30325]: --> [7:CONNECT]
Jul 14 18:22:34.24: [30325]: --> [2:OK]
Jul 14 18:22:34.24: [30325]: RECV recv DCN (disconnect)
Jul 14 18:22:34.24: [30325]: RECV FAX: RSPREC error/got DCN (sender abort)
Jul 14 18:22:34.24: [30325]: RECV FAX: end
Jul 14 18:22:34.24: [30325]: RSPREC error/got DCN (sender abort)
Jul 14 18:22:34.24: [30325]: SESSION END

And then the FAX machine dies with "Nothingsetup" error.

Below I also copy my /var/spool/hylafax/etc/config.tty125 :


ivr-virtual-101:/var/spool/hylafax/etc# cat config.tty125 
# $Id: iaxmodem,v 1.1 2006/04/02 23:18:30 darren Exp $

#
# prototype config for the IAXmodem softmodem which uses
# the spandsp soft-DSP library
#

#
CountryCode:            54
AreaCode:               11
FAXNumber:              XXXXXXXX
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:        "NothingSetup"
TagLineFont:            etc/lutRS18.pcf
TagLineFormat:          "From %%l|%c|Page %%P of %%T"
MaxRecvPages:           25
#

#
#
# Modem-related stuff: should reflect modem command interface
# and hardware connection/cabling (e.g. flow control).
#
ModemType:              Class1          # use this to supply a hint

#
# The modem is taken off-hook during initialization, and then
# placed back on-hook when done to prevent glare.
#
ModemResetCmds:         "ATH1\nAT+VCID=1"       # enables CallID display
ModemReadyCmds:         ATH0

Class1AdaptRecvCmd:     AT+FAR=1
Class1TMConnectDelay:   400             # counteract quick CONNECT response
Class1RMQueryCmd:       "!24,48,72,96"  # V.17 fast-train recv doesn't work well

CallIDPattern:          "NMBR="
CallIDPattern:          "NAME="
CallIDPattern:          "ANID="
CallIDPattern:          "NDID="
# Uncomment these if you really want them, but you probably don't.
#CallIDPattern:          "DATE="
#CallIDPattern:          "TIME="


Any help that you can give me will be highly appreciated.

Thanks in advance!


-- 
Matias Rollan
<cygar@xxxxxxxxxxxxxxxxx>


____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*