vicidial.org

by **erwan** » Wed Nov 29, 2006 7:10 am

Hi,

I've got problem with autodial, when i try to make a manual call all work fine but using autodial it hangup

I'm using IAX trunk and it's well registred

I'got asterisk-perl 0.08 and Asterisk 1.2.13 with chan_concise patch

Here is my iax.conf

Code: Select all: register=acNTM33A30Ro:password@iax.plugandtel.net [acNTM33A30Ro] type=peer secret=password qualify=yes host=iax.plugandtel.net disallow=all allow=g729 context=from-trunk context=default

Here is extrac of my extension.conf

Code: Select all: exten => _910X.,1,AGI(agi://127.0.0.1:4577/call_log) exten => _910X.,2,Dial(IAX2/acNTM33A30Ro:password@iax.plugandtel.net/${EXTEN:2},55,o) exten => _910X.,3,Hangup exten => 8365,1,Playback(sip-silence) exten => 8365,2,AGI(agi://127.0.0.1:4577/call_log) exten => 8365,3,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,4,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,5,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,6,Hangup

Here is my Asterisk CLI output

Code: Select all: -- Executing AGI("Local/910472523311@default-9830,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/910472523311@default-9830,2", "IAX2/acNTM33A30Ro:password@iax.plugandtel.net/0472523311|55|o") in new stack -- Called acNTM33A30Ro:password@iax.plugandtel.net/0472523311 -- Call accepted by 193.202.111.232 (format gsm) -- Format for call is gsm -- IAX2/acNTM33A30Ro-1 is making progress passing it to Local/910472523311@default-9830,2 -- IAX2/acNTM33A30Ro-1 is ringing -- IAX2/acNTM33A30Ro-1 stopped sounds -- IAX2/acNTM33A30Ro-1 answered Local/910472523311@default-9830,2 > Channel Local/910472523311@default-9830,1 was answered. -- Executing Playback("Local/910472523311@default-9830,1", "sip-silence") in new stack -- Playing 'sip-silence' (language 'en') == Spawn extension (default, 910472523311, 2) exited non-zero on 'Local/910472523311@default-9830,2' -- Executing DeadAGI("Local/910472523311@default-9830,2", "agi://127.0.0.1:4577/call_log") in new stack -- Executing AGI("IAX2/acNTM33A30Ro-1", "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("Local/910472523311@default-9830,2", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----3-----0)") in new stack -- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----3-----0) completed, returning 0 -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 -- Executing AGI("IAX2/acNTM33A30Ro-1", "agi-VDADtransfer.agi|65") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi == Manager 'sendcron' logged off from 127.0.0.1 -- AGI Script agi-VDADtransfer.agi completed, returning 0 -- Executing AGI("IAX2/acNTM33A30Ro-1", "agi-VDADtransfer.agi|65") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi -- AGI Script agi-VDADtransfer.agi completed, returning 0 -- Executing AGI("IAX2/acNTM33A30Ro-1", "agi-VDADtransfer.agi|65") in new stack -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi -- AGI Script agi-VDADtransfer.agi completed, returning 0 -- Executing Hangup("IAX2/acNTM33A30Ro-1", "") in new stack == Spawn extension (default, 8365, 6) exited non-zero on 'IAX2/acNTM33A30Ro-1' -- Executing DeadAGI("IAX2/acNTM33A30Ro-1", "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("IAX2/acNTM33A30Ro-1", "agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------)") in new stack -- AGI Script agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------) completed, returning 0 -- Hungup 'IAX2/acNTM33A30Ro-1'

Here is my FASTAGIOut

