Strange MTE Issue with Blind Transfer

Forums

I've got a strange problem, and I don't know where to start diagnosing it. A call comes in to tl-ringgroup-all. The ring group is set up as follows:
Group Name Main
Time Out 25
Options (none)
Phones to Ring SIP/100 SIP/101 SIP/103 SIP/111
Auto Attendant main-psbc

The person who answers the call then blind transfers the call to an extension. The new extension rings. If it is not answered the call should go to that extension's voicemail. But, it drops.

At some point along the way I capture part of the CLI output for one of these, and I just noticed something strange in it. About 2/3 down there is a VoiceMail command with "25|u" in it. After that there is a message saying no entry for 25.

So, it looks like it is trying to send the call to extension 25. But, why?

Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 101-psbc
-- Goto (macro-tl-userexten-base,s-orig-NOANSWER,1)
-- Executing [s-orig-NOANSWER@macro-tl-userexten-base:1] Goto("SIP/dj5bhc-0943a2e0", "s-NA|1") in new stack
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 108-psbc -- Goto (macro-tl-userexten-base,s-NA,1)
-- Executing [s-NA@macro-tl-userexten-base:1] GotoIf("SIP/dj5bhc-0943a2e0", "0?s-exit|1") in new stack
-- Executing [s-NA@macro-tl-userexten-base:2] GotoIf("SIP/dj5bhc-0943a2e0", "1?s-NA-VOICEMAIL|1") in new stack
-- Goto (macro-tl-userexten-base,s-NA-VOICEMAIL,1)
-- Executing [s-NA-VOICEMAIL@macro-tl-userexten-base:1] Answer("SIP/dj5bhc-0943a2e0", "") in new stack
-- Executing [s-NA-VOICEMAIL@macro-tl-userexten-base:2] GotoIf("SIP/dj5bhc-0943a2e0", "0?ringing") in new stack
-- Executing [s-NA-VOICEMAIL@macro-tl-userexten-base:3] GotoIf("SIP/dj5bhc-0943a2e0", "0?ringing") in new stack
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 102-psbc
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 100-psbc
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 103-psbc
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 107-psbc
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 104-psbc
-- Executing [s-NA-VOICEMAIL@macro-tl-userexten-base:4] VoiceMail("SIP/dj5bhc-0943a2e0", "25|u") in new stack
Extension Changed 103[local-extensions-psbc] new state Idle for Notify User 109-psbc
[Apr 30 13:47:12] WARNING[30607]: app_voicemail.c:3896 leave_voicemail: No entry in voicemail config file for '25'
-- Executing [s-NA-VOICEMAIL@macro-tl-userexten-base:5] Hangup("SIP/dj5bhc-0943a2e0", "") in new stack
== Spawn extension (macro-tl-userexten-base, s-NA-VOICEMAIL, 5) exited non-zero on 'SIP/dj5bhc-0943a2e0' in macro 'tl-userexten-base'
== Spawn extension (macro-tl-stdexten, s, 5) exited non-zero on 'SIP/dj5bhc-0943a2e0' in macro 'tl-stdexten'
== Spawn extension (from-inside-redir-psbc, 103, 1) exited non-zero on 'SIP/dj5bhc-0943a2e0'
-- Executing [h@from-inside-redir-psbc:1] Hangup("SIP/dj5bhc-0943a2e0", "") in new stack
== Spawn extension (from-inside-redir-psbc, h, 1) exited non-zero on 'SIP/dj5bhc-0943a2e0'

I checked my logs today and found two more calls like this, but this time it sent the callse to "1*@default-psbc".

[Jun 1 09:54:42] VERBOSE[5136] logger.c: -- Executing [s@macro-tl-transfer-to-voicemail:3] VoiceMail("SIP/dj5bhc-b6b406c8", "1*@default-psbc|") in new stack
[Jun 1 09:55:41] VERBOSE[5675] logger.c: -- Executing [s@macro-tl-transfer-to-voicemail:3] VoiceMail("SIP/dj5bhc-b6cb3468", "1*@default-psbc|") in new stack

Submitted by eeman on Tue, 06/02/2009 Permalink

i thought you used aastra phones? I believe they are incapable of blind transfers.

what version of pbx manager is this?

Submitted by dozment on Tue, 06/02/2009 Permalink

You have an excellent memory! Our first customer has Aastra, and to his day there are things about the Aastra that really miss. But, everyone else is using Polycoms. This problem only seems to exist for one customer.

I'm running MTE v 6.0.1.70

Submitted by eeman on Tue, 06/02/2009 Permalink

I already fixed this in 6.0.1.72 25 is the arg2 value of timeout for your ring group macro. Its a recent behavior with chan_local and recent versions of asterisk when dial exits on a timeout. The other channel variables revert all the way back to the original macro.

Submitted by dozment on Tue, 06/02/2009 Permalink

The timeout is in fact set to 25 seconds. I thought that must be where it was coming from, but didn't know how to find it. Upgrading to 6.0.1.72 as I type...