dbgview shows what is happening:
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Configuring for reliable disconnect, IDLE time is 300.000s
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Receive OPEN "" for session 000095f0, call-id 42742, mailbox <vrlauto>, access 4 (VMClient:025A5EB0)
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Access = ACCESS_UNCLUED_VOICEMAIL:
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Mailbox: vrlauto
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Calling Party: 5556667777
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Display String: 5556667777
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Target Party: 7726
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Greeting Modifier: MainGroup
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Language: enu
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Call Ident: 42742
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Call Status: Unknown (0)
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Call Type: External
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Call Direction: Incoming
22/05 11:18:48.734 DbgOut (01,5) 3720,3768: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_ATTACH)
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Called Party: 5707
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - DDI Number: 5798
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Calling Party Name: 5556667777
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Called Party Name: Patrick Gould
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Caller Party Offset: 0
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Target Party Offset: 0
22/05 11:18:48.734 vmprov5s (09,6) e88, 29c: Session: 000095f0 - Called Party Offset: 0
22/05 11:18:48.734 vmprov5s (0c,9) e88, 29c: SessionManager::RealMailBox(pbx=<02491020>, name=<(null)>, cli=<5556667777>)
22/05 11:18:48.734 vmprov5s (06,9) e88, 29c: VMailBox::FindMailBoxByCLI - pbx=<02491020>, cli=<5556667777> returning 00000000
22/05 11:18:48.734 vmprov5s (06,9) e88, 29c: VMailBox::FindMailBox - pbx=<02491020>, name=<5556667777>, flags=<BYEXT | BYINCDISABLED> - Mailbox not found
22/05 11:18:48.734 vmprov5s (0c,8) e88, 29c: Standard=00000000 vrlauto
22/05 11:18:48.734 vmprov5s (02,8) e88, 29c: > VoiceMailDialog::VoiceMailDialog(075B0490, client=025A5F18)
22/05 11:18:48.734 vmprov5s (07,8) e88, 29c: > IntuityBase::Begin(075B0490 00000004)
22/05 11:18:48.734 vmprov5s (24,8) e88, 29c: > SoundBiteList::AddAnnouncement(075B145C 815 (0 0 0 0 0 0 0 0 0 0) 00000000 (null) 00000000 true false)
22/05 11:18:48.734 vmprov5s (0c,8) e88, 29c: > SoundsManager::AddAnnouncement(815 02A4DB08 enu 02A4EB90[32] true false)
22/05 11:18:48.734 vmprov5s (24,5) e88, 29c: Successfully determined fragments for announcement 815 using language enu, fragments are "287,97"
22/05 11:18:48.734 vmprov5s (0c,8) e88, 29c: < SoundsManager::AddAnnouncement()
22/05 11:18:48.734 vmprov5s (23,5) e88, 29c: SoundsManager::ConvertWAV(0258B8F8, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\287.WAV" [uLaw], 075B0490) - Took 0 ms to process, return is 26240
22/05 11:18:48.734 vmprov5s (23,5) e88, 29c: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\287.WAV" [uLaw]
22/05 11:18:48.734 vmprov5s (23,5) e88, 29c: SoundsManager::ConvertWAV(0258B8B0, "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\97.WAV" [uLaw], 075B0490) - Took 0 ms to process, return is 20480
22/05 11:18:48.734 vmprov5s (23,5) e88, 29c: Succeeded in loading sound bite "C:\Program Files (x86)\Avaya\IP Office\Voicemail Pro\VM\Wavs\enu\97.WAV" [uLaw]
22/05 11:18:48.734 vmprov5s (24,8) e88, 29c: < SoundBiteList::AddAnnouncement()
22/05 11:18:48.734 vmprov5s (07,8) e88, 29c: > IntuityBaseNode:

laySoundbiteList(0756EDC8 true true)
22/05 11:18:48.734 vmprov5s (07,8) e88, 29c: < IntuityBaseNode:

laySoundbiteList()
22/05 11:18:48.734 vmprov5s (07,8) e88, 29c: < IntuityBase::Begin()
22/05 11:18:48.734 vmprov5s (02,8) e88, 29c: < VoiceMailDialog::VoiceMailDialog()