No Rings On The Target Phone, But

[Guest Jack_VR]

Hi,

 

New VG 4 Dialogic, I've tried calling, but the script start running without ringing the target phone, could you tell us what's the reason and how to solve this problem ?

 

Here is the log:

 

093835.44 2 dial [691,4166304544,506280929,830,2359,MoTuWeThFrSaSu,[,,],2,NONE,C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp,C:\Program Files\VoiceGuide\Scripts\CallOut_am.vgp,none,40,2,180,,none] (search started at 093835.44)

093835.45 2 dial DialOnLine start tel[4166304544] ann[NONE] vgs[C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp] am[C:\Program Files\VoiceGuide\Scripts\CallOut_am.vgp] rv[none]

093835.45 0 dial callque update id=691: NextCallTime=0506281238, RetriesLeft=1

093835.47 0 dial callque DB update returned (MSAccess)

093835.47 2 rv clear all

093835.47 2 rv add [OutDial_RetriesLeft]{2}

093835.52 2 dial making call tel[4166304544] ann[NONE] vgs[C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp] am[C:\Program Files\VoiceGuide\Scripts\CallOut_am.vgp] OnHangup[C:\Program Files\VoiceGuide\Scripts\erase_number.vgs] rv[none[OutDial_RetriesLeft]{2}]

093835.53 2 script interpretor: VgMulti v6.0.2028

093835.58 2 state Dialing 4166304544

093835.67 2 timer set 40 EV_TIMEOUT_MAKECALL_NORESPONSE

093836.22 2 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

093836.22 2 event TDX_SETHOOK, code=135, state=5200

093836.23 2 LsWaitAfterDialingOut : 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND,

093836.23 2 tw DialogicEvent 134,TDX_CST,1286,0,0,DE_LCON,,

093836.25 2 event TDX_CST, code=134, state=5200

093836.25 2 LsWaitAfterDialingOut : 134,TDX_CST,1286,0,0,DE_LCON,,

093846.64 2 tw DialogicEvent 134,TDX_CST,199,0,0,DE_TONEON,TID_RNGBK1,

093846.64 2 event RINGBACK, code=134, state=5200

093846.64 2 LsWaitAfterDialingOut : 134,RINGBACK,199,0,0,DE_TONEON,TID_RNGBK1,

093846.66 2 event TID_RNGBK1, code=134, state=5200

093846.66 2 LsWaitAfterDialingOut : 134,TID_RNGBK1,199,0,0,DE_TONEON,TID_RNGBK1,

093846.67 2 event TDX_CST, code=134, state=5200

093846.67 2 LsWaitAfterDialingOut : 134,TDX_CST,199,0,0,DE_TONEON,TID_RNGBK1,

093851.91 1 event callstate OFFERING 1 0 0

093851.91 1 script interpretor: VgMulti v6.0.2028

093851.92 1 ERROR 6.0.2028 TW__LineEvCallState_Error:Subscript out of range

093851.94 1 tw ring 1

093851.94 1 ring time since last ring (ms): 0

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

093851.95 1 event TDX_CST, code=134, state=0

093857.22 2 tw DialogicEvent 134,TDX_CST,200,0,0,DE_TONEON,TID_RNGBK2,

093857.23 2 event RINGBACK, code=134, state=5200

093857.23 2 LsWaitAfterDialingOut : 134,RINGBACK,200,0,0,DE_TONEON,TID_RNGBK2,

093857.23 2 event TID_RNGBK2, code=134, state=5200

093857.25 2 LsWaitAfterDialingOut : 134,TID_RNGBK2,200,0,0,DE_TONEON,TID_RNGBK2,

093857.25 2 event TDX_CST, code=134, state=5200

093857.27 2 LsWaitAfterDialingOut : 134,TDX_CST,200,0,0,DE_TONEON,TID_RNGBK2,

093857.91 1 tw ring 2

093857.91 1 ring time since last ring (ms): 5.96875

093857.92 1 ring match against loaded ring patterns

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

093857.92 1 event TDX_CST, code=134, state=0

093905.91 1 event callstate IDLE 1 0 0

093905.91 1 WorkingMode@Idle=

