Mysterious 15 Seconds Between Dials
Posted: Fri Sep 25, 2009 1:34 am
OK, so I've come here from a support section hoping to get to the bottom of this. It's not a support question really, possibly a bug. I'm not to big on what goes on in the depths of the VD scripts so I could be wrong but I'll explain what I'm seeing.
This is me calling 1111111111 (which is replacing an actual number) with the dial timeout at 10 seconds so the calls are going to NO ANSWER. Notice the 15 seconds between the end of one call and the next call. Can anyone please explain what happens after a NO ANSWER call?
"","0000000000","8368","default","""V0925113512000060204"" <0000000000>","Local/1111111111@default-6f57,1","","Dial","Local/1111111111@default","2009-09-25 11:35:22",,"2009-09-25 11:35:22",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113512000060204"" <0000000000>","Local/1111111111@default-6f57,2","IAX2/mytel-11139","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:35:12",,"2009-09-25 11:35:22",10,0,"NO ANSWER","DOCUMENTATION"
"","0000000000","8368","default","""V0925113537000060205"" <0000000000>","Local/1111111111@default-ce71,1","","Dial","Local/1111111111@default","2009-09-25 11:35:47",,"2009-09-25 11:35:47",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113537000060205"" <0000000000>","Local/1111111111@default-ce71,2","IAX2/mytel-11941","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:35:37",,"2009-09-25 11:35:47",10,0,"NO ANSWER","DOCUMENTATION"
"","0000000000","8368","default","""V0925113602000060206"" <0000000000>","Local/1111111111@default-552d,1","","Dial","Local/1111111111@default","2009-09-25 11:36:12",,"2009-09-25 11:36:12",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113602000060206"" <0000000000>","Local/1111111111@default-552d,2","IAX2/mytel-5430","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:36:02",,"2009-09-25 11:36:12",10,0,"NO ANSWER","DOCUMENTATION"
This is the matching asterisk events on high v.
-- Executing AGI("Local/0290372846@default-6f57,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 Dial("Local/1111111111@default-6f57,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-11139 is ringing
-- Hungup 'IAX2/mytel-11139'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-6f57,2'
-- Executing DeadAGI("Local/0290372846@default-6f57,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/1111111111@default-ce71,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 Dial("Local/1111111111@default-ce71,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-11941 is ringing
-- Hungup 'IAX2/mytel-11941'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-ce71,2'
-- Executing DeadAGI("Local/1111111111@default-ce71,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
== 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
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/0290372846@default-552d,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 Dial("Local/0290372846@default-552d,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-5430 is ringing
== 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
-- Hungup 'IAX2/mytel-5430'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-552d,2'
-- Executing DeadAGI("Local/0290372846@default-552d,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
Just to answer any campaign questions...
campaign_id TESTCAMP
campaign_name TESTCAMP
active Y
dial_status_a
dial_status_b
dial_status_c
dial_status_d
dial_status_e
lead_order DOWN
park_ext
park_file_name
web_form_address
allow_closers Y
hopper_level 100
auto_dial_level 1
next_agent_call oldest_call_finish
local_call_time 24hours
voicemail_ext
dial_timeout 10
dial_prefix X
campaign_cid
campaign_vdad_exten 8368
campaign_rec_exten 8309
campaign_recording ONDEMAND
campaign_rec_filename FULLDATE_CUSTPHONE
campaign_script
get_call_launch WEBFORM
am_message_exten
amd_send_to_vmx N
xferconf_a_dtmf
xferconf_a_number
xferconf_b_dtmf
xferconf_b_number
alt_number_dialing N
scheduled_callbacks Y
lead_filter_id NONE
drop_call_seconds 5
drop_action MESSAGE
safe_harbor_exten 8307
display_dialable_count Y
wrapup_seconds 0
wrapup_message Wrapup Call
closer_campaigns
use_internal_dnc Y
allcalls_delay 0
omit_phone_code Y
dial_method RATIO
available_only_ratio_tally N
adaptive_dropped_percentage 3
adaptive_maximum_level 3.0
adaptive_latest_server_time 2100
adaptive_intensity 0
adaptive_dl_diff_target 0
concurrent_transfers AUTO
auto_alt_dial ALT_ONLY
auto_alt_dial_statuses B N NA DC -
agent_pause_codes_active Y
campaign_description Test campaign for ViciDial
campaign_changedate 2009-09-25 11:34:31
campaign_stats_refresh N
campaign_logindate 2009-09-25 11:36:15
dial_statuses NEW -
disable_alter_custdata N
no_hopper_leads_logins Y
list_order_mix DISABLED
campaign_allow_inbound Y
manual_dial_list_id 998
default_xfer_group ---NONE---
xfer_groups
queue_priority 50
drop_inbound_group ---NONE---
qc_enabled N
qc_statuses NULL
qc_lists NULL
qc_shift_id 24HRMIDNIGHT
qc_get_record_launch NONE
qc_show_recording Y
qc_web_form_address NULL
qc_script NULL
survey_first_audio_file US_pol_survey_hello
survey_dtmf_digits 1238
survey_ni_digit 8
survey_opt_in_audio_file US_pol_survey_transfer
survey_ni_audio_file US_thanks_no_contact
survey_method AGENT_XFER
survey_no_response_action OPTIN
survey_ni_status NI
survey_response_digit_map 1-DEMOCRAT|2-REPUBLICAN|3-INDEPENDANT|8-OPTOUT|X-NO RESPONSE|
survey_xfer_exten 8300
survey_camp_record_dir /home/survey
disable_alter_custphone Y
display_queue_count Y
manual_dial_filter NONE
agent_clipboard_copy NONE
agent_extended_alt_dial N
use_campaign_dnc N
three_way_call_cid CAMPAIGN
three_way_dial_prefix
web_form_target _blank
vtiger_search_category LEAD
vtiger_create_call_record Y
vtiger_create_lead_record Y
vtiger_screen_login Y
cpd_amd_action DISABLED
agent_allow_group_alias N
default_group_alias
This is me calling 1111111111 (which is replacing an actual number) with the dial timeout at 10 seconds so the calls are going to NO ANSWER. Notice the 15 seconds between the end of one call and the next call. Can anyone please explain what happens after a NO ANSWER call?
"","0000000000","8368","default","""V0925113512000060204"" <0000000000>","Local/1111111111@default-6f57,1","","Dial","Local/1111111111@default","2009-09-25 11:35:22",,"2009-09-25 11:35:22",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113512000060204"" <0000000000>","Local/1111111111@default-6f57,2","IAX2/mytel-11139","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:35:12",,"2009-09-25 11:35:22",10,0,"NO ANSWER","DOCUMENTATION"
"","0000000000","8368","default","""V0925113537000060205"" <0000000000>","Local/1111111111@default-ce71,1","","Dial","Local/1111111111@default","2009-09-25 11:35:47",,"2009-09-25 11:35:47",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113537000060205"" <0000000000>","Local/1111111111@default-ce71,2","IAX2/mytel-11941","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:35:37",,"2009-09-25 11:35:47",10,0,"NO ANSWER","DOCUMENTATION"
"","0000000000","8368","default","""V0925113602000060206"" <0000000000>","Local/1111111111@default-552d,1","","Dial","Local/1111111111@default","2009-09-25 11:36:12",,"2009-09-25 11:36:12",0,0,"FAILED","DOCUMENTATION"
"","0000000000","1111111111","default","""V0925113602000060206"" <0000000000>","Local/1111111111@default-552d,2","IAX2/mytel-5430","Dial","IAX2/mytel/1111111111||tTor","2009-09-25 11:36:02",,"2009-09-25 11:36:12",10,0,"NO ANSWER","DOCUMENTATION"
This is the matching asterisk events on high v.
-- Executing AGI("Local/0290372846@default-6f57,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 Dial("Local/1111111111@default-6f57,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-11139 is ringing
-- Hungup 'IAX2/mytel-11139'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-6f57,2'
-- Executing DeadAGI("Local/0290372846@default-6f57,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/1111111111@default-ce71,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 Dial("Local/1111111111@default-ce71,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-11941 is ringing
-- Hungup 'IAX2/mytel-11941'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-ce71,2'
-- Executing DeadAGI("Local/1111111111@default-ce71,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
== 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
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing AGI("Local/0290372846@default-552d,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 Dial("Local/0290372846@default-552d,2", "IAX2/mytel/1111111111||tTor") in new stack
-- Called mytel/1111111111
-- Call accepted by 203.166.103.242 (format ulaw)
-- Format for call is ulaw
-- IAX2/mytel-5430 is ringing
== 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
-- Hungup 'IAX2/mytel-5430'
== Spawn extension (default, 1111111111, 2) exited non-zero on 'Local/1111111111@default-552d,2'
-- Executing DeadAGI("Local/0290372846@default-552d,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0-----CANCEL----------") in new stack
== Manager 'sendcron' logged off from 127.0.0.1
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
Just to answer any campaign questions...
campaign_id TESTCAMP
campaign_name TESTCAMP
active Y
dial_status_a
dial_status_b
dial_status_c
dial_status_d
dial_status_e
lead_order DOWN
park_ext
park_file_name
web_form_address
allow_closers Y
hopper_level 100
auto_dial_level 1
next_agent_call oldest_call_finish
local_call_time 24hours
voicemail_ext
dial_timeout 10
dial_prefix X
campaign_cid
campaign_vdad_exten 8368
campaign_rec_exten 8309
campaign_recording ONDEMAND
campaign_rec_filename FULLDATE_CUSTPHONE
campaign_script
get_call_launch WEBFORM
am_message_exten
amd_send_to_vmx N
xferconf_a_dtmf
xferconf_a_number
xferconf_b_dtmf
xferconf_b_number
alt_number_dialing N
scheduled_callbacks Y
lead_filter_id NONE
drop_call_seconds 5
drop_action MESSAGE
safe_harbor_exten 8307
display_dialable_count Y
wrapup_seconds 0
wrapup_message Wrapup Call
closer_campaigns
use_internal_dnc Y
allcalls_delay 0
omit_phone_code Y
dial_method RATIO
available_only_ratio_tally N
adaptive_dropped_percentage 3
adaptive_maximum_level 3.0
adaptive_latest_server_time 2100
adaptive_intensity 0
adaptive_dl_diff_target 0
concurrent_transfers AUTO
auto_alt_dial ALT_ONLY
auto_alt_dial_statuses B N NA DC -
agent_pause_codes_active Y
campaign_description Test campaign for ViciDial
campaign_changedate 2009-09-25 11:34:31
campaign_stats_refresh N
campaign_logindate 2009-09-25 11:36:15
dial_statuses NEW -
disable_alter_custdata N
no_hopper_leads_logins Y
list_order_mix DISABLED
campaign_allow_inbound Y
manual_dial_list_id 998
default_xfer_group ---NONE---
xfer_groups
queue_priority 50
drop_inbound_group ---NONE---
qc_enabled N
qc_statuses NULL
qc_lists NULL
qc_shift_id 24HRMIDNIGHT
qc_get_record_launch NONE
qc_show_recording Y
qc_web_form_address NULL
qc_script NULL
survey_first_audio_file US_pol_survey_hello
survey_dtmf_digits 1238
survey_ni_digit 8
survey_opt_in_audio_file US_pol_survey_transfer
survey_ni_audio_file US_thanks_no_contact
survey_method AGENT_XFER
survey_no_response_action OPTIN
survey_ni_status NI
survey_response_digit_map 1-DEMOCRAT|2-REPUBLICAN|3-INDEPENDANT|8-OPTOUT|X-NO RESPONSE|
survey_xfer_exten 8300
survey_camp_record_dir /home/survey
disable_alter_custphone Y
display_queue_count Y
manual_dial_filter NONE
agent_clipboard_copy NONE
agent_extended_alt_dial N
use_campaign_dnc N
three_way_call_cid CAMPAIGN
three_way_dial_prefix
web_form_target _blank
vtiger_search_category LEAD
vtiger_create_call_record Y
vtiger_create_lead_record Y
vtiger_screen_login Y
cpd_amd_action DISABLED
agent_allow_group_alias N
default_group_alias