On-hook agent phone immediately hangs up

All installation and configuration problems and questions

Moderators: gerski, enjay, williamconley, Op3r, Staydog, gardo, mflorell, MJCoate, mcargile, Kumba, Michael_N

On-hook agent phone immediately hangs up

Postby sminisini » Wed Sep 26, 2018 8:55 am

Hi

I'm having an issue testing with an on-hook in-group. Whenever the agent answer the phone (xlite softphone) the call gets hung up on the agent side ( the customer side still has the moh playing ).
In the asterisk console, I get the following error when the agent answer the phone:

Code: Select all
SELECT dest_channel FROM cid_channels_recent where (caller_id_name = 'Test Agent' or connected_line_name = '7023734037' or caller_id_name = '7023734037' or connected_line_name = 'Test Agent') and ( linkedid = '1537969370.52' or dest_uniqueid = '1537969370.52' or uniqueid = '1537969370.52') and call_date > '2018-09-26 08:40:57'
NO DestChannel for callid Test Agent|Local/172*024*000*088*100502@default-00000009;1!!!


Looking into the table, there is no record of the incoming call.

OS: Centos 7
Vicidial: 2.14b0.5
Asterisk: 13.21-vici

Here's the complete log:

Code: Select all
[Sep 26 08:42:49]   == Using SIP RTP CoS mark 5
[Sep 26 08:42:49]        > 0x7ff6c4010f30 -- Strict RTP learning after remote address set to: 34.203.250.214:13918
[Sep 26 08:42:49]     -- Executing [+16178198367@trunkinbound:1] Goto("SIP/twilio2-0000000d", "trunkinbound,6178198367,1") in new stack
[Sep 26 08:42:49]     -- Goto (trunkinbound,6178198367,1)
[Sep 26 08:42:49]     -- Executing [6178198367@trunkinbound:1] AGI("SIP/twilio2-0000000d", "agi-DID_route.agi") in new stack
[Sep 26 08:42:49]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
2018-09-26 08:42:49|agi-DID_route.agi||INSERT INTO vicidial_did_log SET uniqueid='1537969369.51',channel='SIP/twilio2-0000000d',server_ip='172.24.0.88',caller_id_number='17023734037',caller_id_name='',extension='6178198367',call_date='2018-09-26 08:42:49',did_id='892',did_route='IN_GROUP';|
2018-09-26 08:42:49|agi-DID_route.agi|--    DID LOG : |1|INSERT INTO vicidial_did_log SET uniqueid='1537969369.51',channel='SIP/twilio2-0000000d',server_ip='172.24.0.88',caller_id_number='17023734037',caller_id_name='',extension='6178198367',call_date='2018-09-26 08:42:49',did_id='892',did_route='IN_GROUP';|
2018-09-26 08:42:49|agi-DID_route.agi|--    CALL LOG : |1|INSERT INTO call_log SET uniqueid='1537969369.51', channel='SIP/twilio2-0000000d', channel_group='DID_INBOUND', server_ip='172.24.0.88', type='SIP', extension='6178198367', number_dialed='6178198367', caller_code='17023734037', start_time='2018-09-26 08:42:49', start_epoch='1537969369';|
2018-09-26 08:42:49|agi-DID_route.agi|--    DID AGENT LOG : |1|INSERT INTO vicidial_did_agent_log SET uniqueid='1537969369.51',server_ip='172.24.0.88',caller_id_number='17023734037',caller_id_name='',extension='6178198367',call_date='2018-09-26 08:42:49',did_id='892',did_route='IN_GROUP',user='VDCL',group_id='SCHED_MAN',did_description='Matt Test';|
2018-09-26 08:42:49|agi-DID_route.agi|exiting the DID app, transferring call to 99909*892***DID @ default
[Sep 26 08:42:49]     -- <SIP/twilio2-0000000d>AGI Script agi-DID_route.agi completed, returning 0
[Sep 26 08:42:49]     -- Executing [99909*892***DID@default:1] Answer("SIP/twilio2-0000000d", "") in new stack
[Sep 26 08:42:50]     -- Executing [99909*892***DID@default:2] AGI("SIP/twilio2-0000000d", "agi-VDAD_ALL_inbound.agi") in new stack
[Sep 26 08:42:50]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|North America CID Check: 1|7023734037|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- accountcode =
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- callerid = +17023734037
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- calleridname = unknown
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- callingani2 = 62
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- callingpres = 0
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- callingtns = 0
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- callington = 0
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- channel = SIP/twilio2-0000000d
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- context = default
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- dnid = +16178198367
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- enhanced = 0.0
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- extension = 99909*892***DID
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- language = en
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- priority = 2
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- rdnis = +16178198367
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- request = agi-VDAD_ALL_inbound.agi
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- threadid = 140698442274560
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- type = SIP
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- uniqueid = 1537969369.51
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi| -- version = 13.17.2-vici
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|AGI Variables: |1537969369.51|SIP/twilio2-0000000d|99909*892***DID|SIP|7023734037|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|+++++ INBOUND CALL VDCL STARTED : |SCHED_MAN|7023734037-6178198367|2018-09-26 08:42:50
[Sep 26 08:42:50]     -- <SIP/twilio2-0000000d> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 26 08:42:50]        > 0x7ff6c4010f30 -- Strict RTP switching to RTP target address 34.203.250.214:13918 as source
[Sep 26 08:42:50]     -- <SIP/twilio2-0000000d> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|VDAD vicidial_list search |7023734037|SELECT lead_id,called_count from vicidial_list where phone_number='7023734037'  order by last_local_call_time desc limit 1;|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|VDAD vicidial_list found |12|SELECT lead_id,called_count from vicidial_list where phone_number='7023734037'  order by last_local_call_time desc limit 1;|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi||SELECT campaign_id FROM vicidial_campaigns where active='Y' and campaign_allow_inbound='Y';|12|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|callerID changed: "Y9260842500000000012 <7023734037>"
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT auto_call_id from vicidial_auto_calls where uniqueid='1537969369.51' and server_ip='172.24.0.88';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi||INSERT INTO vicidial_auto_calls SET server_ip='172.24.0.88', campaign_id='SCHED_MAN', status='LIVE', lead_id='12', uniqueid='1537969369.51', callerid='Y9260842500000000012', channel='SIP/twilio2-0000000d', phone_code='1', phone_number='7023734037', call_time='2018-09-26 08:42:50', call_type='IN', stage='LIVE-0', queue_priority='0' ;|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|1|SELECT count(*) from vicidial_auto_calls where status = 'LIVE' and campaign_id='SCHED_MAN';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|1|UPDATE vicidial_auto_calls SET queue_position='1'  WHERE auto_call_id='8';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi||INSERT INTO vicidial_closer_log SET lead_id='12', campaign_id='SCHED_MAN', call_date='2018-09-26 08:42:50', start_epoch='1537969370', status='QUEUE', phone_code='1', phone_number='7023734037', user='VDCL', processed='N', xfercallid='0', uniqueid='1537969369.51', queue_position='1',called_count='1',list_id='999' ;|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|--    VDCL : |12|0|567|insert to vicidial_closer_log
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|--  ENTER QUEUE VLE insert: |1|
|INSERT INTO vicidial_log_extended set uniqueid='1537969369.51',server_ip='172.24.0.88',call_date='2018-09-26 08:42:50',lead_id = '12',caller_code='Y9260842500000000012',custom_call_id='';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|DID IVR time logged: 2018-09-26 08:42:50|1537969370|1|UPDATE call_log set end_time='2018-09-26 08:42:50',end_epoch='1537969370',length_in_sec=1,length_in_min='    0.02',extension='Y9260842500000000012' where uniqueid='1537969369.51'|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|RING-AGENT CALL SENT: |7023734037|138331*8*Y9260842500000000012*100502*100502|0|30|CUSTOMER_PHONE|CUSTOMER|   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|0|50|60|0|0||0|update of vla table: SCHED_MAN|
|INSERT INTO vicidial_manager values('','','2018-09-26 08:42:50','NEW','N','172.24.0.88','','Originate','7023734037','Exten: 138331*8*Y9260842500000000012*100502*100502','Context: default','Channel: Local/172*024*000*088*100502@default','Priority: 1','Callerid: "7023734037" <7023734037>','Timeout: 29000','','','','')|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
[Sep 26 08:42:50]   == Manager 'sendcron' logged on from 127.0.0.1
[Sep 26 08:42:50]     -- Called 172*024*000*088*100502@default
[Sep 26 08:42:50]     -- Executing [172*024*000*088*100502@default:1] Goto("Local/172*024*000*088*100502@default-00000009;2", "default,100502,1") in new stack
[Sep 26 08:42:50]     -- Goto (default,100502,1)
[Sep 26 08:42:50]     -- Executing [100502@default:1] Dial("Local/172*024*000*088*100502@default-00000009;2", "SIP/100502,60,") in new stack
[Sep 26 08:42:50]   == Using SIP RTP CoS mark 5
[Sep 26 08:42:50]     -- Called SIP/100502
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   29.75|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|0.25|51|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:50|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   29.5|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|0.5|52|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
[Sep 26 08:42:51]        > 0x7ff6c4010f30 -- Strict RTP learning complete - Locking on source address 34.203.250.214:13918
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   29.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|0.75|53|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
[Sep 26 08:42:51]     -- SIP/100502-0000000e is ringing
[Sep 26 08:42:51]     -- Local/172*024*000*088*100502@default-00000009;1 is ringing
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   29|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|1|54|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   28.75|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|1.25|55|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:51|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   28.5|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|1.5|56|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   28.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|1.75|57|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   27.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|2|58|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:52|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
[Sep 26 08:42:52]     -- Started music on hold, class 'default', on channel 'SIP/twilio2-0000000d'
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   26.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|3|59|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:53|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   25.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|4|60|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:54|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|0|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'SCHED_MAN' and call_time < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0' and agent_only='';|
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and group_id='SCHED_MAN' and call_date < "2018-09-26 08:42:50" and lead_id != '12' and queue_priority >= '0';|
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '12' and agent_only='' and ( (queue_priority > '0') or (queue_priority = '0' and call_time < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|0|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|RING-AGENT TIMER:   24.25|30|30   SIP/twilio2-0000000d|Y9260842500000000012
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|--    VDAD get agent: |360|5|61|60|0|0||0|update of vla table: SCHED_MAN|
|SELECT count(*) FROM vicidial_inbound_callback_queue where icbq_status IN('LIVE','SENDING') and lead_id != '12' and ( (queue_priority > '0') or (queue_priority = '0' and call_date < "2018-09-26 08:42:50") );|
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|NNNNN No available balance agent found
2018-09-26 08:42:50|08:42:55|agi-VDAD_ALL_inbound.agi|--    VDAC posttime record: |1|20180926084304|Y9260842500000000012||
[Sep 26 08:42:55]     -- Stopped music on hold on SIP/twilio2-0000000d
[Sep 26 08:42:55]     -- <SIP/twilio2-0000000d> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 26 08:42:55]     -- <SIP/twilio2-0000000d> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 26 08:42:55]     -- <SIP/twilio2-0000000d> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 26 08:42:55]     -- <SIP/twilio2-0000000d> Playing 'generic_hold.slin' (escape_digits=) (sample_offset 0) (language 'en')
[Sep 26 08:42:57]        > 0x7ff6c0018b90 -- Strict RTP learning after remote address set to: 68.227.14.30:58228
[Sep 26 08:42:57]     -- SIP/100502-0000000e answered Local/172*024*000*088*100502@default-00000009;2
[Sep 26 08:42:57]     -- Local/172*024*000*088*100502@default-00000009;1 answered
[Sep 26 08:42:57]     -- Executing [138331*8*Y9260842500000000012*100502*100502@default:1] AGI("Local/172*024*000*088*100502@default-00000009;1", "agi-VDAD_local_optimize.agi,7023734037") in new stack
[Sep 26 08:42:57]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_local_optimize.agi
[Sep 26 08:42:57]     -- Channel SIP/100502-0000000e joined 'simple_bridge' basic-bridge <34762d3b-07dc-46a1-a097-2c2ce5615109>
[Sep 26 08:42:57]     -- Channel Local/172*024*000*088*100502@default-00000009;2 joined 'simple_bridge' basic-bridge <34762d3b-07dc-46a1-a097-2c2ce5615109>
1537969377.430476
SELECT dest_channel FROM cid_channels_recent where (caller_id_name = 'Test Agent' or connected_line_name = '7023734037' or caller_id_name = '7023734037' or connected_line_name = 'Test Agent') and ( linkedid = '1537969370.52' or dest_uniqueid = '1537969370.52' or uniqueid = '1537969370.52') and call_date > '2018-09-26 08:40:57'
NO DestChannel for callid Test Agent|Local/172*024*000*088*100502@default-00000009;1!!!
[Sep 26 08:42:57]     -- <Local/172*024*000*088*100502@default-00000009;1>AGI Script agi-VDAD_local_optimize.agi completed, returning 0
[Sep 26 08:42:57]     -- Executing [138331*8*Y9260842500000000012*100502*100502@default:2] Wait("Local/172*024*000*088*100502@default-00000009;1", "2") in new stack
[Sep 26 08:42:58]        > 0x7ff6c0018b90 -- Strict RTP switching to RTP target address 68.227.14.30:58228 as source
[Sep 26 08:42:58]   == Manager 'sendcron' logged off from 127.0.0.1
[Sep 26 08:42:58]        > 0x7ff6c0018b90 -- Strict RTP learning complete - Locking on source address 68.227.14.30:58228
[Sep 26 08:42:59]     -- Executing [138331*8*Y9260842500000000012*100502*100502@default:3] Hangup("Local/172*024*000*088*100502@default-00000009;1", "") in new stack
[Sep 26 08:42:59]   == Spawn extension (default, 138331*8*Y9260842500000000012*100502*100502, 3) exited non-zero on 'Local/172*024*000*088*100502@default-00000009;1'
[Sep 26 08:42:59] WARNING[10024][C-00000018]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
[Sep 26 08:42:59]     -- Executing [h@default:1] AGI("Local/172*024*000*088*100502@default-00000009;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack
[Sep 26 08:42:59]     -- <Local/172*024*000*088*100502@default-00000009;1>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0
[Sep 26 08:42:59]     -- Channel Local/172*024*000*088*100502@default-00000009;2 left 'simple_bridge' basic-bridge <34762d3b-07dc-46a1-a097-2c2ce5615109>
[Sep 26 08:42:59]   == Spawn extension (default, 100502, 1) exited non-zero on 'Local/172*024*000*088*100502@default-00000009;2'
[Sep 26 08:42:59]     -- Executing [h@default:1] AGI("Local/172*024*000*088*100502@default-00000009;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----8-----2-----SIP 200 OK)") in new stack
[Sep 26 08:42:59]     -- Channel SIP/100502-0000000e left 'simple_bridge' basic-bridge <34762d3b-07dc-46a1-a097-2c2ce5615109>
[Sep 26 08:42:59]     -- <Local/172*024*000*088*100502@default-00000009;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----8-----2-----SIP 200 OK) completed, returning 0
[Sep 26 08:43:01]   == Manager 'sendcron' logged on from 127.0.0.1
[Sep 26 08:43:01]   == Manager 'sendcron' logged off from 127.0.0.1
sminisini
 
