Hello,
I have a problem when collecting DTMF digits in an IVR application. It seems that I cannot reproduce the problem all the time but I get a lot of complaints. I do not believe that it has something to do with the IVR because I have developed other applications that work just fine. I am attaching the part of the MCP lof gile. Do you have any idea what is happening? My users are in contact centers and are typing really fast (which is believed to be the issue). In the first input block the input is supposed to be '12345' but the IVR collects '11233' as you can see..
[code]
http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox
2013-10-16 13:41:24.242 DBUG 00D10125-1002903B 3960 0C000000 VXMLFetchManager.cxx:195 Using FM:CreateRequest as streaming is 0
2013-10-16 13:41:24.242 DBUG 00D10125-1002903B 3960 0C000500 VXMLFetchManager.cxx:313 Fetching document, uri: http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox, nMaxage: -1, nMaxstale: 0, enctype: (null), post: false, timeout: 30000
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 2436 0C000000 VXMLFetchManager.cxx:86 VXMLFetchHelper_FetchDone entered
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 2436 0C000000 VXMLFetchManager.cxx:362 FetchDocDone() FSTATE_FETCHED or FSTATE_MODIFIED
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 2436 0C000000 VXMLFetchManager.cxx:380 FetchDocDone() calling ProcessDocComplete()
2013-10-16T13:41:24.254 Trc 20001 INFO 00D10125-1002903B 3960 02B6871A Fetching Done|15ms|audio|http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox
2013-10-16T13:41:24.254 Int 50043 00D10125-1002903B 3960 fetch_end Done (revalidated):http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:476 HandleFetchDone() entered
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:661 HandleFetchDone() Play fetched audio
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:187 GetNextPrompt() Playing 0x2a3b4e30
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:429 Using smm branch 2 to fetch content as streaming is 0
2013-10-16T13:41:24.254 Int 50134 00D10125-1002903B 3960 prompt_play audio|http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1085 PromptPlay() first:0 changed:0 asr:0 speech:0
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1107 Katie - Audio Streaming is set: 0
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.h:308 Calling AppCallLeg::MediaPlay
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3724 02F00000 MPCWrapper.cxx:1104 MediaPlayStored for session 107BDA04
2013-10-16 13:41:24.254 DBUG 00000000-00000000 3724 0B100000 MPCDialog.cxx:212 MPCDialog::PlayStart, LogID=00D10125-1002903B DialogID=207BDA05
2013-10-16 13:41:24.254 DBUG 00D10125-1002903B 3724 0B200000 MPCPlayer.cxx:320 MPCPlayer::AddToQueue: audio/video[http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox type=audio/x-dialogic-vox] visual[ type=] firstplay=0 lastplay=1
2013-10-16 13:41:24.649 DBUG 00D10125-1002903B 3652 02F00000 MPCWrapper.cxx:3266 DialogPlayNotify with Dialog ID: 544987653
2013-10-16 13:41:24.649 DBUG 00D10125-1002903B 3668 0B200000 MPCPlayer.cxx:2619 IsReady=true, m_nMaxDuration=3135, nResult=0
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3684 0B200000 MPCPlayer.cxx:2271 MPCPlayer::NotifyPlayDone: m_nStopReason=4 m_nState=7 m_nFlushDur=0 m_nLocalBargeinOffset=0; Last prompt info: Orig URI [http://10.2.3.232/IVR_Egkriseis/Resources/Prompts/el-GR/InpCode.vox]; Media URI [smm://003A1FB1]
2013-10-16T13:41:28.270 Trc 21004 INFO 00D10125-1002903B 3684 02B00FAD prompt_stop
2013-10-16 13:41:28.270 DBUG 00000000-00000000 3652 0B100000 MPCDialog.cxx:1405 MPCDialog::ProcessPlayDone, LogID=00D10125-1002903B DialogID=207BDA05 nPlaydoneReason=4 nBargeinLocalDur=0 nMarkDur=0
2013-10-16 13:41:28.270 DBUG 00000000-00000000 3652 0B100000 MPCDialog.cxx:1410 MPCDialog::ProcessPlayDone, LogID=00D10125-1002903B DialogID=207BDA05 nMarkIndex=-1 nTotalPlayedBytes=52448 nTotalPlayedDur=6556
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3652 02F00000 MPCWrapper.cxx:3391 DialogPlayDone DialogID 207BDA05 nPlayDoneReason 4nBargeinLocalDur 0 nMarkDur 0 nMarkIndex -1
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3652 02F00000 MPCWrapper.cxx:3395 DialogPlayDone DialogID 207BDA05 nTotalPlayedBytes 52448 nTotalPlayedDur 6556
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_MEDIAPLAYDONE - CallLegID=1002903B DialogID=207BDA05,ReqID=2,BargeinDur=-1,TotalPlayedBytes=52448,TotalPlayedDur=6556TotalMediaPlayed=0,Result=1,ErrCode=0,MarkTag=,Encoding=
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1157 PromptDone() for MediaPlayDoneEvent
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:152 StepThroughPromptList() Stepping Through PromptList
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1249 PromptDone() PlayDone:Completed
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1282 PromptDone() result 1, state 1, bargein 0
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1339 PromptDone() state 1
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 VXMLFetchObject.cxx:37 ~VXMLFetchObject() Delete VXMLFetchObject 0x206313c0
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 VXMLFetchObject.cxx:37 ~VXMLFetchObject() Delete VXMLFetchObject 0x20631b40
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:4860 VXMLCallLeg::AddNoInputTimer: bDefer=false
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:4899 Add timer for 5000ms
2013-10-16T13:41:28.270 Int 50019 00D10125-1002903B 3960 prompt_end done
2013-10-16 13:41:28.270 DBUG 00D10125-1002903B 3960 0C000000 PromptSession.cxx:1485 PromptDone() DTMFRecoState: 2
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=0, LastProcessedDTMF=false, LastDTMF=18, LastRxTime=4294967295, PacketTimeIndex=3532186975
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3724 02800000 LMCallSIP.C:2243 LMCallSIP::MediaDTMFInput cDTMFDigit 1
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_INBOUNDDTMF - CallLegID=1002903B DialogID=207BDA05,Len=1,DTMF=1
2013-10-16T13:41:28.926 Int 50027 00D10125-1002903B 3960 dtmf :1
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5374 Delete noinput timeout timer.
2013-10-16T13:41:28.926 Trc 21002 INFO 00D10125-1002903B 3960 02B00FA1 dtmf_input 1
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:3837 CallLeg: DTMF 144996 1
2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3960 03A00000 VGDTMFRecognitionThread.C:494 ProcessInput(): Session ID: 144996, Input 1 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:703 HandleDTMFInput(): Session ID: 144996. 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:757 HandleDTMFInput(): Removing noinput timer. Session ID: 144996. 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:902 ProcessInput(): SessionID 388918 with input: 1 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:545 ProcessAllGrammars(): ProcessInput returned PARTIAL_MATCH for grammar 2c5c56b5a1b104aabb010ceb7ebd7758 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:561 ProcessAllGrammars(): ProcessInput returned MATCH (with optional allowed) for grammar 2c5c56b5a1b104aabb010ceb7ebd7758 after pushing end-of-input 2013-10-16 13:41:28.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:807 HandleNextTimerEvent(): Next timeout 3000
2013-10-16 13:41:28.946 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532186975, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532186975, PacketTimeIndex=3532186975
2013-10-16 13:41:28.969 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532186975, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532186975, PacketTimeIndex=3532186975
2013-10-16 13:41:28.989 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532186975, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532186975, PacketTimeIndex=3532186975
2013-10-16 13:41:29.008 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532186975, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532186975, PacketTimeIndex=3532186975
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532186975, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532186975, PacketTimeIndex=3532187275
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3724 02800000 LMCallSIP.C:2243 LMCallSIP::MediaDTMFInput cDTMFDigit 1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_INBOUNDDTMF - CallLegID=1002903B DialogID=207BDA05,Len=1,DTMF=1
2013-10-16T13:41:29.227 Int 50027 00D10125-1002903B 3960 dtmf :1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16T13:41:29.227 Trc 21002 INFO 00D10125-1002903B 3960 02B00FA1 dtmf_input 1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:3837 CallLeg: DTMF 144996 1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3960 03A00000 VGDTMFRecognitionThread.C:494 ProcessInput(): Session ID: 144996, Input 1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:703 HandleDTMFInput(): Session ID: 144996.
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:757 HandleDTMFInput(): Removing noinput timer. Session ID: 144996.
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:902 ProcessInput(): SessionID 388918 with input: 1
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:545 ProcessAllGrammars(): ProcessInput returned PARTIAL_MATCH for grammar 2c5c56b5a1b104aabb010ceb7ebd7758
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:561 ProcessAllGrammars(): ProcessInput returned MATCH (with optional allowed) for grammar 2c5c56b5a1b104aabb010ceb7ebd7758 after pushing end-of-input
2013-10-16 13:41:29.227 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:807 HandleNextTimerEvent(): Next timeout 3000
2013-10-16 13:41:29.246 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.266 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.285 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.309 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.328 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.348 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.367 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.387 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187275
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187275, LastProcessedDTMF=true, LastDTMF=1, LastRxTime=3532187275, PacketTimeIndex=3532187969
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3724 02800000 LMCallSIP.C:2243 LMCallSIP::MediaDTMFInput cDTMFDigit 2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_INBOUNDDTMF - CallLegID=1002903B DialogID=207BDA05,Len=1,DTMF=2
2013-10-16T13:41:29.926 Int 50027 00D10125-1002903B 3960 dtmf :2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16T13:41:29.926 Trc 21002 INFO 00D10125-1002903B 3960 02B00FA1 dtmf_input 2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:3837 CallLeg: DTMF 144996 2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3960 03A00000 VGDTMFRecognitionThread.C:494 ProcessInput(): Session ID: 144996, Input 2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:703 HandleDTMFInput(): Session ID: 144996.
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:757 HandleDTMFInput(): Removing noinput timer. Session ID: 144996.
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:902 ProcessInput(): SessionID 388918 with input: 2
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:545 ProcessAllGrammars(): ProcessInput returned PARTIAL_MATCH for grammar 2c5c56b5a1b104aabb010ceb7ebd7758
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:561 ProcessAllGrammars(): ProcessInput returned MATCH (with optional allowed) for grammar 2c5c56b5a1b104aabb010ceb7ebd7758 after pushing end-of-input
2013-10-16 13:41:29.926 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:807 HandleNextTimerEvent(): Next timeout 3000
2013-10-16 13:41:29.946 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:29.969 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:29.989 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:30.008 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:30.028 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:30.047 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:30.067 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532187969
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532187969, LastProcessedDTMF=true, LastDTMF=2, LastRxTime=3532187969, PacketTimeIndex=3532188419
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3724 02800000 LMCallSIP.C:2243 LMCallSIP::MediaDTMFInput cDTMFDigit 3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_INBOUNDDTMF - CallLegID=1002903B DialogID=207BDA05,Len=1,DTMF=3
2013-10-16T13:41:30.367 Int 50027 00D10125-1002903B 3960 dtmf :3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16T13:41:30.367 Trc 21002 INFO 00D10125-1002903B 3960 02B00FA1 dtmf_input 3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:3837 CallLeg: DTMF 144996 3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3960 03A00000 VGDTMFRecognitionThread.C:494 ProcessInput(): Session ID: 144996, Input 3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:703 HandleDTMFInput(): Session ID: 144996.
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:757 HandleDTMFInput(): Removing noinput timer. Session ID: 144996.
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:902 ProcessInput(): SessionID 388918 with input: 3
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:545 ProcessAllGrammars(): ProcessInput returned PARTIAL_MATCH for grammar 2c5c56b5a1b104aabb010ceb7ebd7758
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:561 ProcessAllGrammars(): ProcessInput returned MATCH (with optional allowed) for grammar 2c5c56b5a1b104aabb010ceb7ebd7758 after pushing end-of-input
2013-10-16 13:41:30.367 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:807 HandleNextTimerEvent(): Next timeout 3000
2013-10-16 13:41:30.387 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532188419, LastProcessedDTMF=true, LastDTMF=3, LastRxTime=3532188419, PacketTimeIndex=3532188419
2013-10-16 13:41:30.407 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532188419, LastProcessedDTMF=true, LastDTMF=3, LastRxTime=3532188419, PacketTimeIndex=3532188419
2013-10-16 13:41:30.426 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532188419, LastProcessedDTMF=true, LastDTMF=3, LastRxTime=3532188419, PacketTimeIndex=3532188419
2013-10-16 13:41:30.446 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532188419, LastProcessedDTMF=true, LastDTMF=3, LastRxTime=3532188419, PacketTimeIndex=3532188419
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3640 0B200000 MPCDTMFDispatcher.cxx:99 MPCDTMFDispatcher::MediaTransmit - LastDTMFRxTimeIndex=3532188419, LastProcessedDTMF=true, LastDTMF=3, LastRxTime=3532188419, PacketTimeIndex=3532188689
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3724 02800000 LMCallSIP.C:2243 LMCallSIP::MediaDTMFInput cDTMFDigit 3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3772 01C00000 CMDialog.C:94 CMDialog::CanDelete - m_nDialogID=207BDA05, m_bDestroying=false, m_pMediaState=true
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 AppDialogEvent.h:66 app.VXML-NG AMEVENT_DIALOG_INBOUNDDTMF - CallLegID=1002903B DialogID=207BDA05,Len=1,DTMF=3
2013-10-16T13:41:30.649 Int 50027 00D10125-1002903B 3960 dtmf :3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16T13:41:30.649 Trc 21002 INFO 00D10125-1002903B 3960 02B00FA1 dtmf_input 3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:3837 CallLeg: DTMF 144996 3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 03A00000 VGDTMFRecognitionThread.C:494 ProcessInput(): Session ID: 144996, Input 3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:703 HandleDTMFInput(): Session ID: 144996.
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:757 HandleDTMFInput(): Removing noinput timer. Session ID: 144996.
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:902 ProcessInput(): SessionID 388918 with input: 3
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionSessionMgr.C:485 ProcessAllGrammars(): ProcessInput returned MATCH for grammar 2c5c56b5a1b104aabb010ceb7ebd7758
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:2505 NotifyRecognitionComplete(): Session ID: 144996. Completion Result: 0, GramID: 2c5c56b5a1b104aabb010ceb7ebd7758, SI Result: (null), ConsumedDigits: 0
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 0C000000 VXMLCallLeg.cxx:4966 VXMLCallLeg::HandleRecognitionComplete called
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3964 03A00000 VGDTMFRecognitionThread.C:807 HandleNextTimerEvent(): Next timeout 0
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:4980 VXMLCallLeg::HandleDTMFRecoDone called. Cause: 0
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:5371 Entered delete noinput timeout timer.
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:2819 VXMLCallLeg::HandlePromptDone: nStatus = 0
2013-10-16 13:41:30.649 DBUG 00D10125-1002903B 3960 0C000000 VXMLCallLeg.cxx:2919 VXMLCallLeg::HandlePlayAndCollectDone
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:1889 ProcessEvents() Started
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:1906 ProcessEvents() Event type [6], session id: [00D10125-1002903B]
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:1955 ProcessEvents(): Play and Collect Done
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:2469 HandlePlayAndCollectDoneEvent() Started
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 PlayAndCollectDoneEvent.cxx:65 Initialize() Recognition result: <?xml version='1.0'?><result><interpretation confidence="100" grammar="session:0x00546c62"><instance>11233</instance><input mode="dtmf">11233</input></interpretation></result>
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:2928 PopulateApplicationLastResult(VXMLPromptDoneInfo) Filling in application.lastresult$
2013-10-16 13:41:30.653 DBUG 00D10125-1002903B 3936 0C000000 VXMLSession.cxx:2970 PopulateApplicationLastResult(VXMLRecoResult) Filling in application.lastresult$
[/code]
Sorry for the long code...