093905.92 1 timer clear

093905.92 1 LineState(iLineId).iVgsIdx = 0

093905.94 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

093906.95 1 timer fired EV_TIMEOUT_TIMETOREINITLINE

093906.95 1 event EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=0

093906.97 1 init atidle reinit : start

093906.97 1 init atidle reinit iConferenceOtherLegLid=-1

093906.98 1 init atidle reinit : reset all LineState variables

093906.98 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

093907.00 1 state Waiting for a call...

093907.34 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

093907.34 1 event EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=0

093907.53 2 tw DialogicEvent 133,TDX_CALLP,10,1,0,TDX_CALLP,CR_CNCT,CON_CAD

093907.56 2 event CADENCE, code=133, state=5200

093907.56 2 LsWaitAfterDialingOut : 133,CADENCE,10,1,0,CR_CNCT,CON_CAD,

093907.58 2 event CONNECT, code=133, state=5200

093907.58 2 LsWaitAfterDialingOut : 133,CONNECT,10,1,0,CR_CNCT,CON_CAD,

093907.59 2 event CON_CAD, code=133, state=5200

093907.61 2 LsWaitAfterDialingOut : 133,CON_CAD,10,1,0,CR_CNCT,CON_CAD,

093907.61 2 event CR_CNCT, code=133, state=5200

093907.63 2 LsWaitAfterDialingOut : 133,CR_CNCT,10,1,0,CON_CAD,,

093907.63 2 event TDX_CALLP, code=133, state=5200

093907.64 2 LsWaitAfterDialingOut : 133,TDX_CALLP,10,1,0,CR_CNCT,CON_CAD,

093907.64 2 LineEvCallState_OutgoingCallWasAnswered 10,1,0

093907.66 2 rv add [$RV_STARTTIME]{6/28/2005 9:39:07 AM}

093907.66 2 rv add [$RV_DEVICEID]{2}

093907.67 2 rv add [$RV_CIDNAME]{}

093907.69 2 rvns add [PathApp]{C:\Program Files\VoiceGuide\}

093907.69 2 rvns add [scriptsPath]{C:\Program Files\VoiceGuide\Scripts\}

093907.70 2 rv add [$RV_CALLEDNUMBER]{4166304544}

093907.70 0 dial callque delete id=691 (MSAccess)

093907.72 2 rvns add [OutDial_Result]{Contacted_Human}

093907.73 2 state live person answered. start [C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp]

093907.73 2 tr NewVgsVgm_Goto C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp,,,,,0

093907.75 2 rv add [scriptEnd_Time]{6/28/2005 9:39:07 AM}

093907.75 2 rv add [scriptEnd_Goto_Script]{C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp}

093907.77 2 rv add [scriptEnd_Goto_Module]{}

093907.77 2 rv add [scriptStart_Time]{6/28/2005 9:39:07 AM}

093907.80 2 rv add [scriptStart_CalledFrom_Script]{C:\Program Files\VoiceGuide\Scripts\erase_number.vgs}

093907.80 2 rv add [scriptStart_CalledFrom_Module]{call length}

093907.81 2 FindFullPathToScript_VgsOrVgp start [C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp]

093907.81 2 FindFullPathToScript_VgsOrVgp 1

093907.83 0 LoadedVgs_Find C:\Program Files\VoiceGuide\Scripts\CallOut_hm.vgp at idx=2

093907.84 2 rvns add [scriptsPath]{C:\Program Files\VoiceGuide\Scripts\}

093907.84 2 StartModuleDefault=[intro msg]

093907.86 2 tr NewVgsVgm_Goto 1

093907.86 2 tr NewVgsVgm_Goto 2

093907.88 2 fn RunModule start

093907.98 2 timer clear

093908.02 2 Play Module start

093908.02 2 state [intro msg] Playing

093908.03 2 state [intro msg] Playing (2k50614audios\3 prompts and short message toll free june 17 2005 Recording 1.wav)

093908.03 2 tw ConvertWav2Vox C:\Program Files\VoiceGuide\Scripts\2k50614audios\3 prompts and short message toll free june 17 2005 Recording 1.wav

