Dialogic Inconsistancy

[kb5gqo]

We are atttempting to dial out, sometimes the script executes before the telephone has answered. When we have this early answer, the live person script runs. The phone is still ringing when our outbound message is playing. Enclosed are two traces the first worked the second failed. Same phone number minutes apart.

 

Using a dialogic d4/pciu version SR5.1.1 DNA5 build 30 Service pack 1 with the pciu update.

 

Voice guide version 6.0.2277

 

Can anyone help?

 

Successful outbound dial trace

 

094441.92 0 Loading from C:\Program Files\VoiceGuide\data\OutDial_New.xml

094441.92 0 dial callque insert tel:2318860 scr:c:\Our Software\test\HumanMessage.vgs time:510120944 esc:ErrorError

094441.94 0 dial DialListParseInputXML no <PhoneNumber>

094441.94 0 Loaded 1 entries.

094442.94 1 dial [196,2318860,510120944,0,2359,MoTuWeThFrSaSu,[,dxxxB1C1,],1,,c:\Our Software\test\HumanMessage.vgs,c:\Our Software\test\AMMessage.vgs,[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[

ppointmentDateTime]{10011030},AllowTransfer]{Yes},240,2,1,ErrorError,ErrorError] (search started at 094442.94)

094442.95 1 dial DialOnLine start tel[2318860] ann[] vgs[c:\Our Software\test\HumanMessage.vgs] am[c:\Our Software\test\AMMessage.vgs] rv[[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[AppointmentDateT

me]{10011030},AllowTransfer]{Yes}]

094442.95 0 dial callque update id=196: NextCallTime=0510120946, RetriesLeft=1

094442.97 0 dial callque DB update returned (MSAccess)

094442.97 1 rv clear all

094442.98 1 rv add [DrName]{Williams}

094442.98 1 rv add [Clinic]{5012551425}

094443.00 1 rv add [PatientName]{John}

094443.02 1 rv add [AppointmentDateTime]{10011030}

094443.02 1 rv add [OutDial_RetriesLeft]{2}

094443.03 1 dial making call tel[2318860] ann[] vgs[c:\Our Software\test\HumanMessage.vgs] am[c:\Our Software\test\AMMessage.vgs] OnHangup[] rv[[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[AppointmentDateT

me]{10011030},AllowTransfer]{Yes}[OutDial_RetriesLeft]{2}]

094443.05 1 script interpretor: VgMulti v6.0.2277

094443.05 1 state Dialing 2318860

094443.06 1 lineMakeCall=>[]

094443.08 1 timer set 240 EV_TIMEOUT_MAKECALL_NORESPONSE

094443.61 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

094443.64 1 event ScriptEventCode TDX_SETHOOK, code=135, state=5200

094443.66 1 LsWaitAfterDialingOut : 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

094516.89 1 tw DialogicEvent 133,TDX_CALLP,10,4,0,TDX_CALLP,CR_CNCT,CON_PAMD

094516.92 1 event ScriptEventCode AM, code=133, state=5200

094516.94 1 LsWaitAfterDialingOut : 133,AM,10,4,0,CR_CNCT,CON_PAMD,

094516.95 1 event ScriptEventCode CONNECT, code=133, state=5200

094516.97 1 LsWaitAfterDialingOut : 133,CONNECT,10,4,0,CR_CNCT,CON_PAMD,

094516.98 1 event ScriptEventCode CON_PAMD, code=133, state=5200

094517.00 1 LsWaitAfterDialingOut : 133,CON_PAMD,10,4,0,CR_CNCT,CON_PAMD,

094517.02 1 event ScriptEventCode CR_CNCT, code=133, state=5200

094517.03 1 LsWaitAfterDialingOut : 133,CR_CNCT,10,4,0,CON_PAMD,,

094517.06 1 event ScriptEventCode TDX_CALLP, code=133, state=5200

094517.08 1 LsWaitAfterDialingOut : 133,TDX_CALLP,10,4,0,CR_CNCT,CON_PAMD,

