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!

  • Congratulations derfloh on being selected by the Tek-Tips community for having the most helpful posts in the forums last week. Way to Go!

VMPRO / offsite transfer, very strange

Status
Not open for further replies.

jiml302

IS-IT--Management
Joined
Jan 11, 2012
Messages
3
Location
US
Very strange issue (4.2.23)

Call route goes as follows (did from pri/ to AA/HUNTgrp, route by status / to menu module. This is setup on 2 did's. when the hunt group A is called and in night service, the RBS goes to transfer shortcode, and works perfectly fine. When huntgroup B gets called while in night RBS sends to transfer SC, the same shortcode non the less it fails with busy. I can dial the shortcode from any phone and it works perfectly fine. I tried rebuilding call flow from scratch in VMPRO, i even set up a NEW test did-HG-just transfer that fails with same busy.




26/09 08:05:25.640 vmprov5s (09,4) c34, f74: New VMAIL Client
26/09 08:05:25.640 DbgOut (01,5) 3124,3852: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
26/09 08:05:25.640 vmprov5s (0a,5) c34, f0c: OSThreadFunc entered[VMClient, 03FCC1E0]
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Configuring for reliable disconnect, IDLE time is 300.000s
26/09 08:05:25.640 vmprov5s (06,5) c34, f74: VMClient::RxOpen 03FCC1C8 TESTAA (1), session=00000072
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive OPEN for session 00000072, call-id 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Check flags[13] 0 for advising recording
26/09 08:05:25.640 vmprov5s (06,5) c34, f74: VMClient::RxOpen 03FCC1C8 TESTAA (1), session=00000072
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive OPEN for session 00000072, call-id 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Access = ACCESS_LEAVE_VOICEMAIL:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Mailbox: TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Calling Party: INCOMINGCALL
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Display String: INCOMINGCALL>TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Target Party: 505
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Greeting Modifier:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Language: enu
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Ident: 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Status: Busy (2)
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Type: External
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Direction: Incoming
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Called Party:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - DDI Number: 5957
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Calling Party Name:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Called Party Name: TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Found VMPNode TESTAA.Leave
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Start Point.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Start Point.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Transfer.0
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Transfer.0
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Transfer.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Transfer.1
26/09 08:05:25.656 vmprov5s (09,4) c34, f74: SetTransfer=555
26/09 08:05:25.656 vmprov5s (06,5) c34, f74: VMClient::RxOpen - Created dialog 03FDAA40
26/09 08:05:25.656 vmprov5s (09,4) c34, f74: - Internal: !Internal!=N
26/09 08:05:25.671 vmprov5s (09,4) c34, f0c: Session: 00000072 - GetTransfer=555, client_info is true
26/09 08:05:25.671 vmprov5s (06,5) c34, f0c: VMClient::Transfer2 03FCC1C8 555 (session=00000072)
26/09 08:05:25.671 vmprov5s (09,4) c34, f0c: Session: 00000072 - SetTransfer=(null)
26/09 08:05:25.687 vmprov5s (09,4) c34, f74: Session: 00000072 - Configuring for reliable disconnect, IDLE time is 300.000s
26/09 08:05:25.687 vmprov5s (06,5) c34, f74: VMClient::RxClose 03FCC1C8 (session=00000072)
26/09 08:05:25.687 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive CLOSE for session 00000072, call-id 31
26/09 08:05:25.703 vmprov5s (09,4) c34, f0c: Session: 00000072 - Mailbox name retrieved=TESTAA new=0 unopened=0 old=0 saved=0
26/09 08:05:25.703 vmprov5s (0a,5) c34, f0c: OSThreadFunc exited[VMClient, 03FCC1E0]
26/09 08:05:25.703 vmprov5s (0a,5) c34, f0c: OSTask::Kill[VMClient, 03FCC1E0]
26/09 08:05:25.703 vmprov5s (09,5) c34, f0c: ~IClient [Connections 0 - 0] [Buffer pool(0:1000 1:250 2:250 3:548), in use:0, seq:0] [Links:9181 from 12000] [CMMsg:0 left from 0] [VM:0 NODES:0 SN:0 SBL:0 TASKS:5 FILES:0 Msgs:1505]
26/09 08:05:25.703 DbgOut (01,5) 3124,3852: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: New VMAIL Client
26/09 08:05:25.640 DbgOut (01,5) 3124,3852: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
26/09 08:05:25.640 vmprov5s (0a,5) c34, f0c: OSThreadFunc entered[VMClient, 03FCC1E0]
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Configuring for reliable disconnect, IDLE time is 300.000s
26/09 08:05:25.640 vmprov5s (06,5) c34, f74: VMClient::RxOpen 03FCC1C8 TESTAA (1), session=00000072
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive OPEN for session 00000072, call-id 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Check flags[13] 0 for advising recording
26/09 08:05:25.640 vmprov5s (06,5) c34, f74: VMClient::RxOpen 03FCC1C8 TESTAA (1), session=00000072
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive OPEN for session 00000072, call-id 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Access = ACCESS_LEAVE_VOICEMAIL:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Mailbox: TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Calling Party: INCOMINGCALL
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Display String: INCOMINGCALL>TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Target Party: 505
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Greeting Modifier:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Language: enu
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Ident: 31
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Status: Busy (2)
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Type: External
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Call Direction: Incoming
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Called Party:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - DDI Number: 5957
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Calling Party Name:
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Called Party Name: TESTAA
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Found VMPNode TESTAA.Leave
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Start Point.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Start Point.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Transfer.0
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Transfer.0
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing request to run node TESTAA.Leave.Transfer.1
26/09 08:05:25.640 vmprov5s (09,4) c34, f74: Session: 00000072 - Executing node TESTAA.Leave.Transfer.1
26/09 08:05:25.656 vmprov5s (09,4) c34, f74: SetTransfer=555
26/09 08:05:25.656 vmprov5s (06,5) c34, f74: VMClient::RxOpen - Created dialog 03FDAA40
26/09 08:05:25.656 vmprov5s (09,4) c34, f74: - Internal: !Internal!=N
26/09 08:05:25.671 vmprov5s (09,4) c34, f0c: Session: 00000072 - GetTransfer=555, client_info is true
26/09 08:05:25.671 vmprov5s (06,5) c34, f0c: VMClient::Transfer2 03FCC1C8 555 (session=00000072)
26/09 08:05:25.671 vmprov5s (09,4) c34, f0c: Session: 00000072 - SetTransfer=(null)
26/09 08:05:25.687 vmprov5s (09,4) c34, f74: Session: 00000072 - Configuring for reliable disconnect, IDLE time is 300.000s
26/09 08:05:25.687 vmprov5s (06,5) c34, f74: VMClient::RxClose 03FCC1C8 (session=00000072)
26/09 08:05:25.687 vmprov5s (09,4) c34, f74: Session: 00000072 - Receive CLOSE for session 00000072, call-id 31
26/09 08:05:25.703 vmprov5s (09,4) c34, f0c: Session: 00000072 - Mailbox name retrieved=TESTAA new=0 unopened=0 old=0 saved=0
26/09 08:05:25.703 vmprov5s (0a,5) c34, f0c: OSThreadFunc exited[VMClient, 03FCC1E0]
26/09 08:05:25.703 vmprov5s (0a,5) c34, f0c: OSTask::Kill[VMClient, 03FCC1E0]
26/09 08:05:25.703 vmprov5s (09,5) c34, f0c: ~IClient [Connections 0 - 0] [Buffer pool(0:1000 1:250 2:250 3:548), in use:0, seq:0] [Links:9181 from 12000] [CMMsg:0 left from 0] [VM:0 NODES:0 SN:0 SBL:0 TASKS:5 FILES:0 Msgs:1505]
26/09 08:05:25.703 DbgOut (01,5) 3124,3852: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
26/09 08:05:40.968 DbgOut (01,5) 4084,2276: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
26/09 08:05:40.968 DbgOut (01,5) 4084,2572: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
26/09 08:06:05.812 vmprov5s (09,4) c34, c70: DevIO::InitializeUpdates 192.168.1.250, godio=03F13DE4
26/09 08:06:05.812 vmprov5s (09,4) c34, c70: DevIO::RxData 192.168.1.250, godio=03F13DE4, dp=03EE9E84 (len=512), stream(03F13DEC len=512)
26/09 08:06:05.812 vmprov5s (09,4) c34, c70: DevIO::RxData 192.168.1.250, godio=03F13DE4, dp=03EE9E84 (len=507), stream(03F13DEC len=1019)
26/09 08:06:05.812 vmprov5s (09,4) c34, c70: DevIO::UpdatesComplete 192.168.1.250, godio=03F13DE4
26/09 08:06:05.812 vmprov5s (09,4) c34, c70: Using config version (4.2.23=42023) for 192.168.1.250
 
Turns out the IPO is still the same POS it was back in the early days, a complete rebuild of the VMPRO server and modules along with call flow and a good config scrub resolved the issue.
 
Status
Not open for further replies.

Part and Inventory Search

Sponsor

Back
Top