autodial problem

[chris80]

We have a problem to run vicidialnow with autodialer. For some reason the call gets disconnected as soon as agi-VDADtransfer.agi completed and returning 0. Agent is logged in and I can see the live agent in mysql. There seems to be a problem updating the uniqueueid and callerid in auto_callers and live agents table. Below is a trace of the call. I have installed vicidialnow 1.2 RC1. Manual dialing is working. Any help would be appreciated. Thank you.

-- SIP/SIPtrunk-08491e68 answered Local/07825884788@default-84c7,2
> Channel Local/07825884788@default-84c7,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/07825884788@default-84c7,1", "agi://127.0.0.1:4577/call_log") in new stack
AGI Tx >> agi_network_script: call_log
AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log
AGI Tx >> agi_channel: Local/07825884788@default-84c7,1
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.30
AGI Tx >> agi_callerid: 0000000000
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: 8365
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing AGI("Local/07825884788@default-84c7,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
AGI Tx >> agi_request: agi-VDADtransfer.agi
AGI Tx >> agi_channel: Local/07825884788@default-84c7,1
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.30
AGI Tx >> agi_callerid: 0000000000
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: 8365
AGI Tx >> agi_priority: 2
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << SET CALLERID V0319163903000000002
AGI Tx >> 200 result=1
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("Local/07825884788@default-84c7,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
AGI Tx >> agi_request: agi-VDADtransfer.agi
AGI Tx >> agi_channel: Local/07825884788@default-84c7,1
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.30
AGI Tx >> agi_callerid: unknown
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: 8365
AGI Tx >> agi_priority: 3
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << SET CALLERID V0319163903000000002
AGI Tx >> 200 result=1
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing AGI("Local/07825884788@default-84c7,1", "agi-VDADtransfer.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
AGI Tx >> agi_request: agi-VDADtransfer.agi
AGI Tx >> agi_channel: Local/07825884788@default-84c7,1
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.30
AGI Tx >> agi_callerid: unknown
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: 8365
AGI Tx >> agi_priority: 4
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
AGI Rx << SET CALLERID V0319163903000000002
AGI Tx >> 200 result=1
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Hangup("Local/07825884788@default-84c7,1", "") in new stack
== Spawn extension (default, 8365, 5) exited non-zero on 'Local/07825884788@default-84c7,1'
-- Executing DeadAGI("Local/07825884788@default-84c7,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
AGI Tx >> agi_network_script: call_log--HVcauses--PRI-----NODEBUG-----16---------------
AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log--HVcauses ... ----------
AGI Tx >> agi_channel: Local/07825884788@default-84c7,1
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.30
AGI Tx >> agi_callerid: unknown
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: h
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
Scheduling destruction of call '4220d44e3575961f78db2b4b3fd66914@194.50.74.68' in 32000 ms
set_destination: Parsing <sip:194.50.74.68;lr=on;ftag=as745841f0> for address/port to send to
set_destination: set destination to 194.50.74.68, port 5060
Reliably Transmitting (no NAT) to 194.50.74.68:5060:
BYE sip:07825884788@194.50.55.13:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.21.170:5060;branch=z9hG4bK4a3e73f1;rport
Route: <sip:194.50.74.68;lr=on;ftag=as745841f0>
From: "V0319163903000000002" <sip:55888877@194.50.74.68>;tag=as745841f0
To: <sip:07825884788@194.50.74.68>;tag=as703e68e1
Call-ID: 4220d44e3575961f78db2b4b3fd66914@194.50.74.68
CSeq: 104 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Proxy-Authorization: Digest username="55888877", realm="194.50.74.68", algorithm=MD5, uri="sip:07825884788@194.50.55.13:5060", nonce="49c277472c1924e6ddb40f4c546a6826eba77f3b", response="ff926593e2afe1cb85efc60273252c8f", opaque=""
Content-Length: 0


---
== Spawn extension (default, 07825884788, 2) exited non-zero on 'Local/07825884788@default-84c7,2'
-- Executing DeadAGI("Local/07825884788@default-84c7,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----14-----3") in new stack
AGI Tx >> agi_network_script: call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----14-----3
AGI Tx >> agi_request: agi://127.0.0.1:4577/call_log--HVcauses ... --14-----3
AGI Tx >> agi_channel: Local/07825884788@default-84c7,2
AGI Tx >> agi_language: en
AGI Tx >> agi_type: Local
AGI Tx >> agi_uniqueid: 1237480744.31
AGI Tx >> agi_callerid: 0000000000
AGI Tx >> agi_calleridname: V0319163903000000002
AGI Tx >> agi_callingpres: 0
AGI Tx >> agi_callingani2: 0
AGI Tx >> agi_callington: 0
AGI Tx >> agi_callingtns: 0
AGI Tx >> agi_dnid: unknown
AGI Tx >> agi_rdnis: unknown
AGI Tx >> agi_context: default
AGI Tx >> agi_extension: h
AGI Tx >> agi_priority: 1
AGI Tx >> agi_enhanced: 0.0
AGI Tx >> agi_accountcode:
AGI Tx >>
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... --14-----3 completed, returning 0
vici*CLI>
<-- SIP read from 194.50.74.68:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.21.170:5060;received=87.194.33.73;branch=z9hG4bK4a3e73f1;rport=56528
Record-Route: <sip:194.50.74.68;lr=on;ftag=as745841f0>
From: "V0319163903000000002" <sip:55888877@194.50.74.68>;tag=as745841f0
To: <sip:07825884788@194.50.74.68>;tag=as703e68e1
Call-ID: 4220d44e3575961f78db2b4b3fd66914@194.50.74.68
CSeq: 104 BYE
User-Agent: Integrics Enswitch
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:07825884788@194.50.74.68:5060>
Content-Length: 0

[mflorell]

You will need to post some output from the agiout.2009XXXX logfile for these calls.

[chris80]

I think the problem lies on the callerid in the live_agent. This field is empty in the database but it should contain: V0319163903000000002. I can see there is something that deletes the uniqueid and callerid when the agent resume. I will get mysql log running and see what exactly happens.