[Jan 19 00:00:11] -- Executing [912012105041@default:1] AGI("Local/912012105041@default-000025d4;2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 19 00:00:11] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=AMDTEST)) [Jan 19 00:00:11] -- AGI Script Executing Application: (EXEC) Options: (SIPAddHeader(X-Vicidial-ID: V1190000110041535171_AMDTEST_208.38.155.226)) [Jan 19 00:00:11] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 19 00:00:11] -- Executing [912012105041@default:2] Dial("Local/912012105041@default-000025d4;2", "SIP/sgw2/12012105041,,To") in new stack [Jan 19 00:00:11] == Using SIP RTP TOS bits 184 [Jan 19 00:00:11] == Using SIP RTP CoS mark 5 [Jan 19 00:00:11] -- Called SIP/sgw2/12012105041 [Jan 19 00:00:13] > 0x7fc380006e10 -- Strict RTP learning after remote address set to: 38.102.250.160:62136 [Jan 19 00:00:13] -- SIP/sgw2-000025d7 is making progress passing it to Local/912012105041@default-000025d4;2 [Jan 19 00:00:13] -- Local/912012105041@default-000025d4;1 is making progress [Jan 19 00:00:13] > 0x7fc380006e10 -- Strict RTP switching to RTP target address 38.102.250.160:62136 as source [Jan 19 00:00:13] -- SIP/sgw2-000025d7 answered Local/912012105041@default-000025d4;2 [Jan 19 00:00:13] -- Local/912012105041@default-000025d4;1 answered [Jan 19 00:00:13] -- Executing [138369@default:1] AGI("Local/912012105041@default-000025d4;1", "agi-VDAD_local_optimize.agi,V1190000110041535171") in new stack [Jan 19 00:00:13] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_local_optimize.agi [Jan 19 00:00:13] -- Channel SIP/sgw2-000025d7 joined 'simple_bridge' basic-bridge <76354791-243e-4d3d-9910-bcb0761e8d50> [Jan 19 00:00:13] -- Channel Local/912012105041@default-000025d4;2 joined 'simple_bridge' basic-bridge <76354791-243e-4d3d-9910-bcb0761e8d50> 1516338013.981015 SELECT dest_channel FROM cid_channels_recent where (caller_id_name = 'V1190000110041535171' or connected_line_name = 'V1190000110041535171' or caller_id_name = 'V1190000110041535171' or connected_line_name = 'V1190000110041535171') and ( linkedid = '1516338011.29055' or dest_uniqueid = '1516338011.29055' or uniqueid = '1516338011.29055') and call_date > '2018-01-18 23:58:13' 6|SIP/sgw2-000025d7 INSERT INTO vicidial_manager values('','','2018-01-19 00:00:13','SENT','N','192.168.200.7','','Redirect','V1190000110041535171','Channel: SIP/sgw2-000025d7','Context: default','Exten: 8369','Priority: 1','','','','','','') /usr/share/astguiclient/AST_send_action_child.pl --SYSLOG --PATHlogs=/var/log/astguiclient --telnet_host=localhost --telnet_port=5038 --ASTmgrUSERNAME=cron --ASTmgrSECRET=1234 --ASTmgrUSERNAMEsend=sendcron --man_id=30633424 --action=Redirect --cmd_line_b='Channel: SIP/sgw2-000025d7' --cmd_line_c='Context: default' --cmd_line_d='Exten: 8369' --cmd_line_e='Priority: 1' >> /var/log/astguiclient/action_send.2018-01-19 [Jan 19 00:00:14] -- AGI Script agi-VDAD_local_optimize.agi completed, returning 0 [Jan 19 00:00:14] -- Executing [138369@default:2] Wait("Local/912012105041@default-000025d4;1", "2") in new stack [Jan 19 00:00:14] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 19 00:00:14] -- Channel SIP/sgw2-000025d7 left 'simple_bridge' basic-bridge <76354791-243e-4d3d-9910-bcb0761e8d50> [Jan 19 00:00:14] -- Channel Local/912012105041@default-000025d4;2 left 'simple_bridge' basic-bridge <76354791-243e-4d3d-9910-bcb0761e8d50> [Jan 19 00:00:14] -- Executing [8369@default:1] AGI("SIP/sgw2-000025d7", "agi://127.0.0.1:4577/call_log") in new stack [Jan 19 00:00:14] == Spawn extension (default, 912012105041, 2) exited non-zero on 'Local/912012105041@default-000025d4;2' [Jan 19 00:00:14] -- Executing [h@default:1] AGI("Local/912012105041@default-000025d4;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----2-----0-----SIP 200 OK)") in new stack [Jan 19 00:00:14] -- AGI Script Executing Application: (EXEC) Options: (Set(_CAMPCUST=AMDTEST)) [Jan 19 00:00:14] -- AGI Script Executing Application: (EXEC) Options: (SIPAddHeader(X-Vicidial-ID: V1190000110041535171_AMDTEST_208.38.155.226)) [Jan 19 00:00:14] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 19 00:00:14] -- Executing [8369@default:2] AMD("SIP/sgw2-000025d7", "2000,2000,1000,5000,120,50,4,256") in new stack [Jan 19 00:00:14] -- AMD: SIP/sgw2-000025d7 (N/A) (N/A) (Fmt: slin) [Jan 19 00:00:14] -- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256] maximumWordLength [5000] [Jan 19 00:00:14] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----2-----0-----SIP 200 OK) completed, returning 0 [Jan 19 00:00:14] == Spawn extension (default, 138369, 2) exited non-zero on 'Local/912012105041@default-000025d4;1' [Jan 19 00:00:14] WARNING[5327][C-0000444f]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel [Jan 19 00:00:14] -- Executing [h@default:1] AGI("Local/912012105041@default-000025d4;1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------)") in new stack [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Changed state to STATE_IN_SILENCE [Jan 19 00:00:14] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------) completed, returning 0 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 40 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Detected Talk, previous silence duration: 100 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Short Word Duration: 20 [Jan 19 00:00:14] -- AMD: Channel [SIP/sgw2-000025d7]. Too long... [Jan 19 00:00:14] -- Executing [8369@default:3] AGI("SIP/sgw2-000025d7", "VD_amd.agi,8369") in new stack [Jan 19 00:00:14] -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi [Jan 19 00:00:14] == Manager 'sendcron' logged off from 127.0.0.1 2018-01-19 00:00:14|VD_amd.agi|Perl Environment Dump: 2018-01-19 00:00:14|VD_amd.agi|0|8369 2018-01-19 00:00:14|VD_amd.agi|AGI Environment Dump: 2018-01-19 00:00:14|VD_amd.agi| -- accountcode = 2018-01-19 00:00:14|VD_amd.agi| -- arg_1 = 8369 2018-01-19 00:00:14|VD_amd.agi| -- callerid = 2012156891 2018-01-19 00:00:14|VD_amd.agi| -- calleridname = V1190000110041535171 2018-01-19 00:00:14|VD_amd.agi| -- callingani2 = 0 2018-01-19 00:00:14|VD_amd.agi| -- callingpres = 0 2018-01-19 00:00:14|VD_amd.agi| -- callingtns = 0 2018-01-19 00:00:14|VD_amd.agi| -- callington = 0 2018-01-19 00:00:14|VD_amd.agi| -- channel = SIP/sgw2-000025d7 2018-01-19 00:00:14|VD_amd.agi| -- context = default 2018-01-19 00:00:14|VD_amd.agi| -- dnid = unknown 2018-01-19 00:00:14|VD_amd.agi| -- enhanced = 0.0 2018-01-19 00:00:14|VD_amd.agi| -- extension = 8369 2018-01-19 00:00:14|VD_amd.agi| -- language = en 2018-01-19 00:00:14|VD_amd.agi| -- priority = 3 2018-01-19 00:00:14|VD_amd.agi| -- rdnis = unknown 2018-01-19 00:00:14|VD_amd.agi| -- request = VD_amd.agi 2018-01-19 00:00:14|VD_amd.agi| -- threadid = 140476029679360 2018-01-19 00:00:14|VD_amd.agi| -- type = SIP 2018-01-19 00:00:14|VD_amd.agi| -- uniqueid = 1516338011.29057 2018-01-19 00:00:14|VD_amd.agi| -- version = 13.17.2-vici 2018-01-19 00:00:14|VD_amd.agi|AGI Variables: |1516338011.29057|SIP/sgw2-000025d7|8369|SIP|V1190000110041535171| 2018-01-19 00:00:14|VD_amd.agi|+++++ VD amd START : |41535171|2018-01-19 00:00:14||3|V1190000110041535171| 2018-01-19 00:00:14|VD_amd.agi|AAAAA AMD results: |NOTSURE|TOOLONG-5040| 2018-01-19 00:00:14|VD_amd.agi| AMD exiting [Jan 19 00:00:14] -- AGI Script VD_amd.agi completed, returning 0 [Jan 19 00:00:14] -- Executing [8369@default:4] AGI("SIP/sgw2-000025d7", "agi-VDAD_ALL_outbound.agi,NORMAL-----LB") in new stack [Jan 19 00:00:14] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi [Jan 19 00:00:15] == Manager 'sendcron' logged off from 127.0.0.1 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|Perl Environment Dump: 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|0|NORMAL-----LB 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|callerID changed: V1190000110041535171 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|AGI Environment Dump: 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- accountcode = 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- arg_1 = NORMAL-----LB 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- callerid = 2012156891 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- calleridname = V1190000110041535171 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- callingani2 = 0 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- callingpres = 0 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- callingtns = 0 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- callington = 0 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- channel = SIP/sgw2-000025d7 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- context = default 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- dnid = unknown 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- enhanced = 0.0 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- extension = 8369 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- language = en 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- priority = 4 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- rdnis = unknown 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- request = agi-VDAD_ALL_outbound.agi 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- threadid = 140476029679360 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- type = SIP 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- uniqueid = 1516338011.29057 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi| -- version = 13.17.2-vici 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|AGI Variables: |1516338011.29057|SIP/sgw2-000025d7|8369|SIP|V1190000110041535171|V1190000110041535171|4| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|+++++ VDAD START : |41535171|2018-01-19 00:00:15|13.12.2-vici|4| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|0|SELECT count(*) FROM vicidial_live_agents where callerid='V1190000110041535171';| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where callerid='V1190000110041535171' and status IN('LIVE','XFER');| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD : |1|update of vac table: V1190000110041535171 |UPDATE vicidial_auto_calls set uniqueid='1516338011.29057', channel='SIP/sgw2-000025d7',status='LIVE',stage='LIVE-0' where callerid='V1190000110041535171' order by call_time desc limit 1;| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi||UPDATE vicidial_list set status='PU' where lead_id='41535171' and status NOT IN('CBHOLD','CALLBK','QCFAIL','DBLCT');| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD vicidial_list PU update: |1|1516338011.29057| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|Preprocess time: |0.019 (1516338015.189303 - 1516338015.170219)| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi||INSERT INTO vicidial_log (uniqueid,lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed,alt_dial,list_id,comments,called_count) values('1516338011.29057','41535171','AMDTEST','2018-01-19 00:00:15','1516338015','QUEUE','1','2012105041','VDAD','N','NONE','9990999','0.019','1')| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD : |41535171|41535171|insert to vicidial_log: 1516338011.29057 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD VLE insert: |1| |INSERT INTO vicidial_log_extended set uniqueid='1516338011.29057',server_ip='192.168.200.7',call_date='2018-01-19 00:00:15',lead_id = '41535171',caller_code='V1190000110041535171',custom_call_id='';| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'AMDTEST' and last_update_time > '20180119000010';| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (1)| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and lead_id != '41535171' and campaign_id = 'AMDTEST' and call_time < "2018-01-19 00:00:11";| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |1|update of vla table: AMDTEST|192.168.200.7 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='41535171',uniqueid='1516338011.29057', channel='SIP/sgw2-000025d7', call_server_ip='192.168.200.7', callerid='V1190000110041535171',comments='AUTO' where status='READY' and lead_id<1 and ring_callerid='' and campaign_id='AMDTEST' and last_update_time > '20180119000010' order by random_id limit 1;| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi||SELECT conf_exten,user,extension,server_ip,ra_user FROM vicidial_live_agents where status IN('QUEUE','INCALL') and campaign_id='AMDTEST' and callerid='V1190000110041535171' and channel='SIP/sgw2-000025d7' order by last_call_time limit 1;| Setting CID ""V1190000110041535171 <2012105041>"" 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- REMOTE EXTEN LOG : |1|CID changed: "V1190000110041535171" <2012105041> |INSERT INTO vicidial_remote_agent_log set callerid='V1190000110041535171',uniqueid='1516338011.29057',ra_user='11000',user='11000',call_time=NOW(),extension='8300',lead_id='41535171',phone_number='2012105041',campaign_id='AMDTEST',processed='N';| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|-- VDAD XFER REMOTE: |1|update of vac table: V1190000110041535171 |UPDATE vicidial_auto_calls set status='XFER', stage='XFER-0', extension='192*168*200*007*8300' where callerid='V1190000110041535171';| 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|exiting the VDAD app, transferring call to 192*168*200*007*8300 2018-01-19 00:00:15|agi-VDAD_ALL_outbound.agi|XXXXXXXXXX VDAD transferred: start|stop 2018-01-19 00:00:15|2018-01-19 00:00:15 [Jan 19 00:00:15] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0 [Jan 19 00:00:15] -- Executing [192*168*200*007*8300@default:1] Goto("SIP/sgw2-000025d7", "default,8300,1") in new stack [Jan 19 00:00:15] -- Goto (default,8300,1) [Jan 19 00:00:15] -- Executing [8300@default:1] Hangup("SIP/sgw2-000025d7", "") in new stack [Jan 19 00:00:15] == Spawn extension (default, 8300, 1) exited non-zero on 'SIP/sgw2-000025d7' [Jan 19 00:00:15] -- Executing [h@default:1] AGI("SIP/sgw2-000025d7", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------------SIP 200 OK)") in new stack