Code: Select all: 2006-11-29 12:56:04|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:04|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:04|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:04|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|ANSWER|4|0)| 2006-11-29 12:56:04|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- channel = Local/910472523311@default-9830,2 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- extension = 910472523311 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- network_script = call_log 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- priority = 1 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- type = Local 2006-11-29 12:56:04|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.261 2006-11-29 12:56:04|TEST_VDfastAGI|begin|AGI Variables: |1164801364.261|Local/910472523311@default-9830,2|910472523311|Local|V1129125604000000037| 2006-11-29 12:56:04|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|START| 2006-11-29 12:56:04|TEST_VDfastAGI|call_log|+++++ CALL LOG START : 2006-11-29 12:56:04 2006-11-29 12:56:04|TEST_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('1164801364.261','Local/910472523311@default-9830,2','Outbound Long Distance','Local','192.168.0.220','910472523311','8365','2006-11-29 12:56:04','1164801364','','','','','V1129125604000000037')| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:07|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- channel = Local/910472523311@default-9830,2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- extension = h 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network_script = call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- priority = 1 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- type = Local 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.261 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Variables: |1164801364.261|Local/910472523311@default-9830,2|h|Local|V1129125604000000037| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|END| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||CALL HUNG UP| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||1164801364.2609999|1164801364| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|QUERY done: start time = 1164801364 | sec: 3 | min: 0.05 | 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||UPDATE call_log set end_time='2006-11-29 12:56:07',end_epoch='1164801367',length_in_sec=3,length_in_min=' 0.05' where uniqueid='1164801364.261'| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1164801364.261' and server_ip='192.168.0.220'| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1164801364.261|Local/910472523311@default-9830,2|h|2006-11-29 12:56:07|min: 0.05| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:07|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:07|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|||)| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:07|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:07|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|ANSWER|3|0)| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- channel = Local/910472523311@default-9830,2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- extension = h 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network_script = VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----3-----0) 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- priority = 2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----3-----0) 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- type = Local 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.261 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Variables: |1164801364.261|Local/910472523311@default-9830,2|h|Local|V1129125604000000037| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|Process to run: |agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----3-----0)|VD_hangup|END| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|DEBUG: NODEBUG 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|VD_hangup : V1129125604000000037 Local/910472523311@default-9830,2 2 37 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|-- VD_hangup Local DEBUG: |PRI|V1129125604000000037|ANSWER|16| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|+++++ VDAD START LOCAL CHANNEL: EXITING- 2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- extension = 8365 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network_script = call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- priority = 2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- type = IAX2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.260 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Variables: |1164801364.260|IAX2/acNTM33A30Ro-1|8365|IAX2|V1129125604000000037| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|START| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|+++++ CALL LOG START : 2006-11-29 12:56:07 2006-11-29 12:56:07|TEST_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('1164801364.260','IAX2/acNTM33A30Ro-1','Outbound Long Distance','IAX2','192.168.0.220','acNTM33A30Ro-1','8365','2006-11-29 12:56:07','1164801367','','','','','V1129125604000000037')| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:07|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:07|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|0|||)| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- extension = h 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network_script = call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- priority = 1 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- type = IAX2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.260 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Variables: |1164801364.260|IAX2/acNTM33A30Ro-1|h|IAX2|V1129125604000000037| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|END| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||CALL HUNG UP| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||1164801364.2600000|1164801367| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|QUERY done: start time = 1164801367 | sec: 0 | min: 0.00 | 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||UPDATE call_log set end_time='2006-11-29 12:56:07',end_epoch='1164801367',length_in_sec=0,length_in_min=' 0.00' where uniqueid='1164801364.260'| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1164801364.260' and server_ip='192.168.0.220'| 2006-11-29 12:56:07|TEST_VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1164801364.260|IAX2/acNTM33A30Ro-1|h|2006-11-29 12:56:07|min: 0.00| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++ 2006-11-29 12:56:07|TEST_VDfastAGI|begin|Perl Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin|0|--debug 2006-11-29 12:56:07|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|||)| 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Environment Dump: 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- accountcode = 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callerid = 0000000000 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- calleridname = V1129125604000000037 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingani2 = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingpres = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callingtns = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- callington = 0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- context = default 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- dnid = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- enhanced = 0.0 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- extension = h 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- language = en 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network = yes 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- network_script = VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------) 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- priority = 2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- rdnis = unknown 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------) 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- type = IAX2 2006-11-29 12:56:07|TEST_VDfastAGI|begin| -- uniqueid = 1164801364.260 2006-11-29 12:56:07|TEST_VDfastAGI|begin|AGI Variables: |1164801364.260|IAX2/acNTM33A30Ro-1|h|IAX2|V1129125604000000037| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|Process to run: |agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16---------------)|VD_hangup|END| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|DEBUG: NODEBUG 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|VD_hangup : V1129125604000000037 IAX2/acNTM33A30Ro-1 2 37 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1164801364.260' limit 1;| 2006-11-29 12:56:07|TEST_VDfastAGI|VD_hangup|VD hangup: no VDAC record found: 1164801364.260 V1129125604000000037

