[hylafax-users] Problem with sending faxes

[AnDY <andrej@xxxxxxxxxx>]

Hello everybody,

I have problem with sending faxes from hylafax to hylafax as shown bellow:
(Side A) : hylafax --> asterisk --> ISDN30 --> TELCO --> EUROISDN --> 
asterisk --> hylafax : (Side B)

On side A I have:
Digium card b410 with settings:
cat /etc/zaptel.conf
# Autogenerated by /usr/local/sbin/sangoma/setup-sangoma -- do not hand edit
# Zaptel Channels Configurations (zaptel.conf)
#
loadzone=us
defaultzone=us

#Sangoma A101 port 1 [slot:2 bus:6 span: 1]
span=1,0,0,ccs,hdb3,crc4
bchan=1-15,17-31
dchan=16

cat /etc/asterisk/zapata.conf

[channels]
context=default
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
canpark=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=yes
rxgain=0
txgain=0
group=1
callgroup=1
pickupgroup=1
language=cz

immediate=no

;Sangoma A101 port 1 [slot:2 bus:6 span: 1]
switchtype=euroisdn
context=default
group=1
signalling=pri_cpe
channel => 1-15,17-31

/etc/iaxmodem/ttyIAX6
device          /dev/ttyIAX6
owner           uucp:uucp
mode            660
port            4576
refresh         50
server          127.0.0.1
peername        iaxmodem6
secret          password
codec           alaw

iax.conf
[iaxmodem6]
type=friend
secret=password
port=4576
host=dynamic
context=fax-out
allow=all


And on side B i have:
Cologne Chip Designs GmbH ISDN network controller [HFC-PCI]

cat /etc/zaptel.conf
# Span 1: ZTHFC1 "HFC-S PCI A ISDN card 0 [TE] layer 1 DEACTIVATED (F3)" 
(MASTER) AMI/CCS
span=1,1,0,ccs,ami
# termtype: te
bchan=1-2
dchan=3

cat /etc/asterisk/zapata.conf

[channels]

language=en
context=from-zaptel
signalling=fxs_ks
rxwink=300              ; Atlas seems to use long (250ms) winks

usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
rxgain=6
txgain=6
group=0
callgroup=1
pickupgroup=1
immediate=no
pridialplan=unknown

faxdetect=both

cat /etc/iaxmodem/ttyIAX0
device          /dev/ttyIAX0
owner           uucp:uucp
mode            660
port            4570
refresh         350
server          127.0.0.1
peername        iaxmodem0
secret          password
codec           alaw

cat /etc/asterisk/iax.conf

[general]
bindport = 4569
bindaddr = 0.0.0.0
disallow=all
allow=alaw

[iaxmodem0]
type=friend
secret=password
port=4570
host=dynamic
context=fax-out
disallow=all
allow=alaw