094517.09 1 StartPlayingAnsweringMachineMessage hCall=518656, params=10,4,0

094517.11 0 dial callque delete id=196 (MSAccess)

094517.14 1 rvns add [OutDial_Result]{Contacted_AM}

094517.16 1 set LineState().hCall = 518656 in StartPlayingAnsweringMachineMessage

094517.17 1 state AM - wait for end of welcome message...

094517.19 1 dial awaiting end of answering machine message (lvl:10 for 20)

094517.20 1 RecSoundStart [C:\Program Files\VoiceGuide\data\RecAm_1.wav] ok

094517.23 1 timer set 120 EV_TIMEOUT_HANGUP

094517.25 1 tw DialogicEvent 134,TDX_CST,21260,0,0,DE_LCON,,

094517.27 1 event ScriptEventCode TDX_CST, code=134, state=1152

094517.28 1 LsDialoutRecAnswerMachineWelcMsg EV_UNKNOWN_134

094524.52 1 event ScriptEventCode EV_SILENCE_DETECTED, code=9300, state=1152

094524.53 1 LsDialoutRecAnswerMachineWelcMsg EV_SILENCE_DETECTED

094524.55 1 rec RecSoundStop ok

094524.56 1 rv rec length: AmWelcMsg_RecLen100ms = 74

094524.58 1 rv add [AmWelcMsg_RecLen100ms]{74}

094524.59 1 timer set 1 EV_REC_FINISHED

094524.61 1 tw DialogicEvent 130,TDX_RECORD,0,0,0,TM_MAXSIL,,

094524.63 1 event ScriptEventCode TDX_RECORD, code=130, state=1152

094524.64 1 LsDialoutRecAnswerMachineWelcMsg EV_UNKNOWN_130

094524.69 1 event ScriptEventCode EV_REC_FINISHED, code=8003, state=1152

094524.70 1 LsDialoutRecAnswerMachineWelcMsg EV_REC_FINISHED

094524.72 1 rv replace start: [$RV[AmWelcMsg_RecLen100ms]]

094524.73 1 rvns [PathSysVoice]{C:\Program Files\VoiceGuide\system\voice\}[PathApp]{C:\Program Files\VoiceGuide\}[PathDataVm]{C:\Program Files\VoiceGuide\data\}[PathVgSys]{C:\Program Files\VoiceGuide\system\}[DrName]{Williams}[Clinic]{5012551425}[PatientName]{John}[AppointmentDateT

me]{10011030}[OutDial_RetriesLeft]{2}[OutDial_Result]{Contacted_AM}[AmWelcMsg_Re

Len100ms]{74}

094524.77 1 rv replace end: [74]

094524.78 1 state Run Answer Machine script [c:\Our Software\test\AMMessage.vgs]

094524.81 1 tr NewVgsVgm_Goto c:\Our Software\test\AMMessage.vgs,,,,,0

094524.83 1 rv add [scriptEnd_Time]{10/12/2005 9:45:24 AM}

094524.84 1 rv add [scriptEnd_Goto_Script]{c:\Our Software\test\AMMessage.vgs}

094524.86 1 rv add [scriptEnd_Goto_Module]{}

094524.88 1 rv add [scriptStart_Time]{10/12/2005 9:45:24 AM}

094524.91 1 rv add [scriptStart_CalledFrom_Module]{Hangup the Call 34}

094524.92 1 FindFullPathToScript_VgsOrVgp start [c:\Our Software\test\AMMessage.vgs]

094524.94 1 FindFullPathToScript_VgsOrVgp 1

094524.95 0 load start [c:\Our Software\test\AMMessage.vgs]

094524.97 0 MemStructVgs_ClearVgs [3]

094525.00 0 load end

094525.02 1 loaded [c:\Our Software\test\AMMessage.vgs] into:3

094525.03 1 rvns add [scriptsPath]{c:\Our Software\test\}

094525.05 1 StartModuleDefault=[AMMessage]

094525.06 2 tr NewVgsVgm_Goto 1

