Call Drops Continuously

[hemanshurpatel]

Hello friend
I am using asterisk 1.2.X with astguiclient 2.0.4
I have activated 8369 answering machine detection extetion in campaign setting by replacing 8365 with 8369.

After that i am having problem to calls drop out, if i dial using manual campaign then it works fine but amd extention doesnt call, if i diam using ratio method then AMD extetion calls but some how after call is picked up by user(customer) it is drop out
What could be the problem?


Asterisk cli:

*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
*CLI>
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
> Channel SIP/100000000-007484c0 was answered.
-- Executing MeetMe("SIP/100000000-007484c0", "8600051") in new stack
== Parsing '/etc/asterisk/meetme.conf': Found
-- Created MeetMe conference 1023 for conference '8600051'
-- Playing 'conf-onlyperson' (language 'en')
== Manager 'sendcron' logged off from XXX.XXX.XXX.XXX
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
-- Executing AGI("Local/9898698835@default-b557,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/9898698835@default-b557,2", "Zap/g1/9898698835|60|tTo") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g1/9898698835
-- Zap/2-1 is proceeding passing it to Local/9898698835@default-b557,2
-- Zap/2-1 is ringing
-- Zap/2-1 answered Local/9898698835@default-b557,2
> Channel Local/9898698835@default-b557,1 was answered.
== Manager 'sendcron' logged off from XXX.XXX.XXX.XXX
-- Executing AGI("Local/9898698835@default-b557,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 AMD("Local/9898698835@default-b557,1", "2000|2000|1000|5000|120|50|4|256") in new stack
-- AMD: Local/9898698835@default-b557,1 0000000000 (null) (Fmt: 64)
-- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256]
== Spawn extension (default, 9898698835, 2) exited non-zero on 'Local/9898698835@default-b557,2'
-- Executing DeadAGI("Local/9898698835@default-b557,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0)") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---7-----0) completed, returning 0
-- Executing DeadAGI("Local/9898698835@default-b557,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---7-----0 completed, returning 0
-- AMD: ANSWERING MACHINE: silenceDuration:2000 initialSilence:2000
-- Executing AGI("Zap/2-1", "VD_amd.agi|8369") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
== Spawn extension (default, 8369, 3) exited non-zero on 'Zap/2-1'
-- Executing DeadAGI("Zap/2-1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ----------) completed, returning 0
-- Executing DeadAGI("Zap/2-1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
-- Hungup 'Zap/2-1'
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
-- Executing AGI("Local/9898698835@default-92f3,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/9898698835@default-92f3,2", "Zap/g1/9898698835|60|tTo") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g1/9898698835
-- Zap/2-1 is proceeding passing it to Local/9898698835@default-92f3,2
-- Zap/2-1 is ringing
-- Zap/2-1 answered Local/9898698835@default-92f3,2
> Channel Local/9898698835@default-92f3,1 was answered.
-- Executing AGI("Local/9898698835@default-92f3,1", "agi://127.0.0.1:4577/call_log") in new stack
== Manager 'sendcron' logged off from XXX.XXX.XXX.XXX
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing AMD("Local/9898698835@default-92f3,1", "2000|2000|1000|5000|120|50|4|256") in new stack
-- AMD: Local/9898698835@default-92f3,1 0000000000 (null) (Fmt: 64)
-- AMD: initialSilence [2000] greeting [2000] afterGreetingSilence [1000] totalAnalysisTime [5000] minimumWordLength [120] betweenWordsSilence [50] maximumNumberOfWords [4] silenceThreshold [256]
== Spawn extension (default, 9898698835, 2) exited non-zero on 'Local/9898698835@default-92f3,2'
-- Executing DeadAGI("Local/9898698835@default-92f3,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0)") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---7-----0) completed, returning 0
-- Executing DeadAGI("Local/9898698835@default-92f3,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----7-----0") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---7-----0 completed, returning 0
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
-- Hungup 'Zap/pseudo-1995249436'
== Spawn extension (default, 8600051, 1) exited non-zero on 'SIP/100000000-007484c0'
-- Executing DeadAGI("SIP/100000000-007484c0", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ----------) completed, returning 0
-- Executing DeadAGI("SIP/100000000-007484c0", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
-- AMD: Word detected. iWordsCount:1
-- AMD: Changed state to STATE_IN_SILENCE
-- AMD: Channel [Zap/2-1]. Too long...
-- Executing AGI("Zap/2-1", "VD_amd.agi|8369") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_amd.agi
-- AGI Script VD_amd.agi completed, returning 0
-- Executing AGI("Zap/2-1", "agi-VDAD_LB_transfer.agi|8369") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_LB_transfer.agi
== Manager 'sendcron' logged off from XXX.XXX.XXX.XXX
-- AGI Script agi-VDAD_LB_transfer.agi completed, returning 0
-- Executing AGI("Zap/2-1", "agi-VDAD_LB_transfer.agi|8369") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_LB_transfer.agi
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
== Spawn extension (default, 8369, 5) exited non-zero on 'Zap/2-1'
-- Executing DeadAGI("Zap/2-1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------)") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ----------) completed, returning 0
-- Executing DeadAGI("Zap/2-1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
-- AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- completed, returning 0
-- Hungup 'Zap/2-1'
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from XXX.XXX.XXX.XXX
== Manager 'sendcron' logged off from XXX.XXX.XXX.XXX


agiout.log :

2008-01-23 11:32:55|agi-VDAD_LB_transfer.agi|NNNNNNNNNN No agent record found!!! 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||SELECT voicemail_ext FROM vicidial_campaigns where campaign_id = '112' limit 1;| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|-- VDAD call_hungup timout: |VH0123113301||Zap/2-1|insert to vicidial_manager 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|-- VDAD vac record deleted: |1|112| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||UPDATE vicidial_log set status='DROP',end_epoch='1201068181',length_in_sec='5' where uniqueid = '1201068163.40';| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|-- VDAD vicidial_log update: |1|1201068163.40 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||UPDATE vicidial_list set status='DROP' where lead_id = '16';| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|-- VDAD vicidial_list update: |1|16 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|Perl Environment Dump: 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|0|8369 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|callerID changed: V0123113243000000016 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|AGI Environment Dump: 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- accountcode = 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- callerid = unknown 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- calleridname = V0123113243000000016 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- callingani2 = 0 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- callingpres = 0 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- callingtns = 0 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- callington = 0 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- channel = Zap/2-1 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- context = default 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- dnid = unknown 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- enhanced = 0.0 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- extension = 8369 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- language = en 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- priority = 5 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- rdnis = unknown 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- request = agi-VDAD_LB_transfer.agi 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- type = Zap 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi| -- uniqueid = 1201068163.40 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|AGI Variables: |1201068163.40|Zap/2-1|8369|Zap|V0123113243000000016|V0123113243000000016|5| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|+++++ VDAD START : |16|2008-01-23 11:33:01|1.2.24|5| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||SELECT count(*) FROM vicidial_live_agents where callerid='V0123113243000000016';| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||SELECT count(*) FROM vicidial_auto_calls where callerid='V0123113243000000016' and status IN('LIVE','XFER');|
2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|-- VDAD : |0E0|update of vac table: V0123113243000000016 |UPDATE vicidial_auto_calls set uniqueid='1201068163.40', channel='Zap/2-1',status='LIVE',stage='LIVE-0' where callerid='V0123113243000000016' order by call_time desc limit 1;| 2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = '' and call_time < "" and lead_id != '';|
2008-01-23 11:33:01|agi-VDAD_LB_transfer.agi|WWWWWWWW VDAD XFER REMOTE WAIT: |0||Zap/2-1|V0123113243000000016|1201068163.40|
~
~


I think even if the call receiver at the other end is human call is been diverted to 8369 agi scripts and somehow it disconnects the call

What could be the problem?

[mflorell]

Have you tried changing the settings for AMD?

It looks like it is going through the AMD engine as it should.

Do you have an amd logfile?

[hemanshurpatel]

i didnt change anything in AMD

amd.conf:
initial_silence = 3500
greeting = 1500
after_greeting_silence = 300
total_analysis_time = 5000
min_word_length = 120
between_words_silence = 50
maximum_number_of_words = 5
silence_threshold = 256

I got amd.log file:(it has got 2 entries only)
2008-01-23 11:32:42|15| |AMD_MACHINE|AMD_INITIALSILENCE-2000-2000|0000000000|
2008-01-23 11:32:55|16| |AMD_NOTSURE|AMD_TOOLONG-5000|0000000000|
~