Delete VM account foulder, now not receiving messages or notifications 2

[driven0]

I went in to delete my folder here: x:\Program Files\Avaya\IP Office\Voicemail Pro\VM\Accounts\<myfolder>

I restarted VMPRO services to let it auto-create the folder again. Now I'm not getting any email notifications and the Message button on our 9611 phones is not lighting up.

I can log into the folder that was re-created and see a message I left, they are in the folder but not showing up on my phone.

Any help is greatly appreciated.

 

[tlpeter]

OK clear.
Use debugview
Run the tool and clear the screen (shift + del)
Then leave a voicemail and put the trace here.


BAZINGA!

I'm not insane, my mother had me tested!

 

[driven0]

This was the last part of it when it failed:
< EMailer::SendRequestUsingEMAPI()
03/05 12:21:29.988 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00014, error Invalid pointer (80004003)


Do you want me to paste the whole thing or is that sufficient?

Thanks again for your all your help, it's truly appreciated.

 

[tlpeter]

Can you do more?
Do you only send it by email or do you also should get a message on the phone itself?

BAZINGA!

I'm not insane, my mother had me tested!

 

[driven0]

We had it done by email and phone, I'm not getting either.

I changed a few things in here for privacy
Changed actual company name to "companyname"
My user is now "jsmith" and I changed the phone # to companyphone