094525.08 2 tr NewVgsVgm_Goto 2

094525.09 1 fn RunModule start

094525.11 1 timer clear

094525.13 1 Play Module start

094525.14 1 state [AMMessage] Playing

094525.16 1 state [AMMessage] Playing (C:\test\MessageForAAnsweringMachine.wav)

094525.17 1 tw ConvertWav2Vox c:\Our Software\test\MessageForAAnsweringMachine.wav

094525.19 1 timer clear

094525.20 2 tr NewVgsVgm_Goto end

094525.23 1 tw wav->vox convert end 526734,c:\Our Software\test\MessageForAAnsweringMachine.vox,1,0,,

094525.25 1 play start ok, lPlayId=526812

094527.72 1 tw PlayEnd 1 526812

094527.73 1 play end id=526812, (current ID)

094527.75 1 event ScriptEventCode EV_PLAY_FINISHED, code=8001, state=1100

094527.77 1 LsPlayMsg EV_PLAY_FINISHED,EV_PLAY_FINISHED

094527.80 1 path {EV_PLAY_FINISHED} not found

094527.81 1 timer set 10 EV_TIMEOUT_HANGUP

094527.83 1 timer set 0 EV_TIMEOUT_GOTOMODULE

094527.84 1 event ScriptEventCode EV_TIMEOUT_GOTOMODULE, code=9002, state=1101

094527.86 1 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE,EV_TIMEOUT_GOTOMODULE

094527.88 1 path {EV_TIMEOUT_GOTOMODULE} not found

094527.89 1 fn RunModule start

094527.91 1 tw DialogicEvent 129,TDX_PLAY,526812,0,0,,,

094527.92 1 event ScriptEventCode TDX_PLAY, code=129, state=6001

094527.94 1 timer clear

094527.95 1 rv add [Hangup Time]{10/12/2005 9:45:27 AM}

094527.97 1 state Hanging up call... [Hangup Module]

094527.98 1 HangupCall_Common hCall=518656

094528.00 1 rec RecSoundStop ok

094528.02 1 play PlaySoundStop ok

094528.03 1 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

094528.05 1 fnHangupCall end

094528.09 1 event callstate IDLE 1 1,0,0

094528.11 1 WorkingMode@Idle=

094528.13 1 timer clear

094528.14 1 rec RecSoundStop ok

094528.16 1 play PlaySoundStop ok

094528.17 1 rv add [CallEndTime]{10/12/2005 9:45:28 AM}

094528.19 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

094528.20 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,,

094528.22 1 event ScriptEventCode TDX_SETHOOK, code=135, state=900

094528.25 1 LsAwaitingCalls EV_UNKNOWN_135

094529.25 1 timer fired EV_TIMEOUT_TIMETOREINITLINE

094529.27 1 event ScriptEventCode EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=900

094529.28 1 init atidle reinit : start

094529.30 1 init atidle reinit iConferenceOtherLegLid=-1

094529.31 1 init atidle reinit : reset all LineState variables

094529.31 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

094529.33 1 state Waiting for a call...

094529.67 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

094529.69 1 event ScriptEventCode EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900

 

 

Unsuccessful outbound trace

 

094723.66 0 Loading from C:\Program Files\VoiceGuide\data\OutDial_New.xml

094723.67 0 dial callque insert tel:2318860 scr:c:\Our Software\test\HumanMessage.vgs time:510120947 esc:ErrorError

094723.69 0 dial DialListParseInputXML no <PhoneNumber>

094723.69 0 Loaded 1 entries.

094724.69 1 dial [198,2318860,510120947,0,2359,MoTuWeThFrSaSu,[,dxxxB1C1,],1,,c:\Our Software\test\HumanMessage.vgs,c:\Our Software\test\AMMessage.vgs,[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[

ppointmentDateTime]{10011030},AllowTransfer]{Yes},240,2,1,ErrorError,ErrorError] (search started at 094724.69)

