3-Way with D/4PCI taking too long

[Guest Adam Konecny]

Hi there

 

I am in need of your help again. The transfer is not working in my

script. I transfer a call to a 800#.

 

It's a 3-way call. My phone lines are such that it should be

1) flash

2) 1 second pause then dial number

3) another 1 second pause then flash again. This works fine manually

 

That's it...4 or 5 seconds. For some reason it takes 15 seconds.

Can you

help. I've included a trace and the vgs file.

 

 

It starts does the first flash:

232921.54 4 [HookFlash1-Office] Playing

232921.55 4 tapic lineGenerateDigits(66255,!,100) => 0

 

(5 secs difference)

 

then dials:

232926.16 4 [Dial800#-Office] Playing

232926.19 4 tapic lineGenerateDigits(66255,,18005497066,,100) => 0

 

(7 secs)

 

then flashes again:

232933.05 4 [HookFlash2-Office] Playing

232933.06 4 tapic lineGenerateDigits(66255,!,100) => 0

 

What does the ,100 mean? Also I've set the ! length at 250ms and pause time of 400ms in TSP

 

Please help and thanks so much!

 

Adam

akonecny@rogers.com

 

Here is the complete trace.

 

232714.02 0 Script Load C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs

232714.09 0 Loaded Script Id 3: C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs

232714.09 0 Erasing Script (new loaded) Id 2

232840.83 0 Script Load C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs

232840.89 0 Loaded Script Id 2: C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs

232840.90 0 Erasing Script (new loaded) Id 3

232855.03 4 dial [28109,15032331555,502282320,1,2359,MoTuWeThFrSaSu,,1,NONE,C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs,C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-msg.wav,none,60,0,9999,,none]

232855.10 4 rv clear

232855.10 0 dial callque update id=28109 next call time 0503072207

232855.11 0 dial callque DB update returned

232855.11 4 dial found entry: tel[15032331555] ann[NONE] vgs[C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs] am[C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-msg.wav] LineSelection:[]

232855.12 4 Dialing: 15032331555

232855.13 4 MakeCall => 65569

232855.13 4 timer set 60 EV_TIMEOUT_MAKECALL_NORESPONSE

232855.13 4 TapiCbTrigSet 65569 7002

232857.66 4 tapi Reply (LineEvReply) ok 65569 0

232857.66 4 TapiCbTrigClear

232857.66 4 linedevstate 2048 0 0

232857.66 4 callstate DIALTONE 66255 0 0

232857.66 4 callstate DIALING 66255 0 0

232857.66 4 callstate PROCEEDING 66255 0 0

232857.66 4 callinfo CALLEDID

232857.67 4 callinfo REASON

232857.70 4 Lev_CallerID [0000,,15032331555]

232857.70 4 rv add [DNIS]{15032331555}

232857.70 4 callinfo ORIGIN

232902.95 0 dial start any summary:|4:dial=0|5:idx=0|6:idx=0|7:idx=0|

232910.41 4 callstate CONNECTED 66255 1 0

232910.41 4 callstate CONNECTED 66255,1,0

232910.41 4 WorkingModeTAPI@Connected=

232910.41 4 WorkingModeScript@Connected=

232910.43 4 rv add [PathSysVoice]{C:\Program Files\VoiceGuide\system\voice\}

232910.43 4 rv add [PathApp]{C:\Program Files\VoiceGuide\}

232910.45 4 rv add [PathDataVm]{C:\Program Files\VoiceGuide\data\}

232910.45 4 rv add [PathVgSys]{C:\Program Files\VoiceGuide\system\}

232910.45 4 rv add [OutDial_RetriesLeft]{0}

232910.45 4 rv add [$RV_STARTTIME]{2/28/2005 11:29:10 PM}

232910.45 4 rv add [$RV_DEVICEID]{4}

232910.45 4 rv add [$RV_CIDNAME]{}

232910.45 4 AddRVns [PathApp]{C:\Program Files\VoiceGuide\}

232910.45 4 rv add [$RV_CALLEDNUMBER]{15032331555}

232910.45 0 dial callque delete id=28109

232910.53 4 AddRVns [OutDial_Result]{Contacted_Human}

232910.53 4 Live person answered, starting C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs

232910.53 2 tr NewVgsVgm_Goto C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs,,,,,0

232910.53 4 rv add [scriptEnd_Time]{2/28/2005 11:29:10 PM}

232910.53 4 rv add [scriptEnd_Goto_Script]{C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs}

232910.53 4 rv add [scriptEnd_Goto_Module]{}

232910.55 4 rv add [scriptStart_Time]{2/28/2005 11:29:10 PM}

232910.55 0 LoadedVgs_Find C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs at idx=2

232910.55 4 AddRVns [scriptsPath]{C:\VoiceGuideScripts\GOGI-NEW\}

232910.55 4 eng StartModuleDefault=[intro]

232910.56 4 timer clear

232910.56 4 [intro] Playing

232910.57 4 [intro] Playing (GOJINEW-human.wav)

232910.57 4 play set playid=55045

232910.91 4 PlaySoundStart ok [C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-human.wav]

232910.91 4 timer clear

232910.91 4 RunModule PLAY end

232910.91 4 wa(56105,5504500)

232910.91 4 callinfo MONITORMODES

232910.92 0 dial start any summary:|4:hc>0|5:idx=0|6:idx=0|7:idx=0|

232917.88 4 dtmf 1 (66255,49,2)

232917.88 4 ScriptEventCode 49 iLineState=1100

232917.88 4 LsPlayMsg 1

232917.93 4 PlaySoundStop err=0

232917.93 4 rv add [intro]{1}

232917.96 4 timer clear

232917.96 4 [CleanNum1] Evaluate [mid($RV_CALLEDNUMBER,2,10)]

232917.96 4 rv replace start: [mid($RV_CALLEDNUMBER,2,10)]

232917.96 4 rv ns none[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]{0}[OutDial_Result]{Contacted_Human}[scriptEnd_Time]{

/28/2005 11:29:10 PM}[scriptEnd_Goto_Script]{C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs}[scriptEnd_Goto_Module]{}[scriptStart_Time]{2/28/2005 11:29:10 PM}[scriptsPath]{C:\VoiceGuideScripts\GOGI-NEW\}[intro]{1}

232917.96 4 rv replace end: [mid(15032331555,2,10)]

232917.98 4 .Eval(mid(15032331555,2,10))

232917.98 4 AddRVns [CleanNum1_Input]{mid(15032331555,2,10)}

232917.98 4 AddRVns [CleanNum1]{5032331555}

232917.98 4 Eval Expr result:[5032331555]

232917.98 4 path {5032331555} not found

232917.98 4 timer clear

232917.98 4 [saveInfo] Run Program

232917.98 4 rv replace start: [command.com /c echo $RV[CleanNum1] $RV_STARTTIME >> c:\Goji#1_O.txt]

232917.98 4 rv ns none[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]{0}[OutDial_Result]{Contacted_Human}[scriptEnd_Time]{

/28/2005 11:29:10 PM}[scriptEnd_Goto_Script]{C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs}[scriptEnd_Goto_Module]{}[scriptStart_Time]{2/28/2005 11:29:10 PM}[scriptsPath]{C:\VoiceGuideScripts\GOGI-NEW\}[intro]{1}[CleanNum1_Input]{mid(15032331555,2,10)}[CleanNum1]{5032331555}

232917.99 4 rv replace end: [command.com /c echo 5032331555 2/28/2005 11:29:10 PM >> c:\Goji#1_O.txt]

232917.99 4 eng run shell [command.com /c echo 5032331555 2/28/2005 11:29:10 PM >> c:\Goji#1_O.txt] window=NormalFocus, wait=0

232918.02 4 Run Program continuing...

232918.02 4 Going down Success path.

232918.03 4 timer clear

232918.03 4 [AnnounceXfer-Office] Playing

232918.03 4 [AnnounceXfer-Office] Playing (GOJINEW-xfer.wav)

232918.05 4 play set playid=62516

232918.12 4 PlaySoundStart ok [C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-xfer.wav]

232918.12 4 timer clear

232918.12 4 RunModule PLAY end

232918.12 4 wa(3198,6251600)

232918.13 4 callinfo MONITORMODES

232921.37 4 wb(6251600)

232921.45 4 play end current play (playid=62516)

232921.45 4 ScriptEventCode 8001 iLineState=1100

232921.45 4 LsPlayMsg EV_PLAY_FINISHED

232921.45 4 timer set 10 EV_TIMEOUT_HANGUP

232921.45 4 timer set 0 EV_TIMEOUT_GOTOMODULE

232921.46 4 ScriptEventCode 9002 iLineState=1101

232921.46 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE

232921.54 4 timer clear

232921.54 4 [HookFlash1-Office] Playing

232921.55 4 tapic lineGenerateDigits(66255,!,100) => 0

232921.56 4 RunModule PLAY end

232923.45 4 generate LINEGENERATETERM_DONE

232923.45 4 timer set 1 EV_TAPI_GENERATE

232926.16 4 timer fired EV_TAPI_GENERATE

232926.16 4 ScriptEventCode 9602 iLineState=1100

232926.16 4 LsPlayMsg EV_TAPI_GENERATE

232926.16 4 timer set 10 EV_TIMEOUT_HANGUP

232926.16 4 timer set 0 EV_TIMEOUT_GOTOMODULE

232926.16 4 ScriptEventCode 9002 iLineState=1101

232926.16 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE

232926.16 4 timer clear

232926.16 4 [Dial800#-Office] Playing

232926.19 4 tapic lineGenerateDigits(66255,,18005497066,,100) => 0

232926.19 4 RunModule PLAY end

232930.26 4 generate LINEGENERATETERM_DONE

232930.26 4 timer set 1 EV_TAPI_GENERATE

232933.03 4 timer fired EV_TAPI_GENERATE

232933.03 4 ScriptEventCode 9602 iLineState=1100

232933.03 4 LsPlayMsg EV_TAPI_GENERATE

232933.03 4 timer set 10 EV_TIMEOUT_HANGUP

232933.05 4 timer set 0 EV_TIMEOUT_GOTOMODULE

232933.05 4 ScriptEventCode 9002 iLineState=1101

232933.05 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE

232933.05 4 timer clear

232933.05 4 [HookFlash2-Office] Playing

232933.06 4 tapic lineGenerateDigits(66255,!,100) => 0

232933.06 4 RunModule PLAY end

232935.09 4 generate LINEGENERATETERM_DONE

232935.09 4 timer set 1 EV_TAPI_GENERATE

232939.70 4 timer fired EV_TAPI_GENERATE

232939.70 4 ScriptEventCode 9602 iLineState=1100

232939.70 4 LsPlayMsg EV_TAPI_GENERATE

232939.70 4 timer set 10 EV_TIMEOUT_HANGUP

232939.70 4 timer set 0 EV_TIMEOUT_GOTOMODULE

232939.70 4 ScriptEventCode 9002 iLineState=1101

232939.70 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE

232939.70 4 timer clear

232939.70 4 [Email-Office] Send Email

232939.72 4 rv replace start: [New Lead! (OFFICE) from $RV[CleanNum1] at $RV_STARTTIME]

232939.72 4 rv ns none[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]{0}[OutDial_Result]{Contacted_Human}[scriptEnd_Time]{

/28/2005 11:29:10 PM}[scriptEnd_Goto_Script]{C:\VoiceGuideScripts\GOGI-NEW\GOJINEW-Office2.vgs}[scriptEnd_Goto_Module]{}[scriptStart_Time]{2/28/2005 11:29:10 PM}[scriptsPath]{C:\VoiceGuideScripts\GOGI-NEW\}[intro]{1}[CleanNum1_Input]{mid(15032331555,2,10)}[CleanNum1]{5032331555}

232939.72 4 rv replace end: [New Lead! (OFFICE) from 5032331555 at 2/28/2005 11:29:10 PM]

232939.72 4 rv add [Email-Office]{gojiopp@yahoo.com;gogoji@excite.com}

232939.72 4 rv add [Email-Office_Subject]{New Lead! (OFFICE) from 5032331555 at 2/28/2005 11:29:10 PM}

232939.73 4 rv add [Email-Office_Message]{}

232939.73 4 rv add [Email-Office_Attachment]{}

232939.73 4 rv add [Email-Office_Cc]{}

232939.73 4 rv add [Email-Office_Bcc]{}

232939.73 4 email que to: gojiopp@yahoo.com;gogoji@excite.com

232939.73 0 sql INSERT INTO MsgQue (ToAddress, ToName, ReturnAddress, ReturnName, CcAddress, CcName, BccAddress, BccName, ReplyAddress, MsgSubject, MsgMessage, MsgAttachment, MsgAttachmentDeleteAfterSend, gw1_SmtpServ, gw1_UseAuthentication, gw1_UsePopAuthentication, gw1_AuthUserName, gw1_AuthUserPassword, gw1_POP3Host, gw1_SMTPPort, gw2_SmtpServ, gw2_UseAuthentication, gw2_UsePopAuthentication, gw2_AuthUserName, gw2_AuthUserPassword, gw2_POP3Host, gw2_SMTPPort, SendTime, SendRetriesLeft, SendDelayBetweenRetries) VALUES ('gojiopp@yahoo.com;gogoji@excite.com', 'gojiopp@yahoo.com;gogoji@excite.com', 'gojiopp@yahoo.com', 'AutoDialer#1', '', '', '', '', 'gojiopp@yahoo.com', 'New Lead! (OFFICE) from 5032331555 at 2/28/2005 11:29:10 PM', '

', '', 0, 'smtp.comcast.net', 0, 0, 'abcmarketing', 'abc12345', '', 0, 'MAPI', 0, 0, '', '', '', 0, 0, 3, 10)

232939.74 4 rv add [Email-Office_SendResult]{OK}

232939.76 4 timer clear

232939.76 4 [Record] Playing

232939.76 4 [Record] Playing ()

232939.76 4 ScriptEventCode 8001 iLineState=1100

232939.76 4 LsPlayMsg EV_PLAY_FINISHED

232939.76 4 timer set 10 EV_TIMEOUT_HANGUP

232939.77 4 timer set 80 EV_TIMEOUT_GOTOMODULE

232939.77 4 RunModule PLAY end

232953.53 0 btn Hangup on line 4

232953.53 4 ScriptEventCode 9001 iLineState=1101

232953.53 4 LsPlayMsgFinished EV_TIMEOUT_HANGUP

232953.55 4 rv add [Hangup Time]{2/28/2005 11:29:53 PM}

232953.55 4 Hanging up call...

232953.55 4 RecSoundStop ok

232953.55 4 PlaySoundStop err=0

232953.55 4 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP

232953.56 4 fnHangupCall end

232953.60 4 linedevstate 2048 0 0

232953.62 4 callstate IDLE 66255 0 0

232953.62 4 WorkingMode@Idle=

232953.62 4 timer clear

232953.63 4 timer set 1 EV_TIMEOUT_TIMETOREINITLINE

232953.63 4 tapi Reply (LineEvReply) ok 66540 0

232956.57 4 timer fired EV_TIMEOUT_TIMETOREINITLINE

232956.57 4 ScriptEventCode 9008 iLineState=900

232956.57 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE

232956.57 4 ReinitTelephony due to IDLE

232956.59 4 tapic lineDeallocateCall(MainCall:66255) 0

232956.94 4 lineOpen(4)=>

232956.94 4 Waiting for a call...

232956.94 4 LineHandle=65945

232956.94 4 timer set 3 EV_TIMEOUT_ATERIDLE_ALLOWOUT

233008.41 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0|

233015.73 4 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT

233015.73 4 ScriptEventCode 9013 iLineState=900

GOJINEW_Office2.vgs

[SupportTeam]

Also I've set the ! length at 250ms and pause time of 400ms in TSP

Please post the screenshot of the TSP config.

 

Is the 3-Way call established in the end?

 

Which version of VG are you using?

 

There does seem to be some problems with the timers on this system. Which version of Windows/Service Pack are you running?

Are you running any other software at the same time?

What is the CPU usage on this system when the transfer is being made?

[Guest Adam Konecny]

-I'm running win2000 with SP2

 

-The system seems to be maxed at 100% resources...so that could explain the timer issue. It's a 128MB PII400 Mhz system. VGmulti.exe is taking 100% of the resources at all times. Also I have 50,000 names loaded.

 

 

-I've attached the TSP screenshots.

 

-the 3 way call is establishblished half the time, the other half it hangs up. but it takes 16 seconds instead of around 5.

 

-I am using the latest version of Voiceguide...5.2.3b

 

-the only other software is VNC, but as stated before, VG is running 99 to 100%

 

What hardware do you reommend? it is a 4 line D4PCI system.

 

Thanks, you guys are great!

Adam

TSP.zip

[SupportTeam]

The system seems to be maxed at 100% resources... It's a 128MB PII400 Mhz system

Please add more memory... take it up to 256MB and see if that changes anything...

Also please post some screenshots of the Task Manager or the program which is showing the memory allocation status etc.

[SupportTeam]

We received an email confirming that system was running at very high CPU utilization (probably from all the swapping to HD given the low RAM). This high CPU usage was causing the delays.

Customer has now updated to a faster system with more RAM.