Announced Transfer Timeout Question

[Guest Seth]

When I define an "on timeout" routine, it seems to ignore it and wait the full 30 second default timeout before following the fail path, but only in the announced transfer module. This is the path info:

 

on {timeout 8} goto [Voicemail Box 10] return [Office Hours Check]

on {fail} goto [Voicemail Box 10]

 

Below is a tracelog:

The lines of interest are from 115134.26 to 115206.75

 

115112.69 5 linedevstate 2048 0 0

115112.69 5 callstate OFFERING 65657 0 4

115112.69 5 No old processes from last call (could not obtain handle) 1224, 1612

115112.69 5 Answer the call at 2/19/2004 11:51:12 AM

115112.71 5 lineAnswer(65657) => 65640

115112.71 5 callinfo CALLEDID

115112.71 5 callinfo ORIGIN

115112.71 5 ring 0

115113.25 5 callstate CONNECTED 65657,1,0

115113.26 5 WorkingModeTAPI@Connected=

115113.26 5 WorkingModeScript@Connected=

115113.34 5 Inband detection not enabled

115113.34 5 StartLoadedVgs at 2/19/2004 11:51:13 AM

115113.34 5 tapi Reply (LineEvReply) ok 65640 0

115113.40 5 [Office Hours Check] Evaluate [((($RV_DAY >= 1) and ($RV_DAY <= 4) and (($RV_HOUR >= 9) and ($RV_HOUR <= 16))) or (($RV_DAY = 5) or ($RV_DAY = 7) and (($RV_HOUR >= 9) and ($RV_HOUR <= 12)))) or $RV_LINEID = 8]

115113.40 5 RVreplace start: [((($RV_DAY >= 1) and ($RV_DAY <= 4) and (($RV_HOUR >= 9) and ($RV_HOUR <= 16))) or (($RV_DAY = 5) or ($RV_DAY = 7) and (($RV_HOUR >= 9) and ($RV_HOUR <= 12)))) or $RV_LINEID = 8]

115113.41 5 .Eval((((4 >= 1) and (4 <= 4) and ((11 >= 9) and (11 <= 16))) or ((4 = 5) or (4 = 7) and ((11 >= 9) and (11 <= 12)))) or 5 = 8)

115113.41 5 Eval Expr result:[True]

115113.41 5 [Attend] Number Input

115113.42 5 [Attend] Playing (Attend.wav)

115113.45 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Attend.wav]

115120.31 5 dtmf 1 (65657,49,2)

115120.32 5 LsGetNbrsPlayWelcMsg 1

115120.38 5 PlaySoundStop err=0

115120.38 5 [Attend] Number Input 1

115120.39 5 [WhoIs] Playing

115120.39 5 [WhoIs] Playing (Caller.wav)

115120.47 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Caller.wav]

115120.47 5 RunModule PLAY end

115122.73 5 wb(76404800)

115122.79 5 Play End line[5] (id=764048)

115122.79 5 LsPlayMsg EV_PLAY_FINISHED

115122.80 5 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE

115122.81 5 [Caller] Recording

115122.83 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\system\voice\beep1.wav]

115123.03 5 wb(76647200)

115123.09 5 Play End line[5] (id=766472)

115123.09 5 LsRecPlayBeep EV_PLAY_FINISHED

115123.09 5 RVreplace start: [$RV_LINEID.wav]

115123.09 5 RVreplace end: [5.wav]

115123.18 5 RecSoundStart file[C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav] ok

115123.18 5 RecFile[C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115127.11 5 LsRecRecording EV_TIMEOUT_GOTOMODULE

115127.17 5 RecSoundStop ok

115127.19 5 [Office] Number Input

115127.19 5 [Office] Playing (Staff.wav)

115127.23 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Staff.wav]

115133.80 5 dtmf 1 (65657,49,2)

115133.80 5 LsGetNbrsPlayWelcMsg 1

115133.85 5 PlaySoundStop err=0

115133.85 5 [Office] Number Input 1