Thanks for your Help

by **mflorell** » Wed Nov 29, 2006 3:58 pm

Can you post some agiout.2006-11-XX file output? FASTagiout output doesn't do much for me in this situation.

by **erwan** » Thu Nov 30, 2006 12:34 am

Here is my agiout

Code: Select all: 2006-11-29 21:00:20|call_log.agi|AGI Environment Dump: 2006-11-29 21:00:20|call_log.agi| -- accountcode = 2006-11-29 21:00:20|call_log.agi| -- callerid = 0000000000 2006-11-29 21:00:20|call_log.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:20|call_log.agi| -- callingani2 = 0 2006-11-29 21:00:20|call_log.agi| -- callingpres = 0 2006-11-29 21:00:20|call_log.agi| -- callingtns = 0 2006-11-29 21:00:20|call_log.agi| -- callington = 0 2006-11-29 21:00:20|call_log.agi| -- channel = Local/910472523311@default-3586,2 2006-11-29 21:00:20|call_log.agi| -- context = default 2006-11-29 21:00:20|call_log.agi| -- dnid = unknown 2006-11-29 21:00:20|call_log.agi| -- enhanced = 0.0 2006-11-29 21:00:20|call_log.agi| -- extension = h 2006-11-29 21:00:20|call_log.agi| -- language = en 2006-11-29 21:00:20|call_log.agi| -- priority = 1 2006-11-29 21:00:20|call_log.agi| -- rdnis = unknown 2006-11-29 21:00:20|call_log.agi| -- request = call_log.agi 2006-11-29 21:00:20|call_log.agi| -- type = Local 2006-11-29 21:00:20|call_log.agi| -- uniqueid = 1164830403.3 2006-11-29 21:00:20|call_log.agi|AGI Variables: |1164830403.3|Local/910472523311@default-3586,2|h|Local|V1129210003000000038| 2006-11-29 21:00:20|call_log.agi||CALL HUNG UP| 2006-11-29 21:00:20|call_log.agi||1164830403.3000000|1164830403| 2006-11-29 21:00:20|call_log.agi|QUERY done: start time = 1164830403 | sec: 17 | min: 0.28 | 2006-11-29 21:00:20|call_log.agi||UPDATE call_log set end_time='2006-11-29 21:00:20',end_epoch='1164830420',length_in_sec=17,length_in_min=' 0.28' where uniqueid='1164830403.3'| 2006-11-29 21:00:20|call_log.agi||DELETE from live_inbound where uniqueid='1164830403.3' and server_ip='192.168.0.220'| 2006-11-29 21:00:20|call_log.agi|+++++ CALL LOG HUNGUP: |1164830403.3|Local/910472523311@default-3586,2|h|2006-11-29 21:00:20|min: 0.28| 2006-11-29 21:00:20|VD_hangup.agi|Perl Environment Dump: 2006-11-29 21:00:20|VD_hangup.agi|0|PRI-----NODEBUG-----16-----ANSWER-----16-----0 2006-11-29 21:00:20|VD_hangup.agi|AGI Environment Dump: 2006-11-29 21:00:20|VD_hangup.agi| -- accountcode = 2006-11-29 21:00:20|VD_hangup.agi| -- callerid = 0000000000 2006-11-29 21:00:20|VD_hangup.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:20|VD_hangup.agi| -- callingani2 = 0 2006-11-29 21:00:20|VD_hangup.agi| -- callingpres = 0 2006-11-29 21:00:20|VD_hangup.agi| -- callingtns = 0 2006-11-29 21:00:20|VD_hangup.agi| -- callington = 0 2006-11-29 21:00:20|VD_hangup.agi| -- channel = Local/910472523311@default-3586,2 2006-11-29 21:00:20|VD_hangup.agi| -- context = default 2006-11-29 21:00:20|VD_hangup.agi| -- dnid = unknown 2006-11-29 21:00:20|VD_hangup.agi| -- enhanced = 0.0 2006-11-29 21:00:20|VD_hangup.agi| -- extension = h 2006-11-29 21:00:20|VD_hangup.agi| -- language = en 2006-11-29 21:00:20|VD_hangup.agi| -- priority = 2 2006-11-29 21:00:20|VD_hangup.agi| -- rdnis = unknown 2006-11-29 21:00:20|VD_hangup.agi| -- request = VD_hangup.agi 2006-11-29 21:00:20|VD_hangup.agi| -- type = Local 2006-11-29 21:00:20|VD_hangup.agi| -- uniqueid = 1164830403.3 2006-11-29 21:00:20|VD_hangup.agi|AGI Variables: |1164830403.3|Local/910472523311@default-3586,2|h|Local|V1129210003000000038| 2006-11-29 21:00:20|VD_hangup.agi|DEBUG: NODEBUG 2006-11-29 21:00:20|VD_hangup.agi|VD_hangup : V1129210003000000038 Local/910472523311@default-3586,2 2 38 2006-11-29 21:00:20|VD_hangup.agi|-- VDhangup Local DEBUG: |PRI|V1129210003000000038|ANSWER|16| 2006-11-29 21:00:20|VD_hangup.agi|+++++ VDAD START LOCAL CHANNEL: EXITING- 2 2006-11-29 21:00:20|call_log.agi|Perl Environment Dump: 2006-11-29 21:00:20|call_log.agi|0|8365 2006-11-29 21:00:20|call_log.agi|AGI Environment Dump: 2006-11-29 21:00:20|call_log.agi| -- accountcode = 2006-11-29 21:00:20|call_log.agi| -- callerid = 0000000000 2006-11-29 21:00:20|call_log.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:20|call_log.agi| -- callingani2 = 0 2006-11-29 21:00:20|call_log.agi| -- callingpres = 0 2006-11-29 21:00:20|call_log.agi| -- callingtns = 0 2006-11-29 21:00:20|call_log.agi| -- callington = 0 2006-11-29 21:00:20|call_log.agi| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 21:00:20|call_log.agi| -- context = default 2006-11-29 21:00:20|call_log.agi| -- dnid = unknown 2006-11-29 21:00:20|call_log.agi| -- enhanced = 0.0 2006-11-29 21:00:20|call_log.agi| -- extension = 8365 2006-11-29 21:00:20|call_log.agi| -- language = en 2006-11-29 21:00:20|call_log.agi| -- priority = 2 2006-11-29 21:00:20|call_log.agi| -- rdnis = unknown 2006-11-29 21:00:20|call_log.agi| -- request = call_log.agi 2006-11-29 21:00:20|call_log.agi| -- type = IAX2 2006-11-29 21:00:20|call_log.agi| -- uniqueid = 1164830403.2 2006-11-29 21:00:20|call_log.agi|AGI Variables: |1164830403.2|IAX2/acNTM33A30Ro-1|8365|IAX2|V1129210003000000038| 2006-11-29 21:00:20|call_log.agi|+++++ CALL LOG START : 2006-11-29 21:00:20 2006-11-29 21:00:20|call_log.agi||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('1164830403.2','IAX2/acNTM33A30Ro-1','','IAX2','192.168.0.220','acNTM33A30Ro-1','8365','2006-11-29 21:00:20','1164830420','','','','','V1129210003000000038')| 2006-11-29 21:00:20|agi-VDADtransfer.agi|Perl Environment Dump: 2006-11-29 21:00:20|agi-VDADtransfer.agi|0|8365 2006-11-29 21:00:20|agi-VDADtransfer.agi|callerID changed: V1129210003000000038 2006-11-29 21:00:20|agi-VDADtransfer.agi|AGI Environment Dump: 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- accountcode = 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- callerid = 0000000000 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- callingani2 = 0 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- callingpres = 0 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- callingtns = 0 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- callington = 0 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- context = default 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- dnid = unknown 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- enhanced = 0.0 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- extension = 8365 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- language = en 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- priority = 3 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- rdnis = unknown 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- request = agi-VDADtransfer.agi 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- type = IAX2 2006-11-29 21:00:20|agi-VDADtransfer.agi| -- uniqueid = 1164830403.2 2006-11-29 21:00:20|agi-VDADtransfer.agi|AGI Variables: |1164830403.2|IAX2/acNTM33A30Ro-1|8365|IAX2|V1129210003000000038|V1129210003000000038|3| 2006-11-29 21:00:20|agi-VDADtransfer.agi|+++++ VDAD START : |38|2006-11-29 21:00:20|1.2.12.1|3| 2006-11-29 21:00:20|agi-VDADtransfer.agi|-- VDAD : ||update of vac table: V1129210003000000038 |UPDATE vicidial_auto_calls set uniqueid='1164830403.2', channel='IAX2/acNTM33A30Ro-1',status='LIVE',stage='LIVE-0' where callerid='V1129210003000000038' order by call_time desc limit 1;| 2006-11-29 21:00:50|call_log.agi|AGI Environment Dump: 2006-11-29 21:00:50|call_log.agi| -- accountcode = 2006-11-29 21:00:50|call_log.agi| -- callerid = unknown 2006-11-29 21:00:50|call_log.agi| -- calleridname = S0611292059208600051 2006-11-29 21:00:50|call_log.agi| -- callingani2 = 0 2006-11-29 21:00:50|call_log.agi| -- callingpres = 0 2006-11-29 21:00:50|call_log.agi| -- callingtns = 0 2006-11-29 21:00:50|call_log.agi| -- callington = 0 2006-11-29 21:00:50|call_log.agi| -- channel = IAX2/3001-4 2006-11-29 21:00:50|call_log.agi| -- context = default 2006-11-29 21:00:50|call_log.agi| -- dnid = unknown 2006-11-29 21:00:50|call_log.agi| -- enhanced = 0.0 2006-11-29 21:00:50|call_log.agi| -- extension = h 2006-11-29 21:00:50|call_log.agi| -- language = en 2006-11-29 21:00:50|call_log.agi| -- priority = 1 2006-11-29 21:00:50|call_log.agi| -- rdnis = unknown 2006-11-29 21:00:50|call_log.agi| -- request = call_log.agi 2006-11-29 21:00:50|call_log.agi| -- type = IAX2 2006-11-29 21:00:50|call_log.agi| -- uniqueid = 1164830360.0 2006-11-29 21:00:50|call_log.agi|AGI Variables: |1164830360.0|IAX2/3001-4|h|IAX2|S0611292059208600051| 2006-11-29 21:00:50|call_log.agi||CALL HUNG UP| 2006-11-29 21:00:50|call_log.agi||DELETE from live_inbound where uniqueid='1164830360.0' and server_ip='192.168.0.220'| 2006-11-29 21:00:50|call_log.agi|+++++ CALL LOG HUNGUP: |1164830360.0|IAX2/3001-4|h|2006-11-29 21:00:50|min: | 2006-11-29 21:00:50|VD_hangup.agi|Perl Environment Dump: 2006-11-29 21:00:50|VD_hangup.agi|0|PRI-----NODEBUG-----0--------------- 2006-11-29 21:00:50|VD_hangup.agi|AGI Environment Dump: 2006-11-29 21:00:50|VD_hangup.agi| -- accountcode = 2006-11-29 21:00:50|VD_hangup.agi| -- callerid = unknown 2006-11-29 21:00:50|VD_hangup.agi| -- calleridname = S0611292059208600051 2006-11-29 21:00:50|VD_hangup.agi| -- callingani2 = 0 2006-11-29 21:00:50|VD_hangup.agi| -- callingpres = 0 2006-11-29 21:00:50|VD_hangup.agi| -- callingtns = 0 2006-11-29 21:00:50|VD_hangup.agi| -- callington = 0 2006-11-29 21:00:50|VD_hangup.agi| -- channel = IAX2/3001-4 2006-11-29 21:00:50|VD_hangup.agi| -- context = default 2006-11-29 21:00:50|VD_hangup.agi| -- dnid = unknown 2006-11-29 21:00:50|VD_hangup.agi| -- enhanced = 0.0 2006-11-29 21:00:50|VD_hangup.agi| -- extension = h 2006-11-29 21:00:50|VD_hangup.agi| -- language = en 2006-11-29 21:00:50|VD_hangup.agi| -- priority = 2 2006-11-29 21:00:50|VD_hangup.agi| -- rdnis = unknown 2006-11-29 21:00:50|VD_hangup.agi| -- request = VD_hangup.agi 2006-11-29 21:00:50|VD_hangup.agi| -- type = IAX2 2006-11-29 21:00:50|VD_hangup.agi| -- uniqueid = 1164830360.0 2006-11-29 21:00:50|VD_hangup.agi|AGI Variables: |1164830360.0|IAX2/3001-4|h|IAX2|S0611292059208600051| 2006-11-29 21:00:50|VD_hangup.agi|DEBUG: NODEBUG 2006-11-29 21:00:50|VD_hangup.agi|VD_hangup : S0611292059208600051 IAX2/3001-4 2 208600051 2006-11-29 21:00:50|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1164830360.0' limit 1;| 2006-11-29 21:00:50|VD_hangup.agi|VD hangup: no VDAC record found: 1164830360.0 S0611292059208600051 2006-11-29 21:00:56|call_log.agi|AGI Environment Dump: 2006-11-29 21:00:56|call_log.agi| -- accountcode = 2006-11-29 21:00:56|call_log.agi| -- callerid = unknown 2006-11-29 21:00:56|call_log.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:56|call_log.agi| -- callingani2 = 0 2006-11-29 21:00:56|call_log.agi| -- callingpres = 0 2006-11-29 21:00:56|call_log.agi| -- callingtns = 0 2006-11-29 21:00:56|call_log.agi| -- callington = 0 2006-11-29 21:00:56|call_log.agi| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 21:00:56|call_log.agi| -- context = default 2006-11-29 21:00:56|call_log.agi| -- dnid = unknown 2006-11-29 21:00:56|call_log.agi| -- enhanced = 0.0 2006-11-29 21:00:56|call_log.agi| -- extension = h 2006-11-29 21:00:56|call_log.agi| -- language = en 2006-11-29 21:00:56|call_log.agi| -- priority = 1 2006-11-29 21:00:56|call_log.agi| -- rdnis = unknown 2006-11-29 21:00:56|call_log.agi| -- request = call_log.agi 2006-11-29 21:00:56|call_log.agi| -- type = IAX2 2006-11-29 21:00:56|call_log.agi| -- uniqueid = 1164830403.2 2006-11-29 21:00:56|call_log.agi|AGI Variables: |1164830403.2|IAX2/acNTM33A30Ro-1|h|IAX2|V1129210003000000038| 2006-11-29 21:00:56|call_log.agi||CALL HUNG UP| 2006-11-29 21:00:56|call_log.agi||1164830403.2000000|1164830420| 2006-11-29 21:00:56|call_log.agi|QUERY done: start time = 1164830420 | sec: 36 | min: 0.60 | 2006-11-29 21:00:56|call_log.agi||UPDATE call_log set end_time='2006-11-29 21:00:56',end_epoch='1164830456',length_in_sec=36,length_in_min=' 0.60' where uniqueid='1164830403.2'| 2006-11-29 21:00:56|call_log.agi||DELETE from live_inbound where uniqueid='1164830403.2' and server_ip='192.168.0.220'| 2006-11-29 21:00:56|call_log.agi|+++++ CALL LOG HUNGUP: |1164830403.2|IAX2/acNTM33A30Ro-1|h|2006-11-29 21:00:56|min: 0.60| 2006-11-29 21:00:56|VD_hangup.agi|Perl Environment Dump: 2006-11-29 21:00:56|VD_hangup.agi|0|PRI-----NODEBUG-----16--------------- 2006-11-29 21:00:56|VD_hangup.agi|AGI Environment Dump: 2006-11-29 21:00:56|VD_hangup.agi| -- accountcode = 2006-11-29 21:00:56|VD_hangup.agi| -- callerid = unknown 2006-11-29 21:00:56|VD_hangup.agi| -- calleridname = V1129210003000000038 2006-11-29 21:00:56|VD_hangup.agi| -- callingani2 = 0 2006-11-29 21:00:56|VD_hangup.agi| -- callingpres = 0 2006-11-29 21:00:56|VD_hangup.agi| -- callingtns = 0 2006-11-29 21:00:56|VD_hangup.agi| -- callington = 0 2006-11-29 21:00:56|VD_hangup.agi| -- channel = IAX2/acNTM33A30Ro-1 2006-11-29 21:00:56|VD_hangup.agi| -- context = default 2006-11-29 21:00:56|VD_hangup.agi| -- dnid = unknown 2006-11-29 21:00:56|VD_hangup.agi| -- enhanced = 0.0 2006-11-29 21:00:56|VD_hangup.agi| -- extension = h 2006-11-29 21:00:56|VD_hangup.agi| -- language = en 2006-11-29 21:00:56|VD_hangup.agi| -- priority = 2 2006-11-29 21:00:56|VD_hangup.agi| -- rdnis = unknown 2006-11-29 21:00:56|VD_hangup.agi| -- request = VD_hangup.agi 2006-11-29 21:00:56|VD_hangup.agi| -- type = IAX2 2006-11-29 21:00:56|VD_hangup.agi| -- uniqueid = 1164830403.2 2006-11-29 21:00:56|VD_hangup.agi|AGI Variables: |1164830403.2|IAX2/acNTM33A30Ro-1|h|IAX2|V1129210003000000038| 2006-11-29 21:00:56|VD_hangup.agi|DEBUG: NODEBUG 2006-11-29 21:00:56|VD_hangup.agi|VD_hangup : V1129210003000000038 IAX2/acNTM33A30Ro-1 2 38 2006-11-29 21:00:56|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1164830403.2' limit 1;| 2006-11-29 21:00:56|VD_hangup.agi|VD hangup: no VDAC record found: 1164830403.2 V1129210003000000038