The last log that I was trying to send something is:
Oct 30 12:17:29.55: [ 4142]: SESSION BEGIN 000000048 +421244634928
Oct 30 12:17:29.55: [ 4142]: HylaFAX (tm) Version 5.2.5
Oct 30 12:17:29.55: [ 4142]: CallID: '00420233090811' '00420233090811' 
'<NONE>' 's'
Oct 30 12:17:29.55: [ 4142]: <-- [4:ATA\r]
Oct 30 12:17:33.27: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:33.27: [ 4142]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyIAX0'
Oct 30 12:17:33.27: [ 4142]: RECV FAX: begin
Oct 30 12:17:33.27: [ 4142]: <-- data [32]
Oct 30 12:17:33.27: [ 4142]: <-- data [2]
Oct 30 12:17:34.19: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:34.19: [ 4142]: <-- data [23]
Oct 30 12:17:34.19: [ 4142]: <-- data [2]
Oct 30 12:17:34.95: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:34.95: [ 4142]: <-- data [13]
Oct 30 12:17:34.95: [ 4142]: <-- data [2]
Oct 30 12:17:35.53: [ 4142]: --> [2:OK]
Oct 30 12:17:35.53: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:35.88: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:37.32: [ 4142]: --> [2:OK]
Oct 30 12:17:37.32: [ 4142]: RECV recv TSI (sender id)
Oct 30 12:17:37.32: [ 4142]: REMOTE TSI "CHANCE a.s."
Oct 30 12:17:37.32: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:37.64: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:37.68: [ 4142]: --> [2:OK]
Oct 30 12:17:37.68: [ 4142]: RECV recv DCS (command signal)
Oct 30 12:17:37.68: [ 4142]: REMOTE wants 9600 bit/s
Oct 30 12:17:37.68: [ 4142]: REMOTE wants A4 page width (215 mm)
Oct 30 12:17:37.68: [ 4142]: REMOTE wants unlimited page length
Oct 30 12:17:37.68: [ 4142]: REMOTE wants 7.7 line/mm
Oct 30 12:17:37.68: [ 4142]: REMOTE wants 2-D MMR
Oct 30 12:17:37.68: [ 4142]: REMOTE wants T.30 Annex A, 256-byte ECM
Oct 30 12:17:37.68: [ 4142]: RECV training at v.29 9600 bit/s
Oct 30 12:17:37.68: [ 4142]: <-- [10:AT+FRM=96\r]
Oct 30 12:17:38.04: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:39.74: [ 4142]: RECV: TCF 2034 bytes, 85% non-zero, 1 zero-run
Oct 30 12:17:39.74: [ 4142]: RECV: reject TCF (too many non-zero, max 10%)
Oct 30 12:17:39.74: [ 4142]: RECV: reject TCF (zero run too short, min 600)
Oct 30 12:17:39.74: [ 4142]: --> [10:NO CARRIER]
Oct 30 12:17:39.74: [ 4142]: <-- [9:AT+FRS=7\r]
Oct 30 12:17:39.80: [ 4142]: --> [2:OK]
Oct 30 12:17:39.80: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:17:39.81: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:39.81: [ 4142]: <-- data [3]
Oct 30 12:17:39.81: [ 4142]: <-- data [2]
Oct 30 12:17:40.89: [ 4142]: --> [2:OK]
Oct 30 12:17:40.89: [ 4142]: TRAINING failed
Oct 30 12:17:40.89: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:41.24: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:42.20: [ 4142]: --> [2:OK]
Oct 30 12:17:42.20: [ 4142]: RECV recv CRP (command repeat)
Oct 30 12:17:42.20: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:17:42.21: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:42.21: [ 4142]: <-- data [32]
Oct 30 12:17:42.21: [ 4142]: <-- data [2]
Oct 30 12:17:43.99: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:43.99: [ 4142]: <-- data [23]
Oct 30 12:17:43.99: [ 4142]: <-- data [2]
Oct 30 12:17:44.73: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:44.73: [ 4142]: <-- data [13]
Oct 30 12:17:44.73: [ 4142]: <-- data [2]
Oct 30 12:17:45.31: [ 4142]: --> [2:OK]
Oct 30 12:17:45.31: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:45.68: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:46.62: [ 4142]: --> [2:OK]
Oct 30 12:17:46.62: [ 4142]: RECV recv CRP (command repeat)
Oct 30 12:17:46.62: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:17:46.63: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:46.63: [ 4142]: <-- data [32]
Oct 30 12:17:46.63: [ 4142]: <-- data [2]
Oct 30 12:17:48.41: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:48.41: [ 4142]: <-- data [23]
Oct 30 12:17:48.41: [ 4142]: <-- data [2]
Oct 30 12:17:49.15: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:49.15: [ 4142]: <-- data [13]
Oct 30 12:17:49.15: [ 4142]: <-- data [2]
Oct 30 12:17:49.73: [ 4142]: --> [2:OK]
Oct 30 12:17:49.73: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:50.10: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:51.04: [ 4142]: --> [2:OK]
Oct 30 12:17:51.04: [ 4142]: RECV recv CRP (command repeat)
Oct 30 12:17:51.04: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:17:51.05: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:51.05: [ 4142]: <-- data [32]
Oct 30 12:17:51.05: [ 4142]: <-- data [2]
Oct 30 12:17:52.83: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:52.83: [ 4142]: <-- data [23]
Oct 30 12:17:52.83: [ 4142]: <-- data [2]
Oct 30 12:17:53.57: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:53.57: [ 4142]: <-- data [13]
Oct 30 12:17:53.57: [ 4142]: <-- data [2]
Oct 30 12:17:54.15: [ 4142]: --> [2:OK]
Oct 30 12:17:54.15: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:17:54.52: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:55.96: [ 4142]: --> [5:ERROR]
Oct 30 12:17:55.96: [ 4142]: MODEM Command error
Oct 30 12:17:55.96: [ 4142]: FCS error
Oct 30 12:17:55.96: [ 4142]: <-- [10:AT+FRS=21\r]
Oct 30 12:17:58.58: [ 4142]: --> [2:OK]
Oct 30 12:17:58.58: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:17:58.59: [ 4142]: --> [7:CONNECT]
Oct 30 12:17:58.59: [ 4142]: <-- data [3]
Oct 30 12:17:58.59: [ 4142]: <-- data [2]
Oct 30 12:17:59.67: [ 4142]: --> [2:OK]
Oct 30 12:17:59.67: [ 4142]: RECV send CRP (command repeat)
Oct 30 12:17:59.67: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:00.06: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:01.00: [ 4142]: --> [2:OK]
Oct 30 12:18:01.00: [ 4142]: RECV recv CRP (command repeat)
Oct 30 12:18:01.00: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:01.01: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:01.01: [ 4142]: <-- data [32]
Oct 30 12:18:01.01: [ 4142]: <-- data [2]
Oct 30 12:18:02.79: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:02.79: [ 4142]: <-- data [23]
Oct 30 12:18:02.79: [ 4142]: <-- data [2]
Oct 30 12:18:03.53: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:03.53: [ 4142]: <-- data [13]
Oct 30 12:18:03.53: [ 4142]: <-- data [2]
Oct 30 12:18:04.12: [ 4142]: --> [2:OK]
Oct 30 12:18:04.12: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:04.50: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:05.92: [ 4142]: --> [5:ERROR]
Oct 30 12:18:05.92: [ 4142]: MODEM Command error
Oct 30 12:18:05.92: [ 4142]: FCS error
Oct 30 12:18:05.92: [ 4142]: <-- [10:AT+FRS=21\r]
Oct 30 12:18:08.54: [ 4142]: --> [2:OK]
Oct 30 12:18:08.54: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:08.55: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:08.55: [ 4142]: <-- data [3]
Oct 30 12:18:08.55: [ 4142]: <-- data [2]
Oct 30 12:18:09.63: [ 4142]: --> [2:OK]
Oct 30 12:18:09.63: [ 4142]: RECV send CRP (command repeat)
Oct 30 12:18:09.63: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:10.04: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:10.98: [ 4142]: --> [2:OK]
Oct 30 12:18:10.98: [ 4142]: RECV recv CRP (command repeat)
Oct 30 12:18:10.98: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:10.99: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:10.99: [ 4142]: <-- data [32]
Oct 30 12:18:10.99: [ 4142]: <-- data [2]
Oct 30 12:18:12.77: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:12.77: [ 4142]: <-- data [23]
Oct 30 12:18:12.77: [ 4142]: <-- data [2]
Oct 30 12:18:13.51: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:13.51: [ 4142]: <-- data [13]
Oct 30 12:18:13.51: [ 4142]: <-- data [2]
Oct 30 12:18:14.09: [ 4142]: --> [2:OK]
Oct 30 12:18:14.09: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:14.52: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:15.20: [ 4142]: --> [5:ERROR]
Oct 30 12:18:15.20: [ 4142]: MODEM Command error
Oct 30 12:18:15.20: [ 4142]: FCS error
Oct 30 12:18:15.20: [ 4142]: <-- [10:AT+FRS=21\r]
Oct 30 12:18:15.66: [ 4142]: --> [2:OK]
Oct 30 12:18:15.66: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:15.67: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:15.67: [ 4142]: <-- data [3]
Oct 30 12:18:15.67: [ 4142]: <-- data [2]
Oct 30 12:18:16.75: [ 4142]: --> [2:OK]
Oct 30 12:18:16.75: [ 4142]: RECV send CRP (command repeat)
Oct 30 12:18:16.75: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:17.12: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:18.54: [ 4142]: --> [5:ERROR]
Oct 30 12:18:18.54: [ 4142]: MODEM Command error
Oct 30 12:18:18.54: [ 4142]: FCS error
Oct 30 12:18:18.54: [ 4142]: <-- [10:AT+FRS=21\r]
Oct 30 12:18:21.50: [ 4142]: --> [2:OK]
Oct 30 12:18:21.50: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:21.51: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:21.51: [ 4142]: <-- data [3]
Oct 30 12:18:21.51: [ 4142]: <-- data [2]
Oct 30 12:18:22.59: [ 4142]: --> [2:OK]
Oct 30 12:18:22.59: [ 4142]: RECV send CRP (command repeat)
Oct 30 12:18:22.59: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:22.98: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:23.38: [ 4142]: --> [5:ERROR]
Oct 30 12:18:23.38: [ 4142]: MODEM Command error
Oct 30 12:18:23.38: [ 4142]: FCS error
Oct 30 12:18:23.38: [ 4142]: <-- [10:AT+FRS=21\r]
Oct 30 12:18:27.60: [ 4142]: --> [2:OK]
Oct 30 12:18:27.60: [ 4142]: <-- [9:AT+FTH=3\r]
Oct 30 12:18:27.61: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:27.61: [ 4142]: <-- data [3]
Oct 30 12:18:27.61: [ 4142]: <-- data [2]
Oct 30 12:18:28.69: [ 4142]: --> [2:OK]
Oct 30 12:18:28.69: [ 4142]: RECV send CRP (command repeat)
Oct 30 12:18:28.69: [ 4142]: <-- [9:AT+FRH=3\r]
Oct 30 12:18:29.02: [ 4142]: --> [7:CONNECT]
Oct 30 12:18:29.96: [ 4142]: --> [2:OK]
Oct 30 12:18:29.96: [ 4142]: RECV recv DCN (disconnect)
Oct 30 12:18:29.96: [ 4142]: RECV FAX: RSPREC error/got DCN (sender 
abort) {E103}
Oct 30 12:18:29.96: [ 4142]: RECV FAX: end
Oct 30 12:18:29.96: [ 4142]: RSPREC error/got DCN (sender abort) {E103}
Oct 30 12:18:29.96: [ 4142]: SESSION END


Does anybody know where is the problem?
Thank you very much!

Andrej


____________________ 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*