HI all,
I try to use 2 application to get logs:
1. use debug view. log detail for debug view. here log when simulate incomming call with destination to IVR and press 1
13/06 11:50:07.067 DbgOut (01,5) 2664,7992: DllMain(hinstDLL=0x10290000, fdwReason=DLL_THREAD_ATTACH)
13/06 11:50:23.499 vmprov5s (06,3) abc, dfc: VMServer:

rocessReceiveBuffer: Received new sequenced call request for session 0000006c (received from 192.168.0.240:4098)
13/06 11:50:23.500 vmprov5s (09,5) abc, dfc: new VMAIL Client [02FB2228] [Connections 0 - 1]
13/06 11:50:23.508 vmprov5s (09,3) abc,19d0: Session: 0000006c - Locale zhi does not exist, using alternative locale of enu
13/06 11:50:23.508 vmprov5s (23,5) abc,19d0: Succeeded in loading cached sound bite "C:\Program Files\Avaya\IP Office\Voicemail Pro\VM\Wavs\AM Rama1.WAV" [uLaw] fro cache
13/06 11:50:23.510 vmprov5s (06,5) abc,19d0: VMClient::RxOpen - Created dialog 02FB30B0
13/06 11:50:23.510 vmprov5s (06,5) abc,19d0: VMClient::RxOpen 02FB21C8 Welcome IVR (4), session=0000006c, dialog=02FB30B0, Fallback number is ""
13/06 11:50:24.502 DbgOut (01,5) 9792,9848: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
13/06 11:50:24.502 DbgOut (01,5) 9792,8668: DllMain(hinstDLL=0x10000000, fdwReason=DLL_THREAD_DETACH)
13/06 11:50:28.794 DbgOut (01,5) 2664,8640: DllMain(hinstDLL=0x10290000, fdwReason=DLL_THREAD_DETACH)
13/06 11:50:28.795 DbgOut (01,5) 2664,7332: DllMain(hinstDLL=0x10290000, fdwReason=DLL_THREAD_DETACH)
13/06 11:50:28.795 DbgOut (01,5) 2664,4960: DllMain(hinstDLL=0x10290000, fdwReason=DLL_THREAD_DETACH)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34:
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: ======================================================================
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: IP Office Voicemail Pro [Hardware mode 00] - Version 11.0.4.3.0 build 1 [CPU Usage 0.0] [CPU Min:0.0, Avg:0.5, Max:104.1] has been running for 13 days, 20 hours and 12 minutes.
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Call information: Received 1160, Made 0, 4 missed voice packets (1159 calls completed, 3 calls/hour) (Call duration(secs) - Min:0, Max:195, Avg:30).
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: IntuityMode is set to true
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: ITZSupport is set to true
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Threads (63 threads), worker threads (63 inuse, 1 free), voicemail tasks (7 tasks)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Voicemail Sessions (Connections 1 - 1) (Links:11623 from 12000) (CMMsg:1 left from 1) (VM:1 NODES:1 SN:1 SCO:22 SBL:1 FILES:2)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Predictive Call objects:- CIPOSessionManager[0], CIVRSession[0], CRecordingSession[0], CCPAPlayerData[0], CPromptPlayer[0], CPBXConnector[0], CPromptHelper[0], CRecordData[0], CRecordHelper[0]
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Allocated buffers (5 buffers, 0 sequenced) (Buffer pool(0:1000 1:250 2:2500 3:1495)) cached prompts (0 ALAW, 22 ULAW)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Mailboxes (138 mailboxes, 3 messages, 2 file objects)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: TCP Connections (1 clients)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Outstanding Emails (0 Emails, 0 VPNM and 0 SMTP)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: SYNC Status: [0 outstanding tasks, 0 cached objects, 0 allocated objects]
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: PBX Configuration:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Voicemail Pro mode, locale: zhi, IPO hardware mode: 00 []
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: EVML:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Maximum recording capacity: Unlimited, Maximum Recording Time: 120 seconds
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Maximum Sessions: 6, Minimum PIN length: 4 digits
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: SMTP:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Host address 192.168.0.1, port 25, Login method "none", login user ""
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: VoiceMail performance information:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Memory statistics:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Physical Memory: 63% in use, 3145268 KB (Available: 1133460 KB)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Commit Charge Limit: 3669556 KB, (Available to commit: 1392808 KB)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Virtual memory: 2097024 KB, (Available: 1922848 KB, Extended available: 0 KB)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Process:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: (Virtual) Private Bytes: 16500 KB (Peak 19892 KB), Page Faults 259596
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: (Physical) Working set: 9236 KB (peak 28644 KB), Paged pool usage: 162 KB (peak 177 KB), Non-Paged pool usage: 20 KB (peak 109 KB)
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Handles:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Number of open handles: 561, GDI Handles: 0, USER Handles: 0
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: IO:-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Reads: 5784, Writes: 5573, Other: 105331165
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Reads: 10944139 bytes, Writes: 11307076 bytes, Other: 1412883929 bytes
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Run time Alarms information
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: Alarms AutoDial List (0 alarms):-
13/06 11:50:35.085 vmprov5s (09,5) abc, f34: ======================================================================
2. use Avaya Monitor, here log when simulate incomming call with destination to IVR and press 1
11:50:09 3687893mS H323Evt: Recv: RegistrationRequest 192.168.110.46; Extn(9133), time since last RRQ-KA 78005, Endpoints registered: 83; Endpoints in registration: 0
11:50:10 3688973mS H323Evt: Recv: RegistrationRequest 192.168.110.8; Extn(9536), time since last RRQ-KA 78008, Endpoints registered: 83; Endpoints in registration: 0
11:50:10 3688988mS H323Evt: Recv: RegistrationRequest 192.168.100.47; Extn(9143), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:11 3689934mS H323Evt: Recv: RegistrationRequest 192.168.110.15; Extn(9140), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:12 3690838mS H323Evt: Recv: RegistrationRequest 192.168.110.45; Extn(9142), time since last RRQ-KA 78004, Endpoints registered: 83; Endpoints in registration: 0
11:50:14 3692966mS H323Evt: Recv: RegistrationRequest 192.168.110.26; Extn(9132), time since last RRQ-KA 78007, Endpoints registered: 83; Endpoints in registration: 0
11:50:15 3693592mS CMCallEvt: 0000000000000000 0.1056.0 -1 BaseEP: NEW CMEndpoint f1760974 TOTAL NOW=1 CALL_LIST=0
11:50:15 3693592mS CMCallEvt: CREATE CALL:18 (f1751df4)
11:50:15 3693593mS CMCallEvt: 0000000000000000 0.1057.0 -1 BaseEP: NEW CMEndpoint f176b108 TOTAL NOW=2 CALL_LIST=0
11:50:15 3693595mS CMLineRx: v=105
CMSetup
Line: type=AnalogueLine 105 Call: lid=105 id=8 in=1
Called[] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=8 chan=7
IE CMIEDeviceDetail (231) c0a800f000000420 LOCALE=zhi HW=15 VER=11 class=CMDeviceAlogTrunk type=0 number=105 channel=0 features=0x1 rx_gain=32 tx_gain=32
ep_callid=1056 ipaddr=192.168.0.240 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
11:50:15 3693595mS CD: CALL: 105.8.1 BState=Idle Cut=1 Music=0.0 Aend="Line 105" (8.7) Bend="" [] (0.0) CalledNum= () CallingNum= () Internal=0 Time=3 AState=Idle
11:50:15 3693595mS CMCallEvt: c0a800f000000420 105.8.1 18 Alog Trunk:105: StateChange: END=A CMCSIdle->CMCSDialInitiated
11:50:15 3693596mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: LOOKUP CALL ROUTE: GID=13 type=0 called_party= sub= calling= calling_sub= dir=in complete=1 ses=0
11:50:15 3693596mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: SET BESTMATCH: length 0 vs -1 match= dest=VM:"Welcome IVR"
11:50:15 3693596mS CMCallEvt: Priority hike: call 18 priority 0->3
11:50:15 3693597mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: LOOKUP ICR: DDI=5320320 CGPN= (Destination VM:"Welcome IVR" ) => CDPN=VM:Welcome IVR
11:50:15 3693597mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105:
ADD TARGET (N): number=VM:Welcome IVR type=0 depth=1 nobar=1 setorig=1 ses=0
11:50:15 3693597mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: ADD VM TARGET
11:50:15 3693597mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105:
MakeVoicemailTarget pbx=<null> local=1 type=1
11:50:15 3693598mS CMCallEvt: 0000000000000000 0.1058.0 -1 BaseEP: NEW CMEndpoint f1755334 TOTAL NOW=3 CALL_LIST=1
11:50:15 3693598mS CMTARGET: c0a800f000000422 0.1058.0 18 RAS.0: ADD PRIMARY
11:50:15 3693598mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: ADD VM TARGET: SUCCEEDED
11:50:15 3693599mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: INITIAL TARGETING SUCCEEDED
11:50:15 3693599mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: GetNoAnswerTimer:12
11:50:15 3693599mS CMCallEvt: c0a800f000000420 105.8.1 18 Alog Trunk:105: StateChange: END=A CMCSDialInitiated->CMCSDialled
11:50:15 3693600mS CMLineTx: v=105
CMProceeding
Line: type=AnalogueLine 105 Call: lid=105 id=8 in=1
11:50:15 3693600mS CMCallEvt: 0000000000000000 0.1057.0 18 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
11:50:15 3693600mS CMCallEvt: c0a800f000000422 0.1058.0 18 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
11:50:15 3693601mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1058 )
11:50:15 3693601mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[Welcome IVR] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=11
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
IE CMIEDeviceDetail (231) c0a800f000000420 LOCALE=zhi HW=15 VER=11 class=CMDeviceAlogTrunk type=0 number=105 channel=0 features=0x21 rx_gain=32 tx_gain=32
ep_callid=1056 ipaddr=192.168.0.240 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIEIcrPriorityDetail (239) Priority = 3
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=5320320
IE CMIEMohSourceId (247) MOH Source = 1
Display [??>Welcome IVR]
Tag type=Text flags=0x1 [Line CO 13.] [0x4c 0x69 0x6e 0x65 0x20 0x43 0x4f 0x20 0x31 0x33 0x00 ]
Timed: 13/06/20 11:50
Locale: zhi
11:50:15 3693601mS CMMap: a=21.11 b=1.65534 T
11:50:15 3693603mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=Welcome IVR
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Welcome IVR
IE CMIEDeviceDetail (231) c0a800f000000422 LOCALE=zhi HW=15 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1058 ipaddr=192.168.0.240 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
Tag type=Text flags=0x1 [Line CO 13.] [0x4c 0x69 0x6e 0x65 0x20 0x43 0x4f 0x20 0x31 0x33 0x00 ]
11:50:15 3693603mS CMCallEvt: c0a800f000000422 0.1058.0 18 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
11:50:15 3693603mS CMCallEvt: 0000000000000000 0.1057.0 18 TargetingEP: RequestEnd c0a800f000000422 0.1058.0 18 RAS.0
11:50:15 3693603mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: CancelTimer CMTCNoAnswerTimeout
11:50:15 3693604mS CMExtnEvt: v=1012 State, new=Connected old=Idle,0,0,RAS
11:50:15 3693604mS CMCallEvt: c0a800f000000420 105.8.1 18 Alog Trunk:105: StateChange: END=A CMCSDialled->CMCSOGConnReq
11:50:15 3693605mS CMLineTx: v=105
CMConnect
Line: type=AnalogueLine 105 Call: lid=105 id=8 in=1
BChan: slot=21 chan=11
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=Welcome IVR
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Welcome IVR
IE CMIEDeviceDetail (231) c0a800f000000422 LOCALE=zhi HW=15 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1058 ipaddr=192.168.0.240 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
Tag type=Text flags=0x1 [Line CO 13.] [0x4c 0x69 0x6e 0x65 0x20 0x43 0x4f 0x20 0x31 0x33 0x00 ]
11:50:15 3693605mS CMLineRx: v=105
CMConnectAck
Line: type=AnalogueLine 105 Call: lid=105 id=8 in=1
BChan: slot=8 chan=7
11:50:15 3693605mS CMCallEvt: c0a800f000000420 105.8.1 18 Alog Trunk:105: StateChange: END=A CMCSOGConnReq->CMCSConnected
11:50:15 3693605mS CMTARGET: c0a800f000000420 105.8.1 18 Alog Trunk:105: ~CMTargetHandler f1764b6c ep f1760974
11:50:15 3693606mS CMCallEvt: c0a800f000000422 0.1058.0 18 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
11:50:15 3693606mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
11:50:15 3693607mS CMCallEvt: 0000000000000000 0.1057.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
11:50:15 3693607mS CMCallEvt: 0000000000000000 0.1057.0 -1 BaseEP: DELETE CMEndpoint f176b108 TOTAL NOW=2 CALL_LIST=1
11:50:15 3693608mS CD: CALL: 105.8.1 BState=Connected Cut=3 Music=0.0 Aend="Line 105" (8.7) Bend="Welcome IVR(Welcome IVR)" [VoiceMail] (21.11) CalledNum=Welcome IVR () CallingNum= () Internal=0 Time=15 AState=Connected
11:50:15 3693609mS CMMap: PCG::MapBChan pcp[94]b1r0 cp_b f5311c90 other_cp_b 0 type CGTypeSimple
11:50:15 3693609mS CMMap: PCG::MapBChan pcp[33]b1r0 cp_b f5360e38 other_cp_b f5311c90 type CGTypeSimple
11:50:15 3693609mS CMMap: a=8.7 b=21.11 M12
11:50:15 3693766mS CMLineRx: v=105
CMConnectAck
Line: type=AnalogueLine 105 Call: lid=105 id=8 in=1
BChan: slot=8 chan=7
IE CMIERespondingPartyNumber (230)(P:2 S:100 T:0 N:0 R:4) number=
IE CMIEDeviceDetail (231) c0a800f000000420 LOCALE=zhi HW=15 VER=11 class=CMDeviceAlogTrunk type=0 number=105 channel=0 features=0x21 rx_gain=32 tx_gain=32
ep_callid=1056 ipaddr=192.168.0.240 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
11:50:15 3693884mS H323Evt: Recv: RegistrationRequest 192.168.110.60; Extn(9100), time since last RRQ-KA 78005, Endpoints registered: 83; Endpoints in registration: 0
11:50:15 3693951mS RES: Sat 13/6/2020 11:50:15 FreeMem=55516924 Heap=55192712(1) Cache=324212 MemObjs=31312(Max 31876) CMMsg=6(8) ASN=0 Buff=5200 1364 1000 7444 5 Links=77164(77373) BTree=2058(4350) CB=6064 MCT=0 CPU=08.41% CPUStats=06.72%/1/3/1490/19
928/22091/00.04%/0/02.62% MCR=0 MCW=0 DEV=0
11:50:15 3693952mS RES2: IP 500 V2 11.0.0.0.0 build 849 Tasks=56 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=12+150 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=106(TLS=6 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
11:50:15 3693952mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
11:50:15 3693952mS RES5: CLog MemObjs=2291 FreePoolMem(Objs)=5668(109) TotalMem=124800 StringsTotalMem=214200
11:50:16 3694993mS H323Evt: Recv: RegistrationRequest 192.168.110.39; Extn(9137), time since last RRQ-KA 78007, Endpoints registered: 83; Endpoints in registration: 0
11:50:17 3695906mS H323Evt: Recv: RegistrationRequest 192.168.110.64; Extn(9109), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:19 3697960mS H323Evt: Recv: RegistrationRequest 192.168.110.36; Extn(9546), time since last RRQ-KA 78011, Endpoints registered: 83; Endpoints in registration: 0
********** SysMonitor v11.0.4.3.0 build 3 [connected to 192.168.0.240 (00E007058193)] **********
11:50:20 3698541mS PRN: Monitor Status IP 500 V2 11.0.0.0.0 build 849
11:50:20 3698542mS PRN: LAW=U PRI=0, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=1(TOT=18)
11:50:20 3698927mS H323Evt: Recv: RegistrationRequest 192.168.110.23; Extn(9110), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:20 3698956mS H323Evt: Recv: RegistrationRequest 192.168.110.42; Extn(9112), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:21 3699947mS H323Evt: Recv: RegistrationRequest 192.168.110.20; Extn(73738), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:23 3701011mS H323Evt: Recv: RegistrationRequest 192.168.110.5; Extn(9108), time since last RRQ-KA 78007, Endpoints registered: 83; Endpoints in registration: 0
11:50:23 3701966mS H323Evt: Recv: RegistrationRequest 192.168.110.28; Extn(9535), time since last RRQ-KA 78007, Endpoints registered: 83; Endpoints in registration: 0
11:50:25 3703008mS H323Evt: Recv: RegistrationRequest 192.168.110.55; Extn(9511), time since last RRQ-KA 78007, Endpoints registered: 83; Endpoints in registration: 0
11:50:26 3704042mS H323Evt: Recv: RegistrationRequest 192.168.110.56; Extn(9410), time since last RRQ-KA 78006, Endpoints registered: 83; Endpoints in registration: 0
11:50:27 3705060mS H323Evt: Recv: RegistrationRequest 192.168.110.43; Extn(9336), time since last RRQ-KA 78008, Endpoints registered: 83; Endpoints in registration: 0