Problems with SS7

Hi all, i kindly asking you to assist me in 1 problem.

I am using Asterisk 1.2.15, Zaptel 1.2.15, chan_ss7 0.9. For MSC connectivity i am using Sangoma A101c E1 card.
My ss7.conf configuration:

[linkset-siuc]
enabled => yes
enable_st => yes
use_connect => yes
hunting_policy => even_mru
context => ss7
language => en
t35 => 15000,timeout
subservice => auto
[link-l1]
linkset => siuc
channels => 1-15,17-31
schannel => 16
firstcic => 1
enabled => yes
echocancel => no
echocan_train => 350
echocan_taps => 128
[host-asterisk-hq]
enabled => yes
opc => 0x14a4
dpc => siuc:0xf31
links => l1:1

My zaptel.conf:
loadzone=us
defaultzone=us
#Sangoma A101 port 1 [slot:2 bus:6 span: 1]
span=1,1,0,ccs,hdb3
bchan=1-31

My zapata.conf (please tell me if i need it):
[trunkgroups]
trunkgroup =>1-15,17-31
[channels]
context=ss7
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.0
txgain=0.0
group=1
callgroup=1
pickupgroup=1

immediate=no

;Sangoma A101 port 1 [slot:2 bus:6 span: 1]
;switchtype=euroISDN
;context=from-zaptel
;signalling=pri_cpe
;channel => 1-31
;channel => 17-31

I configured trunks in FreePBX, otbound routes in addition. I can see that i am dialing via correct trunk but phone remaining in calling state and i see that no CICs are occupied from switch side. From asterisk (ss7 show channels) i see that all CICs in IDLE state.

Please review my call flow log and if possible reply with suggestions:

Mar 16 20:43:48 DEBUG[4136] chan_sip.c: Setting NAT on RTP to 524288
Mar 16 20:43:48 DEBUG[4136] chan_sip.c: Stopping retransmission on ‘ZmY4MzdlZjc1MjY4MTU0YTg3ZDJkZTUyNjYyMjAzMzQ.’ of Response 1: Match Found
Mar 16 20:43:48 DEBUG[4136] chan_sip.c: Setting NAT on RTP to 524288
Mar 16 20:43:48 DEBUG[4136] chan_sip.c: Checking SIP call limits for device 6200
Mar 16 20:43:48 DEBUG[4136] chan_sip.c: build_route: Contact hop: sip:6200@190.0.11.66:62161
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Macro(“SIP/6200-08df4a50”, “dialout-trunk|3|0552001200||”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “DIAL_TRUNK=3”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “_NODEST=”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “DIAL_NUMBER=0552001200”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “ROUTE_PASSWD=”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?noauth”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-dialout-trunk,s,8)
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “GROUP()=OUT_3”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Macro(“SIP/6200-08df4a50”, “user-callerid|SKIPTTL”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is 'device’
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '6200’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “user-callerid: device 6200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?report”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Not taking any branch
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?start”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Not taking any branch
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '6200’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “REALCALLERIDNUM=6200”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “REALCALLERIDNUM is 6200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '6200’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “AMPUSER=6200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is 'Aleksey Reshetnikov’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “AMPUSERCIDNAME=Aleksey Reshetnikov”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?report”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Not taking any branch
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “CALLERID(all)=Aleksey Reshetnikov <6200>”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '6200’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “REALCALLERIDNUM=6200”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “TTL: ARG1: SKIPTTL”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?continue”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-user-callerid,s,21)
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '“Aleksey Reshetnikov” <6200>'
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “Using CallerID “Aleksey Reshetnikov” <6200>”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '6200’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Macro(“SIP/6200-08df4a50”, “record-enable|6200|OUT”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '0’
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?2:4”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-record-enable,s,4)
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing DeadAGI(“SIP/6200-08df4a50”, “recordingcheck|20070316-204348|1174063428.0”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Mar 16 20:43:48 NOTICE[4133] mtp.c: Excessive poll delay 5029!
Mar 16 20:43:48 NOTICE[4133] mtp.c: Full Zaptel input buffer detected, incoming packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Empty Zaptel output buffer detected, outgoing packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Short MTP2 frame len 1 < 5 on link ‘l1’.
Mar 16 20:43:48 DEBUG[4133] mtp.c: Last raw bits on link ‘l1’: f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 f3 8b f3 f3 f3
Mar 16 20:43:48 VERBOSE[4844] logger.c: recordingcheck|20070316-204348|1174063428.0: Outbound recording not enabled
Mar 16 20:43:48 VERBOSE[4844] logger.c: – AGI Script recordingcheck completed, returning 0
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “No recording needed”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?skipoutcid”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Not taking any branch
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “DIAL_TRUNK_OPTIONS=r”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Macro(“SIP/6200-08df4a50”, “outbound-callerid|3”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?start”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-outbound-callerid,s,3)
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “REALCALLERIDNUM is 6200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?normcid”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-outbound-callerid,s,9)
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is ''
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “USEROUTCID=”) in new stack
Mar 16 20:43:48 DEBUG[4844] db.c: Unable to find key ‘6200/emergency_cid’ in family 'DEVICE’
Mar 16 20:43:48 DEBUG[4844] func_db.c: DB: DEVICE/6200/emergency_cid not found in database.
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is ''
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “EMERGENCYCID=”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “TRUNKOUTCID=2001200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?trunkcid”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-outbound-callerid,s,16)
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '0’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “0?usercid”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Not taking any branch
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “CALLERID(all)=2001200”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?report”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-outbound-callerid,s,22)
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is '"" <2001200>'
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing NoOp(“SIP/6200-08df4a50”, “CallerID set to “” <2001200>”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?nomax”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-dialout-trunk,s,16)
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing DeadAGI(“SIP/6200-08df4a50”, “fixlocalprefix”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Mar 16 20:43:48 NOTICE[4133] mtp.c: Excessive poll delay 7474!
Mar 16 20:43:48 NOTICE[4133] mtp.c: Full Zaptel input buffer detected, incoming packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Empty Zaptel output buffer detected, outgoing packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Excessive poll delay 5039!
Mar 16 20:43:48 NOTICE[4133] mtp.c: Full Zaptel input buffer detected, incoming packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Empty Zaptel output buffer detected, outgoing packets may have been lost on link ‘l1’.
Mar 16 20:43:48 VERBOSE[4844] logger.c: – AGI Script fixlocalprefix completed, returning 0
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “OUTNUM=0552001200”) in new stack
Mar 16 20:43:48 NOTICE[4133] mtp.c: Excessive poll delay 3172!
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is 'AMP’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “custom=AMP”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?customtrunk”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-dialout-trunk,s,22)
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is 'AMP:SS7/'
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “pre_num=AMP:SS7/”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is 'OUTNUM’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “the_num=OUTNUM”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Function result is ''
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “post_num=”) in new stack
Mar 16 20:43:48 DEBUG[4844] pbx.c: Expression result is '1’
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing GotoIf(“SIP/6200-08df4a50”, “1?outnum:skipoutnum”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Goto (macro-dialout-trunk,s,26)
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Set(“SIP/6200-08df4a50”, “the_num=0552001200”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – Executing Dial(“SIP/6200-08df4a50”, “SS7/0552001200|300|r”) in new stack
Mar 16 20:43:48 VERBOSE[4844] logger.c: – SS7 request (SS7/0552001200) format = 0x8.
Mar 16 20:43:48 VERBOSE[4844] logger.c: – SS7 channel SS7/0552001200 allocated successfully.
Mar 16 20:43:48 DEBUG[4844] l4isup.c: SS7 call, addr=0552001200, cid=2001200(0x0/Presentation Allowed, Not Screened) CIC=30. linkset 'siuc’
Mar 16 20:43:48 NOTICE[4133] mtp.c: Excessive poll delay 4988!
Mar 16 20:43:48 NOTICE[4133] mtp.c: Full Zaptel input buffer detected, incoming packets may have been lost on link ‘l1’.
Mar 16 20:43:48 NOTICE[4133] mtp.c: Empty Zaptel output buffer detected, outgoing packets may have been lost on link ‘l1’.

thanks, Alex