094724.70 1 dial DialOnLine start tel[2318860] ann[] vgs[c:\Our Software\test\HumanMessage.vgs] am[c:\Our Software\test\AMMessage.vgs] rv[[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[AppointmentDateT

me]{10011030},AllowTransfer]{Yes}]

094724.72 0 dial callque update id=198: NextCallTime=0510120949, RetriesLeft=1

094724.73 0 dial callque DB update returned (MSAccess)

094724.73 1 rv clear all

094724.77 1 rv add [DrName]{Williams}

094724.78 1 rv add [Clinic]{5012551425}

094724.80 1 rv add [PatientName]{John}

094724.81 1 rv add [AppointmentDateTime]{10011030}

094724.83 1 rv add [OutDial_RetriesLeft]{2}

094724.84 1 dial making call tel[2318860] ann[] vgs[c:\Our Software\test\HumanMessage.vgs] am[c:\Our Software\test\AMMessage.vgs] OnHangup[] rv[[DrName]{Williams},[Clinic]{5012551425},[PatientName]{John},[AppointmentDateT

me]{10011030},AllowTransfer]{Yes}[OutDial_RetriesLeft]{2}]

094724.86 1 script interpretor: VgMulti v6.0.2277

094724.89 1 state Dialing 2318860

094724.91 1 lineMakeCall=>[]

094724.92 1 timer set 240 EV_TIMEOUT_MAKECALL_NORESPONSE

094725.44 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

094725.45 1 event ScriptEventCode TDX_SETHOOK, code=135, state=5200

094725.47 1 LsWaitAfterDialingOut : 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

094725.58 1 tw DialogicEvent 134,TDX_CST,1622,0,0,DE_LCON,,

094725.59 1 event ScriptEventCode TDX_CST, code=134, state=5200

094725.61 1 LsWaitAfterDialingOut : 134,TDX_CST,1622,0,0,DE_LCON,,

094747.19 1 tw DialogicEvent 133,TDX_CALLP,9,0,0,CR_NORB,,

094747.20 1 event ScriptEventCode NORINGBACK, code=133, state=5200

094747.22 1 LsWaitAfterDialingOut : 133,NORINGBACK,9,0,0,CR_NORB,,

094747.23 1 event ScriptEventCode CR_NORB, code=133, state=5200

094747.25 1 LsWaitAfterDialingOut : 133,CR_NORB,9,0,0,,,

094747.27 1 event ScriptEventCode TDX_CALLP, code=133, state=5200

094747.28 1 LsWaitAfterDialingOut : 133,TDX_CALLP,9,0,0,CR_NORB,,

094747.30 1 LineEvCallState_OutgoingCallWasAnswered 9,0,0

094747.31 1 init OnCallAnswered_StartScript start

094747.33 1 init StartLoadedVgs_InitLineState start

094747.34 1 rv add [$RV_STARTTIME]{10/12/2005 9:47:47 AM}

094747.36 1 rv add [$RV_DEVICEID]{1}

094747.39 1 rv add [$RV_CIDNAME]{}

094747.41 1 rvns add [PathApp]{C:\Program Files\VoiceGuide\}

094747.42 1 WARN rv ScriptsPath could not be set as LineState(iLineId).iVgsIdx = 0 in StartLoadedVgs_InitLineState

094747.44 1 rv add [$RV_CALLEDNUMBER]{2318860}

094747.45 1 set LineState().hCall = 668859 in OnCallAnswered_StartScript

094747.47 0 dial callque delete id=198 (MSAccess)

094747.48 1 rvns add [OutDial_Result]{Contacted_Human}

094747.50 1 state live person answered. start [c:\Our Software\test\HumanMessage.vgs]

094747.52 1 tr NewVgsVgm_Goto c:\Our Software\test\HumanMessage.vgs,,,,,0

094747.53 1 rv add [scriptEnd_Time]{10/12/2005 9:47:47 AM}

094747.55 1 rv add [scriptEnd_Goto_Script]{c:\Our Software\test\HumanMessage.vgs}