Posts: 5
Joined: Tue Jul 31, 2018 3:18 pm

Re: On-hook agent phone immediately hangs up

Postby williamconley » Mon Nov 12, 2018 7:03 pm

1) You must post the BUILD for Vicidial.

2) You *should* post the installation instructions you used (in case there's a flaw, that link will be shared with others who have the same flaw and *poof* everyone experiencing the same problem will be linked to the solution here when one is found).

3) You posted a very long log. Please post again only this time post JUST the CLI from a single call, beginning to end, with no other traffic. Most of us don't have time to wade through 3000 lines of unrelated code (not saying that's your log length, just a phrase I use to get the point across). Stop using the server for anything else for a minute and JUST test this one call. Post from beginning to end of that one call.
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
williamconley
 
Posts: 20258
Joined: Wed Oct 31, 2007 4:17 pm
Location: Davenport, FL (By Disney!)

Re: On-hook agent phone immediately hangs up

Postby ftartari » Tue Nov 13, 2018 9:47 pm

i found solution!!!!!!!!!!!

on inbound groups option On-Hook CID

only GENERIC or CUSTOMER_PHONE_RING_AGENT will work. Any other option call hangup on answer by agent.

on this option hint has an alert "If you are using Asterisk 13 or higher, only the RINGAGENT options will work properly."

I use asterisk 13 and not observe this.
ftartari
 
Posts: 6
Joined: Tue Oct 23, 2018 3:23 pm

Re: On-hook agent phone immediately hangs up

Postby lvish » Thu Jun 25, 2020 5:42 am

I used CUSTOMER_PHONE, it worked for a day.
Then not sure , i had to revert to GENERIC to receive calls.
lvish
lvish
 
Posts: 103
Joined: Tue Jan 25, 2011 4:56 am


Return to Support

Who is online

Users browsing this forum: Google [Bot] and 109 guests