IP Office disconnects on Voicemail Pro transfer

zachlac · Feb 22, 2010

We're trying to use Voicemail Pro Autoattendant to handle incoming calls. However, whenever an external call comes in and the person types an extension to transfer to, the call is disconnected. I've collected a log from DbgView, shown at the bottom. You can see where, near the end of the call, the session simply receives a CLOSE for no reason.

Why is this automatically disconnecting?

Code:

22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   New VMAIL Client
22/02 15:46:41.640 DbgOut   (01,5) 1340,3732: DllMain(hinstDLL=0x10000000,
fdwReason=DLL_THREAD_ATTACH)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Configuring for reliable
disconnect, IDLE time is 300.000s
22/02 15:46:41.640 vmprov5s (06,5) 1340,1464:   VMClient::RxOpen 04F4855C
Short Codes.autoattend (4), session=0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Receive OPEN  for session
104, call-id 24
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Access =
ACCESS_UNCLUED_VOICEMAIL:
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Mailbox: Short
Codes.autoattend
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Calling Party: 910008
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Display String: 910008
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Called Party:
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Greeting Modifier:
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Language: enu
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Call Ident: 24
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Call Status: Unknown (0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Call Type: External
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Call Direction: Incoming
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:       Call Type: External
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   FindMailBoxByCLI 910008
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   FindMailBoxByCLI 910008
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Found VMPNode Short
Codes.autoattend
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   FindMailBoxByCLI 910008
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Short Codes.autoattend.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Short
Codes.autoattend.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Short Codes.autoattend.Test Conditions.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Short
Codes.autoattend.Test Conditions.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Short Codes.autoattend.Test Conditions.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Short
Codes.autoattend.Test Conditions.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Weather Condition.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Weather Condition.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Weather Condition.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Weather Condition.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Comparison ' == closed' 
Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Holiday.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Holiday.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Holiday.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Holiday.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '04/07/2006
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '23/11/2006
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '25/12/2006
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '01/01/2007
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '04/07/2007
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '22/11/2007
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '25/12/2007
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '01/01/2008
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '10/10/2006
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '17/10/2006
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Date Compare '23/11/2007
vs (null)'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Tested Condition
'HolidayCalendar'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Day Night.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Day Night.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Test Day Night.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Test Day Night.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Day Compare '2'  Result: 1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Time Compare '09:00 vs
18:00'  Result: 1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Day Compare '3'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Day Compare '4'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Day Compare '5'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Day Compare '6'  Result: 0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Tested Condition
'WeeklyBusinessSchedule'  Result: 1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Open for Business.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Open for Business.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Test Conditions.Open for Business.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Test
Conditions.Open for Business.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Short Codes.autoattend.Day Menu.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Short
Codes.autoattend.Day Menu.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Short Codes.autoattend.Day Menu.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Short
Codes.autoattend.Day Menu.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Day Menu
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Day Menu
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Day Menu.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Day
Menu.Start Point.1
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing request to run
node Day Menu.Day Menu.0
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:   Executing node Day
Menu.Day Menu.0
22/02 15:46:41.640 vmprov5s (06,5) 1340,1464:   VMClient::RxOpen - Created
dialog 03B3C1F4
22/02 15:46:41.640 vmprov5s (09,5) 1340,1464:    - Internal: !Internal!=N
22/02 15:46:41.687 vmprov5s (09,5) 1340,1464:   Configuring for reliable
disconnect, IDLE time is 300.000s
22/02 15:46:41.687 vmprov5s (06,5) 1340,1464:   VMClient::RxActive
04F4855C (session=104)
22/02 15:46:41.687 vmprov5s (09,5) 1340,1464:   Receive ACTIVE  for
session 104, call-id 24
22/02 15:46:43.437 vmprov5s (1b,5) 1340,1464:   VMClient::DtmfDetect
04F4855C '2' at 520, length 240 - session:104
22/02 15:46:43.687 vmprov5s (1b,5) 1340,1464:   VMClient::DtmfDetect
04F4855C '3' at 400, length 240 - session:104
22/02 15:46:43.812 vmprov5s (1b,5) 1340,1464:   VMClient::DtmfDetect
04F4855C '4' at 1000, length 240 - session:104
22/02 15:46:43.812 vmprov5s (09,5) 1340,1464:   Executing request to run
node Day Menu.Transfer to Extension.0
22/02 15:46:43.812 vmprov5s (09,5) 1340,1464:   Executing node Day
Menu.Transfer to Extension.0
22/02 15:46:43.812 vmprov5s (09,5) 1340,1464:   Executing request to run
node Day Menu.Transfer to Extension.1
22/02 15:46:43.812 vmprov5s (09,5) 1340,1464:   Executing node Day
Menu.Transfer to Extension.1
22/02 15:46:45.453 vmprov5s (09,5) 1340,3732:   soundbites <= 0 so delete
sbl(0)
22/02 15:46:45.453 vmprov5s (09,5) 1340,3732:   SetTransfer=234
22/02 15:46:45.453 vmprov5s (09,5) 1340,3732:   GetTransfer=234
22/02 15:46:45.453 vmprov5s (06,5) 1340,3732:   VMClient::Transfer2
04F4855C 234 (session=104)
22/02 15:46:45.453 vmprov5s (09,5) 1340,3732:   SetTransfer=(null)
22/02 15:46:45.453 vmprov5s (06,5) 1340,1464:   VMClient::RxClose 04F4855C
(session=104)
22/02 15:46:45.453 vmprov5s (09,5) 1340,1464:   Receive CLOSE  for session
104, call-id 0
22/02 15:46:45.484 DbgOut   (01,5) 1340,3732: DllMain(hinstDLL=0x10000000,
fdwReason=DLL_THREAD_DETACH)

zachlac · Feb 25, 2010

One related question: using Assisted transfer, if a line is busy, then how can I make it still right for the caller? It works to where it comes in on a new line for the recipient, but the caller has no dialing tones.

Thanks everyone for the help.

amriddle01 · Feb 25, 2010

What do you mean? Why would the caller get dialtone? Are you trying to do an assisted transfer off switch/external?

ACS - IP Office Implement

"What the Crocodile Hat....was that?

zachlac · Feb 25, 2010

I'm sorry, what I meant was that they transfer to the busy phone, and the recipient sees a second call coming in, but the caller does not hear the ringing normally associated with a call.

amriddle01 · Feb 25, 2010

They won't with assisted they will hear your hold music, if they hear nothing your holdmusic isn't working or is non-existant, you can route the call when the user is busy by connecting the Busy result to something but that would need call waiting turned off/reserve last ca on the user

ACS - IP Office Implement

"What the Crocodile Hat....was that?

CarGoSki · Feb 26, 2010

Forgot to turn on Layer 1, 2 and 3 in sysmonitor? Its the ISDN tab.

It's people like you who generalize.

zachlac · Feb 26, 2010

I turned on the layers, but forgot to turn on Tx, Rx, for each. New link:

http://www.box.net/shared/ps1khu1523

Problem is still not resolved. The Assisted Transfer works sometimes, not others.

CarGoSki · Feb 26, 2010

You just missed the entry point of the call in the traces you posted.

Well this is the first time I recall seeing CLAIM in the traces:

237556mS PRN: CLAIM called on call which is not ACD A=1.4.1 B=0.1018.0

Wouldn't you want to use VoicemailNode instead of VoicemailCollect when targeting a short code leave point?

http://marketingtools.avaya.com/kno...rojects/manager/sc_features_voicemailnode.htm

231064mS CMTARGET: SYS SC:10 *85 3 sc=type=VoicemailCollect code=*85, num="Short Codes.autoattend"

I would route AA to a group set for no members and queuing off and pick it up at the group vm leave point instead of routing to a SC.

Looks like you are adding a prefix: 231061mS PRN: Number in prefix 91

Do you have anything in the TAG field in ICR for the route?

I don't get you exact callflow. From the traces it looks like somehow calls match *85 then end up at a hunt group, ring the receptionist then try to dial 9#

240374mS ISDNL2Tx: v=1 peb=1
0000 00 01 1c 1a 08 02 80 b5 62 1c 32 9f aa 06 80 01 ........b.2.....
0010 00 82 01 00 8b 01 00 a1 24 02 02 39 23 02 01 0b ........$..9#...
0020 30 80 a0 80 a5 09 0a 01 04 12 04 38 39 30 35 0a 0..........8905.
0030 01 01 00 00 80 05 50 61 75 6c 61 00 00 ......<NAME>..

I suppose you are trying to censor some data since ascii 50 61 75 6c 61 = Paula and not <NAME> But I will say that did attract my attention since I don't recall ever seeing <NAME> in the ISDN dchan messages. BTW if you are tying to censor realize that the string 32 34 30 36 33 31 30 30 32 36 = 2406310026

Since ascii 39 23 is 9# I have to wonder why is 9# in the dchan messages.

Well it is late on Friday and I am anxious to leave so perhaps I am not thinkig clearly however perhaps you could carefully type out the exact call flow from ICR to *85 to AA Day to 9#. The posted trace appears to be one call since the REF number is the same throughout. ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=00B5(Local)

It's people like you who generalize.

tlpeter · Feb 26, 2010

CarGoSki, where you drinking ?

You are sharp!!!

Indeed never use voicemail collect with shortcodes.
I never found it working well.
Voicemail node is made for it so use it for that.

ACS IP Office or is it ACSS

ACA - Implement IP Telephony -- ACA - Design IP Telephony
ACA - Voice Services Management
______________
Women and cats can do as they please and men and dogs should relax and get used to the idea!

zachlac · Feb 26, 2010

Why yes, I was trying to not publish our phone numbers on the internet...as I'm sure you understand...

New link for the trace:

http://www.box.net/shared/sghg81zqom

Again, sorry about not having a great understanding of the system, but I don't really know what Voicemail Node and Voicemail Collect are, or how to edit these settings. In VMP, I see the short code for Auto Attend, and I have it set up so that it eventually rings as "Transfer to Reception", which transfers to a mailbox "Reception". This has the same name as a Hunt Group in IP Office, so I guess those things tie together and ringing a mailbox in VMP rings the hunt group in IP Office.

Thanks for the help.

tlpeter · Feb 26, 2010

In the ipoffice there is a shortcode *85

That shortcode has feature VoicemailCollect

*85
VoicemailCollect
AutoAttendant
Line id 0

Change the VoicemailCollect to VoicemailNode

Voicemail collect is for using mailboxes and or sending call to a module (incoming call route).
VoicemailNode is made for sending calls in to a module by hand.

This is not the best explanation but you have to deal with it

ACS IP Office or is it ACSS

ACA - Implement IP Telephony -- ACA - Design IP Telephony
ACA - Voice Services Management
______________
Women and cats can do as they please and men and dogs should relax and get used to the idea!

Tek-Tips is the largest IT community on the Internet today!

Members share and learn making Tek-Tips Forums the best source of peer-reviewed technical information on the Internet!

IP Office disconnects on Voicemail Pro transfer

zachlac

IS-IT--Management

zachlac

IS-IT--Management

amriddle01

Programmer

zachlac

IS-IT--Management

amriddle01

Programmer

CarGoSki

Programmer

zachlac

IS-IT--Management

CarGoSki

Programmer

tlpeter

Programmer

zachlac

IS-IT--Management

tlpeter

Programmer

Similar threads

Part and Inventory Search

Sponsor