094747.56 1 rv add [scriptEnd_Goto_Module]{}

094747.58 1 rv add [scriptStart_Time]{10/12/2005 9:47:47 AM}

094747.59 1 rv add [scriptStart_CalledFrom_Module]{Hangup the Call 16}

094747.61 1 FindFullPathToScript_VgsOrVgp start [c:\Our Software\test\HumanMessage.vgs]

094747.63 1 FindFullPathToScript_VgsOrVgp 1

094747.64 0 LoadedVgs_Find c:\Our Software\test\HumanMessage.vgs at idx=2

094747.66 1 rvns add [scriptsPath]{C:\Our Software\test\}

094747.67 1 StartModuleDefault=[HumanMessage]

094747.69 2 tr NewVgsVgm_Goto 1

094747.70 2 tr NewVgsVgm_Goto 2

094747.72 1 fn RunModule start

094747.73 1 timer clear

094747.75 1 Play Module start

094747.78 1 state [HumanMessage] Playing

094747.78 1 state [HumanMessage] Playing (C:\test\MessageForAHuman.wav)

094747.81 1 tw ConvertWav2Vox C:\Our Software\test\MessageForAHuman.wav

094747.84 1 timer clear

094747.86 2 tr NewVgsVgm_Goto end

094747.94 1 tw wav->vox convert end 669375,C:\Our Software\test\MessageForAHuman.vox,1,0,,

094747.95 1 play start ok, lPlayId=669515

094750.66 1 tw PlayEnd 1 669515

094750.66 1 play end id=669515, (current ID)

094750.67 1 event ScriptEventCode EV_PLAY_FINISHED, code=8001, state=1100

094750.69 1 LsPlayMsg EV_PLAY_FINISHED,EV_PLAY_FINISHED

094750.70 1 path {EV_PLAY_FINISHED} not found

094750.72 1 timer set 10 EV_TIMEOUT_HANGUP

094750.73 1 timer set 0 EV_TIMEOUT_GOTOMODULE

094750.77 1 event ScriptEventCode EV_TIMEOUT_GOTOMODULE, code=9002, state=1101

094750.78 1 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE,EV_TIMEOUT_GOTOMODULE

094750.78 1 path {EV_TIMEOUT_GOTOMODULE} not found

094750.80 1 fn RunModule start

094750.83 1 tw DialogicEvent 129,TDX_PLAY,669515,0,0,,,

094750.83 1 event ScriptEventCode TDX_PLAY, code=129, state=6001

094750.84 1 timer clear

094750.86 1 rv add [Hangup Time]{10/12/2005 9:47:50 AM}

094750.88 1 state Hanging up call... [Hangup Module]

094750.89 1 HangupCall_Common hCall=668859

094750.91 1 rec RecSoundStop ok

094750.92 1 play PlaySoundStop ok

094750.94 1 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

094750.95 1 fnHangupCall end

094751.00 1 event callstate IDLE 1 1,0,0

094751.02 1 WorkingMode@Idle=

094751.03 1 timer clear

094751.05 1 rec RecSoundStop ok

094751.05 1 play PlaySoundStop ok

094751.06 1 rv add [CallEndTime]{10/12/2005 9:47:51 AM}

094751.08 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

094751.09 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,,

094751.11 1 event ScriptEventCode TDX_SETHOOK, code=135, state=900

094751.13 1 LsAwaitingCalls EV_UNKNOWN_135

094752.13 1 timer fired EV_TIMEOUT_TIMETOREINITLINE

094752.14 1 event ScriptEventCode EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=900

094752.16 1 init atidle reinit : start

094752.16 1 init atidle reinit iConferenceOtherLegLid=-1

094752.17 1 init atidle reinit : reset all LineState variables

094752.19 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

094752.20 1 state Waiting for a call...

094752.55 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

094752.56 1 event ScriptEventCode EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900

094811.27 1 event callstate OFFERING 1 2,0,0

094811.30 1 script interpretor: VgMulti v6.0.2277

094811.31 1 fired OFFERING event to listeners

