E&M outbound dialing.[solved]


#1

When trying to place an outbound call on a fractional T1, i am receiving the following log output:
May 2 18:25:01 DEBUG[5809] chan_zap.c: Took Zap/3-1 off hook
May 2 18:25:01 VERBOSE[5809] logger.c: – Executing Dial(“Zap/3-1”, “Zap/g2/6193926906|20”) in new stack
May 2 18:25:01 DEBUG[5809] chan_zap.c: Dialing '6193926906’
May 2 18:25:01 DEBUG[5809] chan_zap.c: Deferring dialing…
May 2 18:25:01 VERBOSE[5809] logger.c: – Called g2/6193926906
May 2 18:25:02 DEBUG[5809] chan_zap.c: Exception on 20, channel 1
May 2 18:25:02 DEBUG[5809] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
May 2 18:25:04 DEBUG[5809] chan_zap.c: Exception on 20, channel 1
May 2 18:25:04 DEBUG[5809] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 2 18:25:04 DEBUG[5809] chan_zap.c: Enabled echo cancellation on channel 1
May 2 18:25:04 DEBUG[5809] chan_zap.c: Engaged echo training on channel 1
May 2 18:25:06 DEBUG[5809] chan_zap.c: Exception on 20, channel 1
May 2 18:25:06 DEBUG[5809] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 2 18:25:06 DEBUG[5809] chan_zap.c: Echo cancellation already on
May 2 18:25:22 VERBOSE[5809] logger.c: – Nobody picked up in 20000 ms
May 2 18:25:22 DEBUG[5809] chan_zap.c: Hangup: channel: 1 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 2 18:25:22 DEBUG[5809] chan_zap.c: disabled echo cancellation on channel 1
May 2 18:25:22 DEBUG[5809] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
May 2 18:25:22 DEBUG[5809] chan_zap.c: Updated conferencing on 1, with 0 conference users
May 2 18:25:22 VERBOSE[5809] logger.c: – Hungup 'Zap/1-1’
May 2 18:25:22 DEBUG[5809] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
May 2 18:25:30 DEBUG[5809] chan_zap.c: Exception on 22, channel 3
May 2 18:25:30 DEBUG[5809] chan_zap.c: Got event On hook(1) on channel 3 (index 0)
May 2 18:25:30 DEBUG[5809] chan_zap.c: disabled echo cancellation on channel 3
May 2 18:25:30 VERBOSE[5809] logger.c: – Executing Hangup(“Zap/3-1”, “”) in new stack

The other line never rings, has anyone encountered this that may be able to shed some light on the matter.
inbound works great ( thanks to the quick response from earlier) so I didn’t post the configs, because they are working properly. I iwll if need be , though.


#2

the last thing i see it doing is this:
May 3 12:33:01 DEBUG[20832] chan_zap.c: Done dialing, but waiting for progress detection before doing more…

It never progresses from there. I plugged the line into our existing pbx and verified it was a two way span. ( Outbound functions) .
Could this be a timing issue? ANd if so , is there anyway to get an estimate of what I should set the rxwink to. Also since it’s a wink start e&M t1 , is there any other wink options I need to set. Maybe initialize the line then pass the DTMF digits for the call to it?


#3

post your zaptel and zapata conf files, as well as the relevant portion of your extensions.conf…


#4

sorry , posted on a different thread, here they are:

zaptel:
loadzone = us
defaultzone = us
span=1,1,0,esf,b8zs
e&m=1-12

zapata:
language=en
context=zap-t1
signalling=em_w
rxwink=400
;prewink=20
usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
echotraining=1000
rxgain=10
txgain=10
group=0
callgroup=1
callprogress=yes
pickupgroup=1
immediate=no
faxdetect=both
;faxdetect=incoming
;faxdetect=outgoing
;faxdetect=no

;Include genzaptelconf configs
#include zapata-auto.conf

group=1

group=2
channel => 1-12

as far as the extensions, I am simply calling and triggering an outbound call on the Zap channel. ( the inbound call is from a sip channel that I just pass to this context)

