Problem transfering with SIP trunks

[ekaftan]

I have a strange problem... One server that has Zap and SIP trunks has problemas transfering calls made via the SIP trunks to agents.

Call is made, answered and sent to 8365. agi-VDADtransfer.agi complains that the channel is local and does nothing.

This is 8565:

exten => 8365,1,AGI(agi://127.0.0.1:4577/call_log)
exten => 8365,2,AGI(agi-VDADtransfer.agi,${EXTEN})
exten => 8365,3,AGI(agi-VDADtransfer.agi,${EXTEN})
exten => 8365,4,AGI(agi-VDADtransfer.agi,${EXTEN})
exten => 8365,5,Hangup

and this is output from a failed transfer:

2008-08-07 13:49:01|agi-VDADtransfer.agi|XXXXXXXXXX VDAD transferred: start|stop
2008-08-07 13:49:01|2008-08-07 13:49:01
2008-08-07 13:49:11|agi-VDADtransfer.agi|Perl Environment Dump:
2008-08-07 13:49:11|agi-VDADtransfer.agi|0|8365
2008-08-07 13:49:11|agi-VDADtransfer.agi|callerID changed: V0807134848000469584
2008-08-07 13:49:11|agi-VDADtransfer.agi|AGI Environment Dump:
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- accountcode =
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- callerid = 0000000000
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- calleridname = V0807134848000469584
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- callingani2 = 0
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- callingpres = 0
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- callingtns = 0
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- callington = 0
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- channel = Local/956999135134@defaul
t-be1d,1
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- context = default
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- dnid = unknown
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- enhanced = 0.0
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- extension = 8365
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- language = en
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- priority = 3
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- rdnis = unknown
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- request = agi-VDADtransfer.agi
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- type = Local
2008-08-07 13:49:11|agi-VDADtransfer.agi| -- uniqueid = 1218131328.27923
2008-08-07 13:49:11|agi-VDADtransfer.agi|AGI Variables: |1218131328.27923|Local/
956999135134@default-be1d,1|8365|Local|V0807134848000469584|V0807134848000469584
|3|
2008-08-07 13:49:11|agi-VDADtransfer.agi|+++++ VDAD START : |469584|2008-08-07 1
3:49:11|1.2.12.1|3|
2008-08-07 13:49:11|agi-VDADtransfer.agi|+++++ VDAD START LOCAL CHANNEL: EXITING
- 3

And here is asterisk's side of it:
Aug 7 14:05:39 VERBOSE[25346] logger.c: -- SIP/REDVOISS-09f270c8 is making
progress passing it to Local/956999135134@default-219e,2
Aug 7 14:05:48 VERBOSE[25346] logger.c: -- SIP/REDVOISS-09f270c8 is making
progress passing it to Local/956999135134@default-219e,2
Aug 7 14:06:06 VERBOSE[25346] logger.c: -- SIP/REDVOISS-09f270c8 answered L
ocal/956999135134@default-219e,2
Aug 7 14:06:06 VERBOSE[25344] logger.c: > Channel Local/956999135134@def
ault-219e,1 was answered.
Aug 7 14:06:06 DEBUG[25344] manager.c: Manager received command 'Logoff'
Aug 7 14:06:06 VERBOSE[25344] logger.c: == Manager 'sendcron' logged off from
127.0.0.1
Aug 7 14:06:06 VERBOSE[25401] logger.c: -- Executing Playback("Local/956999
135134@default-219e,1", "sip-silence") in new stack
Aug 7 14:06:06 DEBUG[25401] channel.c: Scheduling timer at 160 sample intervals
Aug 7 14:06:06 VERBOSE[25401] logger.c: -- Playing 'sip-silence' (language
'en')
Aug 7 14:06:06 DEBUG[25346] channel.c: Planning to masquerade channel SIP/REDVO
ISS-09f270c8 into the structure of Local/956999135134@default-219e,1
Aug 7 14:06:06 DEBUG[25346] channel.c: Done planning to masquerade channel SIP/
REDVOISS-09f270c8 into the structure of Local/956999135134@default-219e,1
Aug 7 14:06:06 DEBUG[25346] chan_local.c: Not posting to queue since already ma
sked on 'Local/956999135134@default-219e,2'
Aug 7 14:06:06 DEBUG[25401] channel.c: Got clone lock for masquerade on 'SIP/RE
DVOISS-09f270c8' at 0x9d4eda4
Aug 7 14:06:06 DEBUG[25346] channel.c: Didn't get a frame from channel: Local/9
56999135134@default-219e,2
Aug 7 14:06:06 DEBUG[25346] channel.c: Bridge stops bridging channels Local/956
999135134@default-219e,2 and SIP/REDVOISS-09f270c8<MASQ>
Aug 7 14:06:06 DEBUG[25401] channel.c: Putting channel SIP/REDVOISS-09f270c8 in
2/64 formats
Aug 7 14:06:06 DEBUG[25401] channel.c: Released clone lock on 'Local/9569991351
34@default-219e,1<ZOMBIE>'
Aug 7 14:06:06 DEBUG[25346] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Aug 7 14:06:06 VERBOSE[25346] logger.c: == Spawn extension (default, 95699913
5134, 5) exited non-zero on 'Local/956999135134@default-219e,2'
Aug 7 14:06:06 VERBOSE[25346] logger.c: -- Executing DeadAGI("Local/9569991
35134@default-219e,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG
-----16-----ANSWER-----31-----0)") in new stack
Aug 7 14:06:06 DEBUG[25401] channel.c: Done Masquerading SIP/REDVOISS-09f270c8
(6)
Aug 7 14:06:06 VERBOSE[25346] logger.c: -- AGI Script agi://127.0.0.1:4577/
call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----31-----0) completed, r
eturning 0
Aug 7 14:06:06 DEBUG[25346] channel.c: Spy MixMonitor removed from channel Loca
l/956999135134@default-219e,2
Aug 7 14:06:06 VERBOSE[25347] logger.c: == End MixMonitor Recording Local/956
999135134@default-219e,2
Aug 7 14:06:06 DEBUG[25346] cdr_addon_mysql.c: cdr_mysql: inserting a CDR recor
d.
Aug 7 14:06:06 DEBUG[25346] cdr_addon_mysql.c: cdr_mysql: SQL command as follow
s: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,la
stdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('200
8-08-07 14:05:35','558100021365','558100021365','956999135134','default', 'Local
/956999135134@default-219e,2','SIP/REDVOISS-09f270c8','Dial','SIP/REDVOISS/00569
99135134|60|Tto',31,0,'ANSWERED',3,'','')

Any ideas? Thanks,

[mflorell]

You need to add the sip-silence exten to your 8365. take a look at the docs/conf_examples/extensions.conf.sample file at the bottom for examples.

[ekaftan]

You need to add the sip-silence exten to your 8365. take a look at the docs/conf_examples/extensions.conf.sample file at the bottom for examples.

Just added it.
It worked once and then never again

[eliasferreyra]

[Just added it.
It worked once and then never again ]

yes i know why does it happend

you have to use only that

; VICIDIAL_auto_dialer transfer script:
exten => 8365,1,Playback(sip-silence)
exten => 8365,2,AGI(agi://127.0.0.1:4577/call_log)
exten => 8365,3,AGI(agi-VDADtransfer.agi,${EXTEN})
exten => 8365,4,AGI(agi-VDADtransfer.agi,${EXTEN})
exten => 8365,5,Hangup


if you let the twise contexts uncomented you will have this problem[/quote]

[ekaftan]

You need to add the sip-silence exten to your 8365. take a look at the docs/conf_examples/extensions.conf.sample file at the bottom for examples.

Just added it.
It worked once and then never again

I finally solved this today...

Problem was I was not using VICIDIAL to record the call, but using a MixMonitor line right before the Dial... It looks like that confuses asterisk just enough that vicidial cannot transfer the call to the agent.

I removed the MixMonitor line and everything works just fine....