093908.05 2 timer clear

093908.05 2 tr NewVgsVgm_Goto end

093908.08 2 tw wav->vox convert end 303578,C:\Program Files\VoiceGuide\Scripts\2k50614audios\3 prompts and short message toll free june 17 2005 Recording 1.vox,1,0,,

093908.13 2 play start ok, lPlayId=303625

093911.58 0 btn Hangup on line 2

093911.58 2 event EV_TIMEOUT_HANGUP, code=9001, state=1100

093911.59 2 LsPlayMsg EV_TIMEOUT_HANGUP,EV_TIMEOUT_HANGUP

093911.59 2 path {EV_TIMEOUT_HANGUP} not found

093911.61 2 rv add [Hangup Time]{6/28/2005 9:39:11 AM}

093911.63 2 state Hanging up call...

093911.63 2 HangupCall_Common hCall=303187

093911.67 2 rec RecSoundStop ok

093911.73 2 play PlaySoundStop ok

093911.81 2 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

093911.83 2 fnHangupCall end

093911.84 2 tw PlayEnd 2 303625

093911.84 2 play end id=303625, (current ID)

093911.86 2 event EV_PLAY_FINISHED, code=8001, state=900

093911.86 2 LsAwaitingCalls EV_PLAY_FINISHED

093911.89 2 tw DialogicEvent 129,TDX_PLAY,303625,0,0,,,

093911.89 2 event TDX_PLAY, code=129, state=900

093911.91 2 LsAwaitingCalls EV_UNKNOWN_129

093911.92 2 event callstate IDLE 2 0 0

093911.92 2 WorkingMode@Idle=

093911.94 2 timer clear

093911.95 2 rec RecSoundStop ok

093911.97 2 play PlaySoundStop ok

093911.97 2 rv add [CallEndTime]{6/28/2005 9:39:11 AM}

093911.98 2 After Hangup script:[C:\Program Files\VoiceGuide\Scripts\erase_number.vgs]

093912.00 2 FindFullPathToScript_VgsOrVgp start [C:\Program Files\VoiceGuide\Scripts\erase_number.vgs]

093912.00 2 FindFullPathToScript_VgsOrVgp 1

093912.02 0 LoadedVgs_Find C:\Program Files\VoiceGuide\Scripts\erase_number.vgs at idx=3

093912.02 2 fn RunModule start

093912.05 2 timer clear

093912.06 2 state [erase number] Running vb script:[C:\Program Files\VoiceGuide\Scripts\erase_number.vgs]

094022.50 2 FindFullPathToScript_VgsOrVgp start [C:\Program Files\VoiceGuide\Scripts\erase_number.vgs]

094022.52 2 FindFullPathToScript_VgsOrVgp 1

094022.53 0 LoadedVgs_Find C:\Program Files\VoiceGuide\Scripts\erase_number.vgs at idx=3

094022.53 2 fn RunModule start

094022.55 2 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,,

094022.55 2 event TDX_SETHOOK, code=135, state=6001

094022.61 2 timer clear

094022.63 2 state [erase number] Running VB Script...

094022.63 2 rv replace start (strlen>500)

094022.64 2 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\}[OutDial_RetriesLeft]{2}[OutDial_Result]{Contacted_AM}[AmWelcMsg_RecLen100

s]{154}[scriptEnd_Time]{6/28/2005 9:40:13 AM}[scriptEnd_Goto_Script]{C:\Program Files\VoiceGuide\Scripts\CallOut_am.vgp}[scriptEnd_Goto_Module]{}[scriptStart_Time]{6/28/2005 9:40:13 AM}[scriptStart_CalledFrom_Script]{C:\Program Files\VoiceGuide\Scripts\erase_number.vgs}[scriptStart_CalledFrom_Module]{call length}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\}[Hangup Time]{6/28/2005 9:40:22 AM}[CallEndTime]{6/28/2005 9:40:22 AM}