exten => s,1,Answer
exten => s,2,Dial(Zap/g2/16193926906,20)

then the output from cli is :

Connected to Asterisk 1.2.7.1 currently running on asterisk1 (pid = 20538)
Verbosity was 3 and is now 10
– Executing SetVar(“SIP/xx.xx.xx.xx-08be4948”, “FROM_DID=5551212”) in new stack
– Executing SetVar(“SIP/xx.xx.xx.xx-08be4948”, “FAX_RX=disabled”) in new stack
– Executing Goto(“SIP/xx.xx.xx.xx-08be4948”, “custom-Transfer|s|1”) in new stack
– Goto (custom-Transfer,s,1)
– Executing Answer(“SIP/xx.xx.xx.xx-08be4948”, “”) in new stack
– Executing Dial(“SIP/xx.xx.xx.xx-08be4948”, “Zap/g2/16193926906|20”) in new stack
– Called g2/16193926906
– Nobody picked up in 20000 ms
– Hungup ‘Zap/1-1’

the output on the logs is:
May 3 12:39:40 VERBOSE[20865] logger.c: – Executing Dial(“SIP/xx.xx.xx.xx-08be4948”, “Zap/g2/16193926906|20”) in new stack
May 3 12:39:40 DEBUG[20865] chan_zap.c: Dialing '16193926906’
May 3 12:39:40 DEBUG[20865] chan_zap.c: Deferring dialing…
May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for 'Zap/1-1’
May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for 'Zap/1-1’
May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for ‘Zap/1-1’
May 3 12:39:40 VERBOSE[20865] logger.c: – Called g2/16193926906
May 3 12:39:40 DEBUG[20597] chan_sip.c: Stopping retransmission on '237-1-1146674380@xx.xx.xx.xx’ of Response 1: Match Found
May 3 12:39:41 DEBUG[20865] chan_zap.c: Exception on 20, channel 1
May 3 12:39:41 DEBUG[20865] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
May 3 12:39:43 DEBUG[20865] chan_zap.c: Exception on 20, channel 1
May 3 12:39:43 DEBUG[20865] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 3 12:39:43 DEBUG[20865] chan_zap.c: Enabled echo cancellation on channel 1
May 3 12:39:43 WARNING[20865] chan_zap.c: Unable to request echo training on channel 1
May 3 12:39:45 DEBUG[20865] chan_zap.c: Exception on 20, channel 1
May 3 12:39:45 DEBUG[20865] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 3 12:39:45 DEBUG[20865] chan_zap.c: Echo cancellation already on
May 3 12:39:45 DEBUG[20865] chan_zap.c: Done dialing, but waiting for progress detection before doing more…
May 3 12:40:01 VERBOSE[20865] logger.c: – Nobody picked up in 20000 ms

then it hangs up the call. I have tried placing the call with a timeout parameter to make sure it just wasnt timing out before getting the info it was waiting on.


#5

hmmmm…it looks to be okay, but one thing is bothering me:

May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for 'Zap/1-1' May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for 'Zap/1-1' May 3 12:39:40 DEBUG[20560] channel.c: Avoiding initial deadlock for 'Zap/1-1'

it looks like asterisk is trying to access zap group 1…would it be possible to stop asterisk, re-run ztcfg, and bring asterisk back up? did you happen to change any of the channel layouts or groups in zapata.conf without restarting asterisk? that’s the only thing i can think of right now…


#6

you might try setting these two nad see if it makes a difference

usecallingpres=no
callprogress=no


#7

Thanks for the responses. I changed those settings and also reran ztcfg. It didnt change anything.
It makes no sense, for sure.

Thanks again


#8

apparently the problem was in not being properly informed as to what was running. After switching to a new T1 line, it was brought to light that the other line I was supplied was actually coming off an ADIT 600 channel bank. after using the full t1 line , it worked flawlessly. so now it’s just a matter of configuring it to use the line from the channel bank. thanks for the responses.