03/05 12:01:46.565 DbgOut (00,5) 2336,2548: DllMain(hinstDLL=0x10000000, fdwReason=DLL_PROCESS_DETACH)
03/05 12:01:46.627 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:01:46.629 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:01:46.629 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:01:46.629 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:01:46.629 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:01:46.629 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:01:46.630 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:01:48.455 vmprov5s (14,9) ba4, f00: Check pending unsent emails
03/05 12:01:48.455 vmprov5s (26,6) ba4, f00: > VMailBox::ForwardPendingEmails()
03/05 12:01:48.455 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00001
03/05 12:01:48.455 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00001, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.455 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.455 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:1)
03/05 12:01:48.456 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{0f6ceec5-e782-43fe-8941-b4464710763d})
03/05 12:01:48.458 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.458 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.458 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.458 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00002
03/05 12:01:48.458 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00002, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.458 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.458 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:2)
03/05 12:01:48.459 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{dead31c6-61ca-4537-b2ed-93fbfb7a030e})
03/05 12:01:48.461 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.461 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.461 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > ericw) From:companynumber, use EMAPI=true
03/05 12:01:48.461 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00003
03/05 12:01:48.461 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00003, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.461 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.461 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:3)
03/05 12:01:48.462 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{6b214cc3-1132-4725-89d9-22ac5be6c0cd})
03/05 12:01:48.464 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.464 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.465 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > 51818) From:companynumber, use EMAPI=true
03/05 12:01:48.465 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00004
03/05 12:01:48.465 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00004, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.465 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.465 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:4)
03/05 12:01:48.466 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{b83b48eb-b8f5-4d53-9807-2f959bda5cde})
03/05 12:01:48.467 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.468 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.468 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (INTEGRA TELECOM > jsmith) From:3036435103, use EMAPI=true
03/05 12:01:48.479 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00005
03/05 12:01:48.479 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00005, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.479 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.479 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:5)
03/05 12:01:48.480 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{e403cc68-93c6-446b-a02a-6fae5978ea82})
03/05 12:01:48.481 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.481 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.482 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.482 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00006
03/05 12:01:48.482 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00006, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.482 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.482 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:6)
03/05 12:01:48.483 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{e516c501-6023-4b62-b7de-0378eba86e98})
03/05 12:01:48.485 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.485 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.485 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.496 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00007
03/05 12:01:48.496 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00007, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.496 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.496 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:7)
03/05 12:01:48.497 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{e4f3061a-33dc-42fb-a7c9-871046742d33})
03/05 12:01:48.498 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.498 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.499 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.499 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00008
03/05 12:01:48.499 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00008, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.499 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.499 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:8)
03/05 12:01:48.500 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{fbe29c98-809e-4144-9b1c-86e01a197287})
03/05 12:01:48.501 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.501 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.502 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.513 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00009
03/05 12:01:48.513 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00009, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.513 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.513 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:9)
03/05 12:01:48.514 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{6764fb4d-e41d-434b-9365-f34b37d6c8ea})
03/05 12:01:48.515 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.515 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.516 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.527 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00010
03/05 12:01:48.527 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00010, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.527 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.527 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:10)
03/05 12:01:48.528 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{60857eec-7a43-441f-abe4-41f6a16f6f68})
03/05 12:01:48.529 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.529 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.530 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.530 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00011
03/05 12:01:48.530 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00011, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.530 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.530 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:11)
03/05 12:01:48.531 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{269d9b38-6b61-44e5-8840-0140b0e6efc4})
03/05 12:01:48.533 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.533 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.533 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.543 vmprov5s (13,6) ba4, f00: Message being retried for mailbox jsmith is MSG00012
03/05 12:01:48.543 vmprov5s (1f,8) ba4, f00: > VMailBox::UpdateFileState(Mailbox: jsmith, Msg: MSG00012, State: Forward, IsPrivate: 0, IsPriority: 0, Notify: 1, MessageType: 1)
03/05 12:01:48.543 vmprov5s (1f,8) ba4, f00: < VMailBox::UpdateFileState()
03/05 12:01:48.543 vmprov5s (1d,8) ba4, f00: > tblMailbox::LocateMessage(Mailbox ID:{10a09752-740b-47df-ad87-ec7202e85c91}, Message Number:12)
03/05 12:01:48.544 vmprov5s (1d,8) ba4, f00: > tblMessage::GetRecord(Mailbox: {10a09752-740b-47df-ad87-ec7202e85c91}, ID:{06550e83-2440-4bbe-978a-f61792281e66})
03/05 12:01:48.545 vmprov5s (1d,8) ba4, f00: < tblMessage::GetRecord()
03/05 12:01:48.545 vmprov5s (1d,8) ba4, f00: < tblMailbox::LocateMessage()
03/05 12:01:48.546 vmprov5s (14,9) ba4, f00: EMailerRequest::Send: Queued email request for jsmith@companyname.com, subject is Voicemail Message (company name > jsmith) From:companynumber, use EMAPI=true
03/05 12:01:48.557 vmprov5s (26,6) ba4, f00: < VMailBox::ForwardPendingEmails()
03/05 12:01:48.807 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(011DA860 [51818\MSG00001])
03/05 12:01:48.807 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:48.807 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:48.807 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\d64cc5fca39d45f8b3ef800fa9ce1064.WAV", szFilename: "MSG00001.WAV", lSecs: 6, szNumber: companynumber, szName: "company name", szOrder: ;MSG00001.WAV, offset: 72, ulMsgReceived: 3545051497)
03/05 12:01:48.807 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:48.807 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:48.807 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:48.807 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:48.807 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:48.807 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:48.807 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:48.807 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:48.807 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:48.808 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00001, error Invalid pointer (80004003)
03/05 12:01:49.058 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A7E130 [51818\MSG00002])
03/05 12:01:49.058 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:49.058 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:49.058 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > ericw) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\3fc8dd320bd74e2ebbc87541f18c7d87.WAV", szFilename: "MSG00002.WAV", lSecs: 4, szNumber: companynumber, szName: "company name", szOrder: ;MSG00002.WAV, offset: 72, ulMsgReceived: 3545053299)
03/05 12:01:49.058 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:49.058 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:49.058 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:49.058 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.058 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.058 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:49.058 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.058 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:49.058 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:49.058 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00002, error Invalid pointer (80004003)
03/05 12:01:49.308 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(011DA200 [51818\MSG00003])
03/05 12:01:49.308 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:49.308 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:49.308 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > 51818) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\0567d7f0edd44039ad766dee1cd2df62.WAV", szFilename: "MSG00003.WAV", lSecs: 5, szNumber: companynumber, szName: "company name", szOrder: ;MSG00003.WAV, offset: 72, ulMsgReceived: 3545054299)
03/05 12:01:49.308 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:49.308 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:49.308 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:49.308 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.308 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.308 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:49.308 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.308 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:49.308 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:49.308 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00003, error Invalid pointer (80004003)
03/05 12:01:49.558 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(011DA3B8 [51818\MSG00004])
03/05 12:01:49.558 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:49.558 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:49.558 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (INTEGRA TELECOM > jsmith) From:3036435103", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\d084958513fc44de9b319ae769931cf6.WAV", szFilename: "MSG00004.WAV", lSecs: 55, szNumber: 3036435103, szName: "INTEGRA TELECOM", szOrder: ;MSG00004.WAV, offset: 72, ulMsgReceived: 3545054963)
03/05 12:01:49.558 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:49.558 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:49.558 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:49.558 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.558 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.558 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:49.558 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.558 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:49.558 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:49.558 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00004, error Invalid pointer (80004003)
03/05 12:01:49.808 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A7A698 [51818\MSG00005])
03/05 12:01:49.808 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:49.808 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:49.808 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\2b5ec947898e4f3895ee76502daa14dc.WAV", szFilename: "MSG00005.WAV", lSecs: 7, szNumber: companynumber, szName: "company name", szOrder: ;MSG00005.WAV, offset: 72, ulMsgReceived: 3545055719)
03/05 12:01:49.808 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:49.808 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:49.808 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:49.808 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.809 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.809 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:49.809 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:49.809 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:49.809 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:49.809 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00005, error Invalid pointer (80004003)
03/05 12:01:50.059 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A7A850 [51818\MSG00006])
03/05 12:01:50.059 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:50.059 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:50.059 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\5296de2988f2497d9fb92ee9bd7ef4d3.WAV", szFilename: "MSG00006.WAV", lSecs: 8, szNumber: companynumber, szName: "company name", szOrder: ;MSG00006.WAV, offset: 72, ulMsgReceived: 3545055771)
03/05 12:01:50.059 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:50.059 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:50.059 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:50.059 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.059 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.059 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:50.059 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.059 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:50.059 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:50.059 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00006, error Invalid pointer (80004003)
03/05 12:01:50.240 vmprov5s (19,1) ba4, a78: VMIsMAPIAvailable returning [1]
03/05 12:01:50.240 vmprov5s (19,5) ba4, a78: CMAPIImpl::CheckMAPIConnection() - return is 0,m_iMAPIStatus = [-1]
03/05 12:01:50.240 DbgOut (01,5) 2980,1716: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:50.240 vmprov5s (29,9) ba4, a78: Created thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 6b4 (1716))
03/05 12:01:50.241 DbgOut (01,5) 2980,2376: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:50.241 vmprov5s (29,9) ba4, a78: Created thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 948 (2376))
03/05 12:01:50.253 vmprov5s (29,9) ba4, 6b4: Processing thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 0x6b4 (1716))
03/05 12:01:50.253 vmprov5s (19,8) ba4, 6b4: > CMAPIImpl::EventThreadRoutine(02AA4428)
03/05 12:01:50.253 vmprov5s (19,8) ba4, 6b4: > CMAPIImpl::MAPIConnect(SES-EX AvayaVoicemail@companyname.com Outlook false)
03/05 12:01:50.253 vmprov5s (66,8) ba4, 6b4: > CMAPIImpl::Connect(Exchange Server: <SES-EX>, Default Mailbox: <AvayaVoicemail@companyname.com>, Profile: <Outlook>)
03/05 12:01:50.253 vmprov5s (66,8) ba4, 6b4: > CMAPIImpl:isconnect()
03/05 12:01:50.253 vmprov5s (66,8) ba4, 6b4: < CMAPIImpl:isconnect()
03/05 12:01:50.253 vmprov5s (66,5) ba4, 6b4: Configuring for service.
03/05 12:01:50.253 vmprov5s (66,5) ba4, 6b4: Attempting to logon to MAPI using profile Outlook.
03/05 12:01:50.254 vmprov5s (29,9) ba4, 948: Processing thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 0x948 (2376))
03/05 12:01:50.254 vmprov5s (19,8) ba4, 948: > CMAPIImpl::EventThreadRoutine(02AA4490)
03/05 12:01:50.254 vmprov5s (19,8) ba4, 948: > CMAPIImpl::MAPIConnect(SES-EX AvayaVoicemail@companyname.com Outlook true)
03/05 12:01:50.254 vmprov5s (66,8) ba4, 948: > CMAPIImpl::Connect(Exchange Server: <SES-EX>, Default Mailbox: <AvayaVoicemail@companyname.com>, Profile: <Outlook>)
03/05 12:01:50.254 vmprov5s (66,8) ba4, 948: > CMAPIImpl:isconnect()
03/05 12:01:50.254 vmprov5s (66,8) ba4, 948: < CMAPIImpl:isconnect()
03/05 12:01:50.254 vmprov5s (66,5) ba4, 948: Configuring for service.
03/05 12:01:50.254 vmprov5s (66,5) ba4, 948: Attempting to logon to MAPI using profile Outlook.
03/05 12:01:50.275 DbgOut (01,5) 2980,2904: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:50.291 DbgOut (01,5) 2980,1184: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:50.291 DbgOut (01,5) 2980,3460: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:50.344 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A7AA08 [51818\MSG00007])
03/05 12:01:50.344 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:50.344 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:50.344 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\6e7699316c1e452e8a244d431af39bbb.WAV", szFilename: "MSG00007.WAV", lSecs: 3, szNumber: companynumber, szName: "company name", szOrder: ;MSG00007.WAV, offset: 72, ulMsgReceived: 3545055972)
03/05 12:01:50.344 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:50.344 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:50.344 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:50.344 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.344 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.344 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:50.344 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.344 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:50.344 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:50.344 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00007, error Invalid pointer (80004003)
03/05 12:01:50.594 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A7ABC0 [51818\MSG00008])
03/05 12:01:50.594 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:50.594 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:50.594 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\fabbd6910be74d3c97c3a83e88e4926a.WAV", szFilename: "MSG00008.WAV", lSecs: 6, szNumber: companynumber, szName: "company name", szOrder: ;MSG00008.WAV, offset: 72, ulMsgReceived: 3545056025)
03/05 12:01:50.594 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:50.594 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:50.594 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:50.594 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.594 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.594 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:50.594 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.594 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:50.594 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:50.594 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00008, error Invalid pointer (80004003)
03/05 12:01:50.844 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A98448 [51818\MSG00009])
03/05 12:01:50.844 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:50.844 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:50.844 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\ada550ab6d7a44738c11d36da7eea032.WAV", szFilename: "MSG00009.WAV", lSecs: 5, szNumber: companynumber, szName: "company name", szOrder: ;MSG00009.WAV, offset: 72, ulMsgReceived: 3545056052)
03/05 12:01:50.844 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:50.844 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:50.844 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:50.844 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.844 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.844 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:50.844 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:50.844 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:50.844 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:50.844 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00009, error Invalid pointer (80004003)
03/05 12:01:51.094 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A98600 [51818\MSG00010])
03/05 12:01:51.094 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:51.094 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:51.094 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\a08c19515c4648a7b8db3f7ed5c345b3.WAV", szFilename: "MSG00010.WAV", lSecs: 6, szNumber: companynumber, szName: "company name", szOrder: ;MSG00010.WAV, offset: 72, ulMsgReceived: 3545056145)
03/05 12:01:51.094 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:51.094 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:51.094 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:51.094 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.094 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.094 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:51.094 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.094 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:51.094 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:51.094 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00010, error Invalid pointer (80004003)
03/05 12:01:51.345 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A987B8 [51818\MSG00011])
03/05 12:01:51.345 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:51.345 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:51.345 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\54684ef69a1346b08f936f4ba2e55174.WAV", szFilename: "MSG00011.WAV", lSecs: 5, szNumber: companynumber, szName: "company name", szOrder: ;MSG00011.WAV, offset: 72, ulMsgReceived: 3545056434)
03/05 12:01:51.345 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:51.345 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:51.345 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:51.345 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.345 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.345 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:51.345 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.345 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:51.345 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:51.345 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00011, error Invalid pointer (80004003)
03/05 12:01:51.595 vmprov5s (14,9) ba4, f00: > EMailer::SendRequestUsingEMAPI(02A98970 [51818\MSG00012])
03/05 12:01:51.595 vmprov5s (19,8) ba4, f00: > CMAPIImpl::SendUMEmail(00000000)
03/05 12:01:51.595 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail()
03/05 12:01:51.595 vmprov5s (66,8) ba4, f00: > CMAPIImpl::SendUMEmail(pIMsgStore: 00000000, m_TargetAlias: "jsmith@companyname.com", szSubject: "Voicemail Message (company name > jsmith) From:companynumber", szBody: "IP Office Voicemail redirected message", szFile: "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Accounts\jsmith\b8fe2782419f4ee797484c21010dd333.WAV", szFilename: "MSG00012.WAV", lSecs: 6, szNumber: companynumber, szName: "company name", szOrder: ;MSG00012.WAV, offset: 72, ulMsgReceived: 3545056620)
03/05 12:01:51.595 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5714]: At entry, counter=[0]
03/05 12:01:51.595 vmprov5s (66,2) ba4, f00: CMAPIImpl::SendUMEmail[5946]: Error pIMsgStore is NULL.
03/05 12:01:51.595 vmprov5s (66,5) ba4, f00: CMAPIImpl::SendUMEmail[5949]: At Exit, counter=[0]
03/05 12:01:51.595 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.595 vmprov5s (66,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.595 vmprov5s (19,2) ba4, f00: Error (hr=0x80004003) from SendUMEmail
03/05 12:01:51.595 vmprov5s (19,8) ba4, f00: < CMAPIImpl::SendUMEmail()
03/05 12:01:51.595 vmprov5s (14,2) ba4, f00: Error 0x80004003 sending email to jsmith@companyname.com
03/05 12:01:51.595 vmprov5s (14,9) ba4, f00: < EMailer::SendRequestUsingEMAPI()
03/05 12:01:51.595 vmprov5s (14,2) ba4, f00: emailer - SendRequest failed for 51818\MSG00012, error Invalid pointer (80004003)
03/05 12:01:51.656 vmprov5s (66,2) ba4, 6b4: CMAPIImpl::Connect[2820]: Error 0x8004011d [MAPI_E_FAILONEPROVIDER] from OpenMsgStore (Microsoft Exchange is not available. Either there are network problems or the Exchange server is down for maintenance. : Microsoft Exchange Information Store[1318]) trying to open message store for default session.
03/05 12:01:51.656 vmprov5s (66,8) ba4, 6b4: < CMAPIImpl::Connect()
03/05 12:01:51.656 vmprov5s (19,2) ba4, 6b4: Error (hr=0x8004011d [MAPI_E_FAILONEPROVIDER]) from MAPIConnect
03/05 12:01:51.656 vmprov5s (19,8) ba4, 6b4: > CMAPIImpl:isconnect()
03/05 12:01:51.656 vmprov5s (66,8) ba4, 6b4: > CMAPIImpl:isconnect()
03/05 12:01:51.657 vmprov5s (66,8) ba4, 6b4: < CMAPIImpl:isconnect()
03/05 12:01:51.657 vmprov5s (66,8) ba4, 6b4: > CMAPIImpl:isconnect()
03/05 12:01:51.657 vmprov5s (66,8) ba4, 6b4: < CMAPIImpl:isconnect()
03/05 12:01:51.657 vmprov5s (19,8) ba4, 6b4: < CMAPIImpl:isconnect()
03/05 12:01:51.657 vmprov5s (19,8) ba4, 6b4: < CMAPIImpl::MAPIConnect()
03/05 12:01:51.657 vmprov5s (19,8) ba4, 6b4: < CMAPIImpl::EventThreadRoutine()
03/05 12:01:51.657 vmprov5s (29,9) ba4, 6b4: Thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 0x6b4 (1716)) completed
03/05 12:01:51.657 vmprov5s (29,9) ba4, 6b4: Terminating thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 6b4 (1716))
03/05 12:01:51.657 vmprov5s (29,9) ba4, 6b4: Thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 6b4 (1716)) terminated
03/05 12:01:51.657 DbgOut (01,5) 2980,1716: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:51.659 DbgOut (01,5) 2980,2196: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:01:51.660 vmprov5s (25,2) ba4, 948: Exception has occurred in CMAPIImpl::MAPIConnect (.\MAPIImpl.cpp[1963])
03/05 12:01:51.660 vmprov5s (09,2) ba4, 948: Exception has occurred in CMAPIImpl::MAPIConnect (.\MAPIImpl.cpp[1963])
03/05 12:01:51.661 vmprov5s (19,8) ba4, 948: > CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (66,8) ba4, 948: > CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (66,8) ba4, 948: < CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (66,8) ba4, 948: > CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (66,8) ba4, 948: < CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (19,8) ba4, 948: < CMAPIImpl:isconnect()
03/05 12:01:51.661 vmprov5s (19,8) ba4, 948: < CMAPIImpl::MAPIConnect()
03/05 12:01:51.661 vmprov5s (19,8) ba4, 948: < CMAPIImpl::EventThreadRoutine()
03/05 12:01:51.661 vmprov5s (29,9) ba4, 948: Thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 0x948 (2376)) completed
03/05 12:01:51.661 vmprov5s (29,9) ba4, 948: Terminating thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 948 (2376))
03/05 12:01:51.661 vmprov5s (29,9) ba4, 948: Thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 948 (2376)) terminated
03/05 12:01:51.661 DbgOut (01,5) 2980,2376: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:51.662 DbgOut (01,5) 2980,2196: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:53.469 DbgOut (01,5) 2980,4088: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:54.854 DbgOut (01,5) 2980,2436: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:56.291 DbgOut (01,5) 2980,1184: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:56.609 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:01:56.610 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:01:56.610 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:01:56.610 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:01:56.610 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:01:56.610 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:01:56.610 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:01:57.652 DbgOut (01,5) 2980,3460: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:01:57.655 DbgOut (01,5) 2980,2572: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:02:06.609 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:06.609 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:06.609 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:06.609 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:06.609 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:06.610 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:06.610 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:02:16.609 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:16.609 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:16.609 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:16.609 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:16.609 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:16.609 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:16.609 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:02:26.608 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:26.608 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:26.608 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:26.608 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:26.609 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:26.609 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:26.609 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.016ms
03/05 12:02:36.608 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:36.608 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:36.608 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:36.608 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:36.608 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:36.608 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:36.608 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: IP Office Voicemail Pro [Hardware mode 03] - Version 8.1 (9016) [CPU Usage 0.0] [CPU Min:0.0, Avg:0.5, Max:159.9] has been running for 0 days, 1 hours and 35 minutes and received 19 calls (0 missed voice packets) (19 completed, 12 calls/hour) (Call duration(secs) - Min:0, Max:72, Avg:21).
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: System Configuration:-
Voicemail Pro mode, locale: enu, IPO hardware mode: 03 [USA]
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: EVML:-
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: Maximum recording capacity: Unlimited, Maximum Recording Time: 120 seconds
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: Maximum Sessions: 4, Minimum PIN length: 0 digits
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: SMTP:-
Host address 192.168.1.7, port 25, Login method "none", email from "DEN-IPOFFICE@companyname.com", login user ""
03/05 12:02:42.002 vmprov5s (09,5) ba4, a78: Voicemail Sessions (Connections 0 - 0) (Links:11877 from 12000) (CMMsg:2 left from 2) (VM:0 NODES:0 SN:0 SBL:0 FILES:0)
03/05 12:02:46.608 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:46.608 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:46.608 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:46.608 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:46.608 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:46.608 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:46.608 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:02:54.002 vmprov5s (19,1) ba4, a78: VMIsMAPIAvailable returning [1]
03/05 12:02:54.002 vmprov5s (19,5) ba4, a78: CMAPIImpl::CheckMAPIConnection() - return is 0,m_iMAPIStatus = [-1]
03/05 12:02:54.002 DbgOut (01,5) 2980, 768: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:54.002 vmprov5s (29,9) ba4, a78: Created thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 300 (768))
03/05 12:02:54.002 DbgOut (01,5) 2980,3576: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:54.002 vmprov5s (29,9) ba4, a78: Created thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: df8 (3576))
03/05 12:02:54.014 vmprov5s (29,9) ba4, df8: Processing thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 0xdf8 (3576))
03/05 12:02:54.014 vmprov5s (19,8) ba4, df8: > CMAPIImpl::EventThreadRoutine(02AA4490)
03/05 12:02:54.014 vmprov5s (19,8) ba4, df8: > CMAPIImpl::MAPIConnect(SES-EX AvayaVoicemail@companyname.com Outlook true)
03/05 12:02:54.014 vmprov5s (66,8) ba4, df8: > CMAPIImpl::Connect(Exchange Server: <SES-EX>, Default Mailbox: <AvayaVoicemail@companyname.com>, Profile: <Outlook>)
03/05 12:02:54.014 vmprov5s (66,8) ba4, df8: > CMAPIImpl:isconnect()
03/05 12:02:54.014 vmprov5s (66,8) ba4, df8: < CMAPIImpl:isconnect()
03/05 12:02:54.014 vmprov5s (66,5) ba4, df8: Configuring for service.
03/05 12:02:54.014 vmprov5s (66,5) ba4, df8: Attempting to logon to MAPI using profile Outlook.
03/05 12:02:54.014 vmprov5s (29,9) ba4, 300: Processing thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 0x300 (768))
03/05 12:02:54.014 vmprov5s (19,8) ba4, 300: > CMAPIImpl::EventThreadRoutine(02AA43C0)
03/05 12:02:54.014 vmprov5s (19,8) ba4, 300: > CMAPIImpl::MAPIConnect(SES-EX AvayaVoicemail@companyname.com Outlook false)
03/05 12:02:54.014 vmprov5s (66,8) ba4, 300: > CMAPIImpl::Connect(Exchange Server: <SES-EX>, Default Mailbox: <AvayaVoicemail@companyname.com>, Profile: <Outlook>)
03/05 12:02:54.014 vmprov5s (66,8) ba4, 300: > CMAPIImpl:isconnect()
03/05 12:02:54.014 vmprov5s (66,8) ba4, 300: < CMAPIImpl:isconnect()
03/05 12:02:54.014 vmprov5s (66,5) ba4, 300: Configuring for service.
03/05 12:02:54.014 vmprov5s (66,5) ba4, 300: Attempting to logon to MAPI using profile Outlook.
03/05 12:02:54.024 DbgOut (01,5) 2980,2400: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:54.030 DbgOut (01,5) 2980,1428: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:54.031 DbgOut (01,5) 2980,1680: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:54.268 DbgOut (01,5) 2980,1968: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:55.555 vmprov5s (66,2) ba4, df8: CMAPIImpl::Connect[2820]: Error 0x8004011d [MAPI_E_FAILONEPROVIDER] from OpenMsgStore (Microsoft Exchange is not available. Either there are network problems or the Exchange server is down for maintenance. : Microsoft Exchange Information Store[1318]) trying to open message store for default session.
03/05 12:02:55.555 vmprov5s (66,8) ba4, df8: < CMAPIImpl::Connect()
03/05 12:02:55.555 vmprov5s (19,2) ba4, df8: Error (hr=0x8004011d [MAPI_E_FAILONEPROVIDER]) from MAPIConnect
03/05 12:02:55.555 vmprov5s (19,8) ba4, df8: > CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (66,8) ba4, df8: > CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (66,8) ba4, df8: < CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (66,8) ba4, df8: > CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (66,8) ba4, df8: < CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (19,8) ba4, df8: < CMAPIImpl:isconnect()
03/05 12:02:55.555 vmprov5s (19,8) ba4, df8: < CMAPIImpl::MAPIConnect()
03/05 12:02:55.555 vmprov5s (19,8) ba4, df8: < CMAPIImpl::EventThreadRoutine()
03/05 12:02:55.555 vmprov5s (29,9) ba4, df8: Thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: 0xdf8 (3576)) completed
03/05 12:02:55.555 vmprov5s (29,9) ba4, df8: Terminating thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: df8 (3576))
03/05 12:02:55.555 vmprov5s (29,9) ba4, df8: Thread CMAPIImpl::EventThreadRoutine (02A758A8) (TID: df8 (3576)) terminated
03/05 12:02:55.555 DbgOut (01,5) 2980,3576: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:02:55.556 DbgOut (01,5) 2980,4012: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:55.557 vmprov5s (25,2) ba4, 300: Exception has occurred in CMAPIImpl::MAPIConnect (.\MAPIImpl.cpp[1963])
03/05 12:02:55.557 vmprov5s (09,2) ba4, 300: Exception has occurred in CMAPIImpl::MAPIConnect (.\MAPIImpl.cpp[1963])
03/05 12:02:55.557 vmprov5s (19,8) ba4, 300: > CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (66,8) ba4, 300: > CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (66,8) ba4, 300: < CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (66,8) ba4, 300: > CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (66,8) ba4, 300: < CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (19,8) ba4, 300: < CMAPIImpl:isconnect()
03/05 12:02:55.557 vmprov5s (19,8) ba4, 300: < CMAPIImpl::MAPIConnect()
03/05 12:02:55.557 vmprov5s (19,8) ba4, 300: < CMAPIImpl::EventThreadRoutine()
03/05 12:02:55.557 vmprov5s (29,9) ba4, 300: Thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 0x300 (768)) completed
03/05 12:02:55.557 vmprov5s (29,9) ba4, 300: Terminating thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 300 (768))
03/05 12:02:55.557 vmprov5s (29,9) ba4, 300: Thread CMAPIImpl::EventThreadRoutine (02A75488) (TID: 300 (768)) terminated
03/05 12:02:55.557 DbgOut (01,5) 2980, 768: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:02:55.558 DbgOut (01,5) 2980,1788: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
03/05 12:02:55.558 DbgOut (01,5) 2980,4012: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:02:56.607 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:02:56.607 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1], request " VMMESSAGE_POLL Session=00000000, Seq=0"
03/05 12:02:56.607 vmprov5s (26,6) ba4, 5b0: PBX <192.168.54.2> - Backup active = FALSE, backing up PBX <0.0.0.0>
03/05 12:02:56.607 vmprov5s (26,6) ba4, 5b0: POLL from 192.168.54.2:4098 contained the following sync info: bBackupActive=0, ulBackupIp=0.0.0.0, uCentralIP=0.0.0.0, tsPeerVMDNS=, tsPeerIPOName=company-Den, buf->data[35]=0, buf[31-34]=0.0.0.0
03/05 12:02:56.607 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_DISTINFO Session=00000000, Seq=0" to server "192.168.54.2"
03/05 12:02:56.607 vmprov5s (06,8) ba4, 5b0: SOCKSTask::SendBuffer(VMServer [SOCKSTask] port 50791): Sending request " VMMESSAGE_SERVERUP Version=8.1.90.0" to server "192.168.54.2"
03/05 12:02:56.607 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Server[192.168.54.2], request " VMMESSAGE_POLL Session=00000000, Seq=0" - took 0.000ms
03/05 12:02:57.248 vmprov5s (06,8) ba4, f60: SOCKSTask::Main(TFTPTask [SOCKSTask] port 0): Received request " UNKNOWN[00] Data is 029483E8, length is 516" from 192.168.54.2:4185
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask:rocessReceiveBuffer(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=011C8030)
03/05 12:02:57.248 vmprov5s (11,8) ba4, 69c: > TFTPTask:rocessReceiveBuffer(029CB1F0, buf=02672278, IP:192.168.54.2, port=4185, state=TFTP_IDLE, current_pbx=029A8A78)
03/05 12:02:57.248 vmprov5s (11,6) ba4, 69c: TFTP_IDLE - seq=141
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask::InitializeUpdates(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=029A8A78)
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask::InitializeUpdates()
03/05 12:02:57.248 vmprov5s (11,6) ba4, 69c: TFTP_UPDATING - seq=141, newseq=142
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask::RxData(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=029A8A78, dp=026836CC (len=512), stream(029CB334 len=0))
03/05 12:02:57.248 vmprov5s (11,8) ba4, 69c: > TFTPTask::RxData(029CB1F0 - IP:192.168.54.2 [state=TFTP_UPDATING,len=512], current_pbx=029A8A78)
03/05 12:02:57.248 vmprov5s (11,8) ba4, 69c: < TFTPTask::RxData()
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask::RxData()
03/05 12:02:57.248 vmprov5s (06,8) ba4, 69c: SOCKSTask::SendBuffer(TFTPTask [SOCKSTask] port 0): Sending request " UNKNOWN[00] Data is 026836C8, length is 4" to server "192.168.54.2"
03/05 12:02:57.248 vmprov5s (11,8) ba4, 69c: < TFTPTask:rocessReceiveBuffer()
03/05 12:02:57.248 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask:rocessReceiveBuffer()
03/05 12:02:57.249 vmprov5s (06,8) ba4, f60: SOCKSTask::Main(TFTPTask [SOCKSTask] port 0): Received request " UNKNOWN[00] Data is 029483E8, length is 54" from 192.168.54.2:4185
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask:rocessReceiveBuffer(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=011C8030)
03/05 12:02:57.249 vmprov5s (11,8) ba4, 69c: > TFTPTask:rocessReceiveBuffer(029CB1F0, buf=02672278, IP:192.168.54.2, port=4185, state=TFTP_UPDATING, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,6) ba4, 69c: TFTP_UPDATING - seq=142, newseq=143
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask::RxData(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=029A8A78, dp=0268389A (len=50), stream(029CB334 len=512))
03/05 12:02:57.249 vmprov5s (11,8) ba4, 69c: > TFTPTask::RxData(029CB1F0 - IP:192.168.54.2 [state=TFTP_UPDATING,len=50], current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > TFTPDevIOTask::UpdatesComplete(029CB1F0: PBX=029C60D0[company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]] (valid), current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (09,6) ba4, 69c: Using config version (8.1.63=81063) for <company-Den (MASTER:192.168.54.2 - SLAVE:0.0.0.0 [INACTIVE]) [8.1 (63)]>
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > IVRConfig::Load(029CB328, merge=TRUE, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > VMProConfigMerger::StartConfigMerge(029B6160, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < VMProConfigMerger::StartConfigMerge()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > IVRConfig::GetRecord(029CB328, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < IVRConfig::GetRecord()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > VMProConfigMerger::MergeObject(029B6160 [obj=02A012E8])
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > VMProConfigMerger::MergeUser(029B6160 [Name=pdaily], current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > UpdateUser(pdaily->pdaily, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < UpdateUser()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > UserUpdated(pdaily, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < UserUpdated()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < VMProConfigMerger::MergeUser()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < VMProConfigMerger::MergeObject()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > IVRConfig::GetRecord(029CB328, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < IVRConfig::GetRecord()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: > VMProConfigMerger::EndConfigMerge(029B6160, current_pbx=029A8A78)
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < VMProConfigMerger::EndConfigMerge()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < IVRConfig::Load()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask::UpdatesComplete()
03/05 12:02:57.249 vmprov5s (11,8) ba4, 69c: < TFTPTask::RxData()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask::RxData()
03/05 12:02:57.249 vmprov5s (06,8) ba4, 69c: SOCKSTask::SendBuffer(TFTPTask [SOCKSTask] port 0): Sending request " UNKNOWN[00] Data is 02683896, length is 4" to server "192.168.54.2"
03/05 12:02:57.249 vmprov5s (11,8) ba4, 69c: < TFTPTask:rocessReceiveBuffer()
03/05 12:02:57.249 vmprov5s (11,9) ba4, 69c: < TFTPDevIOTask:rocessReceiveBuffer()
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: > VMailBox::ResynchMailbox(Name: pdaily)
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: VMailBox::ResynchMailbox(pdaily) - Updating Mailbox record
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: > VMailBox::SynchMailboxRecord(Name: pdaily)
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: < VMailBox::SynchMailboxRecord()
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: VMailBox::ResynchMailbox(pdaily) - Configuring breakout numbers
03/05 12:02:57.250 vmprov5s (06,8) ba4, cec: > VMailBox::SynchBreakoutNumbers(Name: pdaily)
03/05 12:02:57.250 vmprov5s (1d,8) ba4, cec: > tblMailbox::RemoveBreakoutNumbers(ID:{7424fec2-d755-4591-8ac2-32ec54a2325b})
03/05 12:02:57.250 vmprov5s (1d,8) ba4, cec: < tblMailbox::RemoveBreakoutNumbers()
03/05 12:02:57.250 vmprov5s (1d,8) ba4, cec: > tblMailbox::LocateBreakoutNumber(Mailbox ID:{7424fec2-d755-4591-8ac2-32ec54a2325b}, Number:51801, DtmfKey:0)
03/05 12:02:57.250 vmprov5s (1d,5) ba4, cec: Information: No data found executing SQLFetch (line 1306, file ".\tblMailbox.cpp")
03/05 12:02:57.250 vmprov5s (1d,8) ba4, cec: < tblMailbox::LocateBreakoutNumber()
03/05 12:02:57.250 vmprov5s (1d,8) ba4, cec: > tblBreakoutNumbers::SaveRecord(Mailbox: {7424fec2-d755-4591-8ac2-32ec54a2325b}, ID:{00000000-0000-0000-0000-000000000000})
03/05 12:02:57.251 vmprov5s (1d,8) ba4, cec: < tblBreakoutNumbers::SaveRecord()
03/05 12:02:57.251 vmprov5s (06,8) ba4, cec: < VMailBox::SynchBreakoutNumbers()
03/05 12:02:57.251 vmprov5s (06,8) ba4, cec: < VMailBox::ResynchMailbox()
03/05 12:03:00.031 DbgOut (01,5) 2980,1428: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:03:01.553 DbgOut (01,5) 2980,1680: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:03:01.555 DbgOut (01,5) 2980,2904: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
03/05 12:03:06.607 vmprov5s (06,8) ba4, b90: SOCKSTask::Main(VMServer [SOCKSTask] port 50791): Received request " VMMESSAGE_POLL Session=00000000, Seq=0" from 192.168.54.2:4098
03/05 12:03:06.607 vmprov5s (06,8) ba4, 5b0: [TCPServerThread[0]]: Processing server[192.168.54.2],m_User->Identifier()[192.168.54.2],PBXCount=[1

 

[tlpeter]

This is too much but what i see is queued email.
Are you sure that the email server is not bouncing the voicemails?


BAZINGA!

I'm not insane, my mother had me tested!

 

[driven0]

Unfortunately I don't have access to the exchange server, so I will have to contact the person who does. We will check that out and I'll report back.

Thanks again.

 

[teletechman]

Is the user set to forward or copy? on the email tab. If forward a message is never left in the MB.
Mike

 

[driven0]

It's set on forward, but so is everyone else at this location and they all receive Message notifications and email notifications.

 

[driven0]

Changed to Copy and received all the message on my phone, but they are still not coming to my email. I don't quite understand what would have happened, I just deleted the user folder, it shouldn't have had any effect on exchange.

I tested with another user in the office, They are set on "forward" I called, they received the phone notification and the email.

 

[teletechman]

do you use UMS which syncronizes with the exchange server, maybe with the link broken it is not working like it was. Work on getting the link up and it should work again.
Mike

 

[driven0]

We do use UMS to sync with exchange... here's what I found..

"If Exchange server is restarted you have to restart the voip servers for UC voicemail to come back online"

So I'm wondering if because I restarted the Voicemail Pro service, I will need to restart the exchange service as well (and then of course the server after). We are testing shortly and I will report back.

 

[tlpeter]

That would be nasty.
Lucky that we do not sell that UMS crap at all.


BAZINGA!

I'm not insane, my mother had me tested!

 

[driven0]

Sorry for the late update.

The UC is setup for the main server, and we are the backup. For some reason it took almost a full day to sync and go back to forwarding emails/voicemails.

We didn't make any other changes, I'm not sure if it's common (time it takes to sync) but it's working again.

Thank you to everyone who contributed, I will be hitting the "like" button a lot