094811.33 1 set LineState().hCall = 1 in LINECALLSTATE_OFFERING

094811.34 1 iIvrDev=1

094811.36 1 set linestate CID vars

094811.38 1 call AnswerTheCallIfAllowed from LINECALLSTATE_OFFERING

094811.39 1 rings=0, min rings before answer=0 (iCallerIdHasArrived=0)

094811.41 1 tw ring 1

094811.42 1 ring time since last ring event (sec): 0.00

094811.44 1 rings=1, min rings before answer=0 (iCallerIdHasArrived=0)

094811.45 1 rv clear all

094811.47 1 init LineState ResetAtStartOfNewCall.

094811.48 1 answer the call

094811.50 1 timer clear

094811.52 1 tw DialogicEvent 134,TDX_CST,0,0,0,DE_RINGS,ET_RON,

094811.53 1 event ScriptEventCode TDX_CST, code=134, state=6001

094812.13 1 event callstate CONNECTED 1 256,0,0

094812.14 1 LineEvCallState_Connected hDevice=1 params=256,0,0

094812.16 1 WorkingModes tapi= script= LineState().iState=6001

094812.19 1 Inband detection not enabled

094812.20 1 StartLoadedVgs at 10/12/2005 9:48:12 AM [VgMulti v6.0.2277]

094812.22 1 set LineState().iVgsIdx=1

094812.23 1 set sScriptToRunOnHangup=[] in StartLoadedVgs

094812.25 1 init StartLoadedVgs_InitLineState start

094812.27 1 rv add [$RV_STARTTIME]{10/12/2005 9:48:12 AM}

094812.28 1 rv add [$RV_DEVICEID]{1}

094812.30 1 rv add [$RV_CIDNAME]{}

094812.31 1 rvns add [PathApp]{C:\Program Files\VoiceGuide\}

094812.33 1 rvns add [scriptsPath]{C:\Our Software\test\}

094812.34 1 rv add [$RV_CIDNUMBER]{}

094812.36 1 fn RunModule start

094812.38 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_INBOUND,

094812.41 1 event ScriptEventCode TDX_SETHOOK, code=135, state=6001

094812.42 1 tw DialogicEvent 134,TDX_CST,2113,0,0,DE_LCON,,

094812.44 1 event ScriptEventCode TDX_CST, code=134, state=6001

094812.45 1 timer clear

094812.47 1 rv add [Hangup Time]{10/12/2005 9:48:12 AM}

094812.48 1 state Hanging up call... [Hangup Module]

094812.50 1 HangupCall_Common hCall=1

094812.52 1 rec RecSoundStop ok

094812.53 1 play PlaySoundStop ok

094812.56 1 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

094812.58 1 fnHangupCall end

094814.09 1 event callstate IDLE 1 1,0,0

094814.11 1 WorkingMode@Idle=

094814.13 1 timer clear

094814.14 1 rec RecSoundStop ok

094814.16 1 play PlaySoundStop ok

094814.17 1 rv add [CallEndTime]{10/12/2005 9:48:14 AM}

094814.19 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

094814.20 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,,

094814.22 1 event ScriptEventCode TDX_SETHOOK, code=135, state=900

094814.23 1 LsAwaitingCalls EV_UNKNOWN_135

094815.23 1 timer fired EV_TIMEOUT_TIMETOREINITLINE

094815.23 1 event ScriptEventCode EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=900

094815.25 1 init atidle reinit : start

094815.27 1 init atidle reinit iConferenceOtherLegLid=-1

094815.28 1 init atidle reinit : reset all LineState variables

094815.30 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

094815.31 1 state Waiting for a call...

094815.66 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

094815.67 1 event ScriptEventCode EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900

094820.48 1 event callstate OFFERING 1 2,0,0

094820.50 1 script interpretor: VgMulti v6.0.2277

094820.52 1 fired OFFERING event to listeners

094820.53 1 set LineState().hCall = 1 in LINECALLSTATE_OFFERING

094820.55 1 iIvrDev=1

