Calls Being Dropped sip-silence

[Iz3k34l]

ok so i have installed vici from ViciBox.i686-4.0.3.preload.iso on a Proliant ML350 with a dual quad core 2.5 GHZ w/24GB ram. on VMWare ESXI 5.0.0, with no sangoma hardware or any other software added. I have done the updates as instructed in the installation guide. The only this i have done differently from the installation is switched the zone to external and turned off the firewall, which was done to fix some sip server connection issues.

We have setup a manual dial campaign that works fine, we have setup an auto-dial campaign which is where are running into trouble.

The calls are being made to the phone number in the list we have created no problem, but when a call is either answered or picked up by voice mail the gets dropped (on the callee's end its connected for about 6 seconds) but the agent never knows there was a call connected.

here is the CLI log

Code: Select all

NOTICE[8174]: chan_local.c:612 local_call: No such extension/context 1903***96333@default while calling Local channel
[Feb 12 14:10:47] NOTICE[8174]: channel.c:3623 __ast_request_and_dial: Unable to call channel Local/1903***96333@default
[Feb 12 14:10:49]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 12 14:11:00]   == Parsing '/etc/asterisk/manager.conf': [Feb 12 14:11:00] Found
[Feb 12 14:11:00]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 12 14:11:00]     -- Executing [1903***4504@default:1] AGI("Local/1903***4504@default-fd15,2", "agi://127.0.0.1:4577/call_log") in new stack
[Feb 12 14:11:00]     -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Feb 12 14:11:00]     -- Executing [1903***4504@default:2] Dial("Local/1903***4504@default-fd15,2", "sip/flowroute/1903***4504||o") in new stack
[Feb 12 14:11:00]     -- Called flowroute/1903***4504
[Feb 12 14:11:01]     -- SIP/flowroute-0000000b is ringing
[Feb 12 14:11:01]     -- SIP/flowroute-0000000b is making progress passing it to Local/1903***4504@default-fd15,2
[Feb 12 14:11:02]   == Parsing '/etc/asterisk/manager.conf': [Feb 12 14:11:02] Found
[Feb 12 14:11:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 12 14:11:02]   == Parsing '/etc/asterisk/manager.conf': [Feb 12 14:11:02] Found
[Feb 12 14:11:02]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 12 14:11:02]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 12 14:11:03]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 12 14:11:07]   == Parsing '/etc/asterisk/manager.conf': [Feb 12 14:11:07] Found
[Feb 12 14:11:07]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 12 14:11:07]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 12 14:11:14]     -- Got SIP response 405 "Method Not Allowed" back from 192.168.40.10
[Feb 12 14:11:16]     -- SIP/flowroute-0000000b answered Local/1903***4504@default-fd15,2
[Feb 12 14:11:16]        > Channel Local/1903***4504@default-fd15,1 was answered.
[Feb 12 14:11:16]     -- Executing [8368@default:1] Playback("Local/1903***4504@default-fd15,1", "sip-silence") in new stack
[Feb 12 14:11:16]     -- <Local/1903***4504@default-fd15,1> Playing 'sip-silence' (language 'en')
[Feb 12 14:11:16] WARNING[8249]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Feb 12 14:11:16]     -- Executing [8368@default:2] AGI("Local/1903***4504@default-fd15,1", "agi://127.0.0.1:4577/call_log") in new stack
[Feb 12 14:11:16]     -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Feb 12 14:11:16]     -- Executing [8368@default:3] AGI("Local/1903***4504@default-fd15,1", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack
[Feb 12 14:11:16]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 12 14:11:18]     -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 12 14:11:18]     -- Executing [8368@default:4] AGI("Local/1903***4504@default-fd15,1", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack
[Feb 12 14:11:18]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 12 14:11:18]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 12 14:11:19]     -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 12 14:11:19]     -- Executing [8368@default:5] Hangup("Local/1903***4504@default-fd15,1", "") in new stack
[Feb 12 14:11:19]   == Spawn extension (default, 8368, 5) exited non-zero on 'Local/1903***4504@default-fd15,1'
[Feb 12 14:11:19]     -- Executing [h@default:1] DeadAGI("Local/1903***4504@default-fd15,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Feb 12 14:11:20]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Feb 12 14:11:20]     -- Executing [h@default:1] DeadAGI("Local/1903***4504@default-fd15,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----4") in new stack
[Feb 12 14:11:21]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----4 completed, returning 0
[Feb 12 14:11:21]   == Spawn extension (default, 1903***4504, 2) exited non-zero on 'Local/1903***4504@default-fd15,2'
[Feb 12 14:11:32]   == Parsing '/etc/asterisk/manager.conf': [Feb 12 14:11:32] Found
[Feb 12 14:11:32]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 12 14:11:32]     -- Hungup 'DAHDI/pseudo-440113267'
[Feb 12 14:11:32]   == Spawn extension (default, 8600052, 1) exited non-zero on 'SIP/1002-0000000a'
[Feb 12 14:11:32]     -- Executing [h@default:1] DeadAGI("SIP/1002-0000000a", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack


Here is the Carrier info

Code: Select all

[flowroute]
type=friend
secret=pass
username=user
host=provider
dtmfmode=rfc2833
context=inbound
canreinvite=no
allow=ulaw
allow=g729
insecure=port,invite
fromdomain=sip.provider
qualify=yes
nat=yes


and the dial plan info;

Code: Select all

exten => _1X.,1,AGI(agi://127.0.0.1:4577/call_log)
exten => _1X.,2,Dial(${flowroute}/${EXTEN},50,o)
exten => _1X.,3,Hangup


Thanks in advance
Travis

[williamconley]

According to your cli output, your call was still "local" (Local/1903...) for all the lines of the 8368 dialplan execution. Local means the call has not completed properly. No sound or some other flaw.

Since the call cannot transmit sound, it is never sent to an agent.

So the question is what the difference is between that call and a manual dial call.

Additionally: Do you have g729 installed? (if you have not installed it yourself, you do not ... as such it should not be "allow=g729" so delete that line)

[Iz3k34l]

Thank you for your help,

i went ahead and installed on a physical device 3ghz proc. and 2gb ram, using the same iso as was virtualized. I set up two separate campaigns, the first was manual only campaign, which as expected worked fine. I then copied that exact campaign to create an auto campaign using the same list, lead recycling etc... and again the calls are being disconnected as dropped calls when they are answered, the scenario is the same whether dialing land-lines or cell phones. i did install the g729 codec myself on both machines.

I dont understand the significance of the call being "local" or not and what is should be. I ask because both manual and auto dial scenarios have the leading "local" before the conference number and the phone number.

thanks again

[williamconley]

When a manual dial is generated, it goes through a completely different process, and it is always generated as Local, directly from the meetme room. Since it issues from inside the meetme room, there is no "check" to see if it is still local later. And if you get Sound, it's no longer local as it has been bridged. But autodialed calls are not placed into the meetme room with an agent until after they have been answered and confirmed "live". If they are never answered, the routine marks them unanswered based on the reason (cancel, congestion, busy, etc) and of course does not bother sending the call to an agent. If they are answered, but not "LIVE", they are still not routed to an agent as that would waste the agent's time (why have an agent connected to a client they cannot hear?).

So you'll need to compare the debug output from the manual dial and auto-dial calls and see if you can find the difference. And next time, perform all this testing before making any actual changes to the server (like g729 ...). After all, these stock machines are running all over the world without fail. Something is obviously different about YOURS. The one thing your two installations have in common (aside from non-functional autodial) is ... You. So, don't put in g729 or any other cool special personal touches before testing autodial. That may get you where you want to be.

Of course, I still recommend troubleshooting in debug mode to find the ACTUAL problem, but that can be elusive when the call remains local.

[nrc]

Here is the output from a autodial call

Code: Select all

[Feb 13 13:40:31]        > Channel SIP/1000-00000000 was answered.
[Feb 13 13:40:31]     -- Executing [8600051@default:1] MeetMe("SIP/1000-00000000", "8600051|F") in new stack
[Feb 13 13:40:31]   == Parsing '/etc/asterisk/meetme.conf': [Feb 13 13:40:31] Found
[Feb 13 13:40:31]   == Parsing '/etc/asterisk/meetme-vicidial.conf': [Feb 13 13:40:31] Found
[Feb 13 13:40:31]     -- Created MeetMe conference 1023 for conference '8600051'
[Feb 13 13:40:31]     -- <SIP/1000-00000000> Playing 'conf-onlyperson' (language 'en')
[Feb 13 13:40:33]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 13 13:40:40]   == Parsing '/etc/asterisk/manager.conf': [Feb 13 13:40:40] Found
[Feb 13 13:40:40]   == Manager 'sendcron' logged on from 127.0.0.1
[Feb 13 13:40:40]     -- Executing [1214xxxxxxx@default:1] Dial("Local/1214xxxxxx@default-c9c6,2", "SIP/flowroute/1214xxxxxx||o") in new stack
[Feb 13 13:40:40]     -- Called flowroute/1214xxxxxxx
[Feb 13 13:40:48]     -- SIP/flowroute-00000001 is making progress passing it to Local/1214xxxxxx@default-c9c6,2
[Feb 13 13:40:51]     -- SIP/flowroute-00000001 is ringing
[Feb 13 13:40:51]     -- SIP/flowroute-00000001 is making progress passing it to Local/1214xxxxxx5@default-c9c6,2
[Feb 13 13:40:54]     -- SIP/flowroute-00000001 answered Local/1214xxxxxx@default-c9c6,2
[Feb 13 13:40:54]        > Channel Local/1214xxxxxx@default-c9c6,1 was answered.
[Feb 13 13:40:54]     -- Executing [8368@default:1] Playback("Local/1214xxxxxx@default-c9c6,1", "sip-silence") in new stack
[Feb 13 13:40:54]     -- <Local/1214xxxxxx@default-c9c6,1> Playing 'sip-silence' (language 'en')
[Feb 13 13:40:54] WARNING[17662]: file.c:1297 waitstream_core: Unexpected control subclass '-1'
[Feb 13 13:40:54]     -- Executing [8368@default:2] AGI("Local/1214xxxxxx@default-c9c6,1", "agi://127.0.0.1:4577/call_log") in new stack
[Feb 13 13:40:54]     -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
[Feb 13 13:40:54]     -- Executing [8368@default:3] AGI("Local/1214xxxxxx@default-c9c6,1", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack
[Feb 13 13:40:54]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 13 13:40:55]     -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 13 13:40:55]     -- Executing [8368@default:4] AGI("Local/1214xxxxxxx@default-c9c6,1", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack
[Feb 13 13:40:55]     -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi
[Feb 13 13:40:56]   == Manager 'sendcron' logged off from 127.0.0.1
[Feb 13 13:40:57]     -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0
[Feb 13 13:40:57]     -- Executing [8368@default:5] Hangup("Local/1214xxxxxxx@default-c9c6,1", "") in new stack
[Feb 13 13:40:57]   == Spawn extension (default, 8368, 5) exited non-zero on 'Local/1214xxxxxxxx@default-c9c6,1'
[Feb 13 13:40:57]     -- Executing [h@default:1] DeadAGI("Local/1214xxxxxxxx@default-c9c6,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack
[Feb 13 13:40:58]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0
[Feb 13 13:40:58]     -- Executing [h@default:1] DeadAGI("Local/1214xxxxxxx@default-c9c6,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----18-----4") in new stack
[Feb 13 13:40:59]     -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----18-----4 completed, returning 0
[Feb 13 13:40:59]   == Spawn extension (default, 1214xxxxxxx, 1) exited non-zero on 'Local/1214xxxxxx@default-c9c6,2'

can you help us trace this down? there has been no changes to the files...this was a clean install of vicidial 4 we added the carrier, made a campaign and added 3 numbers to the call list.

[williamconley]

compare the debug output from the manual dial and auto-dial calls

I only see CLI outbput (no debug for SIP ...) and do not see anything resembling a comparison between the two call types.

Also worthy of note: We do not use the preload ... so I am unfamiliar with its methods. Did you, for instance, change the IP address after installation?

[nrc]

William are you available on chat? we have done everything we can think of here...we are just trying to get a proof of concept running.

We had osdial up and running and did auto and manual calls just fine -- but don't like the lack of support/updates
we can get vicidial up and doing manual calls just fine...this is both on a VMWARE machine and Physical machine....both with the preload.iso and the standard.iso

Would love to chat with you online, maybe you can tell me some commands to run to get the debug info you are requesting and bounce ideas of eachother.

thanks!

[williamconley]

Direct contact is reserved for Paying Clients of PoundTeam Incorporated. For that, I am always available, of course. That's what they pay me for. LOL

I do stuff like this when I have 5 minutes between appts.