Voicemail only delivered to email for just one user 1

pdxmunkel · Apr 18, 2015

UMS is on in Avaya, UMS is on in Exchange, all my users get voicemails delivered to phone and exchange account EXCEPT one new user. She only gets the VM delivered to her email acct. Message does not show up in the VM Pro client from the admin side either.

Have turned off and on UMS in Exchange and Avaya IPOffice.
Have cleared acct in VM Pro.
Have rebooted phone system.
Have restarted VM services on VM server.

Been banging my head against the wall for 2 days now, any ideas appreciated. Thanks!

pdxmunkel · Apr 21, 2015

carGoSki, in what program would I be making these changes? I have no view menu in DebugView.

CarGoSki said:
view\debug filters

select vmprov5svc then set debug level to 9-High trace.

Gunnaro · Apr 21, 2015

Filters (in Dbgview)

Kind regards

Gunnar
______________________________________
Mille viae ducunt homines per saecula Romam

pdxmunkel · Apr 21, 2015

OK, thanks for hanging in there guys, was using the wrong dbgview. Using the correct one I generate the following, domain replaced with x's for spam reasons.

Code:

21/04 11:41:21.154 DbgOut   (01,5) 3136,4752: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:24.004 vmprov5s (14,9)  c40, b64: Check pending unsent emails
21/04 11:41:28.277 DbgOut   (01,5) 3136,2724: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:28.279 vmprov5s (23,5)  c40,1290: SoundsManager::ConvertWAV(00F8B6C8, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Wendy C_1.wav" [uLaw], 0D3618A0) - Took 0 ms to process, return is 85480
21/04 11:41:28.279 vmprov5s (23,5)  c40,1290: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Wendy C_1.wav" [uLaw]
21/04 11:41:28.279 DbgOut   (01,5) 3136,3544: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:30.531 vmprov5s (23,5)  c40,1290: SoundsManager::ConvertWAV(00F8B608, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw], 0D3618A0) - Took 0 ms to process, return is 1280
21/04 11:41:30.531 vmprov5s (23,5)  c40,1290: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw]
21/04 11:41:36.600 DbgOut   (01,5) 3136,5104: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:36.612 vmprov5s (23,5)  c40,13f0: Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22823.wav" - Took 0 ms to normalize, maximum energy was 2.545835 and used factor 2.483474 to normalize
21/04 11:41:36.615 vmprov5s (23,5)  c40,13f0: Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22823.wav" - rewritten at 0.180000 FS
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0: > IMAP4Impl::NotifyMsgUpdate(Mailbox: Wendy C, Message:MSG00007, State:Forward)
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Wendy C, MsgId=7, state=F)
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Wendy C, MsgId=7, state=F)
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0: < IMAP4 - VMNotifyMsgUpdate()
21/04 11:41:36.699 vmprov5s (1e,9)  c40,13f0: < IMAP4Impl::NotifyMsgUpdate()
21/04 11:41:36.703 vmprov5s (14,9)  c40,13f0: EMailerRequest::Send: Queued email request for wrc@xxxxx.com, subject is Voicemail Message (Mike H > 136) From:101, use EMAPI=true
21/04 11:41:36.713 DbgOut   (01,5) 3136,5104: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 11:41:36.721 DbgOut   (01,5) 3136,4752: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 11:41:36.758 vmprov5s (14,9)  c40, b64: > EMailer::SendRequestUsingEMAPI(0D3167B8 [136\MSG00007])
21/04 11:41:36.758 vmprov5s (19,8)  c40, b64:   > CMAPIImpl::SendUMEmail(00000000)
21/04 11:41:36.758 vmprov5s (66,8)  c40, b64: > CMAPIImpl::SendUMEmail()
21/04 11:41:36.758 vmprov5s (66,8)  c40, b64:   > CMAPIImpl::SendUMEmail(pIMsgStore: 02A84DAC, m_TargetAlias: "wrc@xxxxx.com", szSubject: "Voicemail Message (Mike H > 136) From:101", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\1b9e2b0e31a94d84a5c9dc5650193813.WAV", szFilename: "MSG00007.WAV", lSecs: 6, szNumber: 101, szName: "Mike H", szOrder: ;MSG00007.WAV, offset: 0, ulMsgReceived: 3607094490)
21/04 11:41:36.758 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5714]: At entry, counter=[35]
21/04 11:41:36.759 vmprov5s (66,9)  c40, b64:     > HrOpenEntry(pIMsgStore: 02A84DAC, EntryID: "C8A57D11A16459488FD538CCE828E8BE0100B50523B8EAC6DA4893B9EBD16452BF6200000046FB85000000009127", Interface: "{0002030c-0000-0000-c000-000000000046}", Flags: 00000001 use MAPI_NO_CACHE)
21/04 11:41:36.766 DbgOut   (01,5) 3136,4724: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:36.767 DbgOut   (01,5) 3136,4160: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:36.768 vmprov5s (66,9)  c40, b64:     < HrOpenEntry()
21/04 11:41:36.773 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::CreateAddressList(pAddressList: 00000000, szRecpientList: "wrc@xxxxx.com")
21/04 11:41:36.781 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::CreateAddressList()
21/04 11:41:36.782 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::AttachFile(pMsg: 02C2F014, pSourceStream: 0CFF0BF0, szFilename: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\1b9e2b0e31a94d84a5c9dc5650193813.WAV", szDisplayName: "MSG00007.WAV")
21/04 11:41:36.782 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9493]: At entry, counter=[39]
21/04 11:41:36.799 DbgOut   (01,5) 3136,4784: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:41:36.832 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9661]: At Exit, counter=[39]
21/04 11:41:36.832 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::AttachFile()
21/04 11:41:36.852 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5866]: Message submitted successfully.[39]
21/04 11:41:36.852 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[35]
21/04 11:41:36.852 vmprov5s (66,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 11:41:36.853 vmprov5s (66,8)  c40, b64: < CMAPIImpl::SendUMEmail()
21/04 11:41:36.853 vmprov5s (19,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 11:41:36.853 vmprov5s (14,9)  c40, b64:   Successfully sent email to wrc@xxxxx.com
21/04 11:41:36.853 vmprov5s (14,9)  c40, b64: < EMailer::SendRequestUsingEMAPI()
21/04 11:41:36.853 vmprov5s (14,9)  c40, b64: emailer - SendRequest succeeded for 136\MSG00007
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64: > IMAP4Impl::NotifyMsgUpdate(Mailbox: Wendy C, Message:MSG00007, State:A)
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Wendy C, MsgId=7, state=A)
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Wendy C, MsgId=7, state=A)
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64: < IMAP4 - VMNotifyMsgUpdate()
21/04 11:41:36.858 vmprov5s (1e,9)  c40, b64: < IMAP4Impl::NotifyMsgUpdate()
21/04 11:41:39.011 vmprov5s (19,1)  c40,1244: VMIsMAPIAvailable returning [1]
21/04 11:41:39.011 vmprov5s (19,5)  c40,1244: CMAPIImpl::CheckMAPIConnection() - return is 0,m_iMAPIStatus = [-1]

pdxmunkel · Apr 21, 2015

And here is a voicemail left for me that works...

Code:

21/04 11:47:31.433 DbgOut   (01,5) 3136,5088: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:38.557 vmprov5s (23,5)  c40,13e0: SoundsManager::ConvertWAV(00F8B6F8, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Mike H_1.wav" [uLaw], 0D3618A0) - Took 0 ms to process, return is 51840
21/04 11:47:38.557 vmprov5s (23,5)  c40,13e0: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Mike H_1.wav" [uLaw]
21/04 11:47:40.309 vmprov5s (23,5)  c40,13e0: SoundsManager::ConvertWAV(00F8B608, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw], 0D3618A0) - Took 0 ms to process, return is 1280
21/04 11:47:40.309 vmprov5s (23,5)  c40,13e0: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw]
21/04 11:47:47.067 DbgOut   (01,5) 3136,4268: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:47.078 vmprov5s (23,5)  c40,10ac: Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22825.wav" - Took 0 ms to normalize, maximum energy was 1.408439 and used factor 3.338917 to normalize
21/04 11:47:47.082 vmprov5s (23,5)  c40,10ac: Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22825.wav" - rewritten at 0.180000 FS
21/04 11:47:47.167 vmprov5s (1e,9)  c40,10ac: > IMAP4Impl::NotifyMsgUpdate(Mailbox: Mike H, Message:MSG00829, State:Forward)
21/04 11:47:47.167 vmprov5s (1e,9)  c40,10ac: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Mike H, MsgId=829, state=F)
21/04 11:47:47.167 vmprov5s (1e,9)  c40,10ac:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Mike H, MsgId=829, state=F)
21/04 11:47:47.167 vmprov5s (1e,9)  c40,10ac:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 11:47:47.168 vmprov5s (1e,9)  c40,10ac: < IMAP4 - VMNotifyMsgUpdate()
21/04 11:47:47.168 vmprov5s (1e,9)  c40,10ac: < IMAP4Impl::NotifyMsgUpdate()
21/04 11:47:47.171 vmprov5s (14,9)  c40,10ac: EMailerRequest::Send: Queued email request for mh@xxxxx.com, subject is Voicemail Message (Mike H > 101) From:101, use EMAPI=true
21/04 11:47:47.183 DbgOut   (01,5) 3136,4268: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 11:47:47.189 DbgOut   (01,5) 3136,5088: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 11:47:47.300 vmprov5s (14,9)  c40, b64: > EMailer::SendRequestUsingEMAPI(0D386CC8 [101\MSG00829])
21/04 11:47:47.300 vmprov5s (19,8)  c40, b64:   > CMAPIImpl::SendUMEmail(00000000)
21/04 11:47:47.300 vmprov5s (66,8)  c40, b64: > CMAPIImpl::SendUMEmail()
21/04 11:47:47.300 vmprov5s (66,8)  c40, b64:   > CMAPIImpl::SendUMEmail(pIMsgStore: 02A84DAC, m_TargetAlias: "mh@xxxxx.com", szSubject: "Voicemail Message (Mike H > 101) From:101", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Mike H\0e396043a29a4b62ae3e73f0f5171a9e.WAV", szFilename: "MSG00829.WAV", lSecs: 7, szNumber: 101, szName: "Mike H", szOrder: ;MSG00829.WAV, offset: 0, ulMsgReceived: 3607094860)
21/04 11:47:47.300 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5714]: At entry, counter=[35]
21/04 11:47:47.301 vmprov5s (66,9)  c40, b64:     > HrOpenEntry(pIMsgStore: 02A84DAC, EntryID: "C8A57D11A16459488FD538CCE828E8BE0100B50523B8EAC6DA4893B9EBD16452BF6200000046FB85000000009127", Interface: "{0002030c-0000-0000-c000-000000000046}", Flags: 00000001 use MAPI_NO_CACHE)
21/04 11:47:47.306 DbgOut   (01,5) 3136,2752: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:47.306 DbgOut   (01,5) 3136,3788: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:47.306 vmprov5s (66,9)  c40, b64:     < HrOpenEntry()
21/04 11:47:47.310 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::CreateAddressList(pAddressList: 00000000, szRecpientList: "mh@xxxxx.com")
21/04 11:47:47.318 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::CreateAddressList()
21/04 11:47:47.319 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::AttachFile(pMsg: 02C33A0C, pSourceStream: 0CFF0BF0, szFilename: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Mike H\0e396043a29a4b62ae3e73f0f5171a9e.WAV", szDisplayName: "MSG00829.WAV")
21/04 11:47:47.319 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9493]: At entry, counter=[39]
21/04 11:47:47.328 DbgOut   (01,5) 3136,1804: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:47.357 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9661]: At Exit, counter=[39]
21/04 11:47:47.357 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::AttachFile()
21/04 11:47:47.377 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5866]: Message submitted successfully.[39]
21/04 11:47:47.378 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[35]
21/04 11:47:47.378 vmprov5s (66,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 11:47:47.378 vmprov5s (66,8)  c40, b64: < CMAPIImpl::SendUMEmail()
21/04 11:47:47.378 vmprov5s (19,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 11:47:47.378 vmprov5s (14,9)  c40, b64:   Successfully sent email to mh@xxxxx.com
21/04 11:47:47.378 vmprov5s (14,9)  c40, b64: < EMailer::SendRequestUsingEMAPI()
21/04 11:47:47.378 vmprov5s (14,9)  c40, b64: emailer - SendRequest succeeded for 101\MSG00829
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64: > IMAP4Impl::NotifyMsgUpdate(Mailbox: Mike H, Message:MSG00829, State:A)
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Mike H, MsgId=829, state=A)
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Mike H, MsgId=829, state=A)
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64: < IMAP4 - VMNotifyMsgUpdate()
21/04 11:47:47.383 vmprov5s (1e,9)  c40, b64: < IMAP4Impl::NotifyMsgUpdate()
21/04 11:47:48.802 vmprov5s (66,9)  c40, b04: > CAdviseSink::OnNotify(00F3D428: "mh@xxxxx.com")
21/04 11:47:48.803 DbgOut   (01,5) 3136,4556: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:48.803 DbgOut   (01,5) 3136,2896: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 11:47:48.806 vmprov5s (66,9)  c40, b04:   OnNotify - Telling "mh@xxxxx.com" it has 1 unread (0 with priority) and 0 read messages
21/04 11:47:48.806 vmprov5s (19,8)  c40, b04: > AdviseNotify(mh@xxxxx.com - 1 Unread (0 with priority) and 0 read messages - data = 02BD4DC8)
21/04 11:47:48.807 vmprov5s (19,8)  c40, b04: < AdviseNotify()
21/04 11:47:48.807 vmprov5s (66,9)  c40, b04: < CAdviseSink::OnNotify()
21/04 11:47:48.807 vmprov5s (66,9)  c40, b04: > CAdviseSink::OnNotify(02C906F0: "mh@xxxxx.com")
21/04 11:47:48.810 vmprov5s (66,9)  c40, b04:   OnNotify - Telling "mh@xxxxx.com" it has 1 unread (0 with priority) and 0 read messages
21/04 11:47:48.810 vmprov5s (19,8)  c40, b04: > AdviseNotify(mh@xxxxx.com - 1 Unread (0 with priority) and 0 read messages - data = 02BF55E8)
21/04 11:47:48.811 vmprov5s (19,8)  c40, b04: < AdviseNotify()
21/04 11:47:48.811 vmprov5s (66,9)  c40, b04: < CAdviseSink::OnNotify()
21/04 11:47:52.753 DbgOut   (01,5) 3316,1248: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)

CarGoSki · Apr 21, 2015