115134.26 5 LsXferStart EV_TIMEOUT_READYTOBEGINTRANSFER

115134.27 5 [Miriam] Announced Transfer to 111 (Generate)

115134.29 5 tapic lineGenerateDigits(65657,!,0) => 0

115134.99 5 generate LINEGENERATETERM_DONE

115135.99 5 LsXferStart EV_TAPI_GENERATE

115137.00 5 LsXferPlayVts EV_HOOKFLASHFINISHED

115137.05 5 tapic lineGenerateDigits(65657,111,0) => 0

115137.50 5 generate LINEGENERATETERM_DONE

115138.45 5 LsXferPlayVts EV_TAPI_GENERATE

115138.45 5 RVreplace start: [Announce.wav, $RV[Caller]]

115138.45 5 RVreplace end: [Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115138.46 5 [Miriam] Playing (Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav)

115138.51 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Announce.wav,C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115144.05 5 wb(78212401)

115144.11 5 Play End line[5] (id=782124)

115144.11 5 LsXferPlayAnn EV_PLAY_FINISHED

115144.11 5 LsXferPlayAnn EV_TIMEOUT_REPLAYMSG

115144.11 5 RVreplace start: [Announce.wav, $RV[Caller]]

115144.13 5 RVreplace end: [Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115144.13 5 [Miriam] Playing (Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav)

115144.15 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Announce.wav,C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115149.73 5 wb(78778201)

115149.78 5 Play End line[5] (id=787782)

115149.79 5 LsXferPlayAnn EV_PLAY_FINISHED

115149.79 5 LsXferPlayAnn EV_TIMEOUT_REPLAYMSG

115149.79 5 RVreplace start: [Announce.wav, $RV[Caller]]

115149.79 5 RVreplace end: [Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115149.80 5 [Miriam] Playing (Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav)

115149.82 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Announce.wav,C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115155.37 5 wb(79346001)

115155.44 5 Play End line[5] (id=793460)

115155.45 5 LsXferPlayAnn EV_PLAY_FINISHED

115155.45 5 LsXferPlayAnn EV_TIMEOUT_REPLAYMSG

115155.45 5 RVreplace start: [Announce.wav, $RV[Caller]]

115155.45 5 RVreplace end: [Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115155.46 5 [Miriam] Playing (Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav)

115155.50 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Announce.wav,C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115201.06 5 wb(79911901)

115201.12 5 Play End line[5] (id=799119)

115201.12 5 LsXferPlayAnn EV_PLAY_FINISHED

115201.12 5 LsXferPlayAnn EV_TIMEOUT_REPLAYMSG

115201.12 5 RVreplace start: [Announce.wav, $RV[Caller]]

115201.13 5 RVreplace end: [Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115201.13 5 [Miriam] Playing (Announce.wav, C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav)

115201.17 5 PlaySoundStart ok [C:\Program Files\VoiceGuide\Scripts\Voicemail\Announce.wav,C:\Program Files\VoiceGuide\Scripts\Voicemail\5.wav]

115206.73 5 Play End line[5] (id=804787)

115206.73 5 LsXferPlayAnn EV_PLAY_FINISHED

115206.75 5 LsXferPlayAnn EV_PLAY_FINISHED

115206.77 5 tapic lineGenerateDigits(65657,!,0) => 0

115207.46 5 generate LINEGENERATETERM_DONE

115208.40 5 LsXferPlayAnn EV_TAPI_GENERATE

115208.40 5 PlayFinishCause=CallRejected

115208.41 5 voicemail used: Scripted

115208.41 5 stack idx=1

115208.41 0 LoadedVgs_Find C:\Program Files\VoiceGuide\system\vm\VmLm.vgs at idx=2

115208.41 5 Going down Fail path (iXferType=1)

115208.42 5 [VmLmStart] Running vb script:[VmLmHangup.vgs]

115219.21 0 LoadedVgs_Find C:\Program Files\VoiceGuide\system\vm\VmLmHangup.vgs at idx=1

115219.21 5 tapi Reply (LineEvReply) ok 65913 0

115219.23 5 [VmLmHangup_ForwardLastMsg] Running VB Script...

115219.23 5 RVreplace start (strlen>500)

115219.38 5 Run Script waiting... (shellid=1448, process=1652)

115219.47 5 vm RV[VmLmRec_RecLen100ms] not set.

115219.48 5 cl Vm_Event 5, FORWARD, 01, C:\Program Files\VoiceGuide\data\VmSave\01_0219115212_1_5__.wav, ,

115219.48 5 vm frwrd vmb 01

115219.49 5 vm frwrd vmb 01 end

115219.49 5 cl Vm_Event 5, MSG_NEW, 01, , ,

115219.55 0 DialListDB_AddCall '6311', 0, 0, 0, , 8, 1, , MSGLAMP, , , 2, 0, 0, ESCALATION:

115219.64 5 VBScript started [wscript "C:\Program Files\VoiceGuide\data\vbs_5_vm_msglamp_on_040219115219.vbs" //I //T:3600]

115219.64 5 vm msglamp_on ok

115219.66 5 cl Run_ResultReturn >>success<<

115219.66 5 path {success} not found

115219.66 5 Hanging up call... [Run_ResultReturn - no path match (diff vgm)]

115219.68 5 HangupCall_Common Mode=AfterHangupRun

115219.68 5 ReinitTelephony due to IDLE

115219.70 5 tapic lineDeallocateCall(MainCall:65657) 0

115219.95 5 lineOpen(5) => 0

115219.96 5 Waiting for a call...

115219.96 5 LineHandle=65794

115220.29 0 dial callque update id=20 next call time 0402191154

115220.32 0 dial callque DB update returned

115220.32 8 dial found entry: tel[6311] ann[NONE] vgs[MSGLAMP] am[NONE]

115220.35 8 Dialing: 6311

115220.35 8 MakeCall => 66511

115220.35 8 TapiCbTrigSet 66511 7002

115221.32 8 tapi Reply (LineEvReply) ok 66511 0

115221.32 8 TapiCbTrigClear

115221.33 8 linedevstate 2048 0 0

115221.33 8 callstate DIALTONE 66477 0 0

115221.33 8 callstate DIALING 66477 0 0

115221.34 8 callstate PROCEEDING 66477 0 0

115221.34 8 callinfo CALLEDID

115221.34 8 callinfo REASON

115221.40 8 Lev_CallerID [0000,]

115221.40 8 callinfo ORIGIN

115230.31 8 LsWaitAfterDialingOut EV_TIMEOUT_MAKECALL_NORESPONSE

115230.31 0 dial callque delete id=20

115230.32 8 Hanging up call... [EV_TIMEOUT_MAKECALL_NORESPONSE]

115230.32 8 RecSoundStop ok

115230.32 8 PlaySoundStop err=0

115230.33 8 Waiting for a call...

115230.33 8 fnHangupCall end

115230.43 8 linedevstate 2048 0 0

115230.44 8 callstate IDLE 66477 0 0

115230.44 8 WorkingMode@Idle=

115230.44 8 LineState(iLineId).iVgsIdx = 0

115230.45 8 tapi Reply (LineEvReply) ok 65691 0

115231.43 8 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE

115231.43 8 ReinitTelephony due to IDLE

115231.44 8 tapic lineDeallocateCall(MainCall:66477) 0

115231.72 8 lineOpen(8) => 0

115231.72 8 Waiting for a call...

115231.72 8 LineHandle=65674

[SupportTeam]

I'll pass the bug report on.

 

In the meantime as a workaround you can set the global default announced transfers/conferences timeout VG.INI file,

section: [PBX]

entry: AnnouncedTransfersMaxWaitTime

[SupportTeam]

Please update your v5.1.8 install with attached .exe

 

This will allow you to set the timeout in the "Announced Call Transfer" using a timeout path.

 

Please note that once timeout is reached the script will go down the "Fail" path - this should be OK in practice as the Fail and Timeout paths will usually be set to go to same destination (as is set in your script).

VgMulti_5.1.8003.zip