by **mflorell** » Thu Nov 30, 2006 4:38 am

the transfer agi script is not able to find the vicidial_auto_calls record for the call.

Do you have a call_log entry before the call is placed?

Are you using the FastAGI call_log or the AGI script call_log.agi?

by **erwan** » Thu Nov 30, 2006 2:42 pm

Like you can see in my Extension.conf, i'm using FastAGI

Code: Select all: exten => _910X.,1,AGI(agi://127.0.0.1:4577/call_log) exten => _910X.,2,Dial(IAX2/acNTM33A30Ro:password@iax.plugandtel.net/${EXTEN:2},55,o) exten => _910X.,3,Hangup exten => 8365,1,Playback(sip-silence) exten => 8365,2,AGI(agi://127.0.0.1:4577/call_log) exten => 8365,3,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,4,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,5,AGI(agi-VDADtransfer.agi,${EXTEN}) exten => 8365,6,Hangup

by **mflorell** » Thu Nov 30, 2006 3:04 pm

Are you by chance using CentOS or Fedora/RedHat?

Are you using "nice" in any way?

What Linux kernel are you using?

by **erwan** » Fri Dec 01, 2006 12:32 am

Yest i'm using Centos
with 2.6.9-42.0.3.EL Kernel

I d'on't understand what it meen :" Are you using "nice" in any way? "

by **kchung** » Fri Dec 01, 2006 2:35 am

man nice

by **mflorell** » Fri Dec 01, 2006 2:39 am

This only seems to be a problem for people on CentOS. For some reason it will automatically reduce priority on perl scripts so that they do not run when they are supposed to causing the exact problem you are having.

Changing to Slackware has solved the problem for every one of people that I have talked to that had this problem. I have no idea how it is activated in CentOS, but this does not seem to be a problem on any other Linux distro.

For more information on "nice":
http://www.oreillynet.com/linux/cmd/cmd.csp?path=n/nice

by **erwan** » Fri Dec 01, 2006 4:22 am

does it meen i have no choice than changing to Slackware ??? for Autodial Working ?

by **mflorell** » Fri Dec 01, 2006 7:40 am

That would be your quickest choice. I have worked with 4 separate people on this same issue, and for some reason however they setup their system it would always have this problem. There are a lot of other out there that are using CentOS and do not have this problem. I do not know what the differences between their setups are, but in every one of the 4 cases where this has happened, moving off of CentOS(to ANY other Linux distro) fixed the problem. You could just move to Fedora and things might work fine for you.

by **erwan** » Fri Dec 01, 2006 8:13 am

Have you take a look at the Perl Version, I've tryed woth Perl 5.8.5 and the las Perl 5.8.8

by **kchung** » Fri Dec 01, 2006 10:53 am

Is there a quick way to test for the centos besides dialing issues?

by **Delta239** » Fri Dec 01, 2006 11:47 am

well to resolve that issue what i did was that i had a backup for the files inside /home/cron/ and i replaced those and now works fine... and im using FC4

by **mflorell** » Fri Dec 01, 2006 2:25 pm

What version of astGUIclient are you using?

vicidial.org

Autodial agi-VDADtransfer.agi Hangup

Autodial agi-VDADtransfer.agi Hangup

Autodial agi-VDADtransfer.agi Hangup

Who is online