Taking too long to change agent status to "CLOSER"

[mtancoff]

Agents loguin fine. Receive first call, when customer hungs up that is perfectly detected.

The problem is for the agent to receive a new call, since vicidial takes too long (Up to ten minutes) to change back the status to "CLOSER".

AST_update.pl is running

Posting asterisk -gcvvvvvvvvvvvvvvvvvvvvvvvvvvv screen:


-- Executing AGI("Zap/1-1", "agi-VDADcloser_inboundCID.agi|CL_ENTRANTES-----7275555134-----Closer-----park----------999-----1") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCID.agi
Perl Environment Dump:
0|CL_ENTRANTES-----7275555134-----Closer-----park----------999-----1|
AGI Environment Dump:
-- accountcode =
-- callerid = 1600
-- calleridname = 1600
-- callingani2 = 0
-- callingpres = 0
-- callingtns = 0
-- callington = 0
-- channel = Zap/1-1
-- context = default
-- dnid = unknown
-- enhanced = 0.0
-- extension = s
-- language = en
-- priority = 3
-- rdnis = unknown
-- request = agi-VDADcloser_inboundCID.agi
-- type = Zap
-- uniqueid = 1156368184.3
== Parsing '/etc/asterisk/manager.conf': Found

INBOUND CALL VDCL STARTED
+++++ INBOUND CALL VDCL STARTED : |1156368184.3|CL_ENTRANTES|Zap/1-1|s|Zap|Closer-7275555134|2006-08-23 18:23:05
AGI Environment Dump: |1156368184.3|Zap/1-1|s|Zap|Closer|

INBOUND CALL RECEIVED IN FROM VDCL: Closer Zap/1-1
+++++ INBOUND VDCL START : |1156368184.3|Zap/1-1|s|Zap|2006-08-23 18:23:05

|INSERT INTO vicidial_list (entry_date,modify_date,status,user,vendor_lead_code,source_id,list_id,called_since_last_reset,phone_code,phone_number,security_phrase,called_count,gmt_offset_now,comments) values('2006-08-23 18:23:05','20060823182305','INBND','7275555134','CL_ENTRANTES','VDCL','999','Y','1','1600','CL_ENTRANTES','1','-5.00','1600');|360|
FAIL (unexpected result '')
callerID changed: Y0823182305000000360

|INSERT INTO vicidial_auto_calls values('','127.0.0.1','CL_ENTRANTES','LIVE','360','1156368184.3','Y0823182305000000360','Zap/1-1','1','1600','2006-08-23 18:23:05','IN')|

|INSERT INTO vicidial_closer_log (lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed) values('360','CL_ENTRANTES','2006-08-23 18:23:05','1156368185','QUEUE','1','1600','VDCL','N')|
-- VDCL : |360|insert to vicidial_closer_log


|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='127.0.0.1' and campaign_id = 'CL_ENTRANTES' and call_time < "2006-08-23 18:23:05" and lead_id != '360';|
-- VDAD get agent: |360|0|1|update of vla table: CL_ENTRANTES|127.0.0.1
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='360',uniqueid='1156368184.3', channel='Zap/1-1', callerid='Y0823182305000000360' where status IN('CLOSER','READY') and server_ip='127.0.0.1' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ENTRANTES %" and last_update_time > '19691231205955' order by last_call_time limit 1;|


|SELECT conf_exten,user,extension FROM vicidial_live_agents where status = 'QUEUE' and server_ip='127.0.0.1' and campaign_id LIKE "%CLOSER%" and callerid='Y0823182305000000360' and channel='Zap/1-1' order by last_call_time limit 1;|
-- VDCL XFER : |1|update of vac table: Y0823182305000000360
|UPDATE vicidial_auto_calls set status='CLOSER' where callerid='Y0823182305000000360';|
-- closer log : |1|update of vcl table: 360
|UPDATE vicidial_closer_log set user='1600' where lead_id='360' order by call_date desc limit 1;|

exiting the VDAD app, transferring call to 8600051
FAIL (unexpected result '')
FAIL (unexpected result '')
FAIL (unexpected result '')

XXXXXXXXXX VDAD transferred: start|stop 2006-08-23 18:23:05|2006-08-23 18:23:05
-- AGI Script agi-VDADcloser_inboundCID.agi completed, returning 0
-- Executing MeetMe("Zap/1-1", "8600051") in new stack
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Spawn extension (default, 8600051, 1) exited non-zero on 'Zap/1-1'
-- Executing DeadAGI("Zap/1-1", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
== Connect attempt from '127.0.0.1' unable to authenticate
+++++ CALL LOG HUNGUP: |1156368184.3|Zap/1-1|h|2006-08-23 18:23:19|min: |
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Zap/1-1", "VD_hangup.agi|PRI-----NODEBUG-----0---------------") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Perl Environment Dump:
0|PRI-----NODEBUG-----0---------------|
AGI Environment Dump:
-- accountcode =
-- callerid = unknown
-- calleridname = Y0823182305000000360
-- callingani2 = 0
-- callingpres = 0
-- callingtns = 0
-- callington = 0
-- channel = Zap/1-1
-- context = default
-- dnid = unknown
-- enhanced = 0.0
-- extension = h
-- language = en
-- priority = 2
-- rdnis = unknown
-- request = VD_hangup.agi
-- type = Zap
-- uniqueid = 1156368184.3
AGI Environment Dump: |1156368184.3|Zap/1-1|h|Zap|Y0823182305000000360|Y0823182305000000360|2|

DEBUG: NODEBUG

VD_hangup : Y0823182305000000360 Zap/1-1 2
+++++ VD hangup START : |1156368184.3|Zap/1-1|h|Zap|Y0823182305000000360|360|2006-08-23 18:23:19||2|Y0823182305000000360|


|SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1156368184.3' limit 1;|
-- VDAC record deleted: |1| |360|1156368184.3|Y0823182305000000360|127.0.0.1


|SELECT start_epoch,status FROM vicidial_log where uniqueid='1156368184.3' and lead_id='360' limit 1;|

VD hangup: no VDL record found: 1156368184.3 Y0823182305000000360 360 1156368184.3


|SELECT start_epoch,status,closecallid FROM vicidial_closer_log where lead_id = '360' and call_date > "2006-08-23 12:23:19" order by call_date desc limit 1;|


|UPDATE vicidial_log set end_epoch='1156368199',length_in_sec='14' where uniqueid = '1156368184.3';|
-- VDAD vicidial_log update: |0|1156368184.3|


|UPDATE vicidial_closer_log set status='DROP',end_epoch='1156368199',length_in_sec='14' where closecallid = '354';|
-- VDCL update: |1|1156368184.3|354|
-- AGI Script VD_hangup.agi completed, returning 0
-- Hungup 'Zap/1-1'
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== 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
== Manager 'sendcron' logged off from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate
== Parsing '/etc/asterisk/manager.conf': Found
== Connect attempt from '127.0.0.1' unable to authenticate

Regards!

[mflorell]

I have never seen that behavior before, we are running several inbound CLOSER campaigns and this does not happen on them. The status is not changed by the server, but by the vicidial.php code once the agent presses the RESUME button or if they disposition a call their status should change to CLOSER immediately.

What build of vicidial.php are you using?

How many agents?

What is the loadavg of your server?