vicidial.org

by **scratchspace** » Tue Oct 31, 2006 1:24 pm

I've setup a voicemail extension and confirmed it's working properly. Our Inbound campaign has worked fine up to this point, but we can't seem to get calls to route to the voicemail extension instead of dropping. Changes to the "Drop Call Seconds" are being recognized, but the voicemail extension is being ignored. Within the "In-Group" configuration screen, I have set "Use Drop Message" to no, which according to the help file is supposed to enable (or not override) the voicemail extension. Do I need to restart some processes or do something at the campaign level to enable this feature?

by **mflorell** » Tue Oct 31, 2006 4:06 pm

Can you post AGI output (/var/log/astguiclient/agiout) when this happens as well as Asterisk CLI output?

by **scratchspace** » Wed Nov 01, 2006 6:32 pm

It's a vicidial 1.x system, here's the output from listen.2006-11-01

2006-11-01 18:13:28|Event: Newchannel
Privilege: call,all
Channel: SIP/customer-09dc9030
State: Ring
CallerID: 4085551212
CallerIDName: <unknown>
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newchannel
Privilege: call,all
Channel: SIP/customer-09dc9030
State: Ring
CallerID: 4085551212
CallerIDName: <unknown>
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 1
Application: NoOp
AppData: Received incoming SIP connection from unknown peer to 8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 2
Application: Set
AppData: DID=8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 3
Application: Goto
AppData: s|1
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: s
Priority: 1
Application: GotoIf
AppData: 1?from-trunk|8005552121|1
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 1
Application: NoOp
AppData: Received incoming SIP connection from unknown peer to 8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 2
Application: Set
AppData: DID=8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: 8005552121
Priority: 3
Application: Goto
AppData: s|1
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-sip-external
Extension: s
Priority: 1
Application: GotoIf
AppData: 1?from-trunk|8005552121|1
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 1
Application: Set
AppData: FROM_DID=8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 2
Application: Set
AppData: FAX_RX=disabled
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 3
Application: Goto
AppData: custom-vicidial-inbound|s|1
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: custom-vicidial-inbound
Extension: s
Priority: 1
Application: Answer
AppData:
Uniqueid: 1162422863.66062

Event: Newstate
Privilege: call,all
Channel: SIP/customer-09dc9030
State: Up
CallerID: 4085551212
CallerIDName: <unknown>
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 1
Application: Set
AppData: FROM_DID=8005552121
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 2
Application: Set
AppData: FAX_RX=disabled
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: from-trunk
Extension: 8005552121
Priority: 3
Application: Goto
AppData: custom-vicidial-inbound|s|1
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: custom-vicidial-inbound
Extension: s
Priority: 1
Application: Answer
AppData:
Uniqueid: 1162422863.66062

Event: Newstate
Privilege: call,all
Channel: SIP/customer-09dc9030
State: Up
CallerID: 4085551212
CallerIDName: <unknown>
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: custom-vicidial-inbound
Extension: s
Priority: 2
Application: AGI
AppData: agi-VDADcloser_inboundANI.agi|CL_ABC_L-----8005552121-----Closer-----park----------900-----1
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: custom-vicidial-inbound
Extension: s
Priority: 2
Application: AGI
AppData: agi-VDADcloser_inboundANI.agi|CL_ABC_L-----8005552121-----Closer-----park----------900-----1
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newcallerid
Privilege: call,all
Channel: SIP/customer-09dc9030
CallerID: <Unknown>
CallerIDName: Y1101181423000270795
Uniqueid: 1162422863.66062
CID-CallingPres: 0 (Presentation Allowed, Not Screened)

|
2006-11-01 18:13:33|Event: Newcallerid
Privilege: call,all
Channel: SIP/customer-09dc9030
CallerID: <Unknown>
CallerIDName: Y1101181423000270795
Uniqueid: 1162422863.66062
CID-CallingPres: 0 (Presentation Allowed, Not Screened)

|
2006-11-01 18:13:33|Event: PeerStatus
Privilege: system,all
Peer: SIP/130
PeerStatus: Registered

|
2006-11-01 18:13:28|Event: PeerStatus
Privilege: system,all
Peer: SIP/130
PeerStatus: Registered

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 1
Application: Answer
AppData:
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 1
Application: Answer
AppData:
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 2
Application: Playback
AppData: vm-goodbye
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 2
Application: Playback
AppData: vm-goodbye
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 3
Application: Hangup
AppData:
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: 8307
Priority: 3
Application: Hangup
AppData:
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: h
Priority: 1
Application: DeadAGI
AppData: call_log.agi|h
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: h
Priority: 2
Application: DeadAGI
AppData: VD_hangup.agi|PRI-----NODEBUG-----16---------------
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:28|Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: h
Priority: 1
Application: DeadAGI
AppData: call_log.agi|h
Uniqueid: 1162422863.66062

Event: Newexten
Privilege: call,all
Channel: SIP/customer-09dc9030
Context: default
Extension: h
Priority: 2
Application: DeadAGI
AppData: VD_hangup.agi|PRI-----NODEBUG-----16---------------
Uniqueid: 1162422863.66062

|
2006-11-01 18:13:33|Event: Hangup
Privilege: call,all
Channel: SIP/customer-09dc9030
Uniqueid: 1162422863.66062
Cause: 16
Cause-txt: Normal Clearing

|
2006-11-01 18:13:28|Event: Hangup
Privilege: call,all
Channel: SIP/customer-09dc9030
Uniqueid: 1162422863.66062
Cause: 16
Cause-txt: Normal Clearing

|

by **scratchspace** » Thu Nov 02, 2006 4:09 pm

Any thoughts? Looks like the call is just getting transferred to the drop extension. Am I missing some setting in the in-groups or campaign settings that is overriding the voicemail extension?

by **mflorell** » Thu Nov 02, 2006 5:20 pm

You didn't post AGIoutput or Asterisk CLI output so I can't tell you much.

by **scratchspace** » Thu Nov 02, 2006 8:06 pm

Sorry about the missing CLI, but I don't have an agiout log, similar output seems to be prvided in listen.YYYY-MM-DD. This box is runninbg vicidial 1.x. If I'm missing something, let me know.