094022.66 2 vbs ran [vbs_2_1.vbs], cmd [wscript "C:\Program Files\VoiceGuide\data\vbs_2_1.vbs" //I //T:60]

094022.70 2 Run Script waiting. iRunWait=1 (shellid=968, process=1504)

094022.72 2 timer set 1 EV_TIMEOUT_CHECKONSTATE

094023.80 2 timer fired EV_TIMEOUT_CHECKONSTATE

094023.86 2 event EV_TIMEOUT_CHECKONSTATE, code=9007, state=1501

094023.88 2 LsRunWaitTillFinished EV_TIMEOUT_CHECKONSTATE

094023.88 2 task completed (shellid=968, process=1504) vgm=40, iRunWait=1, iRunWait_ExeResult_NextVgm=0

094023.89 2 no result file returned from program (VGRUNRESULT_2.TXT or SHLRESLT.TXT)

094023.91 2 task completed LsRunWaitChooseNext iNextVgm=42

094023.91 2 fn RunModule start

094023.98 2 timer clear

094024.00 2 state [call length] Running VB Script...

094024.00 2 rv replace start (strlen>500)

094024.02 2 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\}[OutDial_RetriesLeft]{2}[OutDial_Result]{Contacted_AM}[AmWelcMsg_RecLen100

s]{154}[scriptEnd_Time]{6/28/2005 9:40:13 AM}[scriptEnd_Goto_Script]{C:\Program Files\VoiceGuide\Scripts\CallOut_am.vgp}[scriptEnd_Goto_Module]{}[scriptStart_Time]{6/28/2005 9:40:13 AM}[scriptStart_CalledFrom_Script]{C:\Program Files\VoiceGuide\Scripts\erase_number.vgs}[scriptStart_CalledFrom_Module]{call length}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\}[Hangup Time]{6/28/2005 9:40:22 AM}[CallEndTime]{6/28/2005 9:40:22 AM}

094024.03 2 vbs ran [vbs_2_2.vbs], cmd [wscript "C:\Program Files\VoiceGuide\data\vbs_2_2.vbs" //I //T:5]

094024.23 2 Run Script waiting. iRunWait=1 (shellid=352, process=1508)

094024.25 2 timer set 1 EV_TIMEOUT_CHECKONSTATE

094025.41 2 timer fired EV_TIMEOUT_CHECKONSTATE

094025.42 2 event EV_TIMEOUT_CHECKONSTATE, code=9007, state=1501

094025.44 2 LsRunWaitTillFinished EV_TIMEOUT_CHECKONSTATE

094025.45 2 task still running (shellid=352, process=1508)

094025.45 2 timer set 1 EV_TIMEOUT_CHECKONSTATE

094026.56 2 timer fired EV_TIMEOUT_CHECKONSTATE

094026.56 2 event EV_TIMEOUT_CHECKONSTATE, code=9007, state=1501

094026.58 2 LsRunWaitTillFinished EV_TIMEOUT_CHECKONSTATE

094026.58 2 task completed (shellid=352, process=1508) vgm=42, iRunWait=1, iRunWait_ExeResult_NextVgm=0

094026.59 2 no result file returned from program (VGRUNRESULT_2.TXT or SHLRESLT.TXT)

094026.61 2 already IDLE. HangupCall ignored.

094026.61 2 init atidle reinit : start

094026.63 2 init atidle reinit iConferenceOtherLegLid=-1

094026.63 2 init atidle reinit : reset all LineState variables

094026.64 2 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

094026.66 2 state Waiting for a call...

094027.00 2 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

094027.03 2 event EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900

 

 

Regards,

Jack

[SupportTeam]

Trace shows two outgoing calls:

 

In the first outgoing call the Dialogic card said the call was connected as it stopped hearing the ringback tone:

 

093907.59 2 event CON_CAD, code=133, state=5200

 

In the second call the Dialogic card heard a recorded message on the line, so the answering machine script was started:

 

093957.98 2 event CON_PAMD, code=133, state=5200

...

093958.03 2 StartPlayingAnsweringMachineMessage 10,4,0

 

(trace shows a number of other tones was also detected before that...)

 

With analog lines you will get some false AM detections on outgoing calls if the phone company playes recorded messages on the lines instead of just playing tones. Only Digital T1/E1 ISDN lines will positively indicate for you when the outgoing call has been answered.