094820.56 1 set linestate CID vars

094820.58 1 call AnswerTheCallIfAllowed from LINECALLSTATE_OFFERING

094820.59 1 rings=0, min rings before answer=0 (iCallerIdHasArrived=0)

094820.61 1 tw ring 1

094820.63 1 ring time since last ring event (sec): 0.00

094820.64 1 rings=1, min rings before answer=0 (iCallerIdHasArrived=0)

094820.66 1 rv clear all

094820.66 1 init LineState ResetAtStartOfNewCall.

094820.67 1 answer the call

094820.69 1 timer clear

094820.72 1 tw DialogicEvent 134,TDX_CST,0,0,0,DE_RINGS,ET_RON,

094820.73 1 event ScriptEventCode TDX_CST, code=134, state=6001

094821.33 1 event callstate CONNECTED 1 256,0,0

094821.34 1 LineEvCallState_Connected hDevice=1 params=256,0,0

094821.36 1 WorkingModes tapi= script= LineState().iState=6001

094821.38 1 Inband detection not enabled

094821.41 1 StartLoadedVgs at 10/12/2005 9:48:21 AM [VgMulti v6.0.2277]

094821.42 1 set LineState().iVgsIdx=1

094821.44 1 set sScriptToRunOnHangup=[] in StartLoadedVgs

094821.45 1 init StartLoadedVgs_InitLineState start

094821.47 1 rv add [$RV_STARTTIME]{10/12/2005 9:48:21 AM}

094821.48 1 rv add [$RV_DEVICEID]{1}

094821.50 1 rv add [$RV_CIDNAME]{}

094821.52 1 rvns add [PathApp]{C:\Program Files\VoiceGuide\}

094821.53 1 rvns add [scriptsPath]{C:\Our Software\test\}

094821.55 1 rv add [$RV_CIDNUMBER]{}

094821.56 1 fn RunModule start

094821.58 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_INBOUND,

094821.59 1 event ScriptEventCode TDX_SETHOOK, code=135, state=6001

094821.61 1 tw DialogicEvent 134,TDX_CST,723,0,0,DE_LCON,,

094821.64 1 event ScriptEventCode TDX_CST, code=134, state=6001

094821.66 1 timer clear

094821.67 1 rv add [Hangup Time]{10/12/2005 9:48:21 AM}

094821.69 1 state Hanging up call... [Hangup Module]

094821.70 1 HangupCall_Common hCall=1

094821.72 1 rec RecSoundStop ok

094821.73 1 play PlaySoundStop ok

094821.77 1 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

094821.78 1 fnHangupCall end

094823.30 1 event callstate IDLE 1 1,0,0

094823.31 1 WorkingMode@Idle=

094823.33 1 timer clear

094823.34 1 rec RecSoundStop ok

094823.36 1 play PlaySoundStop ok

094823.38 1 rv add [CallEndTime]{10/12/2005 9:48:23 AM}

094823.39 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

094823.41 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,,

094823.42 1 event ScriptEventCode TDX_SETHOOK, code=135, state=900

094823.44 1 LsAwaitingCalls EV_UNKNOWN_135

094824.44 1 timer fired EV_TIMEOUT_TIMETOREINITLINE

094824.45 1 event ScriptEventCode EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=900

094824.47 1 init atidle reinit : start

094824.48 1 init atidle reinit iConferenceOtherLegLid=-1

094824.48 1 init atidle reinit : reset all LineState variables

094824.50 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

094824.52 1 state Waiting for a call...

094824.86 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

094824.88 1 event ScriptEventCode EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900

[SupportTeam]

In the second trace the call was connected as the Dialogic card informed VoiceGuide that on the outgoing call it has never detected any Ringbacks:

 

094747.20 1 event ScriptEventCode NORINGBACK, code=133, state=5200

 

You may want to modify the Ringback tone definitions in ConfigLine.xml file to better match the ringbacks played on the line when the number is dialed.

 

(digital T1 ISDN lines do not have such problems)