I can see where the stored message for both users is retrieved by ums for email from the user account folder:
21/04 11:41:36.758 vmprov5s (66,8) c40, b64: > CMAPIImpl::SendUMEmail(pIMsgStore: 02A84DAC, m_TargetAlias: "wrc@xxxxx.com", szSubject: "Voicemail Message (Mike H > 136) From:101", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\1b9e2b0e31a94d84a5c9dc5650193813.WAV", szFilename: "MSG00007.WAV", lSecs: 6, szNumber: 101, szName: "Mike H", szOrder: ;MSG00007.WAV, offset: 0, ulMsgReceived: 3607094490)

21/04 11:47:47.300 vmprov5s (66,8) c40, b64: > CMAPIImpl::SendUMEmail(pIMsgStore: 02A84DAC, m_TargetAlias: "mh@xxxxx.com", szSubject: "Voicemail Message (Mike H > 101) From:101", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Mike H\0e396043a29a4b62ae3e73f0f5171a9e.WAV", szFilename: "MSG00829.WAV", lSecs: 7, szNumber: 101, szName: "Mike H", szOrder: ;MSG00829.WAV, offset: 0, ulMsgReceived: 3607094860)

what is missing from your trace for both is the system saving the wav and the thread termination.
Your post is missing a ton of processing.

You should post a trace that starts at the entry point through the thread termination.

entry point looks like this:
21/04 15:07:39.917 vmprov5s (29,8) 6f4, af8: VMThreadFunc entered[(VMTask[VMClient(0231A79C)] (023168F8) (TID: af8 (2808))), created 21/04 15:07:39.901, priority 2]
21/04 15:07:39.917 vmprov5s (06,9) 6f4, af8: > VMClient::Main(0231A780 <session=0000006c>)
21/04 15:07:39.917 vmprov5s (09,5) 6f4, af8: Session: 0000006c - VMClient::CheckInputQueue there are now 2 entries in the queue for session 0000006c (object 0231A780)
21/04 15:07:39.917 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Configuring for reliable disconnect, IDLE time is 300.000s
21/04 15:07:39.917 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Receive OPEN "" for session 0000006c, call-id 17, mailbox <550>, access 1 (VMClient:0231A780), media port <0>
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Access = ACCESS_LEAVE_VOICEMAIL:
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Mailbox: 550
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Calling Party: 300
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Display String: Extn300>550
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Target Party: 550
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Greeting Modifier:
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Language: enu
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Call Ident: 17
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Call Status: No Answer (1)
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Call Type: Internal
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Call Direction: Incoming
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Called Party: 550
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - DDI Number:
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Calling Party Name: Extn300
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Called Party Name: 550
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Caller Party Offset: 0
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Target Party Offset: 0
21/04 15:07:39.932 vmprov5s (09,6) 6f4, af8: Session: 0000006c - Called Party Offset: 0

thread termination looks like this:
21/04 15:08:01.323 vmprov5s (09,6) 6f4, f48: Background saved message "C:\Program Files\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG00090.wav" to at least 1 mailboxes and took 258 milli-seconds
21/04 15:08:01.323 vmprov5s (07,8) 6f4, f48: < IntuityRecordNode::SaveRecordingForMailboxList()
21/04 15:08:01.323 vmprov5s (29,9) 6f4, f48: Thread IntuityRecordNode::SaveRecordingForMailboxList (02307528) (TID: 0xf48 (3912)) completed
21/04 15:08:01.323 vmprov5s (29,9) 6f4, f48: Terminating thread IntuityRecordNode::SaveRecordingForMailboxList (02307528) (TID: f48 (3912))
21/04 15:08:01.323 vmprov5s (29,9) 6f4, f48: Thread IntuityRecordNode::SaveRecordingForMailboxList (02307528) (TID: f48 (3912)) terminated
21/04 15:08:01.323 vmprov5s (29,9) 6f4, f48: Terminating worker thread (022AC3B0) (TID: f48 (3912))
21/04 15:08:01.323 vmprov5s (09,5) 6f4, f48: Terminated worker thread (022AC3B0) (TID: f48 (3912)), used by "IntuityRecordNode::SaveRecordingForMailboxList" since 21/04/2015 15:08:01
21/04 15:08:01.323 DbgOut (01,5) 1780,3912: DllMain(hinstDLL=0x01170000, fdwReason=DLL_THREAD_DETACH)

pdxmunkel · Apr 21, 2015

Thank you, I had to turn on a lot of items in the filtering so I apologize if this is too much info.

Code:

21/04 13:13:13.838 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:13:13.838 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:13:13.839 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:13:13.839 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:13:13.839 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:13:13.839 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:13:13.839 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:13:18.346 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_SEQ VMMESSAGE_SEQ, seq=0000024d - VMMESSAGE_OPEN Session=0000017d, Seq=0" from 10.0.0.20:4098
21/04 13:13:18.346 vmprov5s (06,9)  c40, 4d0: > VMUser::ProcessSequencedData(buf=02A843E0, tx_seq=63, rx_seq=589)
21/04 13:13:18.346 vmprov5s (06,9)  c40, 4d0:   > VMUser::SendAck(0.0.2.77)
21/04 13:13:18.346 vmprov5s (06,8)  c40, 4d0:     SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SEQ VMSEQ_ACK, seq=0000024d" to server "10.0.0.20"
21/04 13:13:18.346 vmprov5s (06,9)  c40, 4d0:   < VMUser::SendAck()
21/04 13:13:18.346 vmprov5s (06,9)  c40, 4d0:   Calling ProcessReceiveBuffer(02A843E0)
21/04 13:13:18.346 vmprov5s (02,6)  c40, 4d0:   TestConfigChanged:: Should the config be re-loaded?
21/04 13:13:18.346 vmprov5s (02,6)  c40, 4d0:   TestConfigChanged:: File date has NOT changed, so use current call flow
21/04 13:13:18.346 vmprov5s (02,6)  c40, 4d0:   TestConfigChanged:: Config state is correct <State:5>
21/04 13:13:18.346 vmprov5s (29,9)  c40, 4d0:   > VMTask::VMTask(name=VMClient)
21/04 13:13:18.346 vmprov5s (29,9)  c40, 4d0:   < VMTask::VMTask()
21/04 13:13:18.346 vmprov5s (0c,9)  c40, 4d0:   > IClient::IClient(INBAND DTMF=YES)
21/04 13:13:18.346 vmprov5s (09,6)  c40, 4d0:     New VMAIL Client
21/04 13:13:18.346 vmprov5s (09,5)  c40, 4d0:     new VMAIL Client [0D37DD30] [Connections 0 - 1] [INBAND DTMF=YES]
21/04 13:13:18.346 vmprov5s (0c,9)  c40, 4d0:   < IClient::IClient()
21/04 13:13:18.346 vmprov5s (0c,6)  c40, 4d0:   New Voicemail Client (VMClient object=0D37DCC8, parent 02B4C038, INBAND)
21/04 13:13:18.347 vmprov5s (29,9)  c40, 4d0:   Created thread VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 914 (2324))
21/04 13:13:18.347 vmprov5s (06,8)  c40, 4d0:   [IClient::ProcessBuffer]: Processing data received from BHLSPDX-PBX (MASTER:10.0.0.20 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (69)] left 0ms ago (open type=1,session=17d), request " VMMESSAGE_OPEN Session=0000017d, Seq=0"
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d - Configuring for reliable disconnect, IDLE time is 300.000s
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d - Receive OPEN "" for session 0000017d, call-id 773, mailbox <136>, access 1 (VMClient:0D37DCC8)
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d - Access = ACCESS_LEAVE_VOICEMAIL:
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Mailbox:             136
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Calling Party:       101
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Display String:      Mike H>#136
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Target Party:        
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Greeting Modifier:   
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Language:            enu
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Call Ident:          773
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Call Status:         Unknown (0)
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Call Type:           Internal
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Call Direction:      Incoming
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Called Party:        
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     DDI Number:          
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Calling Party Name:  Mike H
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Called Party Name:   
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Caller Party Offset: 0
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Target Party Offset: 0
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d -     Called Party Offset: 0
21/04 13:13:18.347 vmprov5s (06,9)  c40, 4d0:   VMailBox::FindMailBox - pbx=<02B4C038>, name=<136>, flags=<BYEXT | BYNAME | BYINCDISABLED> - Found Mailbox BYEXT
21/04 13:13:18.347 vmprov5s (0c,9)  c40, 4d0:   SessionManager::RealMailBox(pbx=<02B4C038>, name=<136>, cli=<101>)
21/04 13:13:18.347 vmprov5s (06,9)  c40, 4d0:   VMailBox::FindMailBox - pbx=<02B4C038>, name=<136>, flags=<BYEXT | BYNAME | BYINCDISABLED> - Found Mailbox BYEXT
21/04 13:13:18.347 vmprov5s (0c,8)  c40, 4d0:   Standard=02C23708 136
21/04 13:13:18.347 vmprov5s (02,8)  c40, 4d0:   > VoiceMailDialog::VoiceMailDialog(0D3618A0, client=0D37DD30)
21/04 13:13:18.347 vmprov5s (09,6)  c40, 4d0:     Session: 0000017d - LeaveMessage mailbox=(Wendy C), (1)
21/04 13:13:18.347 vmprov5s (06,8)  c40, 4d0:     > VMClient::SendMsg(Session=0000017d,buf=027B7098)
21/04 13:13:18.347 vmprov5s (06,8)  c40, 4d0:       SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_CONTROL Session=0000017d, Seq=0" to server "10.0.0.20"
21/04 13:13:18.347 vmprov5s (06,8)  c40, 4d0:     < VMClient::SendMsg()
21/04 13:13:18.348 vmprov5s (07,8)  c40, 4d0:     > IntuityBase::Begin(0D3618A0 00000001)
21/04 13:13:18.348 vmprov5s (07,8)  c40, 4d0:       > IntuityBaseNode::PlaySoundbiteList(00F43D20 true true)
21/04 13:13:18.348 vmprov5s (07,8)  c40, 4d0:       < IntuityBaseNode::PlaySoundbiteList()
21/04 13:13:18.348 vmprov5s (07,8)  c40, 4d0:     < IntuityBase::Begin()
21/04 13:13:18.348 vmprov5s (02,8)  c40, 4d0:   < VoiceMailDialog::VoiceMailDialog()
21/04 13:13:18.348 vmprov5s (02,9)  c40, 4d0:   IVRNodeDialog::IVRNodeDialog(0D37DD30, 02C23708, 1, 101, Mike H>#136, , Wendy C, enu, 0, 773, Unknown, Internal)
21/04 13:13:18.348 vmprov5s (06,5)  c40, 4d0:   VMClient::RxOpen - Created dialog 0D3618A0
21/04 13:13:18.348 vmprov5s (06,5)  c40, 4d0:   VMClient::RxOpen 0D37DCC8 136 (1), session=0000017d, dialog=0D3618A0, Fallback number is ""
21/04 13:13:18.348 vmprov5s (06,9)  c40, 4d0: < VMUser::ProcessSequencedData()
21/04 13:13:18.348 DbgOut   (01,5) 3136,2324: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:18.358 vmprov5s (29,9)  c40, 914: Processing thread VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 0x914 (2324))
21/04 13:13:18.360 vmprov5s (29,8)  c40, 914: VMThreadFunc entered[(VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 914 (2324))), created 21/04 13:13:18.346, priority 2]
21/04 13:13:18.360 vmprov5s (06,9)  c40, 914: > VMClient::Main(0D37DCC8 <session=0000017d>)
21/04 13:13:23.838 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:13:23.838 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:13:23.838 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:13:23.838 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:13:23.838 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:13:23.839 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:13:23.839 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:13:24.101 vmprov5s (19,1)  c40,1244: VMIsMAPIAvailable returning [1]
21/04 13:13:24.101 vmprov5s (19,5)  c40,1244: CMAPIImpl::CheckMAPIConnection() - return is 0,m_iMAPIStatus = [-1] 
21/04 13:13:24.347 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_SEQ VMMESSAGE_SEQ, seq=0000024e - VMMESSAGE_ACTIVE Session=0000017d, Seq=0" from 10.0.0.20:4098
21/04 13:13:24.347 vmprov5s (06,9)  c40, 4d0: > VMUser::ProcessSequencedData(buf=02A843E0, tx_seq=63, rx_seq=590)
21/04 13:13:24.347 vmprov5s (06,9)  c40, 4d0:   > VMUser::SendAck(0.0.2.78)
21/04 13:13:24.347 vmprov5s (06,8)  c40, 4d0:     SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SEQ VMSEQ_ACK, seq=0000024e" to server "10.0.0.20"
21/04 13:13:24.347 vmprov5s (06,9)  c40, 4d0:   < VMUser::SendAck()
21/04 13:13:24.347 vmprov5s (06,9)  c40, 4d0:   Calling ProcessReceiveBuffer(02A843E0)
21/04 13:13:24.347 vmprov5s (06,8)  c40, 4d0:   [IClient::ProcessBuffer]: Processing data received from BHLSPDX-PBX (MASTER:10.0.0.20 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (69)] left 0ms ago (open type=9,session=17d), request " VMMESSAGE_ACTIVE Session=0000017d, Seq=0"
21/04 13:13:24.347 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d - Receive ACTIVE "" for session 0000017d, call-id 773 (VMClient:0D37DCC8)
21/04 13:13:24.347 vmprov5s (06,9)  c40, 4d0: < VMUser::ProcessSequencedData()
21/04 13:13:24.352 vmprov5s (06,9)  c40, 914: > VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914: > IntuityBase::EndPlay()
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:   > IntuityBase::Complete(  (0))
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:     > IntuityBaseNode::ChangeFixedNode(0D363310 00000000)
21/04 13:13:25.479 vmprov5s (07,6)  c40, 914:       ChangeFixedNode called by IntuityBase
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:       > IntuityBaseNode::PlaySoundbiteList(00F43D20 true true)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:       < IntuityBaseNode::PlaySoundbiteList()
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:       > IntuityLeaveVoiceMail::Begin(0D3618A0 00000000)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:         > IntuityPlayNode::Begin(0D363310 0D3618A0 0 true true false 1 true - state = 1)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:           > IntuityPlayNode::Begin(0D3618A0 00000000)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:           < IntuityPlayNode::Begin()
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:           > IntuityLeaveVoiceMail::PlayWAV(  (0) - state = 1)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             > IntuityPlayNode::WasEntryValid(0D367C58, TRUE, Entry Valid)
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             < IntuityPlayNode::WasEntryValid()
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The calltypes for Mailbox "Wendy C" are 0x000020
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The calltypes for Mailbox "Wendy C" are 0x000020
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 0
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 0x000020 for greeting 1
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 2
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 3
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 4
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 5
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 6
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 7
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 8
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             The greeting calltype for Mailbox "Wendy C" is 00000000 for greeting 9
21/04 13:13:25.479 vmprov5s (07,8)  c40, 914:             Playing greeting 1 (call types is 00000000)
21/04 13:13:25.479 vmprov5s (21,8)  c40, 914:             DiskSpaceAlarm::AnalyseDiskSpaceAvailable(File: C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts)
21/04 13:13:25.479 vmprov5s (21,8)  c40, 914:             DiskSpaceAlarm::SetCurrentDiskState(value: DiskStateFree)
21/04 13:13:25.479 vmprov5s (21,8)  c40, 914:             DiskSpaceAlarm::AnalyseDiskSpaceAvailable(File: C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts)
21/04 13:13:25.480 vmprov5s (21,8)  c40, 914:             DiskSpaceAlarm::SetCurrentDiskState(value: DiskStateFree)
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:             The CAD setting for Mailbox "Wendy C" is false
21/04 13:13:25.480 vmprov5s (23,5)  c40, 914:             SoundsManager::ConvertWAV(00F8B578, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Wendy C_1.wav" [uLaw], 0D3618A0) - Took 0 ms to process, return is 85480
21/04 13:13:25.480 vmprov5s (23,5)  c40, 914:             Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\Wendy C_1.wav" [uLaw]
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:             > IntuityPlayNode::PlayWAV(0D367C58 true true - state = 1)
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:               > IntuityBaseNode::PlaySoundbiteList(0D367C58 true true)
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:               < IntuityBaseNode::PlaySoundbiteList()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:             < IntuityPlayNode::PlayWAV()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:           < IntuityLeaveVoiceMail::PlayWAV()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:         < IntuityPlayNode::Begin()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:       < IntuityLeaveVoiceMail::Begin()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:     < IntuityBaseNode::ChangeFixedNode()
21/04 13:13:25.480 vmprov5s (07,8)  c40, 914:   < IntuityBase::Complete()
21/04 13:13:25.481 vmprov5s (07,8)  c40, 914: < IntuityBase::EndPlay()
21/04 13:13:25.481 vmprov5s (06,9)  c40, 914: < VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:25.491 vmprov5s (06,9)  c40, 914: > VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:26.953 vmprov5s (14,9)  c40, b64: Check pending unsent emails
21/04 13:13:26.953 vmprov5s (26,6)  c40, b64: > VMailBox::ForwardPendingEmails()
21/04 13:13:26.953 vmprov5s (26,6)  c40, b64: < VMailBox::ForwardPendingEmails()
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244: IP Office Voicemail Pro [Hardware mode 03] - Version 8.1 (9203) [CPU Usage 0.0] [CPU Min:0.0, Avg:1.6, Max:678.3] has been running for 0 days, 22 hours and 35 minutes and received 162 calls (0 missed voice packets) (161 completed, 7 calls/hour) (Call duration(secs) - Min:0, Max:380, Avg:22).
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244: System Configuration:-
    Voicemail Pro mode, locale: enu, IPO hardware mode: 03 [USA]
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244:     EVML:-
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244:         Maximum recording capacity: Unlimited, Maximum Recording Time: 120 seconds
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244:         Maximum Sessions: 4, Minimum PIN length: 0 digits
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244:     SMTP:-
        Host address 0.0.0.0, port 25, Login method "none", email from "", login user ""
21/04 13:13:27.102 vmprov5s (09,5)  c40,1244:     Voicemail Sessions (Connections 1 - 1) (Links:11553 from 12000) (CMMsg:2 left from 2) (VM:2 NODES:111 SN:2 SBL:1 FILES:3)
21/04 13:13:27.155 DbgOut   (01,5) 3136,4952: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:27.595 vmprov5s (1b,5)  c40, 4d0: IClient::DtmfDetect 0D37DD30 '1 (49)' at 880, length 480 - session:0000017d
21/04 13:13:27.595 vmprov5s (1b,9)  c40, 4d0: DTMF Detected - Adding to queue (0D37DCC8 '1 (49)', pos=880, fpos=1760, len=480, session=0000017d)
21/04 13:13:27.602 vmprov5s (1b,9)  c40, 914: DTMF Detected - Processing(0D37DCC8 '1 (49)', session=0000017d)
21/04 13:13:27.602 vmprov5s (06,8)  c40, 914: > VMClient::SafeCheckInputQueue(VMClient object=0D37DCC8, session=0000017d, event=('1 (49)', pos=1760, len=480))
21/04 13:13:27.602 vmprov5s (06,8)  c40, 914: VMClient::PurgeSendQueue(0D37DCC8 <session=0000017d>)
21/04 13:13:27.602 vmprov5s (06,8)  c40, 914: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SNAP Session=0000017d, Seq=0" to server "10.0.0.20"
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914: > SoundNode::DTMFHit(02C32B78, 1 (49))
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914:   > VoiceMailDialog::DTMFHit(0D3618A0, 1 (49))
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914:     > IntuityLeaveVoiceMail::DTMFHit(1 (49) - state = 1)
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914:       > IntuityPlayNode::DTMFHit(1 (49) - state = 1)
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914:       < IntuityPlayNode::DTMFHit()
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:       > IntuityLeaveVoiceMail::StartRecording(1 (49))
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:         > IntuityBaseNode::ChangeFixedNode(02CABF18 00310000)
21/04 13:13:27.602 vmprov5s (07,6)  c40, 914:           ChangeFixedNode called by IntuityLeaveVoiceMail
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:           > IntuityBaseNode::PlaySoundbiteList(00F43D20 true true)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:           < IntuityBaseNode::PlaySoundbiteList()
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:           > IntuityRecordNode::Begin(0D3618A0 00310000)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:             > IntuityPlayNode::Begin(02CABF18 0D3618A0 310000 true true false 0 true - state = 0)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:               > IntuityPlayNode::Begin(0D3618A0 00000000)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:               < IntuityPlayNode::Begin()
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:               > IntuityRecordNode::PlayWAV(1 (49) - state = 0)
21/04 13:13:27.602 vmprov5s (1b,8)  c40, 914:                 > IntuityRecordNode::DTMFHit(1 (49) - state = 0)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                   > IntuityRecordNode::EndPlay(state = 0)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                     > IntuityPlayNode::LocalEndPlay(state = 0)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                     < IntuityPlayNode::LocalEndPlay()
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                     > IntuityRecordNode::PlayWAV(  (0) - state = 0)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                       > IntuityPlayNode::WasEntryValid(00F43D20, TRUE, Entry Valid)
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                       < IntuityPlayNode::WasEntryValid()
21/04 13:13:27.602 vmprov5s (07,8)  c40, 914:                       > IntuityRecordNode::StartNewRecording()
21/04 13:13:27.604 vmprov5s (07,8)  c40, 914:                         > IntuityRecordNode::ConfigureRecordingNode(true)
21/04 13:13:27.604 vmprov5s (21,8)  c40, 914:                           DiskSpaceAlarm::AnalyseDiskSpaceAvailable(File: C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav)
21/04 13:13:27.604 vmprov5s (21,8)  c40, 914:                           DiskSpaceAlarm::SetCurrentDiskState(value: DiskStateFree)
21/04 13:13:27.604 vmprov5s (07,8)  c40, 914:                         < IntuityRecordNode::ConfigureRecordingNode()
21/04 13:13:27.604 vmprov5s (07,8)  c40, 914:                       < IntuityRecordNode::StartNewRecording()
21/04 13:13:27.604 vmprov5s (24,8)  c40, 914:                       > SoundBiteList::AddAnnouncement(02CABF18 1146 (0 0 0 0 0 0 0 0 0 0) 00000000 (null) 00000000 true false)
21/04 13:13:27.604 vmprov5s (0c,8)  c40, 914:                         > SoundsManager::AddAnnouncement(1146 0C3FE700 enu 0C3FF788[32] true false)
21/04 13:13:27.604 vmprov5s (24,5)  c40, 914:                           Successfully determined fragments for announcement 1146 using language enu, fragments are "1440"
21/04 13:13:27.604 vmprov5s (0c,8)  c40, 914:                         < SoundsManager::AddAnnouncement()
21/04 13:13:27.605 vmprov5s (23,5)  c40, 914:                         SoundsManager::ConvertWAV(00F8B6F8, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw], 0D3618A0) - Took 0 ms to process, return is 1280
21/04 13:13:27.605 vmprov5s (23,5)  c40, 914:                         Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\1440.WAV" [uLaw]
21/04 13:13:27.605 vmprov5s (24,8)  c40, 914:                       < SoundBiteList::AddAnnouncement()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                       > IntuityPlayNode::PlayWAV(00F43D20 false false - state = 0)
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                         > IntuityBaseNode::PlaySoundbiteList(00F43D20 false false)
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                         < IntuityBaseNode::PlaySoundbiteList()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                       < IntuityPlayNode::PlayWAV()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                     < IntuityRecordNode::PlayWAV()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:                   < IntuityRecordNode::EndPlay()
21/04 13:13:27.605 vmprov5s (1b,8)  c40, 914:                 < IntuityRecordNode::DTMFHit()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:               < IntuityRecordNode::PlayWAV()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:             < IntuityPlayNode::Begin()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:           < IntuityRecordNode::Begin()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:         < IntuityBaseNode::ChangeFixedNode()
21/04 13:13:27.605 vmprov5s (07,8)  c40, 914:       < IntuityLeaveVoiceMail::StartRecording()
21/04 13:13:27.605 vmprov5s (1b,8)  c40, 914:     < IntuityLeaveVoiceMail::DTMFHit()
21/04 13:13:27.605 vmprov5s (1b,8)  c40, 914:   < VoiceMailDialog::DTMFHit()
21/04 13:13:27.605 vmprov5s (1b,8)  c40, 914: < SoundNode::DTMFHit()
21/04 13:13:27.605 vmprov5s (06,8)  c40, 914: < VMClient::SafeCheckInputQueue(VMClient object=0D37DCC8, session=0000017d, event=('1 (49)', pos=1760, len=480))
21/04 13:13:27.605 vmprov5s (06,9)  c40, 914: < VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:27.615 vmprov5s (06,9)  c40, 914: > VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:27.615 vmprov5s (07,8)  c40, 914: > IntuityRecordNode::EndPlay(state = 0)
21/04 13:13:27.615 vmprov5s (07,8)  c40, 914:   > IntuityPlayNode::LocalEndPlay(state = 0)
21/04 13:13:27.615 vmprov5s (07,8)  c40, 914:   < IntuityPlayNode::LocalEndPlay()
21/04 13:13:27.615 vmprov5s (09,6)  c40, 914:   Session: 0000017d - StartRecording C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav
21/04 13:13:27.617 vmprov5s (07,8)  c40, 914: < IntuityRecordNode::EndPlay()
21/04 13:13:27.617 vmprov5s (06,9)  c40, 914: < VMClient::Play(0D37DCC8 <session=0000017d>)
21/04 13:13:27.728 vmprov5s (1b,9)  c40, 4d0: > GoertzelDetectorBase::SetRecording(Recording mode is now ON)
21/04 13:13:27.728 vmprov5s (1b,9)  c40, 4d0: < GoertzelDetectorBase::SetRecording()
21/04 13:13:33.838 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:13:33.838 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:13:33.838 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:13:33.838 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:13:33.838 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:13:33.838 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:13:33.838 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:13:34.257 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_SEQ VMMESSAGE_SEQ, seq=0000024f - VMMESSAGE_CLOSE Session=0000017d, Seq=0" from 10.0.0.20:4098
21/04 13:13:34.257 vmprov5s (06,9)  c40, 4d0: > VMUser::ProcessSequencedData(buf=02A843E0, tx_seq=63, rx_seq=591)
21/04 13:13:34.257 vmprov5s (06,9)  c40, 4d0:   > VMUser::SendAck(0.0.2.79)
21/04 13:13:34.257 vmprov5s (06,8)  c40, 4d0:     SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SEQ VMSEQ_ACK, seq=0000024f" to server "10.0.0.20"
21/04 13:13:34.257 vmprov5s (06,9)  c40, 4d0:   < VMUser::SendAck()
21/04 13:13:34.257 vmprov5s (06,9)  c40, 4d0:   Calling ProcessReceiveBuffer(02A843E0)
21/04 13:13:34.257 vmprov5s (06,8)  c40, 4d0:   [IClient::ProcessBuffer]: Processing data received from BHLSPDX-PBX (MASTER:10.0.0.20 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (69)] left 0ms ago (open type=2,session=17d), request " VMMESSAGE_CLOSE Session=0000017d, Seq=0"
21/04 13:13:34.257 vmprov5s (09,6)  c40, 4d0:   Session: 0000017d - Receive CLOSE "" for session 0000017d, call-id 773 (VMClient:0D37DCC8)
21/04 13:13:34.257 vmprov5s (06,9)  c40, 4d0: < VMUser::ProcessSequencedData()
21/04 13:13:34.263 vmprov5s (06,9)  c40, 914: > IUser::RemoveClient(Client<0D37DD30> )
21/04 13:13:34.263 vmprov5s (02,8)  c40, 914:   > VoiceMailDialog::Terminating(0D3618A0)
21/04 13:13:34.263 vmprov5s (09,6)  c40, 914:     Session: 0000017d - LeaveMessage mailbox=(Wendy C), (0)
21/04 13:13:34.263 vmprov5s (06,8)  c40, 914:     > VMClient::SendMsg(Session=0000017d,buf=027B7098)
21/04 13:13:34.263 vmprov5s (06,8)  c40, 914:       SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_CONTROL Session=0000017d, Seq=0" to server "10.0.0.20"
21/04 13:13:34.263 vmprov5s (06,8)  c40, 914:     < VMClient::SendMsg()
21/04 13:13:34.263 vmprov5s (07,8)  c40, 914:     > IntuityRecordNode::SetMailboxInfo(136)
21/04 13:13:34.263 vmprov5s (07,9)  c40, 914:       MailBox Value after copy is : "136" 
21/04 13:13:34.263 vmprov5s (07,8)  c40, 914:     < IntuityRecordNode::SetMailboxInfo()
21/04 13:13:34.263 vmprov5s (07,8)  c40, 914:     > IntuityRecordNode::SaveRecording(FSavedCalled=0, FRecordType=2 (rtRecordMessage))
21/04 13:13:34.263 vmprov5s (07,8)  c40, 914:       > IntuityRecordNode::GetLengthOfRecording()
21/04 13:13:34.263 vmprov5s (0c,9)  c40, 914:         > RecordingNode::LocalEndPlay(0D364F28)
21/04 13:13:34.263 vmprov5s (09,6)  c40, 914:           Session: 0000017d - EndRecording
21/04 13:13:34.264 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_SEQ VMMESSAGE_SEQ, seq=00000250 - VMMESSAGE_CLOSE Session=0000017d, Seq=0" from 10.0.0.20:4098
21/04 13:13:34.296 vmprov5s (21,8)  c40, 914:           DiskSpaceAlarm::AnalyseDiskSpaceAvailable(File: C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav)
21/04 13:13:34.297 vmprov5s (21,8)  c40, 914:           DiskSpaceAlarm::SetCurrentDiskState(value: DiskStateFree)
21/04 13:13:34.297 vmprov5s (0c,9)  c40, 914:         < RecordingNode::LocalEndPlay()
21/04 13:13:34.297 vmprov5s (07,8)  c40, 914:       < IntuityRecordNode::GetLengthOfRecording()
21/04 13:13:34.297 vmprov5s (29,9)  c40, 914:       Created thread IntuityRecordNode::SaveRecordingForMailboxList (02CBFC28) (TID: e34 (3636))
21/04 13:13:34.297 vmprov5s (09,5)  c40, 914:       Session: 0000017d - SetRecordingThreadOver(InProgress) called for client 0D37DD30 (0000017d) () - reference count 2->3.
21/04 13:13:34.297 vmprov5s (06,9)  c40, 4d0: > VMUser::ProcessSequencedData(buf=00FA70F8, tx_seq=63, rx_seq=592)
21/04 13:13:34.297 vmprov5s (06,9)  c40, 4d0:   > VMUser::SendAck(0.0.2.80)
21/04 13:13:34.297 vmprov5s (06,8)  c40, 4d0:     SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SEQ VMSEQ_ACK, seq=00000250" to server "10.0.0.20"
21/04 13:13:34.297 vmprov5s (06,9)  c40, 4d0:   < VMUser::SendAck()
21/04 13:13:34.297 vmprov5s (06,9)  c40, 4d0:   Calling ProcessReceiveBuffer(00FA70F8)
21/04 13:13:34.297 vmprov5s (06,9)  c40, 4d0: < VMUser::ProcessSequencedData()
21/04 13:13:34.298 DbgOut   (01,5) 3136,3636: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:34.308 vmprov5s (29,9)  c40, e34: Processing thread IntuityRecordNode::SaveRecordingForMailboxList (02CBFC28) (TID: 0xe34 (3636))
21/04 13:13:34.308 vmprov5s (07,8)  c40, e34: > IntuityRecordNode::SaveRecordingForMailboxList()
21/04 13:13:34.308 vmprov5s (09,5)  c40, e34:   SetRecordingThreadOver(InProgress) called for client 0D37DD30 (0000017d) () - reference count 3->4.
21/04 13:13:34.308 vmprov5s (09,6)  c40, e34:   Background saving "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to 1 mailboxes
21/04 13:13:34.309 vmprov5s (23,5)  c40, e34:   Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" - Took 0 ms to normalize, maximum energy was 5.399853 and used factor 1.705233 to normalize
21/04 13:13:34.312 vmprov5s (23,5)  c40, e34:   Normalized wave file "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" - rewritten at 0.180000 FS
21/04 13:13:34.312 vmprov5s (09,6)  c40, e34:   Background saving message "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to 136 [entry 1 of 1]
21/04 13:13:34.312 vmprov5s (21,8)  c40, e34:   DiskSpaceAlarm::AnalyseDiskSpaceAvailable(File: C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav)
21/04 13:13:34.312 vmprov5s (21,8)  c40, e34:   DiskSpaceAlarm::SetCurrentDiskState(value: DiskStateFree)
21/04 13:13:34.312 vmprov5s (06,9)  c40, e34:   VMailBox::FindMailBox - pbx=<02B4C038>, name=<136>, flags=<BYEXT> - Found Mailbox BYEXT
21/04 13:13:34.312 vmprov5s (07,9)  c40, e34:   > IntuityRecordNode::SaveRecordingToFile(Mailbox: 02C23708 (Wendy C))
21/04 13:13:34.317 vmprov5s (09,5)  c40, 914: Session: 0000017d - SetRecordingThreadOver(Finished) called for client 0D37DD30 (0000017d) (Background saving message "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to 136 [entry 1 of 1]) - reference count 4->3.
21/04 13:13:34.317 vmprov5s (07,8)  c40, 914: < IntuityRecordNode::SaveRecording()
21/04 13:13:34.317 vmprov5s (02,8)  c40, 914: > VoiceMailDialog::PlayNodeDestroyed(0D3618A0, node=02CABF18, current=00000000)
21/04 13:13:34.317 vmprov5s (02,8)  c40, 914: < VoiceMailDialog::PlayNodeDestroyed()
21/04 13:13:34.317 vmprov5s (0c,9)  c40, 914: > RecordingNode::LocalEndPlay(0D364F28)
21/04 13:13:34.317 vmprov5s (0c,9)  c40, 914: < RecordingNode::LocalEndPlay()
21/04 13:13:34.317 vmprov5s (02,8)  c40, 914: < VoiceMailDialog::Terminating()
21/04 13:13:34.317 vmprov5s (06,9)  c40, 914: < IUser::RemoveClient()
21/04 13:13:34.383 vmprov5s (1f,8)  c40, e34: > VMailBox::UpdateFileState(Mailbox: Wendy C, Msg: MSG00009, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
21/04 13:13:34.386 vmprov5s (13,5)  c40, e34:   Renaming message file "Wendy C/C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\MSG00009.wav" to "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\3c2429e5b56d4596bcc135f5b578fb85.WAV"
21/04 13:13:34.386 vmprov5s (1d,8)  c40, e34:   > tblMailbox::LocateMessage(Mailbox ID:{32f681de-f3c0-4660-a223-460b152eeeb4}, Message Number:9)
21/04 13:13:34.389 vmprov5s (1d,5)  c40, e34:     Information: No data found executing SQLFetch (line 2558, file ".\tblMailbox.cpp")
21/04 13:13:34.389 vmprov5s (1d,8)  c40, e34:   < tblMailbox::LocateMessage()
21/04 13:13:34.390 vmprov5s (1d,8)  c40, e34:   > tblMessage::SaveRecord(Mailbox: {32f681de-f3c0-4660-a223-460b152eeeb4}, ID:{00000000-0000-0000-0000-000000000000})
21/04 13:13:34.392 vmprov5s (1d,8)  c40, e34:   < tblMessage::SaveRecord()
21/04 13:13:34.392 vmprov5s (24,5)  c40, e34:   ITZ is not supported.
21/04 13:13:34.392 vmprov5s (1d,8)  c40, e34:   > tblMessage::SaveRecord(Mailbox: {32f681de-f3c0-4660-a223-460b152eeeb4}, ID:{f0b7f86d-d0d9-4e3a-810e-55daf7b89274})
21/04 13:13:34.395 vmprov5s (1d,8)  c40, e34:   < tblMessage::SaveRecord()
21/04 13:13:34.395 vmprov5s (13,8)  c40, e34:   VMailBox::AddToMessageList(Name:Wendy C, msg:MSG00009, status:F (70))
21/04 13:13:34.395 vmprov5s (26,6)  c40, e34:   RefreshSyncObjectFromFD [Add]: [ACCOUNTS]/Wendy C/3c2429e5b56d4596bcc135f5b578fb85.wav (file size is 107154) [message <"MSG00009">
21/04 13:13:34.395 vmprov5s (06,8)  c40, e34:   VMailBox::SendMailboxInfo(Name: Wendy C, New: 0, [Unopened: 0], Old: 0, Saved: 0, Session Id: 0x00000000)
21/04 13:13:34.395 vmprov5s (1e,9)  c40, e34:   > IMAP4Impl::NotifyMsgUpdate(Mailbox: Wendy C, Message:MSG00009, State:Forward)
21/04 13:13:34.396 vmprov5s (1e,9)  c40, e34: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Wendy C, MsgId=9, state=F)
21/04 13:13:34.396 vmprov5s (1e,9)  c40, e34:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Wendy C, MsgId=9, state=F)
21/04 13:13:34.396 vmprov5s (1e,9)  c40, e34:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 13:13:34.396 vmprov5s (1e,9)  c40, e34: < IMAP4 - VMNotifyMsgUpdate()
21/04 13:13:34.396 vmprov5s (1e,9)  c40, e34:   < IMAP4Impl::NotifyMsgUpdate()
21/04 13:13:34.396 vmprov5s (06,5)  c40, e34:   NotifyMessageEventToPhoneManager Message ID = MSG00009
21/04 13:13:34.414 vmprov5s (01,9)  c40, e34:   > ScriptEventMailboxMessage(Wendy C, [Accounts]\Wendy C\MSG00009, Forward)
21/04 13:13:34.414 vmprov5s (01,9)  c40, e34:   < ScriptEventMailboxMessage()
21/04 13:13:34.414 vmprov5s (1f,8)  c40, e34: < VMailBox::UpdateFileState()
21/04 13:13:34.414 vmprov5s (1d,8)  c40, e34: > tblMailbox::LocateMessage(Mailbox ID:{32f681de-f3c0-4660-a223-460b152eeeb4}, Message Number:9)
21/04 13:13:34.416 vmprov5s (1d,8)  c40, e34:   > tblMessage::GetRecord(Mailbox: {32f681de-f3c0-4660-a223-460b152eeeb4}, ID:{f0b7f86d-d0d9-4e3a-810e-55daf7b89274})
21/04 13:13:34.418 vmprov5s (1d,8)  c40, e34:   < tblMessage::GetRecord()
21/04 13:13:34.418 vmprov5s (1d,8)  c40, e34: < tblMailbox::LocateMessage()
21/04 13:13:34.418 vmprov5s (14,9)  c40, e34: EMailerRequest::Send: Queued email request for wrc@xxxxx.com, subject is Voicemail Message (Mike H > 136) From:101, use EMAPI=true
21/04 13:13:34.418 vmprov5s (09,6)  c40, e34: IntuityRecordNode - Sent recording to mailbox: "136" <Private: 0> <Priority: 0>
21/04 13:13:34.418 vmprov5s (07,9)  c40, e34: < IntuityRecordNode::SaveRecordingToFile()
21/04 13:13:34.428 vmprov5s (09,5)  c40, e34: SetRecordingThreadOver(Finished) called for client 0D37DD30 (0000017d) (Background saving message "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to 136 [entry 1 of 1]) - reference count 3->2.
21/04 13:13:34.428 vmprov5s (09,6)  c40, e34: Background saved message "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to at least 1 mailboxes and took 109 milli-seconds
21/04 13:13:34.428 vmprov5s (07,8)  c40, e34: < IntuityRecordNode::SaveRecordingForMailboxList()
21/04 13:13:34.428 vmprov5s (29,9)  c40, e34: Thread IntuityRecordNode::SaveRecordingForMailboxList (02CBFC28) (TID: 0xe34 (3636)) completed
21/04 13:13:34.428 vmprov5s (29,9)  c40, e34: Terminating thread IntuityRecordNode::SaveRecordingForMailboxList (02CBFC28) (TID: e34 (3636))
21/04 13:13:34.428 vmprov5s (29,9)  c40, e34: Thread IntuityRecordNode::SaveRecordingForMailboxList (02CBFC28) (TID: e34 (3636)) terminated
21/04 13:13:34.428 DbgOut   (01,5) 3136,3636: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 13:13:34.434 vmprov5s (09,2)  c40, 914: Session: 0000017d - GetRecordingThreadOver - thread 02CBFC28 no longer exists, removing from list (client 0D37DD30 (0000017d) (Background saved message "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Greetings\MSG22843.wav" to at least 1 mailboxes and took 109 milli-seconds)).
21/04 13:13:34.434 vmprov5s (0c,8)  c40, 914: > IClient::UnLink(No Vtable=no)
21/04 13:13:34.434 vmprov5s (0c,6)  c40, 914:   Unlinking Voicemail Client (IClient object=0D37DD30, session=0000017d)
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   > VoiceMailDialog::PlayNodeDestroyed(0D3618A0, node=0D363310, current=00000000)
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   < VoiceMailDialog::PlayNodeDestroyed()
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   > VoiceMailDialog::PlayNodeDestroyed(0D3618A0, node=0D36286C, current=00000000)
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   < VoiceMailDialog::PlayNodeDestroyed()
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   > VoiceMailDialog::PlayNodeDestroyed(0D3618A0, node=0D362058, current=00000000)
21/04 13:13:34.434 vmprov5s (02,8)  c40, 914:   < VoiceMailDialog::PlayNodeDestroyed()
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914:   > VMDialog::~VMDialog()()
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914:   < VMDialog::~VMDialog()()
21/04 13:13:34.434 vmprov5s (06,8)  c40, 914:   VMailBox::SendMailboxInfo(Name: Wendy C, New: 0, [Unopened: 0], Old: 0, Saved: 0, Session Id: 0x0000017d)
21/04 13:13:34.434 vmprov5s (0c,8)  c40, 914: < IClient::UnLink()
21/04 13:13:34.434 vmprov5s (06,9)  c40, 914: < VMClient::Main(0D37DCC8 <session=0000017d>)
21/04 13:13:34.434 vmprov5s (29,8)  c40, 914: VMThreadFunc exited[(VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 914 (2324))), created 21/04 13:13:18.346, priority 2]
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914: > VMClient::Destroy(VMClient object=0D37DCC8, session=0000017d)
21/04 13:13:34.434 vmprov5s (06,9)  c40, 914:   > IUser::RemoveClient(Client<0D37DD30> called from destructor)
21/04 13:13:34.434 vmprov5s (06,9)  c40, 914:   < IUser::RemoveClient()
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914:   > IClient::~IClient(VMClient object=0D37DD30, session=0000017d)
21/04 13:13:34.434 vmprov5s (09,5)  c40, 914:     Session: 0000017d - ~IClient [Call duration: 16 secs, 0 missed packets] [Connections 0 - 0] [Buffer pool(0:1000 1:250 2:2500 3:1497) allocated:3, seq:0] [Links:11554 from 12000] [CMMsg:2 left from 2] [VM:1 NODES:55 SN:1 SBL:0 TASKS:6 FILES:1 Msgs:132]
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914:   < IClient::~IClient()
21/04 13:13:34.434 vmprov5s (29,9)  c40, 914:   > VMTask::~VMTask(name=VMClient, this=0D37DCE4)
21/04 13:13:34.434 vmprov5s (29,9)  c40, 914:   < VMTask::~VMTask()
21/04 13:13:34.434 vmprov5s (0c,9)  c40, 914: < VMClient::Destroy()
21/04 13:13:34.434 vmprov5s (29,9)  c40, 914: Thread VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 0x914 (2324)) completed
21/04 13:13:34.434 vmprov5s (29,9)  c40, 914: Terminating thread VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 914 (2324))
21/04 13:13:34.434 vmprov5s (29,9)  c40, 914: Thread VMTask[VMClient(0D37DCE4)] (02CBFE90) (TID: 914 (2324)) terminated
21/04 13:13:34.434 DbgOut   (01,5) 3136,2324: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 13:13:34.453 vmprov5s (14,9)  c40, b64: > EMailer::SendRequestUsingEMAPI(0D366C70 [136\MSG00009])
21/04 13:13:34.453 vmprov5s (19,8)  c40, b64:   > CMAPIImpl::SendUMEmail(00000000)
21/04 13:13:34.453 vmprov5s (66,8)  c40, b64: > CMAPIImpl::SendUMEmail()
21/04 13:13:34.453 vmprov5s (66,8)  c40, b64:   > CMAPIImpl::SendUMEmail(pIMsgStore: 02A84DAC, m_TargetAlias: "wrc@xxxxx.com", szSubject: "Voicemail Message (Mike H > 136) From:101", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\3c2429e5b56d4596bcc135f5b578fb85.WAV", szFilename: "MSG00009.WAV", lSecs: 7, szNumber: 101, szName: "Mike H", szOrder: ;MSG00009.WAV, offset: 0, ulMsgReceived: 3607100007)
21/04 13:13:34.453 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5714]: At entry, counter=[35]
21/04 13:13:34.454 vmprov5s (66,9)  c40, b64:     > HrOpenEntry(pIMsgStore: 02A84DAC, EntryID: "C8A57D11A16459488FD538CCE828E8BE0100B50523B8EAC6DA4893B9EBD16452BF6200000046FB85000000009127", Interface: "{0002030c-0000-0000-c000-000000000046}", Flags: 00000001 use MAPI_NO_CACHE)
21/04 13:13:34.460 DbgOut   (01,5) 3136,4796: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:34.460 DbgOut   (01,5) 3136,3848: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:34.461 vmprov5s (66,9)  c40, b64:     < HrOpenEntry()
21/04 13:13:34.464 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::CreateAddressList(pAddressList: 00000000, szRecpientList: "wrc@xxxxx.com")
21/04 13:13:34.473 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::CreateAddressList()
21/04 13:13:34.473 vmprov5s (66,8)  c40, b64:     > CMAPIImpl::AttachFile(pMsg: 02C2F014, pSourceStream: 0CFF0BF0, szFilename: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\Wendy C\3c2429e5b56d4596bcc135f5b578fb85.WAV", szDisplayName: "MSG00009.WAV")
21/04 13:13:34.473 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9493]: At entry, counter=[39]
21/04 13:13:34.489 DbgOut   (01,5) 3136,1040: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:13:34.513 vmprov5s (66,5)  c40, b64:       CMAPIImpl::AttachFile[9661]: At Exit, counter=[39]
21/04 13:13:34.513 vmprov5s (66,8)  c40, b64:     < CMAPIImpl::AttachFile()
21/04 13:13:34.528 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5866]: Message submitted successfully.[39]
21/04 13:13:34.529 vmprov5s (66,5)  c40, b64:     CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[35]
21/04 13:13:34.529 vmprov5s (66,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 13:13:34.529 vmprov5s (66,8)  c40, b64: < CMAPIImpl::SendUMEmail()
21/04 13:13:34.529 vmprov5s (19,8)  c40, b64:   < CMAPIImpl::SendUMEmail()
21/04 13:13:34.529 vmprov5s (14,9)  c40, b64:   Successfully sent email to wrc@xxxxx.com
21/04 13:13:34.529 vmprov5s (14,9)  c40, b64: < EMailer::SendRequestUsingEMAPI()
21/04 13:13:34.529 vmprov5s (14,9)  c40, b64: emailer - SendRequest succeeded for 136\MSG00009
21/04 13:13:34.529 vmprov5s (06,9)  c40, b64: VMailBox::FindMailBox - pbx=<00000000>, name=<136>, flags=<BYEXT> - Found Mailbox BYEXT
21/04 13:13:34.529 vmprov5s (1f,8)  c40, b64: > VMailBox::UpdateFileState(Mailbox: Wendy C, Msg: MSG00009, State: A, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
21/04 13:13:34.532 vmprov5s (13,8)  c40, b64:   VMailBox::AddToMessageList(Name:Wendy C, msg:MSG00009, status:A (65))
21/04 13:13:34.532 vmprov5s (26,6)  c40, b64:   RefreshSyncObjectFromFD [Refresh]: [ACCOUNTS]/Wendy C/3c2429e5b56d4596bcc135f5b578fb85.wav (file size is 107154) [message <"MSG00009">
21/04 13:13:34.532 vmprov5s (06,8)  c40, b64:   VMailBox::SendMailboxInfo(Name: Wendy C, New: 0, [Unopened: 0], Old: 0, Saved: 0, Session Id: 0x00000000)
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64:   > IMAP4Impl::NotifyMsgUpdate(Mailbox: Wendy C, Message:MSG00009, State:A)
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64: > IMAP4 - VMNotifyMsgUpdate(phConnection=02A848F8, Mailbox=Wendy C, MsgId=9, state=A)
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64:   > IMAP4 - ConnectionInfo::NotifyMsgUpdate(Mailbox=Wendy C, MsgId=9, state=A)
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64:   < IMAP4 - ConnectionInfo::NotifyMsgUpdate()
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64: < IMAP4 - VMNotifyMsgUpdate()
21/04 13:13:34.532 vmprov5s (1e,9)  c40, b64:   < IMAP4Impl::NotifyMsgUpdate()
21/04 13:13:34.532 vmprov5s (06,5)  c40, b64:   NotifyMessageEventToPhoneManager Message ID = MSG00009
21/04 13:13:34.532 vmprov5s (01,9)  c40, b64:   > ScriptEventMailboxMessage(Wendy C, [Accounts]\Wendy C\MSG00009, A)
21/04 13:13:34.532 vmprov5s (01,9)  c40, b64:   < ScriptEventMailboxMessage()
21/04 13:13:34.532 vmprov5s (1f,8)  c40, b64: < VMailBox::UpdateFileState()
21/04 13:13:34.532 vmprov5s (1d,8)  c40, b64: > tblMailbox::LocateMessage(Mailbox ID:{32f681de-f3c0-4660-a223-460b152eeeb4}, Message Number:9)
21/04 13:13:34.535 vmprov5s (1d,8)  c40, b64:   > tblMessage::GetRecord(Mailbox: {32f681de-f3c0-4660-a223-460b152eeeb4}, ID:{f0b7f86d-d0d9-4e3a-810e-55daf7b89274})
21/04 13:13:34.537 vmprov5s (1d,8)  c40, b64:   < tblMessage::GetRecord()
21/04 13:13:34.537 vmprov5s (1d,8)  c40, b64: < tblMailbox::LocateMessage()
21/04 13:13:43.837 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:13:43.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:13:43.837 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:13:43.837 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:13:43.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:13:43.838 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:13:43.838 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:13:53.837 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:13:53.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:13:53.837 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:13:53.837 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:13:53.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:13:53.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:13:53.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:13:59.162 DbgOut   (01,5) 3136,3468: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
21/04 13:14:03.837 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:14:03.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:14:03.837 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:14:03.837 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:14:03.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:14:03.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:14:03.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:14:13.837 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:14:13.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:14:13.837 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:14:13.837 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:14:13.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:14:13.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:14:13.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:14:22.162 DbgOut   (01,5) 3136,2932: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
21/04 13:14:23.836 vmprov5s (06,8)  c40,1350: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 10.0.0.20:4098
21/04 13:14:23.836 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Processing server[10.0.0.20],m_User->Identifier()[10.0.0.20],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
21/04 13:14:23.836 vmprov5s (26,6)  c40, 634: PBX <10.0.0.20> - Backup active = FALSE, backing up PBX <0.0.0.0>
21/04 13:14:23.836 vmprov5s (26,6)  c40, 634: POLL from 10.0.0.20:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=BHLSPDX-PBX, buf->data[35]=0, buf[31-34]=0.0.0.0
21/04 13:14:23.836 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "10.0.0.20"
21/04 13:14:23.837 vmprov5s (06,8)  c40, 634: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.92.0" to server "10.0.0.20"
21/04 13:14:23.837 vmprov5s (06,8)  c40, 634: [TCPServerThread[0]]: Server[10.0.0.20], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
21/04 13:14:27.127 vmprov5s (09,5)  c40,1244: IP Office Voicemail Pro [Hardware mode 03] - Version 8.1 (9203) [CPU Usage 0.1] [CPU Min:0.0, Avg:1.6, Max:678.3] has been running for 0 days, 22 hours and 36 minutes and received 162 calls (0 missed voice packets) (162 completed, 7 calls/hour) (Call duration(secs) - Min:0, Max:380, Avg:22).
21/04 13:14:27.127 vmprov5s (09,5)  c40,1244: System Configuration:-
Session=00000000, Seq=0" - took 0.000ms

CarGoSki · Apr 22, 2015

Does [ACCOUNTS]/Wendy C/3c2429e5b56d4596bcc135f5b578fb85.wav exist?

pdxmunkel · Apr 22, 2015

Yes it does.

CarGoSki said:
Does [ACCOUNTS]/Wendy C/3c2429e5b56d4596bcc135f5b578fb85.wav exist?

CarGoSki · Apr 23, 2015

open status.txt and find the wav and copy and post the entire line.

pdxmunkel · Apr 23, 2015

the above referenced wav is now gone, there is another wav from yesterday in the folder though. Wav from yesterday is 7b34e839703140c2bd04434028e5d81a.WAV.

Here is the status.txt contents.

Code:

d7014e3a,+D,N,N,MSG00010,{7b34e839-7031-40c2-bd04-434028e5d81a},M

Thanks!

CarGoSki · Apr 23, 2015

your message has been deleted = +D.
Since the user vmail is set forward then I would expect this.
Turn off user vmail forwarding.

pdxmunkel · Apr 23, 2015

After I turned off vmail forwarding in Avaya manager the message goes to the phone but not to her email.

smokinjoe2938 · Apr 23, 2015

have you tried a copy this should send it to email and voicemail

pdxmunkel · Apr 23, 2015

Won't a "copy" not be unified in the sense that if the user deletes it in one location it doesn't delete in the other?

smokinjoe2938 · Apr 23, 2015

yes that is correct, but some customers do request this

pdxmunkel · Apr 23, 2015

Thanks, that's what I thought, all my users are set to forward and it works how we like, one message goes to email and voicemail, delete from either location deletes in both locations.

smokinjoe2938 · Apr 23, 2015

a forward should not send it to the phone, you said when you delete a forward it deleted it from both so now you have me questioning this.

pdxmunkel · Apr 23, 2015

My setup is as follows:
All users set to voicemail forward in Avaya manager
All users have UMS enabled in Exchange

smokinjoe2938 · Apr 23, 2015

ok key word here UMS, thanks

pdxmunkel · Apr 28, 2015

Anyone have any further ideas on this, I really don't want to change her extension now that everyone knows it and her DD is outside the office. Thanks!

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!

Voicemail only delivered to email for just one user 1

IS-IT--Management

IS-IT--Management

Vendor

IS-IT--Management

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Programmer

IS-IT--Management

Similar threads

Log in

Part and Inventory Search

Sponsor