vicidial.org

Hello Vicidial Team,

I am having an issue where the agent alert is sometimes playing on agent side and sometimes not. Let's say happening only on 3 out of 10 calls. We are running only on a single server where the DB,WEB and VICI(Asterisk) are hosted. I've noticed that there are times that 83047777777777@vicidial-auto is not being executed.

Hope you could assist. Thanks.


Working

-- Executing [+15713862444@trunkinbound:1] Goto("SIP/twilio3-0000226c", "trunkinbound,5713862444,1") in new stack
-- Goto (trunkinbound,5713862444,1)
-- Executing [5713862444@trunkinbound:1] AGI("SIP/twilio3-0000226c", "agi-DID_route.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
-- <SIP/twilio3-0000226c>AGI Script agi-DID_route.agi completed, returning 0
-- Executing [s@TEST_INBOUND_IVR:1] Answer("SIP/twilio3-0000226c", "") in new stack
> 0x7f25380d9b40 -- Strict RTP switching to RTP target address 34.203.251.163:16896 as source
-- Executing [s@TEST_INBOUND_IVR:2] AGI("SIP/twilio3-0000226c", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----YES-----TEST_INBOUND_IVR-----24hours-----CALLMENU-----CLOSED_IVR----------NO") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
-- <SIP/twilio3-0000226c> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio3-0000226c> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio3-0000226c>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
-- Executing [s@TEST_INBOUND_IVR:3] Set("SIP/twilio3-0000226c", "INVCOUNT=0") in new stack
-- Executing [s@TEST_INBOUND_IVR:4] BackGround("SIP/twilio3-0000226c", "test_ivr_open") in new stack
-- <SIP/twilio3-0000226c> Playing 'test_ivr_open.slin' (language 'en')
> 0x7f25380d9b40 -- Strict RTP learning complete - Locking on source address 34.203.251.163:16896
[Mar 22 00:05:53] DTMF[9860][C-000042c6]: channel.c:4126 __ast_read: DTMF begin '1' received on SIP/twilio3-0000226c
[Mar 22 00:05:53] DTMF[9860][C-000042c6]: channel.c:4130 __ast_read: DTMF begin ignored '1' on SIP/twilio3-0000226c
[Mar 22 00:05:54] DTMF[9860][C-000042c6]: channel.c:4040 __ast_read: DTMF end '1' received on SIP/twilio3-0000226c, duration 240 ms
[Mar 22 00:05:54] DTMF[9860][C-000042c6]: channel.c:4110 __ast_read: DTMF end passthrough '1' on SIP/twilio3-0000226c
-- Executing [1@TEST_INBOUND_IVR:1] AGI("SIP/twilio3-0000226c", "cm.agi,CALLMENU-----1-----comments-----1-----0") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/cm.agi
-- <SIP/twilio3-0000226c>AGI Script cm.agi completed, returning 0
-- Executing [1@TEST_INBOUND_IVR:2] AGI("SIP/twilio3-0000226c", "agi-VDAD_ALL_inbound.agi,CID-----LB-----TEST_INBOUND-----TEST_INBOUND_IVR--------------------998-----1-----TESTCAMP------------------------------") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
-- <SIP/twilio3-0000226c> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio3-0000226c> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio3-0000226c> Playing 'ENV_QAMessaging.slin' (escape_digits=) (sample_offset 0) (language 'en')
== Manager 'sendcron' logged on from 127.0.0.1
-- Called 58600051@default
-- Executing [58600051@default:1] MeetMe("Local/58600051@default-000026a5;2", "8600051,Fmq") in new stack
-- Local/58600051@default-000026a5;1 answered
-- Executing [8309@default:1] Answer("Local/58600051@default-000026a5;1", "") in new stack
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing [8309@default:2] Monitor("Local/58600051@default-000026a5;1", "wav,20190322-000558_19999") in new stack
-- Executing [8309@default:3] Wait("Local/58600051@default-000026a5;1", "3600") in new stack
-- Called 78600051@default
-- Executing [78600051@default:1] MeetMe("Local/78600051@default-000026a6;2", "8600051,Fq") in new stack
-- Local/78600051@default-000026a6;1 answered
-- Executing [83047777777777@vicidial-auto:1] Answer("Local/78600051@default-000026a6;1", "") in new stack
-- Executing [83047777777777@vicidial-auto:2] Playback("Local/78600051@default-000026a6;1", "SMS_All_Program") in new stack
-- <Local/78600051@default-000026a6;1> Playing 'SMS_All_Program.slin' (language 'en')
== Manager 'sendcron' logged off from 127.0.0.1
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing [83047777777777@vicidial-auto:3] Hangup("Local/78600051@default-000026a6;1", "") in new stack
== Spawn extension (vicidial-auto, 83047777777777, 3) exited non-zero on 'Local/78600051@default-000026a6;1'
[Mar 22 00:06:00] WARNING[9886][C-000042ca]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel


------------------------------------


Not working

== Using SIP RTP CoS mark 5
> 0x7f25380d9b40 -- Strict RTP learning after remote address set to: 34.203.251.198:15054
-- Executing [+15713862444@trunkinbound:1] Goto("SIP/twilio4-00002273", "trunkinbound,5713862444,1") in new stack
-- Goto (trunkinbound,5713862444,1)
-- Executing [5713862444@trunkinbound:1] AGI("SIP/twilio4-00002273", "agi-DID_route.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-DID_route.agi
-- <SIP/twilio4-00002273>AGI Script agi-DID_route.agi completed, returning 0
-- Executing [s@TEST_INBOUND_IVR:1] Answer("SIP/twilio4-00002273", "") in new stack
== Manager 'sendcron' logged on from 127.0.0.1
== DTLS ECDH initialized (automatic), faster PFS enabled
== Using SIP RTP CoS mark 5
-- Called 101118
> 0x7f25380d9b40 -- Strict RTP switching to RTP target address 34.203.251.198:15054 as source
-- Executing [s@TEST_INBOUND_IVR:2] AGI("SIP/twilio4-00002273", "agi-VDAD_inbound_calltime_check.agi,CALLMENU-----YES-----TEST_INB OUND_IVR-----24hours-----CALLMENU-----CLOSED_IVR----------NO") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_inbound_calltime_check.agi
-- SIP/101118-00002274 is ringing
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273>AGI Script agi-VDAD_inbound_calltime_check.agi completed, returning 0
-- Executing [s@TEST_INBOUND_IVR:3] Set("SIP/twilio4-00002273", "INVCOUNT=0") in new stack
-- Executing [s@TEST_INBOUND_IVR:4] BackGround("SIP/twilio4-00002273", "test_ivr_open") in new stack
-- <SIP/twilio4-00002273> Playing 'test_ivr_open.slin' (language 'en')
> 0x7f2524109240 -- Strict RTP learning after remote address set to: 192.168.56.1:64096
> 0x7f2524109240 -- Strict RTP learning after remote address set to: 10.10.50.183:64097
> 0x7f2524109240 -- Strict RTP learning after remote address set to: 10.130.6.89:64098
-- SIP/101118-00002274 answered
-- Executing [8600051@meetme-enter-login:1] MeetMe("SIP/101118-00002274", "8600051,FG(agent-loginok)") in new stack
== Parsing '/etc/asterisk/meetme.conf': Found
== Parsing '/etc/asterisk/meetme-vicidial.conf': Found
-- Created MeetMe conference 1023 for conference '8600051'
-- <SIP/101118-00002274> Playing 'agent-loginok.ulaw' (language 'en')
> 0x7f2524109240 -- Strict RTP learning after ICE completion
> 0x7f25380d9b40 -- Strict RTP learning complete - Locking on source address 34.203.251.198:15054
== Manager 'sendcron' logged off from 127.0.0.1
[Mar 22 00:08:33] DTMF[11018][C-000042e1]: channel.c:4126 __ast_read: DTMF begin '1' received on SIP/twilio4-00002273
[Mar 22 00:08:33] DTMF[11018][C-000042e1]: channel.c:4130 __ast_read: DTMF begin ignored '1' on SIP/twilio4-00002273
-- <SIP/101118-00002274> Playing 'conf-onlyperson.ulaw' (language 'en')
> 0x7f2524109240 -- Strict RTP switching to RTP target address 10.10.50.183:64097 as source
[Mar 22 00:08:34] DTMF[11018][C-000042e1]: channel.c:4040 __ast_read: DTMF end '1' received on SIP/twilio4-00002273, duration 240 ms
[Mar 22 00:08:34] DTMF[11018][C-000042e1]: channel.c:4110 __ast_read: DTMF end passthrough '1' on SIP/twilio4-00002273
-- Executing [1@TEST_INBOUND_IVR:1] AGI("SIP/twilio4-00002273", "cm.agi,CALLMENU-----1-----comments-----1-----0") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/cm.agi
> 0x7f2524109240 -- Strict RTP learning complete - Locking on source address 10.10.50.183:64097
-- <SIP/twilio4-00002273>AGI Script cm.agi completed, returning 0
-- Executing [1@TEST_INBOUND_IVR:2] AGI("SIP/twilio4-00002273", "agi-VDAD_ALL_inbound.agi,CID-----LB-----TEST_INBOUND-----TEST_INB OUND_IVR--------------------998-----1-----TESTCAMP------------------------------") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'ENV_QAMessaging.slin' (escape_digits=) (sample_offset 0) (language 'en')
== Manager 'sendcron' logged on from 127.0.0.1
-- Called 58600051@default
-- Executing [58600051@default:1] MeetMe("Local/58600051@default-000026b1;2", "8600051,Fmq") in new stack
-- Local/58600051@default-000026b1;1 answered
-- Executing [8309@default:1] Answer("Local/58600051@default-000026b1;1", "") in new stack
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing [8309@default:2] Monitor("Local/58600051@default-000026b1;1", "wav,20190322-000838_19999") in new stack
-- Called 78600051@default
-- Executing [8309@default:3] Wait("Local/58600051@default-000026b1;1", "3600") in new stack
-- Executing [78600051@default:1] MeetMe("Local/78600051@default-000026b2;2", "8600051,Fq") in new stack
== Spawn extension (default, 78600051, 1) exited non-zero on 'Local/78600051@default-000026b2;2'
[Mar 22 00:08:38] WARNING[11048][C-000042e5]: func_hangupcause.c:140 hangupcause_read: Unable to find information for channel
-- Executing [h@default:1] AGI("Local/78600051@default-000026b2;2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG----- 0--------------------)") in new stack
-- <Local/78600051@default-000026b2;2>AGI Script agi://127.0.0.1:4577/call_log--HVcauses ... ---------- -) completed, returning 0
== Manager 'sendcron' logged off from 127.0.0.1
== Manager 'sendcron' logged off from 127.0.0.1
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273> Playing 'sip-silence.gsm' (escape_digits=) (sample_offset 0) (language 'en')
-- <SIP/twilio4-00002273>AGI Script agi-VDAD_ALL_inbound.agi completed, returning 0
-- Executing [172*023*008*189*8600051@default:1] Goto("SIP/twilio4-00002273", "default,8600051,1") in new stack

Hope you could help me to isolate this issue. Thanks in advance.

Hello Everyone,

Any idea why it is happening?

Most likely reason is that your system is overloaded. Could be a misconfiguration somewhere as well. If you are using any kind of virtualization that could certainly cause this. You're also on a scratch install system instead of VICIbox, which tends to be another reason we see strange issues like this.

1) I'd have been here a couple weeks ago, but I watch "unanswered" more than "unseen previously" and your post disappeared from "unanswered" when you posted your second and third comments. For future reference, if noone has commented on your post yet, it's better to edit your original than to post another note. The sytem views "thread with another post" to be Answered, even if the "nother post" is by OP . Thus it disappears from the "unanswered" queue and guys like me ... never see it. Other boards (like Redmine, which I recommend highly for project management) work well with "pinging" your thread, but phpBB doesn't reward that behavior or everyone would constantly be pinging their posts to stay on top and nothing new would ever be visible. lol. (Free public boards have different policies than private project managers, I suppose)

2) Are you certain that all these calls are taking the same path to get where they are going? Timeout vs Invalid vs valid option in a call menu may go through different pathways and invoke different options. As do different DID options and Ingroup pathways.

3) For the record, which audio file is your agent alert? test_ivr_open? or ENV_QAMessaging? SMS_All_Program?

4) Is this output from screen -r asterisk? asterisk -R? or from a log file? (note that only "screen -r asterisk" has perl agi script crash notices, so it's always a good idea to have at least one gander at that just in case ... especially since you have a non-Vicibox install)

5) When you say "3 of 10" is this at all times? Or only when busy? For instance, if you TEST one call at a time after hours, will 3 of 10 still fail?