== 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
-- Executing NoOp("SIP/customer-b7300470", "Received incoming SIP connection from unknown peer to 8005552121") in new stack
-- Executing Set("SIP/customer-b7300470", "DID=8005552121") in new stack
-- Executing Goto("SIP/customer-b7300470", "s|1") in new stack
-- Goto (from-sip-external,s,1)
-- Executing GotoIf("SIP/customer-b7300470", "1?from-trunk|8005552121|1") in new stack
-- Goto (from-trunk,8005552121,1)
-- Executing Set("SIP/customer-b7300470", "FROM_DID=8005552121") in new stack
-- Executing Set("SIP/customer-b7300470", "FAX_RX=disabled") in new stack
-- Executing Goto("SIP/customer-b7300470", "custom-vicidial-inbound|s|1") in new stack
-- Goto (custom-vicidial-inbound,s,1)
-- Executing Answer("SIP/customer-b7300470", "") in new stack
-- Executing AGI("SIP/customer-b7300470", "agi-VDADcloser_inboundANI.agi|CL_ABC_L-----8005552121-----Closer-----park----------900-----1") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundANI.agi
Perl Environment Dump:
0|CL_ABC_L-----8005552121-----Closer-----park----------900-----1|
AGI Environment Dump:
-- accountcode =
-- callerid = 4085551212
-- calleridname = unknown
-- callingani2 = 0
-- callingpres = 0
-- callingtns = 0
-- callington = 0
-- channel = SIP/customer-b7300470
-- context = custom-vicidial-inbound
-- dnid = 8005552121
-- enhanced = 0.0
-- extension = s
-- language = en
-- priority = 2
-- rdnis = unknown
-- request = agi-VDADcloser_inboundANI.agi
-- type = SIP
-- uniqueid = 1162515185.1166

INBOUND CALL VDCL STARTED
+++++ INBOUND CALL VDCL STARTED : |1162515185.1166|CL_ABC_L|SIP/customer-b7300470|s|SIP|4085551212-8005552121|2006-11-02 19:53:05
AGI Environment Dump: |1162515185.1166|SIP/customer-b7300470|s|SIP|4085551212|

INBOUND CALL RECEIVED IN FROM VDCL: 4085551212 SIP/customer-b7300470
+++++ INBOUND VDCL START : |1162515185.1166|SIP/customer-b7300470|s|SIP|2006-11-02 19:53: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-11-02 19:53:05','20061102195305','INBND','8005552121','CL_ABC_L','VDCL','900','Y','1','4085551212','CL_ABC_L','1','-5.00','');|270814|
FAIL (unexpected result '')
callerID changed: Y1102195305000270814

|INSERT INTO vicidial_auto_calls values('','10.0.10.50','CL_ABC_L','LIVE','270814','1162515185.1166','Y1102195305000270814','SIP/customer-b7300470','1','4085551212','2006-11-02 19:53:05','IN')|

|INSERT INTO vicidial_closer_log (lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed) values('270814','CL_ABC_L','2006-11-02 19:53:05','1162515185','QUEUE','1','4085551212','VDCL','N')|
-- VDCL : |270814|insert to vicidial_closer_log

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|0|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|1|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|2|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|3|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|4|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|5|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|6|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|7|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|8|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|9|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

|SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='10.0.10.50' and campaign_id = 'CL_ABC_L' and call_time < "2006-11-02 19:53:05" and lead_id != '270814';|
-- VDAD get agent: |10|10|0|update of vla table: CL_ABC_L|10.0.10.50
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='270814',uniqueid='1162515185.1166', channel='SIP/customer-b7300470', callerid='Y1102195305000270814' where status IN('CLOSER','READY') and server_ip='10.0.10.50' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% CL_ABC_L %" and last_update_time > '19691231185955' order by random_id limit 1;|

exiting the VDAD app, transferring call to 8307
FAIL (unexpected result '')
FAIL (unexpected result '')
FAIL (unexpected result '')
-- VDCL vac record deleted: |1| CL_ABC_L|10.0.10.50

|UPDATE vicidial_closer_log set status='DROP',end_epoch='1162515202',length_in_sec='10' where lead_id = '270814' order by start_epoch desc limit 1;|
-- VDCL vicidial_closer_log update: |1|270814

