We have tried multiple carriers and direct ips. What seems to be happening is the outbound agi is looking for an answer on the Local/XX channel and doesn't receive it so it hangs up with a status of "NA" within one second . Here is the excerpt of the FastAGI log.
Thanks for your assistance
2009-04-01 18:34:58|VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2009-04-01 18:34:58|VDfastAGI|begin|Perl Environment Dump:
2009-04-01 18:34:58|VDfastAGI|begin|0|--debug
2009-04-01 18:34:58|VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|ANSWER|15|0|
2009-04-01 18:34:58|VDfastAGI|begin|AGI Environment Dump:
2009-04-01 18:34:58|VDfastAGI|begin| -- accountcode =
2009-04-01 18:34:58|VDfastAGI|begin| -- callerid = unknown
2009-04-01 18:34:58|VDfastAGI|begin| -- calleridname = S81899922938600159
2009-04-01 18:34:58|VDfastAGI|begin| -- callingani2 = 0
2009-04-01 18:34:58|VDfastAGI|begin| -- callingpres = 0
2009-04-01 18:34:58|VDfastAGI|begin| -- callingtns = 0
2009-04-01 18:34:58|VDfastAGI|begin| -- callington = 0
2009-04-01 18:34:58|VDfastAGI|begin| -- channel = SIP/159-09bd80a0
2009-04-01 18:34:58|VDfastAGI|begin| -- context = safesoft
2009-04-01 18:34:58|VDfastAGI|begin| -- dnid = unknown
2009-04-01 18:34:58|VDfastAGI|begin| -- enhanced = 0.0
2009-04-01 18:34:58|VDfastAGI|begin| -- extension = h
2009-04-01 18:34:58|VDfastAGI|begin| -- language = en
2009-04-01 18:34:58|VDfastAGI|begin| -- network = yes
2009-04-01 18:34:58|VDfastAGI|begin| -- network_script = call_log--HVcauses--PRI-----NODEBUG-----16---------------
2009-04-01 18:34:58|VDfastAGI|begin| -- priority = 1
2009-04-01 18:34:58|VDfastAGI|begin| -- rdnis = unknown
2009-04-01 18:34:58|VDfastAGI|begin| -- request =
agi://127.0.0.1:4577/call_log--HVcauses ... ----------
2009-04-01 18:34:58|VDfastAGI|begin| -- type = SIP
2009-04-01 18:34:58|VDfastAGI|begin| -- uniqueid = 1238635656.6
2009-04-01 18:34:58|VDfastAGI|begin|AGI Variables: |1238635656.6|SIP/159-09bd80a0|h|SIP|S81899922938600159|
2009-04-01 18:34:58|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------|call_log|END|
2009-04-01 18:34:58|VDfastAGI|call_log||CALL HUNG UP|
2009-04-01 18:34:58|VDfastAGI|call_log|URL HVcauses: |PRI|NODEBUG|16||||
2009-04-01 18:34:58|VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1238635656.6' and server_ip='10.10.40.20'|
2009-04-01 18:34:58|VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1238635656.6|SIP/159-09bd80a0|h|2009-04-01 18:34:58|min: 0.25|
2009-04-01 18:34:58|VDfastAGI|call_log|DEBUG: NODEBUG
2009-04-01 18:34:58|VDfastAGI|call_log|VD_hangup : S81899922938600159 SIP/159-09bd80a0 1 8600159
2009-04-01 18:34:58|VDfastAGI|call_log||SELECT lead_id,callerid,campaign_id,alt_dial,stage,UNIX_TIMESTAMP(call_time),uniqueid,status FROM vicidial_auto_calls where uniqueid = '1238635656.6' or callerid = 'S81899922938600159' limit 1;|
2009-04-01 18:34:58|VDfastAGI|call_log|VD hangup: no VDAC record found: 1238635656.6 S81899922938600159
2009-04-01 18:35:09|VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2009-04-01 18:35:09|VDfastAGI|begin|Perl Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin|0|--debug
2009-04-01 18:35:09|VDfastAGI|begin|AGI Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin| -- accountcode =
2009-04-01 18:35:09|VDfastAGI|begin| -- callerid = 8189992293
2009-04-01 18:35:09|VDfastAGI|begin| -- calleridname = V0401183456000098323
2009-04-01 18:35:09|VDfastAGI|begin| -- callingani2 = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingpres = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingtns = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callington = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- channel = Local/024119163847223@safesoft-e9b6,1
2009-04-01 18:35:09|VDfastAGI|begin| -- context = safesoft
2009-04-01 18:35:09|VDfastAGI|begin| -- dnid = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- enhanced = 0.0
2009-04-01 18:35:09|VDfastAGI|begin| -- extension = 8365
2009-04-01 18:35:09|VDfastAGI|begin| -- language = en
2009-04-01 18:35:09|VDfastAGI|begin| -- network = yes
2009-04-01 18:35:09|VDfastAGI|begin| -- network_script = call_log
2009-04-01 18:35:09|VDfastAGI|begin| -- priority = 2
2009-04-01 18:35:09|VDfastAGI|begin| -- rdnis = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- request =
agi://127.0.0.1:4577/call_log
2009-04-01 18:35:09|VDfastAGI|begin| -- type = Local
2009-04-01 18:35:09|VDfastAGI|begin| -- uniqueid = 1238636096.14
2009-04-01 18:35:09|VDfastAGI|begin|AGI Variables: |1238636096.14|Local/024119163847223@safesoft-e9b6,1|8365|Local|V0401183456000098323|
2009-04-01 18:35:09|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|START|
2009-04-01 18:35:09|VDfastAGI|call_log|+++++ CALL LOG START : 2009-04-01 18:35:09
2009-04-01 18:35:09|VDfastAGI|call_log|Local Channel Line: 0|024119163847223|
2009-04-01 18:35:09|VDfastAGI|call_log||INSERT INTO call_log (uniqueid,channel,channel_group,type,server_ip,extension,number_dialed,start_time,start_epoch,end_time,end_epoch,length_in_sec,length_in_min,caller_code) values('1238636096.14','Local/024119163847223@safesoft-e9b6,1','Local Channel Line','Local','10.10.40.20','8365','8365','2009-04-01 18:35:09','1238636109','','','','','V0401183456000098323')|
2009-04-01 18:35:09|VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2009-04-01 18:35:09|VDfastAGI|begin|Perl Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin|0|--debug
2009-04-01 18:35:09|VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16||||
2009-04-01 18:35:09|VDfastAGI|begin|AGI Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin| -- accountcode =
2009-04-01 18:35:09|VDfastAGI|begin| -- callerid = 8189992293
2009-04-01 18:35:09|VDfastAGI|begin| -- calleridname = V0401183456000098323
2009-04-01 18:35:09|VDfastAGI|begin| -- callingani2 = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingpres = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingtns = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callington = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- channel = Local/024119163847223@safesoft-e9b6,1
2009-04-01 18:35:09|VDfastAGI|begin| -- context = safesoft
2009-04-01 18:35:09|VDfastAGI|begin| -- dnid = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- enhanced = 0.0
2009-04-01 18:35:09|VDfastAGI|begin| -- extension = h
2009-04-01 18:35:09|VDfastAGI|begin| -- language = en
2009-04-01 18:35:09|VDfastAGI|begin| -- network = yes
2009-04-01 18:35:09|VDfastAGI|begin| -- network_script = call_log--HVcauses--PRI-----NODEBUG-----16---------------
2009-04-01 18:35:09|VDfastAGI|begin| -- priority = 1
2009-04-01 18:35:09|VDfastAGI|begin| -- rdnis = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- request =
agi://127.0.0.1:4577/call_log--HVcauses ... ----------
2009-04-01 18:35:09|VDfastAGI|begin| -- type = Local
2009-04-01 18:35:09|VDfastAGI|begin| -- uniqueid = 1238636096.14
2009-04-01 18:35:09|VDfastAGI|begin|AGI Variables: |1238636096.14|Local/024119163847223@safesoft-e9b6,1|h|Local|V0401183456000098323|
2009-04-01 18:35:09|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------|call_log|END|
2009-04-01 18:35:09|VDfastAGI|call_log||CALL HUNG UP|
2009-04-01 18:35:09|VDfastAGI|call_log|URL HVcauses: |PRI|NODEBUG|16||||
2009-04-01 18:35:09|VDfastAGI|call_log||1238636096.14|1238636109|
2009-04-01 18:35:09|VDfastAGI|call_log|QUERY done: start time = 1238636109 | sec: 0 | min: 0.00 |
2009-04-01 18:35:09|VDfastAGI|call_log||UPDATE call_log set end_time='2009-04-01 18:35:09',end_epoch='1238636109',length_in_sec=0,length_in_min=' 0.00',channel='Local/024119163847223@safesoft-e9b6,1' where uniqueid='1238636096.14'|
2009-04-01 18:35:09|VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1238636096.14' and server_ip='10.10.40.20'|
2009-04-01 18:35:09|VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1238636096.14|Local/024119163847223@safesoft-e9b6,1|h|2009-04-01 18:35:09|min: 0.00|
2009-04-01 18:35:09|VDfastAGI|call_log|DEBUG: NODEBUG
2009-04-01 18:35:09|VDfastAGI|call_log|VD_hangup : V0401183456000098323 Local/024119163847223@safesoft-e9b6,1 1 98323
2009-04-01 18:35:09|VDfastAGI|call_log|-- VD_hangup Local DEBUG: |PRI|V0401183456000098323||16|
2009-04-01 18:35:09|VDfastAGI|call_log|+++++ VDAD START LOCAL CHANNEL: EXITING- 1
2009-04-01 18:35:09|VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2009-04-01 18:35:09|VDfastAGI|begin|Perl Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin|0|--debug
2009-04-01 18:35:09|VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16||||
2009-04-01 18:35:09|VDfastAGI|begin|AGI Environment Dump:
2009-04-01 18:35:09|VDfastAGI|begin| -- accountcode =
2009-04-01 18:35:09|VDfastAGI|begin| -- callerid = 8189992293
2009-04-01 18:35:09|VDfastAGI|begin| -- calleridname = V0401183456000098323
2009-04-01 18:35:09|VDfastAGI|begin| -- callingani2 = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingpres = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callingtns = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- callington = 0
2009-04-01 18:35:09|VDfastAGI|begin| -- channel = Local/024119163847223@safesoft-e9b6,2
2009-04-01 18:35:09|VDfastAGI|begin| -- context = safesoft
2009-04-01 18:35:09|VDfastAGI|begin| -- dnid = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- enhanced = 0.0
2009-04-01 18:35:09|VDfastAGI|begin| -- extension = h
2009-04-01 18:35:09|VDfastAGI|begin| -- language = en
2009-04-01 18:35:09|VDfastAGI|begin| -- network = yes
2009-04-01 18:35:09|VDfastAGI|begin| -- network_script = call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----13-----0
2009-04-01 18:35:09|VDfastAGI|begin| -- priority = 1
2009-04-01 18:35:09|VDfastAGI|begin| -- rdnis = unknown
2009-04-01 18:35:09|VDfastAGI|begin| -- request =
agi://127.0.0.1:4577/call_log--HVcauses ... --13-----0
2009-04-01 18:35:09|VDfastAGI|begin| -- type = Local
2009-04-01 18:35:09|VDfastAGI|begin| -- uniqueid = 1238636096.15
2009-04-01 18:35:09|VDfastAGI|begin|AGI Variables: |1238636096.15|Local/024119163847223@safesoft-e9b6,2|h|Local|V0401183456000098323|
2009-04-01 18:35:09|VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----13-----0|call_log|END|
2009-04-01 18:35:09|VDfastAGI|call_log||CALL HUNG UP|
2009-04-01 18:35:09|VDfastAGI|call_log|URL HVcauses: |PRI|NODEBUG|16|ANSWER|13|0|
2009-04-01 18:35:09|VDfastAGI|call_log||1238636096.15|1238636096|
2009-04-01 18:35:09|VDfastAGI|call_log|QUERY done: start time = 1238636096 | sec: 13 | min: 0.22 |
2009-04-01 18:35:09|VDfastAGI|call_log||UPDATE call_log set end_time='2009-04-01 18:35:09',end_epoch='1238636109',length_in_sec=13,length_in_min=' 0.22',channel='Local/024119163847223@safesoft-e9b6,2' where uniqueid='1238636096.15'|
2009-04-01 18:35:09|VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1238636096.15' and server_ip='10.10.40.20'|
2009-04-01 18:35:09|VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1238636096.15|Local/024119163847223@safesoft-e9b6,2|h|2009-04-01 18:35:09|min: 0.22|
2009-04-01 18:35:09|VDfastAGI|call_log|DEBUG: NODEBUG
2009-04-01 18:35:09|VDfastAGI|call_log|VD_hangup : V0401183456000098323 Local/024119163847223@safesoft-e9b6,2 1 98323
2009-04-01 18:35:09|VDfastAGI|call_log|-- VD_hangup Local DEBUG: |PRI|V0401183456000098323|ANSWER|16|
2009-04-01 18:35:09|VDfastAGI|call_log|+++++ VDAD START LOCAL CHANNEL: EXITING- 1