ViciNow, do calls but hangup

[michaelux]

Hi all..

I finally could load new lead, I test with my number and I can log in in the agent, the softphone ring but when I pick up the phone the call hangup :s I have logs, I configured an iax tunel with another asterisk server. We have vicidialnow 1.2

Any idea what this happen? here are the logs..

There is a particular warning but I dont know if that is the cause:
channel.c:780 channel_find_locked: Avoided deadlock for '0x846da10

Connected to Asterisk 1.2.30.2 currently running on vici (pid = 4613)
Verbosity is at least 4
Sep 16 17:38:51 WARNING[4705]: channel.c:780 channel_find_locked: Avoided deadlock for '0x846da10', 9 retries!
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/018099858027@default-d974,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing Set("Local/018099858027@default-d974,2", "CALLERID(name)=encuestas") in new stack
-- Executing Dial("Local/018099858027@default-d974,2", "IAX2/vicidial:545De4f@172.22.0.5/018099858027|55|tTo") in new stack
-- Called vicidial:545De4f@172.22.0.5/018099858027
-- Call accepted by 172.22.0.5 (format ulaw)
-- Format for call is ulaw
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/018099858027@default-e45f,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing Set("Local/018099858027@default-e45f,2", "CALLERID(name)=encuestas") in new stack
-- Executing Dial("Local/018099858027@default-e45f,2", "IAX2/vicidial:545De4f@172.22.0.5/018099858027|55|tTo") in new stack
-- Called vicidial:545De4f@172.22.0.5/018099858027
-- Call accepted by 172.22.0.5 (format ulaw)
-- Format for call is ulaw
-- IAX2/172.22.0.5:4569-10484 is making progress passing it to Local/018099858027@default-d974,2
-- IAX2/172.22.0.5:4569-10484 answered Local/018099858027@default-d974,2
> Channel Local/018099858027@default-d974,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/018099858027@default-d974,1", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing AGI("Local/018099858027@default-d974,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("Local/018099858027@default-d974,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
== Spawn extension (default, 018099858027, 3) exited non-zero on 'Local/018099858027@default-d974,2'
-- Executing DeadAGI("Local/018099858027@default-d974,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("IAX2/172.22.0.5:4569-10484", "agi-VDADtransfer.agi|8365") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("Local/018099858027@default-d974,2", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----5-----0)") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ---5-----0) completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Hangup("IAX2/172.22.0.5:4569-10484", "") in new stack
== Spawn extension (default, 8365, 5) exited non-zero on 'IAX2/172.22.0.5:4569-10484'
-- Executing DeadAGI("IAX2/172.22.0.5:4569-10484", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("IAX2/172.22.0.5:4569-10484", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ----------) completed, returning 0
-- Hungup 'IAX2/172.22.0.5:4569-10484'
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/018099858027@default-f6e6,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing Set("Local/018099858027@default-f6e6,2", "CALLERID(name)=encuestas") in new stack
-- Executing Dial("Local/018099858027@default-f6e6,2", "IAX2/vicidial:545De4f@172.22.0.5/018099858027|55|tTo") in new stack
-- Called vicidial:545De4f@172.22.0.5/018099858027
-- Call accepted by 172.22.0.5 (format ulaw)
-- Format for call is ulaw
-- IAX2/172.22.0.5:4569-7745 is making progress passing it to Local/018099858027@default-e45f,2
-- IAX2/172.22.0.5:4569-7745 answered Local/018099858027@default-e45f,2
> Channel Local/018099858027@default-e45f,1 was answered.
-- Executing AGI("Local/018099858027@default-e45f,1", "agi://127.0.0.1:4577/call_log") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
== Spawn extension (default, 018099858027, 3) exited non-zero on 'Local/018099858027@default-e45f,2'
-- Executing DeadAGI("Local/018099858027@default-e45f,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing AGI("IAX2/172.22.0.5:4569-7745", "agi-VDADtransfer.agi|8365") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- Executing DeadAGI("Local/018099858027@default-e45f,2", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----4-----0)") in new stack
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ---4-----0) completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("IAX2/172.22.0.5:4569-7745", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("IAX2/172.22.0.5:4569-7745", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Hangup("IAX2/172.22.0.5:4569-7745", "") in new stack
== Spawn extension (default, 8365, 5) exited non-zero on 'IAX2/172.22.0.5:4569-7745'
-- Executing DeadAGI("IAX2/172.22.0.5:4569-7745", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("IAX2/172.22.0.5:4569-7745", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ----------) completed, returning 0
-- Hungup 'IAX2/172.22.0.5:4569-7745'
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Manager 'sendcron' logged off from 127.0.0.1
-- IAX2/172.22.0.5:4569-2763 is making progress passing it to Local/018099858027@default-f6e6,2
== Manager 'sendcron' logged off from 127.0.0.1
-- IAX2/172.22.0.5:4569-2763 answered Local/018099858027@default-f6e6,2
> Channel Local/018099858027@default-f6e6,1 was answered.
-- Executing AGI("Local/018099858027@default-f6e6,1", "agi://127.0.0.1:4577/call_log") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing AGI("Local/018099858027@default-f6e6,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("Local/018099858027@default-f6e6,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
== Spawn extension (default, 018099858027, 3) exited non-zero on 'Local/018099858027@default-f6e6,2'
-- Executing DeadAGI("Local/018099858027@default-f6e6,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("IAX2/172.22.0.5:4569-2763", "agi-VDADtransfer.agi|8365") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- Executing DeadAGI("Local/018099858027@default-f6e6,2", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----0)") in new stack
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ---6-----0) completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Hangup("IAX2/172.22.0.5:4569-2763", "") in new stack
== Spawn extension (default, 8365, 5) exited non-zero on 'IAX2/172.22.0.5:4569-2763'
-- Executing DeadAGI("IAX2/172.22.0.5:4569-2763", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing DeadAGI("IAX2/172.22.0.5:4569-2763", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcause ... ----------) completed, returning 0
-- Hungup 'IAX2/172.22.0.5:4569-2763'

[mflorell]

You are changing the callerIDname, that breaks ViciDial.

[michaelux]

You are changing the callerIDname, that breaks ViciDial.

what do you mean?

This is the same data that wast in a server before and it works good, I only move the data, so why can be it?

[okli]

Did you change your dialplan?

-- Executing Set("Local/018099858027@default-e45f,2", "CALLERID(name)=encuestas")

Here is where you rewrite CallerIDname, and you should NOT be doing so, as this breaks Vicidial.

[michaelux]

Did you change your dialplan?

-- Executing Set("Local/018099858027@default-e45f,2", "CALLERID(name)=encuestas")

Here is where you rewrite CallerIDname, and you should NOT be doing so, as this breaks Vicidial.

umm :s but, in the logs of old vicidial is the same, here is a log from old server with vicidial, it do the same :s

Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/013055955534@default-27df,2", "agi://127.0.0.1:4577/call_log") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing Set("Local/013055955534@default-27df,2", "CALLERID(name)=encuestas") in new stack
-- Executing Dial("Local/013055955534@default-27df,2", "IAX2/vicidial:545De4f@172.22.0.5/013055955534|55|tTo") in new stack
-- Called vicidial:545De4f@172.22.0.5/013055955534
-- Call accepted by 172.22.0.5 (format ulaw)
-- Format for call is ulaw

[gardo]

Try dialing without using the callerid flag first and see if it works.

[michaelux]

Hi...

Finally now I can test calling to my mobile number but...

I connected to the other asterisk server and check the CLI...

My phone ring but when I pick it up, the call hungup... when I check the log in the other asterisk server I got this:

I think that is the reason why the call always hungup... isnt it?

Why the address is with 2 // /asterisk//cdr-csv//Master.csv :s

The file Master.csv is there in /cdr-csv/

I will check...

---
== Spawn extension (vicidial, 017867092488, 1) exited non-zero on 'IAX2/vicidial-14498'
-- Hungup 'IAX2/vicidial-14498'
Sep 21 11:55:05 ERROR[28615]: cdr_csv.c:237 csv_log: Unable to re-open master file /var/log/asterisk//cdr-csv//Master.csv : No such file or directory
NLUSDCSNEWBOT03*CLI>
<-- SIP read from 66.33.157.119:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.22.0.5:5060;branch=z9hG4bK1da59d78;received=172.22.0.5;rport=5060
From: "encuestas" <sip:50322070213@172.22.0.5>;tag=as58d8210c
To: <sip:17867092488@66.33.157.119>;tag=ccid-386520433-1-1532
Call-ID: 5a13b32e7fe63ccf095f21e07e994f58@172.22.0.5
CSeq: 103 BYE
Content-Length: 0

[michaelux]

Hi, I have been reading and reading a lot...

I have a doubt, I see the error with cdr-csv but I dont know if the problem about the call hungup is for this or FIRST the call hungup and then launch the error with cdr-csv..

this if the first error:

or is it the error and the problem?

Code: Select all

== Spawn extension (vicidial, 017867492488, 1) exited non-zero on 'IAX2/vicidial-14498'
-- Hungup 'IAX2/vicidial-14498'

What this mean? where should I check?....

Thank you

[michaelux]

Try dialing without using the callerid flag first and see if it works.

Hi, sorry, but how can I do that?

thanks

[gardo]

Look for the line that has the entry below and remove them from your dialplan:

"CALLERID(name)=encuestas"

[michaelux]

Look for the line that has the entry below and remove them from your dialplan:

"CALLERID(name)=encuestas"

Thanks man. But I have this in extensions.conf

Code: Select all

; This 'o' Dial flag is VERY important for VICIDIAL on outbound calls,
exten => _X.,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _X.,2,Set(CALLERID(name)=encuestas)
exten => _X.,3,Dial(${TRUNKNEWBOT}/${EXTEN},55,tTo)
exten => _X.,4,Hangup

if I coment the lines there arent any calls :s

In sip.conf file the user who I use to connect has:

Code: Select all

[6674]
secret=3311127
nat=yes
username=6674
host=dynamic
type=friend
dtmfmode=rfc2833
disallow=all
allow=ilbc
allow=ulaw
allow=alaw
allow=gsm
context=default
callerid=encuestas <50321132700>

I commented the line callerid and the call go out but the same problem.. the call seems to hung up but I can hear the conversation... :s

[okli]

Code: Select all

; This 'o' Dial flag is VERY important for VICIDIAL on outbound calls,
exten => _X.,1,AGI(agi://127.0.0.1:4577/call_log)
;exten => _X.,2,Set(CALLERID(name)=encuestas)
exten => _X.,2,Dial(${TRUNKNEWBOT}/${EXTEN},55,tTo)
exten => _X.,3,Hangup

Code: Select all

[6674]....
....
;callerid=encuestas <50321132700>

Then in Asterisk CLI:

Code: Select all

sip reload
extensions reload

[michaelux]

Thanks Okli..

it worked!!


I dont know why in the old server the same configuration run perfect :s ...


I had a server with vicidial 2.0.4, which use 58 tables and vicidialnow use 2.0.5 with 78 and I ran the script upgrade for the database... I hope dont get any other problem...

Thanks a lot

[gardo]

This dialplan is very dangerous since it's a catch all. This can mess up other dialplan. I would recommend you change it to something more specific.

Thanks man. But I have this in extensions.conf

Code: Select all

; This 'o' Dial flag is VERY important for VICIDIAL on outbound calls,
exten => _X.,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _X.,2,Set(CALLERID(name)=encuestas)
exten => _X.,3,Dial(${TRUNKNEWBOT}/${EXTEN},55,tTo)
exten => _X.,4,Hangup