|UPDATE vicidial_list set status='XDROP' where lead_id = '270814';|
-- VDCL vicidial_list update: |1|270814
-- AGI Script agi-VDADcloser_inboundANI.agi completed, returning 0
-- Executing Answer("SIP/customer-b7300470", "") in new stack
-- Executing Playback("SIP/customer-b7300470", "vm-goodbye") in new stack
-- Playing 'vm-goodbye' (language 'en')
-- Executing Hangup("SIP/customer-b7300470", "") in new stack
== Spawn extension (default, 8307, 3) exited non-zero on 'SIP/customer-b7300470'
-- Executing DeadAGI("SIP/customer-b7300470", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
AGI Environment Dump:
-- accountcode =
-- callerid = unknown
-- calleridname = Y1102195305000270814
-- callingani2 = 0
-- callingpres = 0
-- callingtns = 0
-- callington = 0
-- channel = SIP/customer-b7300470
-- context = default
-- dnid = 8005552121
-- enhanced = 0.0
-- extension = h
-- language = en
-- priority = 1
-- rdnis = unknown
-- request = call_log.agi
-- type = SIP
-- uniqueid = 1162515185.1166
AGI Environment Dump: |1162515185.1166|SIP/customer-b7300470|h|SIP|Y1102195305000270814|

CALL HUNG UP

CONNECTED
+++++ CALL LOG HUNGUP: |1162515185.1166|SIP/customer-b7300470|h|2006-11-02 19:53:23|min: |

All Done Exiting:
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("SIP/customer-b7300470", "VD_hangup.agi|PRI-----NODEBUG-----16---------------") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Perl Environment Dump:
0|PRI-----NODEBUG-----16---------------|
AGI Environment Dump:
-- accountcode =
-- callerid = unknown
-- calleridname = Y1102195305000270814
-- callingani2 = 0
-- callingpres = 0
-- callingtns = 0
-- callington = 0
-- channel = SIP/customer-b7300470
-- context = default
-- dnid = 8005552121
-- enhanced = 0.0
-- extension = h
-- language = en
-- priority = 2
-- rdnis = unknown
-- request = VD_hangup.agi
-- type = SIP
-- uniqueid = 1162515185.1166
AGI Environment Dump: |1162515185.1166|SIP/customer-b7300470|h|SIP|Y1102195305000270814|Y1102195305000270814|2|

DEBUG: NODEBUG

VD_hangup : Y1102195305000270814 SIP/customer-b7300470 2
+++++ VD hangup START : |1162515185.1166|SIP/customer-b7300470|h|SIP|Y1102195305000270814|270814|2006-11-02 19:53:24||2|Y1102195305000270814|

|SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1162515185.1166' limit 1;|

VD hangup: no VDAC record found: 1162515185.1166 Y1102195305000270814
-- AGI Script VD_hangup.agi completed, returning 0
== Parsing '/etc/asterisk/manager.conf': Found
== Parsing '/etc/asterisk/vicidial_manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1

by **mflorell** » Thu Nov 02, 2006 11:09 pm

First, I would recommend upgrading to 2.0.1.

Could you post the vicidial_live_agents records when this happens, because it seems that there are no available agents that are logged into a CLOSER campaign with the selected inbound group.

by **scratchspace** » Fri Nov 03, 2006 11:50 am

An upgrade is on the roadmap... An agent was logged in at the time, but "paused". The reason we setup voicemail for this campaign is to catch calls when no agents are logged in, or agents are on other calls. Am I misunderstanding the purpose of this feature?

I can't test this at the moment because the system is live with callers, but I can post vicidial_live_agents if needed.

by **scratchspace** » Tue Nov 07, 2006 12:31 am

by **mflorell** » Tue Nov 07, 2006 3:59 pm

Could you post your vicidial_campaigns settings and vicidial_inbound_groups settings for these calls?

by **scratchspace** » Tue Nov 28, 2006 1:18 am

Sorry for the late reply. Vicidial 2.0 is also behaving the same as in 1.x. Here is the info you requested.

mysql> select * from vicidial_campaigns where campaign_id='ATCLOSER';
+-------------+-----------------------------+--------+---------------+---------------+---------------+---------------+---------------+------------+----------+----------------+------------------+---------------+--------------+-----------------+-----------------+-----------------+---------------+--------------+-------------+--------------+---------------------+--------------------+--------------------+-----------------------+-----------------+-----------------+------------------+-----------------+-----------------+-------------------+-----------------+-------------------+--------------------+---------------------+----------------+-------------------+---------------------+-------------------+------------------------+----------------+----------------+------------------+------------------+----------------+-----------------+-------------+----------------------------+-----------------------------+------------------------+-----------------------------+--------------------+-------------------------+
| campaign_id | campaign_name | active | dial_status_a | dial_status_b | dial_status_c | dial_status_d | dial_status_e | lead_order | park_ext | park_file_name | web_form_address | allow_closers | hopper_level | auto_dial_level | next_agent_call | local_call_time | voicemail_ext | dial_timeout | dial_prefix | campaign_cid | campaign_vdad_exten | campaign_rec_exten | campaign_recording | campaign_rec_filename | campaign_script | get_call_launch | am_message_exten | amd_send_to_vmx | xferconf_a_dtmf | xferconf_a_number | xferconf_b_dtmf | xferconf_b_number | alt_number_dialing | scheduled_callbacks | lead_filter_id | drop_call_seconds | safe_harbor_message | safe_harbor_exten | display_dialable_count | wrapup_seconds | wrapup_message | closer_campaigns | use_internal_dnc | allcalls_delay | omit_phone_code | dial_method | available_only_ratio_tally | adaptive_dropped_percentage | adaptive_maximum_level | adaptive_latest_server_time | adaptive_intensity | adaptive_dl_diff_target |
+-------------+-----------------------------+--------+---------------+---------------+---------------+---------------+---------------+------------+----------+----------------+------------------+---------------+--------------+-----------------+-----------------+-----------------+---------------+--------------+-------------+--------------+---------------------+--------------------+--------------------+-----------------------+-----------------+-----------------+------------------+-----------------+-----------------+-------------------+-----------------+-------------------+--------------------+---------------------+----------------+-------------------+---------------------+-------------------+------------------------+----------------+----------------+------------------+------------------+----------------+-----------------+-------------+----------------------------+-----------------------------+------------------------+-----------------------------+--------------------+-------------------------+
| ABCLOSER | ABC Closer/Inbound Campaign | Y | NEW | | | | | DOWN | 8301 | park | | Y | 1 | 1 | random | 24hours | | 60 | 9 | | 8365 | 8309 | ONDEMAND | FULLDATE_CUSTPHONE | | NONE | | N | | | | | N | N | NONE | 5 | N | 8307 | Y | 0 | Wrapup Call | CL_ABC_L - | N | 0 | N | RATIO | N | 5 | 3.0 | 2100 | 0 | 0 |
+-------------+-----------------------------+--------+---------------+---------------+---------------+---------------+---------------+------------+----------+----------------+------------------+---------------+--------------+-----------------+-----------------+-----------------+---------------+--------------+-------------+--------------+---------------------+--------------------+--------------------+-----------------------+-----------------+-----------------+------------------+-----------------+-----------------+-------------------+-----------------+-------------------+--------------------+---------------------+----------------+-------------------+---------------------+-------------------+------------------------+----------------+----------------+------------------+------------------+----------------+-----------------+-------------+----------------------------+-----------------------------+------------------------+-----------------------------+--------------------+-------------------------+

mysql> select * from vicidial_inbound_groups;
+----------+------------+-------------+--------+-------------------------------------------+---------------+-----------------+-----------------+----------------+-----------------+-----------------+-------------------+-----------------+-------------------+-------------------+--------------+------------+
| group_id | group_name | group_color | active | web_form_address | voicemail_ext | next_agent_call | fronter_display | ingroup_script | get_call_launch | xferconf_a_dtmf | xferconf_a_number | xferconf_b_dtmf | xferconf_b_number | drop_call_seconds | drop_message | drop_exten |
+----------+------------+-------------+--------+-------------------------------------------+---------------+-----------------+-----------------+----------------+-----------------+-----------------+-------------------+-----------------+-------------------+-------------------+--------------+------------+
| CL_ABC_L | IN_ABC | blue | Y | http://192.168.1.1/popup.php | 200 | random | Y | | WEBFORM | | | | | 60 | N | 8307 |
+----------+------------+-------------+--------+-------------------------------------------+---------------+-----------------+-----------------+----------------+-----------------+-----------------+-------------------+-----------------+-------------------+-------------------+--------------+------------+

by **scratchspace** » Tue Dec 05, 2006 12:04 am

Any thoughts on what might be causing this? Everything looks correct, I just can't figure out why the calls aren't transferred as configured.

by **mflorell** » Tue Dec 05, 2006 8:59 am

I cannot test this at this time. Let me know how your upgrade goes and if that fixes your problems. I will try to duplicate these settings on the current release next week.

by **gerski** » Tue Dec 12, 2006 9:53 am

Hello,

im having problem also in inbound voicemail here is my config:

extensions.conf:

Code: Select all: exten => _8888,1,Ringing ; call ringing exten => _8888,2,Wait(1) ; Wait 5 second for CallerID delivery from IAX exten => _8888,3,Answer ; Answer the line exten => _8888,3,AGI(agi-VDADcloser_inboundCIDlookup20.agi,8888-----8888-----C$ exten => _8888,3,Voicemail,u688

i have edited my agi after 20 seconds it will drop the call, and will go to voicemail.. but it seems like it does not executing the voicemail

here is my asterisk CLI

Code: Select all: -- Executing Ringing("SIP/9999-b6406388", "") in new stack -- Executing Wait("SIP/9999-b6406388", "1") in new stack -- Executing Answer("SIP/9999-b6406388", "") in new stack -- Executing AGI("SIP/9999-b6406388", "agi-VDADcloser_inboundCIDlookup20.agi|8888-----8888-----Closer---------------999-----1") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup20.agi == 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 -- AGI Script agi-VDADcloser_inboundCIDlookup20.agi completed, returning 0 -- Executing Answer("SIP/9999-b6406388", "") in new stack -- Executing Playback("SIP/9999-b6406388", "vm-goodbye") in new stack -- Playing 'vm-goodbye' (language 'en') -- Executing Hangup("SIP/9999-b6406388", "") in new stack == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/9999-b6406388' -- Executing DeadAGI("SIP/9999-b6406388", "call_log.agi|h") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi -- AGI Script call_log.agi completed, returning 0 -- Executing DeadAGI("SIP/9999-b6406388", "VD_hangup.agi|PRI-----NODEBUG-----16---------------") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi -- AGI Script VD_hangup.agi completed, returning 0

here is my agi out

Code: Select all: 2006-12-12 22:32:42|VD_hangup.agi|Perl Environment Dump: 2006-12-12 22:32:42|VD_hangup.agi|0|PRI-----NODEBUG-----16--------------- 2006-12-12 22:32:42|VD_hangup.agi|AGI Environment Dump: 2006-12-12 22:32:42|VD_hangup.agi| -- accountcode = 2006-12-12 22:32:42|VD_hangup.agi| -- callerid = unknown 2006-12-12 22:32:42|VD_hangup.agi| -- calleridname = Y1212223157000403986 2006-12-12 22:32:42|VD_hangup.agi| -- callingani2 = 0 2006-12-12 22:32:42|VD_hangup.agi| -- callingpres = 0 2006-12-12 22:32:42|VD_hangup.agi| -- callingtns = 0 2006-12-12 22:32:42|VD_hangup.agi| -- callington = 0 2006-12-12 22:32:42|VD_hangup.agi| -- channel = SIP/9999-b6406388 2006-12-12 22:32:42|VD_hangup.agi| -- context = default 2006-12-12 22:32:42|VD_hangup.agi| -- dnid = 8888 2006-12-12 22:32:42|VD_hangup.agi| -- enhanced = 0.0 2006-12-12 22:32:42|VD_hangup.agi| -- extension = h 2006-12-12 22:32:42|VD_hangup.agi| -- language = en 2006-12-12 22:32:42|VD_hangup.agi| -- priority = 2 2006-12-12 22:32:42|VD_hangup.agi| -- rdnis = unknown 2006-12-12 22:32:42|VD_hangup.agi| -- request = VD_hangup.agi 2006-12-12 22:32:42|VD_hangup.agi| -- type = SIP 2006-12-12 22:32:42|VD_hangup.agi| -- uniqueid = 1165933916.13407 2006-12-12 22:32:42|VD_hangup.agi|AGI Variables: |1165933916.13407|SIP/9999-b6406388|h|SIP|Y1212223157000403986| 2006-12-12 22:32:42|VD_hangup.agi|DEBUG: NODEBUG 2006-12-12 22:32:42|VD_hangup.agi|VD_hangup : Y1212223157000403986 SIP/9999-b6406388 2 403986 2006-12-12 22:32:42|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1165933916.13407' limit 1;| 2006-12-12 22:32:42|VD_hangup.agi|VD hangup: no VDAC record found: 1165933916.13407 Y1212223157000403986

also this:

Code: Select all: 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='192. 168.0.20' and campaign_id = '8888' and call_time < "2006-12-12 22:31:57" and lead_id != '403986';| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead _id='403986',uniqueid='1165933916.13407', channel='SIP/9999-b6406388', callerid='Y1212223157000403986' where status IN('CLOSER','READY') and server_ip='192.168.0.20' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order b y last_call_finish limit 1;| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='192. 168.0.20' and campaign_id = '8888' and call_time < "2006-12-12 22:31:57" and lead_id != '403986';| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead _id='403986',uniqueid='1165933916.13407', channel='SIP/9999-b6406388', callerid='Y1212223157000403986' where status IN('CLOSER','READY') and server_ip='192.168.0.20' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order b y last_call_finish limit 1;| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='192. 168.0.20' and campaign_id = '8888' and call_time < "2006-12-12 22:31:57" and lead_id != '403986';| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead _id='403986',uniqueid='1165933916.13407', channel='SIP/9999-b6406388', callerid='Y1212223157000403986' where status IN('CLOSER','READY') and server_ip='192.168.0.20' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order b y last_call_finish limit 1;| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='192. 168.0.20' and campaign_id = '8888' and call_time < "2006-12-12 22:31:57" and lead_id != '403986';| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead _id='403986',uniqueid='1165933916.13407', channel='SIP/9999-b6406388', callerid='Y1212223157000403986' where status IN('CLOSER','READY') and server_ip='192.168.0.20' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order b y last_call_finish limit 1;| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='192. 168.0.20' and campaign_id = '8888' and call_time < "2006-12-12 22:31:57" and lead_id != '403986';| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead _id='403986',uniqueid='1165933916.13407', channel='SIP/9999-b6406388', callerid='Y1212223157000403986' where status IN('CLOSER','READY') and server_ip='192.168.0.20' and campaign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order b y last_call_finish limit 1;| 2006-12-12 22:31:57|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2006-12-12 22:32:41|agi-VDADcloser_inboundCIDlookup20.agi|exiting the VDAD app, transferring call to 8307 2006-12-12 22:32:41|agi-VDADcloser_inboundCIDlookup20.agi|-- VDCL vac record deleted: |1| | 2006-12-12 22:32:41|agi-VDADcloser_inboundCIDlookup20.agi|-- VDCL vcl update: |1|403986| |UPDATE vicidial_closer_log set status='DROP',end_epoch='1165933961',length_in_sec='30',queue_seconds='30' where lead_id = '403986' order by start_epoch desc limit 1;| 2006-12-12 22:32:41|agi-VDADcloser_inboundCIDlookup20.agi|-- VDCL vl update: |1|403986| |UPDATE vicidial_list set status='XDROP' where lead_id = '403986';|

by **mflorell** » Tue Dec 12, 2006 11:57 am

Finally had a chance to debug this and yes it is a bug(Thanks for finding it).

It's a really simple and stupid bug, a single line in most of the closer AGI scripts:
if ($drop_message=='Y')
changed to
if ($drop_message =~ /Y/)

There is also a similar bug for safe_harbor in the transfer agi scripts.

All have been fixed in SVN and will be fixed for the 2.0.2 release

Thanks again!

by **gerski** » Tue Dec 12, 2006 12:02 pm

ok thanks a lot matt...

by **gerski** » Tue Dec 12, 2006 12:10 pm

still not working matt..

here is my asterisk CLI

Code: Select all: -- Executing Ringing("SIP/9999-b640b1a8", "") in new stack -- Executing Wait("SIP/9999-b640b1a8", "1") in new stack -- Executing Answer("SIP/9999-b640b1a8", "") in new stack -- Executing AGI("SIP/9999-b640b1a8", "agi-VDADcloser_inboundCIDlookup20.agi|8888-----8888-----Closer---------------999-----1") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup20.agi == 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 -- AGI Script agi-VDADcloser_inboundCIDlookup20.agi completed, returning 0 -- Executing Answer("SIP/9999-b640b1a8", "") in new stack -- Executing Playback("SIP/9999-b640b1a8", "vm-goodbye") in new stack -- Playing 'vm-goodbye' (language 'en') -- Executing Hangup("SIP/9999-b640b1a8", "") in new stack == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/9999-b640b1a8' -- Executing DeadAGI("SIP/9999-b640b1a8", "call_log.agi|h") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi -- AGI Script call_log.agi completed, returning 0 -- Executing DeadAGI("SIP/9999-b640b1a8", "VD_hangup.agi|PRI-----NODEBUG-----16---------------") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi -- AGI Script VD_hangup.agi completed, returning 0

here is wt i edited:

Code: Select all: $DROPexten = ''; if ($drop_seconds < 1) { $drop_seconds = $DROP_TIME; if ($drop_message =~/Y/) { $DROPexten = "$drop_exten"; } else { $cbc=0;

by **mflorell** » Tue Dec 12, 2006 1:56 pm

I have tested it on two different systems with a drop message and without(so it sent to voicemail) and it works with both systems.

Check your drop message setting(make sure it's set to N)

by **gerski** » Tue Jan 23, 2007 6:18 am

hello matt,

my inbound voicemail is working but after upgrading to 2.0.2 that uses fast agi.. it doesn't work.. i also try adding fast agi script in my extensions.conf still the same..

by **gerski** » Tue Jan 23, 2007 10:28 am

here is what ive seen in my agiout:

Code: Select all: 2007-01-23 22:52:20|agi-VDADcloser_inboundCIDlookup20.agi|NNNNN No available agent found 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|Perl Environment Dump: 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|0|8888-----8888-----Closer---------------999-----1 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- accountcode = 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- callerid = 9999 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- calleridname = unknown 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- callingani2 = 0 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- callingpres = 0 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- callingtns = 0 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- callington = 0 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- channel = SIP/9999-b5a12dc8 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- context = default 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- dnid = 8888 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- enhanced = 0.0 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- extension = 8888 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- language = en 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- priority = 4 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- rdnis = unknown 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- request = agi-VDADcloser_inboundCIDlookup30.agi 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- type = SIP 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi| -- uniqueid = 1169564119.62293 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|AGI Variables: |1169564119.62293|SIP/9999-b5a12dc8|8888|SIP|Closer| 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|+++++ INBOUND CALL VDCL STARTED : |8888|Closer-8888|2007-01-23 22:55:22 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi||SELECT lead_id from vicidial_list where list_id='999' and phone_number='' order by modify_date lim it 1;|430511| 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|callerID changed: Y0123225522000430511 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi||INSERT INTO vicidial_auto_calls (server_ip,campaign_id,status,lead_id,uniqueid,callerid,channel,ph one_code,phone_number,call_time,call_type,stage) values('192.168.0.21','8888','LIVE','430511','1169564119.62293','Y0123225522000430511','SIP/9999-b5a12dc8', '1','','2007-01-23 22:55:22','IN','LIVE-0')| 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi||INSERT INTO vicidial_closer_log (lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone _number,user,processed) values('430511','8888','2007-01-23 22:55:22','1169564122','QUEUE','1','','VDCL','N')| 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|-- VDCL : |430511|insert to vicidial_closer_log

Code: Select all: 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|-- VDAD get agent: |0E0|8888|UPDATE vicidial_live_agents set status='QUEUE',lead_id='430511',uni queid='1169564119.62293', channel='SIP/9999-b5a12dc8', callerid='Y0123225522000430511' where status IN('CLOSER','READY') and server_ip='192.168.0.21' and cam paign_id LIKE "%CLOSER%" and closer_campaigns LIKE "% 8888 %" and last_update_time > '19700101075955' order by last_call_finish limit 1;| 2007-01-23 22:55:22|agi-VDADcloser_inboundCIDlookup30.agi|NNNNN No available agent found 2007-01-23 22:56:06|agi-VDADcloser_inboundCIDlookup30.agi||SELECT voicemail_ext FROM vicidial_inbound_groups where group_id = '8888' limit 1;| 2007-01-23 22:56:06|agi-VDADcloser_inboundCIDlookup30.agi|-- VDCL call_hungup timout: |VH0123225606||SIP/9999-b5a12dc8|insert to vicidial_manager 2007-01-23 22:56:06|agi-VDADcloser_inboundCIDlookup30.agi|-- VDCL vac record deleted: |1| | 2007-01-23 22:56:06|agi-VDADcloser_inboundCIDlookup30.agi|-- VDCL vcl update: |1|430511| |UPDATE vicidial_closer_log set status='DROP',end_epoch='1169564166',length_in_sec='30',queue_seconds='30' where lead_id = '430511' order by start_epoch desc limit 1;| 2007-01-23 22:56:06|agi-VDADcloser_inboundCIDlookup30.agi|-- VDCL vl update: |1|430511| |UPDATE vicidial_list set status='XDROP' where lead_id = '430511';|

here is my asterisk CLI:

Code: Select all: -- Executing Ringing("SIP/9999-b5a12dc8", "") in new stack -- Executing Wait("SIP/9999-b5a12dc8", "1") in new stack -- Executing Answer("SIP/9999-b5a12dc8", "") in new stack -- Executing AGI("SIP/9999-b5a12dc8", "agi-VDADcloser_inboundCIDlookup30.agi|8888-----8888-----Closer---------------999-----1") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup.agi -- AGI Script agi-VDADcloser_inboundCIDlookup.agi completed, returning 0 -- Executing VoiceMail("SIP/9999-b5a12dc8", "u688") in new stack -- Playing 'vm-theperson' (language 'en') == Parsing '/etc/asterisk/manager.conf': Found == Manager 'sendcron' logged on from 127.0.0.1 == Spawn extension (default, 8888, 5) exited non-zero on 'SIP/9999-b5a12dc8' -- Executing DeadAGI("SIP/9999-b5a12dc8", "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 DeadAGI("SIP/9999-b5a12dc8", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack -- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------) completed, returning 0

extension.conf

Code: Select all: exten => _8888,1,Ringing ; call ringing exten => _8888,2,Wait(1) ; Wait 5 second for CallerID delivery from IAX exten => _8888,3,Answer ; Answer the line exten => _8888,4,AGI(agi-VDADcloser_inboundCIDlookup30.agi,8888-----8888-----Closer---------------999-----1) exten => _8888,5,Voicemail,u688

by **mflorell** » Tue Jan 23, 2007 3:00 pm

It looks like the call is going to voicemail, so what exactly is going wrong?

by **gerski** » Tue Jan 23, 2007 4:27 pm

once it get to voicemail i only hear beep and it will automatically drop..

by **mflorell** » Tue Jan 23, 2007 9:39 pm

That sounds more like an Asterisk or configuration issue, it looks like it's getting there and playing the unavailable message.

Do you see any output when you turn on debugging and view the Asterisk message file?

by **gerski** » Wed Jan 24, 2007 12:21 am

this is debug output of sip:

Code: Select all: Scheduling destruction of call 'EDF949B3-0257-4C78-8203-0CECD17471CA@192.168.0.29' in 15000 ms -- Executing Answer("SIP/9999-0820a5b8", "") in new stack We're at XX.XX.XX.XX port 15844 Adding codec 0x2 (gsm) to SDP Adding non-codec 0x1 (telephone-event) to SDP Reliably Transmitting (NAT) to 58.69.226.71:5060: SIP/2.0 200 OK Via: SIP/2.0/UDP 192.168.0.102;branch=z9hG4bKc0a800660000012245b6eb0100002c76000004d2;received=58.69.226.71;rport=5060 From: "unknown"<sip:9999@XX.XX.XX.XX>;tag=1065475019892 To: <sip:8888@XX.XX.XX.XX>;tag=as59746f32 Call-ID: 557DF5A9-01EE-4FA9-A7E5-AB43E41C9E2C@192.168.0.102 CSeq: 2 INVITE User-Agent: Asterisk PBX Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: <sip:8888@XX.XX.XX.XX> Content-Type: application/sdp Content-Length: 215 v=0 o=root 3618 3618 IN IP4 XX.XX.XX.XX s=session c=IN IP4 XX.XX.XX.XX t=0 0 m=audio 15844 RTP/AVP 3 101 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - --- -- Executing AGI("SIP/9999-0820a5b8", "agi-VDADcloser_inboundCIDlookup30.agi|8888-----8888-----Closer---------------999-----1") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup30.agi Asterisk*CLI> <-- SIP read from 58.69.226.71:5060: ACK sip:8888@XX.XX.XX.XXSIP/2.0 Via: SIP/2.0/UDP 192.168.0.102;rport;branch=z9hG4bKc0a800660000012245b6eb0200006560000004d6 Content-Length: 0 Call-ID: 557DF5A9-01EE-4FA9-A7E5-AB43E41C9E2C@192.168.0.102 CSeq: 2 ACK From: "unknown"<sip:9999@XX.XX.XX.XX>;tag=1065475019892 Max-Forwards: 70 To: <sip:8888@XX.XX.XX.XX>;tag=as59746f32 User-Agent: SJphone/1.60.289a (SJ Labs) Destroying call '2AF368EA-1FFA-476F-9960-F1CC6A09912F@192.168.0.15' -- AGI Script agi-VDADcloser_inboundCIDlookup30.agi completed, returning 0 -- Executing VoiceMail("SIP/9999-0820a5b8", "u688") in new stack -- Playing 'vm-theperson' (language 'en') == Parsing '/etc/asterisk/manager.conf': Found == Manager 'sendcron' logged on from 127.0.0.1 == Spawn extension (default, 8888, 5) exited non-zero on 'SIP/9999-0820a5b8' -- Executing DeadAGI("SIP/9999-0820a5b8", "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 DeadAGI("SIP/9999-0820a5b8", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack -- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------) completed, returning 0 Scheduling destruction of call '557DF5A9-01EE-4FA9-A7E5-AB43E41C9E2C@192.168.0.102' in 32000 ms set_destination: Parsing <sip:9999@192.168.0.102:5060> for address/port to send to set_destination: set destination to 192.168.0.102, port 5060 Reliably Transmitting (NAT) to 58.69.226.71:5060: BYE sip:9999@192.168.0.102:5060 SIP/2.0 Via: SIP/2.0/UDP XX.XX.XX.XX:5060;branch=z9hG4bK4edd6887;rport From: <sip:8888@XX.XX.XX.XX>;tag=as59746f32 To: "unknown"<sip:9999@XX.XX.XX.XX>;tag=1065475019892 Call-ID: 557DF5A9-01EE-4FA9-A7E5-AB43E41C9E2C@192.168.0.102 CSeq: 102 BYE User-Agent: Asterisk PBX Max-Forwards: 70 Content-Length: 0

it is working on 2.0.1 that uses call_log.agi after upgrade it doesn't work..

i only change drop time and also set drop message to N instead of Y

by **mflorell** » Wed Jan 24, 2007 10:58 am

Can you post Asterisk debug from messages log without SIP debug?

If the call gets here it shouldn't have anything to do with vicidial components, the call should be answering in voicemail:

-- Executing VoiceMail("SIP/9999-b5a12dc8", "u688") in new stack
-- Playing 'vm-theperson' (language 'en')

by **gerski** » Mon Jan 29, 2007 10:18 am

Hello Matt,

I have upgraded the astguiclient from 2.0.1 to 2.0.2..

2.0.1 works agi-VDADinboundcloser before voicemail but afte updating here is the asterisk CLI:

Code: Select all: -- Saved useragent "SJphone/1.60.289a (SJ Labs)" for peer 9001 -- Executing Ringing("SIP/9001-08214b18", "") in new stack -- Executing Wait("SIP/9001-08214b18", "1") in new stack -- Executing Answer("SIP/9001-08214b18", "") in new stack -- Executing AGI("SIP/9001-08214b18", "agi-VDADcloser_inboundCIDlookup20.agi|8888-----8888-----Closer---------------999-----1") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup20.agi -- AGI Script agi-VDADcloser_inboundCIDlookup20.agi completed, returning 0 -- Executing VoiceMail("SIP/9001-08214b18", "u688") in new stack -- Playing 'vm-theperson' (language 'en') -- Playing 'digits/6' (language 'en') -- Playing 'digits/8' (language 'en') -- Playing 'digits/8' (language 'en') -- Playing 'vm-isunavail' (language 'en') -- Playing 'vm-intro' (language 'en') -- Playing 'beep' (language 'en') -- Recording the message -- x=0, open writing: /var/spool/asterisk/voicemail/default/688/tmp/O39g7b format: wav49, 0x81541a0 -- x=1, open writing: /var/spool/asterisk/voicemail/default/688/tmp/O39g7b format: gsm, 0x81d9548 -- x=2, open writing: /var/spool/asterisk/voicemail/default/688/tmp/O39g7b format: wav, 0x81900d8 -- User hung up == Spawn extension (default, 8888, 5) exited non-zero on 'SIP/9001-08214b18' -- Executing DeadAGI("SIP/9001-08214b18", "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 DeadAGI("SIP/9001-08214b18", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack -- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----0---------------) completed, returning 0

it ignored the AGI script and it goes directly to voicemail..
here is my extensions.conf:

Code: Select all: exten => _8888,1,Ringing ; call ringing exten => _8888,2,Wait(1) ; Wait 5 second for CallerID delivery from IAX exten => _8888,3,Answer ; Answer the line exten => _8888,4,AGI(agi-VDADcloser_inboundCIDlookup20.agi,8888-----8888-----Closer---------------999-----1) exten => _8888,5,Voicemail,u688

my changes in agi-VDADcloser_inboundCIDlookup20.agi is

Code: Select all: if ($drop_message =~ /N/) and $script = 'agi-VDADcloser_inboundCIDlookup20.agi'; $DROP_TIME = 30

by **mflorell** » Mon Jan 29, 2007 11:06 am

What do you have safe_harbor_message set to for this campaign?

by **gerski** » Mon Jan 29, 2007 5:16 pm

The default is N and it is set to 8307, but i don't think this has something to do with it cause it will work even it is not activate in the campaign right?

by **mflorell** » Mon Jan 29, 2007 5:52 pm

If you have safe_harbor activated then the voicemail transfer will not happen.

by **gerski** » Mon Jan 29, 2007 5:58 pm

its still the same matt...

i have use also the original setting that will drop after 360 seconds still the same..

by **mflorell** » Mon Jan 29, 2007 8:15 pm

Very strange, I did test this when I fixed the bug in SVN. Guess I'l have to take another look at it.

by **gerski** » Tue Jan 30, 2007 11:07 pm

Ok.. i have check all inbound closer agi still the same.. i think it has something to do with drop.. after going to next extension it will drop in 2 to 3 seconds..

by **gardo** » Tue Feb 20, 2007 4:51 pm

i'm also trying to setup voicemail for my inbound. here's my inbound entry in extensions.conf:

;#Inbound calls
exten => _8004191068,1,Ringing ; call ringing
exten => _8004191068,n,Wait(1) ; wait 1 sec for CID delivery
exten => _8004191068,n,Answer ; answer
exten => _8004191068,n,AGI(agi-VDAD_LB_closer_inboundCID.agi,Prolixus-----8004191068-----Closer-----park----------999-----1)
exten => _8004191068,n,Voicemail(777@mb_tutorial)
exten => _8004191068,n,PlayBack(vm-goodbye)
exten => _8004191068,n,Hangup ; hangup

What i'm actually getting after the default message timeout is the message "that is not a valid extension". Here's the output of my asterisk cli:

-- AGI Script agi-VDAD_LB_closer_inboundCID.agi completed, returning 0
-- Sent into invalid extension '85026666666666' in context 'default' on IAX2/1023-6
-- Executing Playback("IAX2/1023-6", "invalid") in new stack
-- Playing 'invalid' (language 'en')
Feb 21 05:22:33 WARNING[11244]: res_musiconhold.c:422 spawn_mp3: Found no files in '/var/lib/asterisk/mohmp3'
Feb 21 05:22:33 WARNING[11244]: res_musiconhold.c:504 monmp3thread: Unable to spawn mp3player
-- Timeout on IAX2/1023-6
== CDR updated on IAX2/1023-6
-- Executing Goto("IAX2/1023-6", "#|1") in new stack
-- Goto (default,#,1)
-- Executing Playback("IAX2/1023-6", "invalid") in new stack
-- Playing 'invalid' (language 'en')
-- Executing Hangup("IAX2/1023-6", "") in new stack
== Spawn extension (default, #, 2) exited non-zero on 'IAX2/1023-6'
-- Executing DeadAGI("IAX2/1023-6", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("IAX2/1023-6", "VD_hangup.agi|PRI-----NODEBUG-----16---------------") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- Hungup 'IAX2/1023-6'

Searching on extension "85026666666666 ", it seems that after the script is finished, i'm sent to this extension:

; this is used to allow the GUI to send live calls directly into voicemail
; don't forget to set GUI variable $voicemail_dump_exten to this extension
exten => _85026666666666.,1,Wait(2)
exten => _85026666666666.,2,Voicemail(${EXTEN:14})
exten => _85026666666666.,3,Hangup

by **mflorell** » Tue Feb 20, 2007 10:41 pm

What do you have the voicemail field set to in your campaigns screen?

by **gardo** » Wed Feb 21, 2007 5:25 am

This is what I have:

Voicemail Field: Blank
Use Safe Harbor Message:N

by **mflorell** » Wed Feb 21, 2007 11:22 am

What astGUIclient version are you using?

What is the last CHANGELOG date in the code of the transfer AGI script that you are using?

by **gardo** » Wed Feb 21, 2007 2:25 pm

astguiclient 2.0.2

are these the ones you're looking for?

# Copyright (C) 2006 Matt Florell <vicidial@gmail.com> LICENSE: GPLv2
#
# changes:
# 60504-1122 - Added drop second/message/exten options from inbound_group
# 60809-1056 - Changed to DBI by Marin Blu
# - changed to use /etc/astguiclient.conf for configs
# 60821-1021 - added output options check from database
# - changed look for agent every 0.5 second for first 4 sec on hold
# 60905-1047 - changed look for agent every 0.25 second for first 4 sec on hold
#

$script = 'agi-VDAD_LB_closer_inboundCID.agi';

by **mflorell** » Thu Feb 22, 2007 4:48 am

If possible you should upgrade to the latest beta release: 2.0.3b3

There was a bug in the script that causes what you are experiencing that has been fixed in the latest version.

by **irfanfarooq** » Wed Oct 10, 2007 2:48 pm

scratchspace wrote:I've setup a voicemail extension and confirmed it's working properly. Our Inbound campaign has worked fine up to this point, but we can't seem to get calls to route to the voicemail extension instead of dropping. Changes to the "Drop Call Seconds" are being recognized, but the voicemail extension is being ignored. Within the "In-Group" configuration screen, I have set "Use Drop Message" to no, which according to the help file is supposed to enable (or not override) the voicemail extension. Do I need to restart some processes or do something at the campaign level to enable this feature?

Hi

Actually I have configured vicidial successfully and able to connect outbound/inbound calls but i am facing some problem in voicemail.

When I recieved incoming call and no agent are available to recieve this call then call goes to voicemail but after playing vm-the person call is drop. Please give me any suggestin to resolve this issue.

Make sure i have follow these instructions of this forum post but still the same problem:

=862

-- Executing Ringing("SIP/192.168.100.182-08ded158", "") in new stack
-- Executing Wait("SIP/192.168.100.182-08ded158", "1") in new stack
-- Executing AGI("SIP/192.168.100.182-08ded158", "agi://127.0.0.1:4577/call_log--fullCID--16467231856-----"unknown" <3129226500>-----3129226500-----unknown") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--fullCID- ... 31856-----"unknown" <3129226500>-----3129226500-----unknown completed, returning 0
-- Executing Answer("SIP/192.168.100.182-08ded158", "") in new stack
-- Executing AGI("SIP/192.168.100.182-08ded158", "agi-VDADcloser_inboundCIDlookup.agi|SALESLINE-----16467231856-----Closer---------------999-----1") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADcloser_inboundCIDlookup.agi

-- AGI Script agi-VDADcloser_inboundCIDlookup.agi completed, returning 0
-- Executing VoiceMail("SIP/192.168.100.182-08ded158", "u3") in new stack
-- Playing 'vm-theperson' (language 'en')

Extensions.conf:

exten => 16467231856,1,Ringing
exten => 16467231856,2,Wait(1)
exten => 16467231856,3,AGI(agi://127.0.0.1:4577/call_log--fullCID--${EXTEN}-----${CALLERID}-----${CALLERIDNUM}-----${CALLERIDNAME})
exten => 16467231856,4,Answer
exten => 16467231856,5,AGI(agi-VDADcloser_inboundCIDlookup.agi,SALESLINE-----16467231856-----Closer---------------999-----1)
exten => 16467231856,6,Voicemail,u3

by **gerski** » Wed Oct 10, 2007 8:53 pm

instead of putting voicemail after fifth extension AGI put Hangup

then on voicemail put it under in-groups-->salesline group-->voicemail and see if it works.. everything is in Manager's Manual.

vicidial.org

Inbound Voicemail Extension not getting calls

Inbound Voicemail Extension not getting calls

Re: Inbound Voicemail Extension not getting calls

Who is online