sddev Report post Posted 09/09/2005 11:15 PM Having problem when multiple numbers queued up rapidly in sequence. This is a routine occurance. Log files indicate following First number dialed, detects incoming call and terminates (There was no incoming call) Second number declares no answer after only 4 seconds (dialed number never rang) Third number answered and executed script perfectly Subsequent numbers queued are repeats resulting from parsing of Failed call log files. Results for these entries are similar to the initial calls. If I queue one number at a time, it works well. I have one port of Dialogic D/4PCIUF card connected to a phone line. I would think it should handle each queued call in sequence properly. Can you provide any insight. As always, your help is appreciated. 0909vgm.txt 135713.79 0 --------------------------------------------------- 135713.83 0 VoiceGuide for Dialogic 135713.83 0 v6.0.2116 135713.83 0 --------------------------------------------------- 135713.83 0 rv global loaded >>><<< 135713.83 0 Inband Signaling Config file not specified 135714.04 0 init ApiMode=[DialogicGC] 135714.07 0 vm loading VMB data begin 135714.08 0 vm loading VMB data - about to delete old entries 135714.16 0 vm loading VMB data - sorting/ordering 135714.16 0 vm loading VMB data end 135714.16 0 dial DbConnectString_ADO is blank 135714.18 0 init DB Workspaces 135714.19 0 init Voicemail LoadIniSettings 135714.19 0 init read Config.xml start 135714.21 0 dxxxB1C1 dxxxB1C1 C:\vg\statreq.vgs allowDialout=1 135714.21 0 init read Config.xml end 135714.22 0 cl Dialer_OutDialQueAdd 9,5037447, 0, 0, 0, , , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{7}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, , 135714.22 0 dial DialListDB_AddCall '9,5037447', 0, 0, 0, MoTuWeThFrSaSu, , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{7}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, ESCALATION: 135714.22 0 init LineOpenDialogic dxxxB1C1 => [1] 135714.22 1 iIvrDev=1 135714.36 1 init strDlgcDevName_VoiceResource=[dxxxB1C1] (LoadXMLConfig) 135714.36 0 load start [C:\vg\statreq.vgs] 135714.36 0 MemStructVgs_ClearVgs [1] 135714.36 0 load end 135714.36 0 init hires timer setup start 135714.36 0 init hires timer setup end 135714.36 1 state Waiting for a call... 135714.36 1 timer set 2 EV_TIMEOUT_ATERIDLE_ALLOWOUT 135714.36 0 init lineids: 0-1 135714.36 0 init route channels start 135714.43 0 init route channels => [] 135714.43 0 init no dti channels opened. Not opening signaling channel. 135714.43 0 init iLineDevicesControlled=1 135714.43 0 init main form load end 135714.43 1 event callstate IDLE 1 0 0 135714.43 1 WorkingMode@Idle= 135714.43 1 timer clear 135714.43 1 LineState(iLineId).iVgsIdx = 0 135714.43 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 135714.43 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,, 135714.43 1 event TDX_SETHOOK, code=135, state=0 135714.43 0 cl Dialer_OutDialQueAdd 9,5037447, 0, 0, 0, , , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{8}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, , 135714.43 0 dial DialListDB_AddCall '9,5037447', 0, 0, 0, MoTuWeThFrSaSu, , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{8}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, ESCALATION: 135714.46 0 cl Dialer_OutDialQueAdd 9,5037447, 0, 0, 0, , , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{9}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, , 135714.46 0 dial DialListDB_AddCall '9,5037447', 0, 0, 0, MoTuWeThFrSaSu, , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{9}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt}[DIAL_EXT]{307}, 30, 0, 0, ESCALATION: 135714.54 1 event callstate OFFERING 1 0 0 135714.55 1 script interpretor: VgMulti v6.0.2116 135714.55 1 fired OFFERING event to listeners 135714.55 1 iIvrDev=1 135714.55 1 set linestate CID vars 135714.55 1 call AnswerTheCallIfAllowed 135714.55 1 do not answer call - still awaiting re-init after last call 135714.55 1 tw ring 1 135714.55 1 ring time since last ring (ms): 0 135714.55 1 do not answer call - still awaiting re-init after last call 135714.55 1 tw DialogicEvent 134,TDX_CST,0,0,0,DE_RINGS,ET_RON, 135714.55 1 event TDX_CST, code=134, state=0 135715.46 1 timer fired EV_TIMEOUT_TIMETOREINITLINE 135715.46 1 event EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=0 135715.46 1 init atidle reinit : start 135715.46 1 init atidle reinit iConferenceOtherLegLid=-1 135715.46 1 init atidle reinit : reset all LineState variables 135715.46 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 135715.46 1 state Waiting for a call... 135715.55 1 tw DialogicEvent 134,TDX_CST,193,0,0,DE_TONEON,TID_DIAL_LCL, 135715.55 1 event DIALTONE, code=134, state=0 135715.55 1 event TID_DIAL_LCL, code=134, state=0 135715.55 1 event TDX_CST, code=134, state=0 135715.55 1 tw DialogicEvent 134,TDX_CST,194,0,0,DE_TONEON,TID_DIAL_INTL, 135715.55 1 event DIALTONE, code=134, state=0 135715.55 1 event TID_DIAL_INTL, code=134, state=0 135715.55 1 event TDX_CST, code=134, state=0 135715.55 1 tw DialogicEvent 134,TDX_CST,195,0,0,DE_TONEON,TID_DIAL_XTRA, 135715.55 1 event DIALTONE, code=134, state=0 135715.55 1 event TID_DIAL_XTRA, code=134, state=0 135715.55 1 event TDX_CST, code=134, state=0 135715.79 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 135715.79 1 event EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=0 135716.16 1 dial [339,9,5037447,0,0,2359,MoTuWeThFrSaSu,[,,],2,NONE,c:\vg\notify.vgp,ignore,[LOG_ID]{7}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}[DIAL_EXT]{307},30,0,0,,none] (search started at 135716.16) 135716.16 1 dial DialOnLine start tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] rv[[LOG_ID]{7}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}[DIAL_EXT]{307}] 135716.16 0 dial callque update id=339: NextCallTime=0509091359, RetriesLeft=-1 135716.16 0 dial callque DB update returned (MSAccess) 135716.16 1 rv clear all 135716.16 1 rv add [LOG_ID]{7} 135716.16 1 rv add [uSER_PIN]{210} 135716.16 1 rv add [TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt} 135716.16 1 rv add [DIAL_EXT]{307} 135716.16 1 rv add [OutDial_RetriesLeft]{0} 135716.16 1 dial making call tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] OnHangup[] rv[[LOG_ID]{7}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}[DIAL_EXT]{307}[OutDial_RetriesLeft]{0}] 135716.16 1 script interpretor: VgMulti v6.0.2116 135716.16 1 state Dialing 9,5037447 135716.16 1 lineMakeCall=>[inbound call arriving on this line. Outgoing call canceled.] 135716.16 1 DialoutProcessFailedDial start sCalledFrom=[DialOnLine lineMakeCall=>[inbound call arriving on this line. Outgoing call canceled.]] 135716.18 0 dial callque delete id=339 (MSAccess) 135716.18 1 rvns add [OutDial_Result]{Uncontactable_NoAnswer} 135716.18 1 timer clear 135716.18 1 dial no escalation info 135716.18 1 rv add [Hangup Time]{9/9/2005 1:57:16 PM} 135716.18 1 state Hanging up call... [lineMakeCall=>[inbound call arriving on this line. Outgoing call canceled.]] 135716.18 1 HangupCall_Common hCall=0 135716.18 1 rec RecSoundStop ok 135716.18 1 play PlaySoundStop ok 135716.18 1 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 135716.18 1 HangupCall : lCallHandle& = 0 - lets close and open line 135716.18 1 state Waiting for a call... 135716.18 1 fnHangupCall end 135716.18 1 event callstate IDLE 1 0 0 135716.18 1 WorkingMode@Idle= 135716.18 1 timer clear 135716.18 1 LineState(iLineId).iVgsIdx = 0 135716.18 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 135716.18 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_ONHOOK,, 135716.18 1 event TDX_SETHOOK, code=135, state=900 135716.18 1 LsAwaitingCalls EV_UNKNOWN_135 135717.21 1 timer fired EV_TIMEOUT_TIMETOREINITLINE 135717.21 1 event EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=900 135717.21 1 init atidle reinit : start 135717.21 1 init atidle reinit iConferenceOtherLegLid=-1 135717.21 1 init atidle reinit : reset all LineState variables 135717.21 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 135717.21 1 state Waiting for a call... 135717.54 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 135717.54 1 event EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=900 135718.18 1 dial [340,9,5037447,0,0,2359,MoTuWeThFrSaSu,[,,],2,NONE,c:\vg\notify.vgp,ignore,[LOG_ID]{8}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}[DIAL_EXT]{307},30,0,0,,none] (search started at 135718.18) 135718.18 1 dial DialOnLine start tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] rv[[LOG_ID]{8}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}[DIAL_EXT]{307}] 135718.18 0 dial callque update id=340: NextCallTime=0509091359, RetriesLeft=-1 135718.18 0 dial callque DB update returned (MSAccess) 135718.18 1 rv clear all 135718.18 1 rv add [LOG_ID]{8} 135718.18 1 rv add [uSER_PIN]{210} 135718.18 1 rv add [TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt} 135718.18 1 rv add [DIAL_EXT]{307} 135718.18 1 rv add [OutDial_RetriesLeft]{0} 135718.18 1 dial making call tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] OnHangup[] rv[[LOG_ID]{8}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}[DIAL_EXT]{307}[OutDial_RetriesLeft]{0}] 135718.18 1 script interpretor: VgMulti v6.0.2116 135718.18 1 state Dialing 9,5037447 135718.19 1 lineMakeCall=>[] 135718.19 1 timer set 30 EV_TIMEOUT_MAKECALL_NORESPONSE 135718.74 1 tw DialogicEvent 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND, 135718.74 1 event TDX_SETHOOK, code=135, state=5200 135718.74 1 LsWaitAfterDialingOut : 135,TDX_SETHOOK,0,0,0,DX_OFFHOOK,CALL_OUTBOUND, 135719.66 0 cl Dialer_OutDialQueAdd 307, 0, 0, 0, , , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{10}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}, 30, 0, 0, , 135719.66 0 dial DialListDB_AddCall '307', 0, 0, 0, MoTuWeThFrSaSu, , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{10}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21421_3_tts.txt}, 30, 0, 0, ESCALATION: 135722.54 1 event callstate IDLE 1 0 0 135722.54 1 WorkingMode@Idle= 135722.54 1 DialoutProcessFailedDial start sCalledFrom=[LineEvCallState-LINECALLSTATE_IDLE] 135722.54 0 dial callque delete id=340 (MSAccess) 135722.55 1 rvns add [OutDial_Result]{Uncontactable_NoAnswer} 135722.55 1 timer clear 135722.55 1 dial no escalation info 135722.55 1 timer clear 135722.55 1 LineState(iLineId).iVgsIdx = 0 135722.55 1 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 135723.55 1 timer fired EV_TIMEOUT_TIMETOREINITLINE 135723.55 1 event EV_TIMEOUT_TIMETOREINITLINE, code=9008, state=5200 135723.55 1 init atidle reinit : start 135723.55 1 init atidle reinit iConferenceOtherLegLid=-1 135723.55 1 init atidle reinit : reset all LineState variables 135723.55 1 timer set 0.3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 135723.55 1 state Waiting for a call... 135723.88 1 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 135723.88 1 event EV_TIMEOUT_ATERIDLE_ALLOWOUT, code=9013, state=5200 135724.19 1 dial [341,9,5037447,0,0,2359,MoTuWeThFrSaSu,[,,],2,NONE,c:\vg\notify.vgp,ignore,[LOG_ID]{9}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt}[DIAL_EXT]{307},30,0,0,,none] (search started at 135724.19) 135724.19 1 dial DialOnLine start tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] rv[[LOG_ID]{9}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt}[DIAL_EXT]{307}] 135724.19 0 dial callque update id=341: NextCallTime=0509091359, RetriesLeft=-1 135724.19 0 dial callque DB update returned (MSAccess) 135724.19 1 rv clear all 135724.19 1 rv add [LOG_ID]{9} 135724.19 1 rv add [uSER_PIN]{210} 135724.19 1 rv add [TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt} 135724.19 1 rv add [DIAL_EXT]{307} 135724.19 1 rv add [OutDial_RetriesLeft]{0} 135724.19 1 dial making call tel[9,5037447] ann[NONE] vgs[c:\vg\notify.vgp] am[ignore] OnHangup[] rv[[LOG_ID]{9}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21423_3_tts.txt}[DIAL_EXT]{307}[OutDial_RetriesLeft]{0}] 135724.19 1 script interpretor: VgMulti v6.0.2116 135724.19 1 state Dialing 9,5037447 135724.19 1 lineMakeCall=>[] 135724.21 1 timer set 30 EV_TIMEOUT_MAKECALL_NORESPONSE 135724.80 0 cl Dialer_OutDialQueAdd 307, 0, 0, 0, , , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{11}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}, 30, 0, 0, , 135724.80 0 dial DialListDB_AddCall '307', 0, 0, 0, MoTuWeThFrSaSu, , 2, , c:\vg\notify.vgp, ignore, [LOG_ID]{11}[uSER_PIN]{210}[TTS_PATH]{C:\Program Files\VoiceGuide\Data\21420_1_tts.txt}, 30, 0, 0, ESCALATION: 135733.71 1 tw DialogicEvent 134,TDX_CST,199,0,0,DE_TONEON,TID_RNGBK1, 135733.71 1 event RINGBACK, code=134, state=5200 135733.71 1 LsWaitAfterDialingOut : 134,RINGBACK,199,0,0,DE_TONEON,TID_RNGBK1, 135733.71 1 event TID_RNGBK1, code=134, state=5200 135733.71 1 LsWaitAfterDialingOut : 134,TID_RNGBK1,199,0,0,DE_TONEON,TID_RNGBK1, 135733.71 1 event TDX_CST, code=134, state=5200 135733.71 1 LsWaitAfterDialingOut : 134,TDX_CST,199,0,0,DE_TONEON,TID_RNGBK1, 135737.60 1 tw DialogicEvent 133,TDX_CALLP,10,4,0,TDX_CALLP,CR_CNCT,CON_PAMD 135737.60 1 event AM, code=133, state=5200 135737.60 1 LsWaitAfterDialingOut : 133,AM,10,4,0,CR_CNCT,CON_PAMD, 135737.60 1 event CONNECT, code=133, state=5200 135737.60 1 LsWaitAfterDialingOut : 133,CONNECT,10,4,0,CR_CNCT,CON_PAMD, 135737.60 1 event CON_PAMD, code=133, state=5200 135737.60 1 LsWaitAfterDialingOut : 133,CON_PAMD,10,4,0,CR_CNCT,CON_PAMD, 135737.60 1 event CR_CNCT, code=133, state=5200 135737.61 1 LsWaitAfterDialingOut : 133,CR_CNCT,10,4,0,CON_PAMD,, 135737.61 1 event TDX_CALLP, code=133, state=5200 135737.61 1 LsWaitAfterDialingOut : 133,TDX_CALLP,10,4,0,CR_CNCT,CON_PAMD, 135737.61 1 StartPlayingAnsweringMachineMessage 10,4,0 135737.61 1 LineEvCallState_OutgoingCallWasAnswered 0,0,0 135737.61 1 init OnCallAnswered_StartScript start 135737.61 1 init StartLoadedVgs_InitLineState start 135737.61 1 rv add [$RV_STARTTIME]{9/9/2005 1:57:37 PM} 135737.61 1 rv add [$RV_DEVICEID]{1} 135737.61 1 rv add [$RV_CIDNAME]{} 135737.61 1 rvns add [PathApp]{C:\Program Files\VoiceGuide\} 135737.61 1 WARN rv ScriptsPath could not be set as LineState(iLineId).iVgsIdx = 0 in StartLoadedVgs_InitLineState 135737.61 1 rv add [$RV_CALLEDNUMBER]{9,5037447} 135737.61 0 dial callque delete id=341 (MSAccess) 135737.61 1 rvns add [OutDial_Result]{Contacted_Human} 135737.61 1 state live person answered. start [c:\vg\notify.vgp] 0909tw.txt 135005.599 AppCleanup completed. 135713.786 ocxfn LogLevels 10,C:\Program Files\VoiceGuide\log\,tw.txt,0, 135713.833 ocxfn LogLevels 10,C:\Program Files\VoiceGuide\log\,tw.txt,0, 135713.927 ------------------------------------------------------------------------------ 135713.927 ktTelControl OCX v2.2.0, Aug 7 2005 17:59:01 [For Dialogic] 135713.927 Initialise at 0909 135713.927 135713.927 ------------------------------------------------------------------------------ 135713.927 dlgc Dialogic boards present: dti:0, dxxx:1 135713.974 fn AppInit_Dialogic 135714.005 dlgc dx_open(dxxxB1) => 1 135714.005 dlgc board type : D/41 135714.005 init config board file [C:\Program Files\VoiceGuide\data\Config.xml] 135714.005 init config board file load start (len=1512) 135714.005 dlgc dx_setparm(1, DXBD_T_IDD, &[15]) => 0, err=0, No error 135714.005 dlgc dx_setparm(1, DXBD_TTDATA, &[10]) => 0, err=0, No error 135714.005 dlgc dx_setparm(1, DXBD_PAUSETM, &[200]) => 0, err=0, No error 135714.005 dlgc dx_setparm(1, DXBD_FLASHTM, &[10]) => 0, err=0, No error 135714.005 dlgc dx_setparm(1, DXBD_S_BNC, &[4]) => 0, err=0, No error 135714.021 dlgc dx_setparm(1, DXBD_PAUSETM, &[450]) => 0, err=0, No error 135714.021 init config board file load end 135714.021 dlgc dx_open(dxxxB1C1) => 2 135714.021 dlgc dxxxB1C1 id=0x6021, family=2, devmode=0, network=3(Analog), bus=0, encode=0 135714.021 dlgc dx_getfeaturelist(2, &FT) => 0 135714.021 ft_play=0x57ff, ft_record=0x5fff, ft_tone=0x7, ft_e2p_brd_cfg=0x6, ft_fax=0x30 135714.021 ft_front_end=0x5, ft_misc=0x1, ft_rfu=0x1160da, ft_send=0x19, ft_receive=0x18, ft_rfu=0x57ff 0 5fff 0 0 0 135714.021 dlgc dx_close(1) ok (board dxxxB1) 135714.021 init enable event processing 135714.036 ocxfn SetParam(0,VgIniFilename,c:\program files\voiceguide\VG.INI,0) 135714.036 SetParam VgIniFilename 135714.036 eng CtEventPolling thread start 135714.067 ocxfn GetLineCount() => 0 135714.224 ocxfn LineOpenDialogic(dxxx=dxxxB1C1, dti=dxxxB1C1, protocol=pdk_na_an_io, opt=, opt=0) 135714.224 init opening straight analog line 135714.224 dlgc dx_open(dxxxB1C1) => 1 135714.224 dlgc initial lines state is offhook. will return line to offhook on exit. 135714.224 001 init tones not set by TSF File as EDX_FEATUREDISABLED (TSF feature is disabled in Configuration Manager Advanced Options) dti=[dxxxB1C1] 135714.224 001 init config line file not specified. default settings will remain. 135714.224 init LineOpenDialogic ok => [1] 135714.224 ocxfn SetParam(1,ConfigLine_Filename,C:\Program Files\VoiceGuide\data\ConfigLine.xml,0) 135714.224 SetParam ConfigLine_Filename begin 135714.224 001 init tones not set by TSF File as EDX_FEATUREDISABLED (TSF feature is disabled in Configuration Manager Advanced Options) dti=[dxxxB1C1] 135714.224 001 tone section start A 135714.239 001 tone section start B 135714.239 001 init config line as per [C:\Program Files\VoiceGuide\data\ConfigLine.xml] 135714.239 001 init config line file load start (len=5250) 135714.239 tone ch id:TID_BUSY1:253, f1:500:200, f2:0:0, cad:55:40,55:40, count:4 135714.239 tone an id:TID_BUSY1:191, f1:500:200, cad:55:40:55:40, count:4 135714.255 tone ch id:TID_BUSY2:255, f1:500:200, f2:525:175, cad:55:40,55:40, count:4 135714.255 tone an id:TID_BUSY2:192, f1:500:200, f2:525:175, cad:55:40:55:40, count:4 135714.255 tone ch id:TID_DIAL_LCL:250, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 135714.255 tone an id:TID_DIAL_LCL:193, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 135714.271 tone ch id:TID_DIAL_INTL:251, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 135714.271 tone an id:TID_DIAL_INTL:194, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 135714.271 tone ch id:TID_DIAL_XTRA:252, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 135714.271 tone an id:TID_DIAL_XTRA:195, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 135714.286 tone ch id:TID_DISCONNECT:257, f1:500:200, f2:525:175, cad:55:40,55:40, count:4 135714.286 tone an id:TID_DISCONNECT:196, f1:500:200, f2:525:175, cad:55:40:55:40, count:4 135714.302 tone ch id:TID_FAX1:258, f1:2150:50, f2:0:0, cad:25:25,0:0, count:1 135714.302 tone an id:TID_FAX1:197, f1:2150:50, cad:25:25:0:0, count:1 135714.302 tone ch id:TID_FAX2:259, f1:1100:50, f2:0:0, cad:25:25,0:0, count:1 135714.302 tone an id:TID_FAX2:198, f1:1100:50, cad:25:25:0:0, count:1 135714.317 tone ch id:TID_RNGBK1:254, f1:438:138, f2:0:0, cad:130:105,400:200, count:1 135714.317 tone an id:TID_RNGBK1:199, f1:438:138, cad:130:105:400:200, count:1 135714.317 tone ch id:TID_RNGBK2:256, f1:438:138, f2:438:138, cad:130:105,400:200, count:1 135714.317 tone an id:TID_RNGBK2:200, f1:438:138, f2:438:138, cad:130:105:400:200, count:1 135714.333 tone an id:DISCONNECT_PBX:201, f1:500:50, cad:47:5:47:5, count:2 135714.349 tone an id:DISCONNECT_Telstra:202, f1:425:50, cad:37:5:37:5, count:2 135714.349 001 xml parse error at line 211:junk after document element 135714.349 001 init config line file load end 135714.364 SetParam ConfigLine_Filename end 135714.364 ocxfn LineOpenDialogic(dxxx=, dti=, protocol=, opt=route, opt=0) 135714.411 dlgc ln823 dxxxB1C1 [106]:[invalid Switching Handler Bus Mode] 135714.411 001 ev TDX_SETHOOK (SetHook Completed) 135714.427 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 135714.427 ocxev DoFireDialogic(dwIdx=1, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 135714.536 001 ev TDX_CST (CST Event Received) 135714.536 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1ca008, hli->chdev=1, hli->linedev=1) 135714.536 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 135714.536 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 135714.536 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 135714.536 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 135714.536 dlgc caller id frame type: UNKNOWN [0x00] 135714.536 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 135714.552 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 135714.552 ocxev DoFireDialogic(dwIdx=4, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 135715.458 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 135715.552 001 ev TDX_CST (CST Event Received) 135715.552 001 ev TDX_CST DE_TONEON data=193 135715.552 ocxev DoFireDialogic(dwIdx=5, 1, 134, [TDX_CST], 193, 0, 0, [DE_TONEON], [TID_DIAL_LCL], []) 135715.552 001 ev TDX_CST (CST Event Received) 135715.552 001 ev TDX_CST DE_TONEON data=194 135715.552 ocxev DoFireDialogic(dwIdx=6, 1, 134, [TDX_CST], 194, 0, 0, [DE_TONEON], [TID_DIAL_INTL], []) 135715.552 001 ev TDX_CST (CST Event Received) 135715.552 001 ev TDX_CST DE_TONEON data=195 135715.552 ocxev DoFireDialogic(dwIdx=7, 1, 134, [TDX_CST], 195, 0, 0, [DE_TONEON], [TID_DIAL_XTRA], []) 135716.161 001 ocxfn LineMakeCall(1, 0, 9,5037447, DX_PAMDOPTEN, 30, 0, 0, , ) 135716.177 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 135716.177 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 135716.177 001 ocxfn LineDrop(sLineId=1, sOpt=0) 135716.177 001 ev TDX_SETHOOK (SetHook Completed) 135716.177 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 135716.177 ocxev DoFireDialogic(dwIdx=9, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 135717.208 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 135718.177 001 ocxfn LineMakeCall(1, 0, 9,5037447, DX_PAMDOPTEN, 30, 0, 0, , ) 135718.192 LineMakeCall using analog dialing as dti=[dxxxB1C1] 135718.192 dx_sethook(1, DX_OFFHOOK) ok 135718.739 001 ev TDX_SETHOOK (SetHook Completed) 135718.739 ocxev DoFireDialogic(dwIdx=10, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_OFFHOOK], [CALL_OUTBOUND], []) 135718.739 dx_dial([9,5037447], DX_CALLP) ok 135722.536 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1ca008) 135722.536 ev001 OnFireTimer_RingGoesAway (hli=0x1ca008) 135722.536 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 135723.552 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 135724.192 001 ocxfn LineMakeCall(1, 0, 9,5037447, DX_PAMDOPTEN, 30, 0, 0, , ) 135724.192 LineMakeCall using analog dialing as dti=[dxxxB1C1] 135724.192 dx_sethook(1, DX_OFFHOOK) => -1 135733.708 001 ev TDX_CST (CST Event Received) 135733.708 001 ev TDX_CST DE_TONEON data=199 135733.708 ocxev DoFireDialogic(dwIdx=12, 1, 134, [TDX_CST], 199, 0, 0, [DE_TONEON], [TID_RNGBK1], []) 135737.599 001 ev TDX_CALLP (Call Progress Completed) 135737.599 001 TDX_CALLP CR_CNCT called line was connected 135737.599 001 TDX_CALLP CR_CNCT CON_PAMD connection due to Positive Answering Machine Detection 135737.599 ocxev DoFireDialogic(dwIdx=13, 1, 133, [TDX_CALLP], 10, 4, 0, [TDX_CALLP], [CR_CNCT], [CON_PAMD]) 135737.661 001 ev TDX_CST (CST Event Received) 135737.661 001 ev TDX_CST DE_LCON data=2317 135737.661 ocxev DoFireDialogic(dwIdx=14, 1, 134, [TDX_CST], 2317, 0, 0, [DE_LCON], [], []) 135737.692 001 ocxfn PlayStart(hLine=1, strSoundFile=307, lPlayId=182437(0x2c8a5), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 135737.692 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 135737.692 ocxfn Dial(1,182437,[307],,0,0,,) 135737.692 dx_dial(1,[307],off) as ca_intflg=2 135737.692 dx_dial(1,307) ok 135737.692 play .Dial returned (playing DTMF tones) 135738.286 001 ev TDX_DIAL (Dial Completed) Share this post Link to post
SupportTeam Report post Posted 09/11/2005 01:20 AM Trace shows that an incoming call was arriving into this system at about the same time as VoiceGuide was trying to make an outgoing call: 135714.54 1 event callstate OFFERING 1 0 0 The incoming call resulted in VoiceGuide not handling the outgoing calls properly. The attached .exe will not make any outgoing calls if there are any incoming calls at the same time. Please place the attached .exe in VoiceGuide's directory overwriting previous file. VgMulti_6.0.2202.zip Share this post Link to post
sddev Report post Posted 09/12/2005 10:06 PM I appreciate your response and efforts to assist. I have installed the new .exe you sent. Unfortunately, my problem persists. I realize that the trace indicates an incoming call. The issue is that there was no incoming call. This sequence of events is encountered every time I queue multiple numbers at one time. If I only queue up one number, there is never an inbound call detected. Is it possible that this is because the same number is being dialed repeatedly? Is it possible caused by the fact that start time is always 0 indicating the need to dial immediately? I am sure I can work around this by monitoring the results files. In fact this distraction will prove beneficial in developing logic to handle the eventuality of incoming calls actually occuring when I am trying to dial out. I would like to get at the root of the current problem, though, so that erroneous results can be avoided. Thank you. Share this post Link to post
SupportTeam Report post Posted 09/13/2005 02:17 AM Please post the MMDDtw.txt trace capturing what happens. Is it possible that this is because the same number is being dialed repeatedly? No, shouldn't affect things. Is it possible caused by the fact that start time is always 0 indicating the need to dial immediately? No, shouldn't affect things. Share this post Link to post
sddev Report post Posted 09/13/2005 02:37 PM mmddtw.txt trace is attached to original post. Do you want me to recreate and attach again? Share this post Link to post
SupportTeam Report post Posted 09/13/2005 10:02 PM Please post the trace when using the v6.0.2202 of VG. (attached previously) Share this post Link to post
sddev Report post Posted 09/14/2005 01:01 AM Sorry I did not understand your initial request. Hope this helps. Thanks. 090753.271 ocxfn LogLevels 10,C:\Program Files\VoiceGuide\log\,tw.txt,0, 090753.302 ocxfn LogLevels 10,C:\Program Files\VoiceGuide\log\,tw.txt,0, 090753.349 ------------------------------------------------------------------------------ 090753.349 ktTelControl OCX v2.2.0, Aug 7 2005 17:59:01 [For Dialogic] 090753.349 Initialise at 0912 090753.349 090753.349 ------------------------------------------------------------------------------ 090753.349 dlgc Dialogic boards present: dti:0, dxxx:1 090753.396 fn AppInit_Dialogic 090753.427 dlgc dx_open(dxxxB1) => 1 090753.427 dlgc board type : D/41 090753.427 init config board file [C:\Program Files\VoiceGuide\data\Config.xml] 090753.427 init config board file load start (len=1512) 090753.427 dlgc dx_setparm(1, DXBD_T_IDD, &[15]) => 0, err=0, No error 090753.427 dlgc dx_setparm(1, DXBD_TTDATA, &[10]) => 0, err=0, No error 090753.427 dlgc dx_setparm(1, DXBD_PAUSETM, &[200]) => 0, err=0, No error 090753.427 dlgc dx_setparm(1, DXBD_FLASHTM, &[10]) => 0, err=0, No error 090753.427 dlgc dx_setparm(1, DXBD_S_BNC, &[4]) => 0, err=0, No error 090753.427 dlgc dx_setparm(1, DXBD_PAUSETM, &[450]) => 0, err=0, No error 090753.427 init config board file load end 090753.442 dlgc dx_open(dxxxB1C1) => 2 090753.442 dlgc dxxxB1C1 id=0x6021, family=2, devmode=0, network=3(Analog), bus=0, encode=0 090753.442 dlgc dx_getfeaturelist(2, &FT) => 0 090753.442 ft_play=0x57ff, ft_record=0x5fff, ft_tone=0x7, ft_e2p_brd_cfg=0x6, ft_fax=0x30 090753.442 ft_front_end=0x5, ft_misc=0x1, ft_rfu=0x1160da, ft_send=0x19, ft_receive=0x18, ft_rfu=0x57ff 0 5fff 0 0 0 090753.442 dlgc dx_close(1) ok (board dxxxB1) 090753.442 init enable event processing 090753.458 eng CtEventPolling thread start 090753.458 ocxfn SetParam(0,VgIniFilename,c:\program files\voiceguide\VG.INI,0) 090753.458 SetParam VgIniFilename 090753.489 ocxfn GetLineCount() => 0 090753.646 ocxfn LineOpenDialogic(dxxx=dxxxB1C1, dti=dxxxB1C1, protocol=pdk_na_an_io, opt=, opt=0) 090753.646 init opening straight analog line 090753.646 dlgc dx_open(dxxxB1C1) => 1 090753.646 dlgc initial lines state is offhook. will return line to offhook on exit. 090753.646 001 init tones not set by TSF File as EDX_FEATUREDISABLED (TSF feature is disabled in Configuration Manager Advanced Options) dti=[dxxxB1C1] 090753.646 001 init config line file not specified. default settings will remain. 090753.646 init LineOpenDialogic ok => [1] 090753.646 ocxfn SetParam(1,ConfigLine_Filename,C:\Program Files\VoiceGuide\data\ConfigLine.xml,0) 090753.646 SetParam ConfigLine_Filename begin 090753.646 001 init tones not set by TSF File as EDX_FEATUREDISABLED (TSF feature is disabled in Configuration Manager Advanced Options) dti=[dxxxB1C1] 090753.646 001 tone section start A 090753.661 001 tone section start B 090753.661 001 init config line as per [C:\Program Files\VoiceGuide\data\ConfigLine.xml] 090753.661 001 init config line file load start (len=5250) 090753.661 tone ch id:TID_BUSY1:253, f1:500:200, f2:0:0, cad:55:40,55:40, count:4 090753.661 tone an id:TID_BUSY1:191, f1:500:200, cad:55:40:55:40, count:4 090753.661 tone ch id:TID_BUSY2:255, f1:500:200, f2:525:175, cad:55:40,55:40, count:4 090753.661 tone an id:TID_BUSY2:192, f1:500:200, f2:525:175, cad:55:40:55:40, count:4 090753.677 tone ch id:TID_DIAL_LCL:250, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 090753.677 tone an id:TID_DIAL_LCL:193, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 090753.692 tone ch id:TID_DIAL_INTL:251, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 090753.692 tone an id:TID_DIAL_INTL:194, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 090753.692 tone ch id:TID_DIAL_XTRA:252, f1:340:40, f2:440:40, cad:100:0,0:0, count:1 090753.692 tone an id:TID_DIAL_XTRA:195, f1:340:40, f2:440:40, cad:100:0:0:0, count:1 090753.708 tone ch id:TID_DISCONNECT:257, f1:500:200, f2:525:175, cad:55:40,55:40, count:4 090753.708 tone an id:TID_DISCONNECT:196, f1:500:200, f2:525:175, cad:55:40:55:40, count:4 090753.708 tone ch id:TID_FAX1:258, f1:2150:50, f2:0:0, cad:25:25,0:0, count:1 090753.708 tone an id:TID_FAX1:197, f1:2150:50, cad:25:25:0:0, count:1 090753.724 tone ch id:TID_FAX2:259, f1:1100:50, f2:0:0, cad:25:25,0:0, count:1 090753.724 tone an id:TID_FAX2:198, f1:1100:50, cad:25:25:0:0, count:1 090753.739 tone ch id:TID_RNGBK1:254, f1:438:138, f2:0:0, cad:130:105,400:200, count:1 090753.739 tone an id:TID_RNGBK1:199, f1:438:138, cad:130:105:400:200, count:1 090753.739 tone ch id:TID_RNGBK2:256, f1:438:138, f2:438:138, cad:130:105,400:200, count:1 090753.739 tone an id:TID_RNGBK2:200, f1:438:138, f2:438:138, cad:130:105:400:200, count:1 090753.755 tone an id:DISCONNECT_PBX:201, f1:500:50, cad:47:5:47:5, count:2 090753.771 tone an id:DISCONNECT_Telstra:202, f1:425:50, cad:37:5:37:5, count:2 090753.771 001 xml parse error at line 211:junk after document element 090753.771 001 init config line file load end 090753.786 SetParam ConfigLine_Filename end 090753.786 ocxfn LineOpenDialogic(dxxx=, dti=, protocol=, opt=route, opt=0) 090753.833 001 ev TDX_SETHOOK (SetHook Completed) 090753.833 dlgc ln823 dxxxB1C1 [106]:[invalid Switching Handler Bus Mode] 090753.849 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090753.849 ocxev DoFireDialogic(dwIdx=1, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090753.958 001 ev TDX_CST (CST Event Received) 090753.958 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) 090753.958 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 090753.974 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 090753.974 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 090753.974 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 090753.974 dlgc caller id frame type: UNKNOWN [0x00] 090753.974 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 090753.974 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 090753.974 ocxev DoFireDialogic(dwIdx=4, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 090754.880 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 090754.942 001 ev TDX_CST (CST Event Received) 090754.942 001 ev TDX_CST DE_TONEON data=193 090754.942 ocxev DoFireDialogic(dwIdx=5, 1, 134, [TDX_CST], 193, 0, 0, [DE_TONEON], [TID_DIAL_LCL], []) 090754.942 001 ev TDX_CST (CST Event Received) 090754.942 001 ev TDX_CST DE_TONEON data=194 090754.942 ocxev DoFireDialogic(dwIdx=6, 1, 134, [TDX_CST], 194, 0, 0, [DE_TONEON], [TID_DIAL_INTL], []) 090754.942 001 ev TDX_CST (CST Event Received) 090754.942 001 ev TDX_CST DE_TONEON data=195 090754.942 ocxev DoFireDialogic(dwIdx=7, 1, 134, [TDX_CST], 195, 0, 0, [DE_TONEON], [TID_DIAL_XTRA], []) 090801.958 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1c8008) 090801.958 ev001 OnFireTimer_RingGoesAway (hli=0x1c8008) 090801.958 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090802.989 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 090811.755 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 090811.880 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090811.896 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090811.896 001 ocxfn LineDrop(sLineId=1, sOpt=0) 090811.896 001 ev TDX_SETHOOK (SetHook Completed) 090811.911 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090811.942 ocxev DoFireDialogic(dwIdx=10, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090812.989 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 090813.911 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 090813.927 LineMakeCall using analog dialing as dti=[dxxxB1C1] 090813.927 dx_sethook(1, DX_OFFHOOK) ok 090814.458 001 ev TDX_SETHOOK (SetHook Completed) 090814.458 ocxev DoFireDialogic(dwIdx=11, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_OFFHOOK], [CALL_OUTBOUND], []) 090814.474 dx_dial([9,5167778], DX_CALLP) ok 090814.474 001 ev TDX_CST (CST Event Received) 090814.474 001 ev TDX_CST DE_LCON data=2061 090814.474 ocxev DoFireDialogic(dwIdx=12, 1, 134, [TDX_CST], 2061, 0, 0, [DE_LCON], [], []) 090828.989 001 ev TDX_CST (CST Event Received) 090828.989 001 ev TDX_CST DE_TONEON data=199 090828.989 ocxev DoFireDialogic(dwIdx=13, 1, 134, [TDX_CST], 199, 0, 0, [DE_TONEON], [TID_RNGBK1], []) 090833.974 001 ev TDX_CALLP (Call Progress Completed) 090833.974 001 TDX_CALLP CR_CNCT called line was connected 090833.974 001 TDX_CALLP CR_CNCT CON_PAMD connection due to Positive Answering Machine Detection 090833.974 ocxev DoFireDialogic(dwIdx=14, 1, 133, [TDX_CALLP], 10, 4, 0, [TDX_CALLP], [CR_CNCT], [CON_PAMD]) 090834.317 001 ocxfn PlayStart(hLine=1, strSoundFile=307, lPlayId=39046(0x9886), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090834.317 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090834.317 ocxfn Dial(1,39046,[307],,0,0,,) 090834.317 dx_dial(1,[307],off) as ca_intflg=2 090834.317 dx_dial(1,307) ok 090834.317 play .Dial returned (playing DTMF tones) 090834.927 001 ev TDX_DIAL (Dial Completed) 090834.927 001 ocxev PlayEnd(dwLineId=1, dwPlayId=39046) 090834.974 ocxev DoFireDialogic(dwIdx=15, 1, 132, [TDX_DIAL], 0, 0, 0, [], [], []) 090838.208 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=42937, strWavFilesIn=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090838.208 conv zStoreWavVoxTaskInfo_Add insert into index 1 end 090838.208 conv start on idx 1 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 090838.208 conv start on token 0 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 090838.208 conv not .vox 090838.208 conv get infile info 090838.208 conv scan for outfiles 090838.208 conv FindFirstFile call 090838.208 conv wav[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav][sat Nov 30 18:12:04 2002 ] same or older then vox[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox][Fri Aug 12 15:09:36 2005 ] (modified time comparison). not converting wav->vox. 090838.208 conv iSearchResult=2 090838.208 conv finished converting 090838.208 evt OnFireConvertWavVoxFinished wParam=1 lParam=0 090838.208 001 ocxev ConvertWavVoxFinished(1, 42937, C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, 1, 0, , ) 090838.208 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=42953(0xa7c9), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090838.208 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090838.208 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=42953(0xa7c9), lParam1=0, lParam2=0, strParam1=, strParam2=) 090838.208 conv finished freeing and will now look at next entry for converting 090838.239 play open(strFname[0]=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox) => 3, read_bytes=26726/26726, buff_addr=0x42f1288 (file_created_time=Fri Aug 12 15:09:36 2005 , file_modified_time=Fri Aug 12 15:09:36 2005 ) 090838.239 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42f1288, io_offset=0, io_length=26726, io_nextp=0x0, io_prevp=0x0 090838.239 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090838.255 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090840.646 001 ev TDX_CST (CST Event Received) 090840.646 001 ev TDX_CST DE_DIGITS data=49 [1] 090840.646 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 090840.661 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090840.692 001 ev TDX_PLAY (Play Completed) 090840.692 play free(0x42f1288, 0x42f0768) eot 090840.692 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090840.692 ocxev DoFireDialogic(dwIdx=17, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 090840.692 001 ocxev PlayEnd(dwLineId=1, dwPlayId=42953) 090840.708 ocxev DoFireDialogic(dwIdx=18, 1, 129, [TDX_PLAY], 42953, 0, 0, [], [], []) 090840.786 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=45515, strWavFilesIn=c:\vg\enterPin.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090840.786 conv zStoreWavVoxTaskInfo_Add insert into index 2 end 090840.833 conv start on idx 2 [c:\vg\enterPin.wav] 090840.833 conv start on token 0 [c:\vg\enterPin.wav] 090840.833 conv not .vox 090840.833 conv get infile info 090840.833 conv scan for outfiles 090840.833 conv FindFirstFile call 090840.833 conv wav[c:\vg\enterPin.wav][Fri Sep 09 08:44:58 2005 ] same or older then vox[c:\vg\enterPin.vox][Fri Sep 09 08:46:19 2005 ] (modified time comparison). not converting wav->vox. 090840.833 conv iSearchResult=2 090840.833 conv finished converting 090840.833 evt OnFireConvertWavVoxFinished wParam=2 lParam=0 090840.833 001 ocxev ConvertWavVoxFinished(1, 45515, c:\vg\enterPin.vox, 1, 0, , ) 090840.833 001 ocxfn PlayStart(hLine=1, strSoundFile=c:\vg\enterPin.vox, lPlayId=45578(0xb20a), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090840.833 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090840.833 play start (hli=0x1c8008, strSoundFile=c:\vg\enterPin.vox, lPlayId=45578(0xb20a), lParam1=0, lParam2=0, strParam1=, strParam2=) 090840.833 conv finished freeing and will now look at next entry for converting 090840.880 play open(strFname[0]=c:\vg\enterPin.vox) => 3, read_bytes=23776/23776, buff_addr=0x42f1768 (file_created_time=Fri Sep 09 08:42:48 2005 , file_modified_time=Fri Sep 09 08:46:19 2005 ) 090840.880 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42f1768, io_offset=0, io_length=23776, io_nextp=0x0, io_prevp=0x0 090840.880 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090840.880 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090846.896 001 ev TDX_PLAY (Play Completed) 090846.896 play free(0x42f1768, 0x42f0768) eot 090846.896 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090846.896 001 ocxev PlayEnd(dwLineId=1, dwPlayId=45578) 090846.942 ocxev DoFireDialogic(dwIdx=19, 1, 129, [TDX_PLAY], 45578, 0, 0, [], [], []) 090847.317 001 ev TDX_CST (CST Event Received) 090847.317 001 ev TDX_CST DE_DIGITS data=50 [2] 090847.317 001 te LINE_MONITORDIGITS(1, 0x1, 0x32, 0x32) 090847.349 ocxev DoFireDialogic(dwIdx=21, 1, 134, [TDX_CST], 50, 0, 0, [DE_DIGITS], [], []) 090847.630 001 ev TDX_CST (CST Event Received) 090847.630 001 ev TDX_CST DE_DIGITS data=49 [1] 090847.630 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 090847.677 ocxev DoFireDialogic(dwIdx=23, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 090848.130 001 ev TDX_CST (CST Event Received) 090848.130 001 ev TDX_CST DE_DIGITS data=48 [0] 090848.130 001 te LINE_MONITORDIGITS(1, 0x1, 0x30, 0x30) 090848.208 ocxev DoFireDialogic(dwIdx=25, 1, 134, [TDX_CST], 48, 0, 0, [DE_DIGITS], [], []) 090849.192 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=53921, strWavFilesIn=C:\Program Files\VoiceGuide\data\tts1.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090849.192 conv zStoreWavVoxTaskInfo_Add insert into index 3 end 090849.224 conv start on idx 3 [C:\Program Files\VoiceGuide\data\tts1.wav] 090849.224 conv start on token 0 [C:\Program Files\VoiceGuide\data\tts1.wav] 090849.224 conv not .vox 090849.224 conv get infile info 090849.224 conv scan for outfiles 090849.224 conv FindFirstFile call 090849.224 conv wav[C:\Program Files\VoiceGuide\data\tts1.wav][Mon Sep 12 09:08:49 2005 ] newer then vox[C:\Program Files\VoiceGuide\data\tts1.vox][Fri Sep 09 17:33:41 2005 ] (modified time comparison) will convert wav->vox. 090849.224 conv FindFirstFile call 090849.224 conv iSearchResult=1 090849.224 conv ms search no up-to-date vox, converting 090849.224 conv test opening output file 090849.224 conv wav2vox(C:\Program Files\VoiceGuide\data\tts1.wav, C:\Program Files\VoiceGuide\data\tts1.vox, ) 090849.224 conv WAV -> ADPCM 090849.224 conv C:\Program Files\VoiceGuide\data\tts1.wav (8000Hz, 16bit, mono) -> C:\Program Files\VoiceGuide\data\tts1.vox 090849.239 conv end iBytesRead=165588, iBytesWritten=41397 090849.239 conv finished converting 090849.239 evt OnFireConvertWavVoxFinished wParam=3 lParam=0 090849.239 001 ocxev ConvertWavVoxFinished(1, 53921, C:\Program Files\VoiceGuide\data\tts1.vox, 1, 0, , ) 090849.255 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=54000(0xd2f0), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090849.255 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090849.255 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=54000(0xd2f0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090849.255 play open(strFname[0]=C:\Program Files\VoiceGuide\data\tts1.vox) => 3, read_bytes=41397/41397, buff_addr=0x42f6a20 (file_created_time=Thu Aug 11 15:17:19 2005 , file_modified_time=Mon Sep 12 09:08:49 2005 ) 090849.255 conv finished freeing and will now look at next entry for converting 090849.255 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42f6a20, io_offset=0, io_length=41397, io_nextp=0x0, io_prevp=0x0 090849.255 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090849.255 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090859.677 001 ev TDX_PLAY (Play Completed) 090859.677 play free(0x42f6a20, 0x42f0768) eot 090859.677 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090859.677 001 ocxev PlayEnd(dwLineId=1, dwPlayId=54000) 090859.708 ocxev DoFireDialogic(dwIdx=26, 1, 129, [TDX_PLAY], 54000, 0, 0, [], [], []) 090902.021 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090902.021 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090902.036 001 ocxfn LineDrop(sLineId=1, sOpt=0) 090902.083 001 ev TDX_SETHOOK (SetHook Completed) 090902.083 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090902.083 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090902.099 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090902.114 ocxev DoFireDialogic(dwIdx=28, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090902.208 001 ev TDX_CST (CST Event Received) 090902.208 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) 090902.208 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 090902.208 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 090902.208 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 090902.208 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 090902.208 dlgc caller id frame type: UNKNOWN [0x00] 090902.208 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 090902.239 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 090902.239 ocxev DoFireDialogic(dwIdx=31, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 090903.130 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 090910.208 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1c8008) 090910.208 ev001 OnFireTimer_RingGoesAway (hli=0x1c8008) 090910.208 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090911.005 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 090911.083 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090911.099 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090911.114 001 ocxfn LineDrop(sLineId=1, sOpt=0) 090911.130 001 ev TDX_SETHOOK (SetHook Completed) 090911.130 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090911.146 ocxev DoFireDialogic(dwIdx=34, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090911.333 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 090912.177 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 090912.177 LineMakeCall using analog dialing as dti=[dxxxB1C1] 090912.177 dx_sethook(1, DX_OFFHOOK) ok 090912.724 001 ev TDX_SETHOOK (SetHook Completed) 090912.724 ocxev DoFireDialogic(dwIdx=35, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_OFFHOOK], [CALL_OUTBOUND], []) 090912.739 dx_dial([9,5167778], DX_CALLP) ok 090912.739 001 ev TDX_CST (CST Event Received) 090912.739 001 ev TDX_CST DE_LCON data=1065 090912.739 ocxev DoFireDialogic(dwIdx=36, 1, 134, [TDX_CST], 1065, 0, 0, [DE_LCON], [], []) 090926.911 001 ev TDX_CST (CST Event Received) 090926.911 001 ev TDX_CST DE_TONEON data=199 090926.911 ocxev DoFireDialogic(dwIdx=37, 1, 134, [TDX_CST], 199, 0, 0, [DE_TONEON], [TID_RNGBK1], []) 090931.849 001 ev TDX_CALLP (Call Progress Completed) 090931.849 001 TDX_CALLP CR_CNCT called line was connected 090931.849 001 TDX_CALLP CR_CNCT CON_PAMD connection due to Positive Answering Machine Detection 090931.849 ocxev DoFireDialogic(dwIdx=38, 1, 133, [TDX_CALLP], 10, 4, 0, [TDX_CALLP], [CR_CNCT], [CON_PAMD]) 090932.130 001 ocxfn PlayStart(hLine=1, strSoundFile=307, lPlayId=96875(0x17a6b), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090932.130 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090932.130 ocxfn Dial(1,96875,[307],,0,0,,) 090932.130 dx_dial(1,[307],off) as ca_intflg=2 090932.130 dx_dial(1,307) ok 090932.130 play .Dial returned (playing DTMF tones) 090932.739 001 ev TDX_DIAL (Dial Completed) 090932.739 001 ocxev PlayEnd(dwLineId=1, dwPlayId=96875) 090932.771 ocxev DoFireDialogic(dwIdx=39, 1, 132, [TDX_DIAL], 0, 0, 0, [], [], []) 090936.021 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=100750, strWavFilesIn=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090936.021 conv zStoreWavVoxTaskInfo_Add insert into index 4 end 090936.021 conv start on idx 4 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 090936.021 conv start on token 0 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 090936.021 conv not .vox 090936.021 conv get infile info 090936.021 conv scan for outfiles 090936.021 conv FindFirstFile call 090936.021 conv wav[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav][sat Nov 30 18:12:04 2002 ] same or older then vox[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox][Fri Aug 12 15:09:36 2005 ] (modified time comparison). not converting wav->vox. 090936.021 conv iSearchResult=2 090936.021 conv finished converting 090936.021 evt OnFireConvertWavVoxFinished wParam=4 lParam=0 090936.021 001 ocxev ConvertWavVoxFinished(1, 100750, C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, 1, 0, , ) 090936.036 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=100781(0x189ad), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090936.036 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090936.036 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=100781(0x189ad), lParam1=0, lParam2=0, strParam1=, strParam2=) 090936.036 play open(strFname[0]=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox) => 3, read_bytes=26726/26726, buff_addr=0x42fc0b0 (file_created_time=Fri Aug 12 15:09:36 2005 , file_modified_time=Fri Aug 12 15:09:36 2005 ) 090936.036 conv finished freeing and will now look at next entry for converting 090936.036 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=26726, io_nextp=0x0, io_prevp=0x0 090936.036 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090936.036 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090938.177 001 ev TDX_CST (CST Event Received) 090938.177 001 ev TDX_CST DE_DIGITS data=49 [1] 090938.177 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 090938.177 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090938.208 001 ev TDX_PLAY (Play Completed) 090938.208 play free(0x42fc0b0, 0x42f0768) eot 090938.208 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090938.239 ocxev DoFireDialogic(dwIdx=41, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 090938.255 001 ocxev PlayEnd(dwLineId=1, dwPlayId=100781) 090938.271 ocxev DoFireDialogic(dwIdx=42, 1, 129, [TDX_PLAY], 100781, 0, 0, [], [], []) 090938.302 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=103031, strWavFilesIn=c:\vg\enterPin.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090938.302 conv zStoreWavVoxTaskInfo_Add insert into index 5 end 090938.349 conv start on idx 5 [c:\vg\enterPin.wav] 090938.349 conv start on token 0 [c:\vg\enterPin.wav] 090938.349 conv not .vox 090938.349 conv get infile info 090938.349 conv scan for outfiles 090938.349 conv FindFirstFile call 090938.349 conv wav[c:\vg\enterPin.wav][Fri Sep 09 08:44:58 2005 ] same or older then vox[c:\vg\enterPin.vox][Fri Sep 09 08:46:19 2005 ] (modified time comparison). not converting wav->vox. 090938.349 conv iSearchResult=2 090938.349 conv finished converting 090938.349 evt OnFireConvertWavVoxFinished wParam=5 lParam=0 090938.349 001 ocxev ConvertWavVoxFinished(1, 103031, c:\vg\enterPin.vox, 1, 0, , ) 090938.364 001 ocxfn PlayStart(hLine=1, strSoundFile=c:\vg\enterPin.vox, lPlayId=103109(0x192c5), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090938.364 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090938.364 play start (hli=0x1c8008, strSoundFile=c:\vg\enterPin.vox, lPlayId=103109(0x192c5), lParam1=0, lParam2=0, strParam1=, strParam2=) 090938.364 play open(strFname[0]=c:\vg\enterPin.vox) => 3, read_bytes=23776/23776, buff_addr=0x42fc0b0 (file_created_time=Fri Sep 09 08:42:48 2005 , file_modified_time=Fri Sep 09 08:46:19 2005 ) 090938.364 conv finished freeing and will now look at next entry for converting 090938.364 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=23776, io_nextp=0x0, io_prevp=0x0 090938.364 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090938.364 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090941.552 001 ev TDX_CST (CST Event Received) 090941.552 001 ev TDX_CST DE_DIGITS data=50 [2] 090941.552 001 te LINE_MONITORDIGITS(1, 0x1, 0x32, 0x32) 090941.567 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090941.614 ocxev DoFireDialogic(dwIdx=44, 1, 134, [TDX_CST], 50, 0, 0, [DE_DIGITS], [], []) 090941.646 001 ev TDX_PLAY (Play Completed) 090941.646 play free(0x42fc0b0, 0x42f0768) eot 090941.646 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090941.646 001 ocxev PlayEnd(dwLineId=1, dwPlayId=103109) 090941.677 ocxev DoFireDialogic(dwIdx=45, 1, 129, [TDX_PLAY], 103109, 0, 0, [], [], []) 090941.849 001 ev TDX_CST (CST Event Received) 090941.849 001 ev TDX_CST DE_DIGITS data=49 [1] 090941.849 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 090941.896 ocxev DoFireDialogic(dwIdx=47, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 090942.192 001 ev TDX_CST (CST Event Received) 090942.192 001 ev TDX_CST DE_DIGITS data=48 [0] 090942.192 001 te LINE_MONITORDIGITS(1, 0x1, 0x30, 0x30) 090942.255 ocxev DoFireDialogic(dwIdx=49, 1, 134, [TDX_CST], 48, 0, 0, [DE_DIGITS], [], []) 090942.505 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=107234, strWavFilesIn=C:\Program Files\VoiceGuide\data\tts1.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 090942.505 conv zStoreWavVoxTaskInfo_Add insert into index 6 end 090942.536 conv start on idx 6 [C:\Program Files\VoiceGuide\data\tts1.wav] 090942.536 conv start on token 0 [C:\Program Files\VoiceGuide\data\tts1.wav] 090942.536 conv not .vox 090942.536 conv get infile info 090942.536 conv scan for outfiles 090942.536 conv FindFirstFile call 090942.536 conv wav[C:\Program Files\VoiceGuide\data\tts1.wav][Mon Sep 12 09:09:42 2005 ] newer then vox[C:\Program Files\VoiceGuide\data\tts1.vox][Mon Sep 12 09:08:49 2005 ] (modified time comparison) will convert wav->vox. 090942.536 conv FindFirstFile call 090942.536 conv iSearchResult=1 090942.536 conv ms search no up-to-date vox, converting 090942.536 conv test opening output file 090942.536 conv wav2vox(C:\Program Files\VoiceGuide\data\tts1.wav, C:\Program Files\VoiceGuide\data\tts1.vox, ) 090942.536 conv WAV -> ADPCM 090942.536 conv C:\Program Files\VoiceGuide\data\tts1.wav (8000Hz, 16bit, mono) -> C:\Program Files\VoiceGuide\data\tts1.vox 090942.567 conv end iBytesRead=161096, iBytesWritten=40274 090942.567 conv finished converting 090942.567 evt OnFireConvertWavVoxFinished wParam=6 lParam=0 090942.567 001 ocxev ConvertWavVoxFinished(1, 107234, C:\Program Files\VoiceGuide\data\tts1.vox, 1, 0, , ) 090942.567 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=107312(0x1a330), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 090942.567 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 090942.567 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=107312(0x1a330), lParam1=0, lParam2=0, strParam1=, strParam2=) 090942.567 play open(strFname[0]=C:\Program Files\VoiceGuide\data\tts1.vox) => 3, read_bytes=40274/40274, buff_addr=0x42fc0b0 (file_created_time=Thu Aug 11 15:17:19 2005 , file_modified_time=Mon Sep 12 09:09:42 2005 ) 090942.567 conv finished freeing and will now look at next entry for converting 090942.567 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=40274, io_nextp=0x0, io_prevp=0x0 090942.567 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 090942.583 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 090952.708 001 ev TDX_PLAY (Play Completed) 090952.708 play free(0x42fc0b0, 0x42f0768) eot 090952.708 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 090952.708 001 ocxev PlayEnd(dwLineId=1, dwPlayId=107312) 090952.739 ocxev DoFireDialogic(dwIdx=50, 1, 129, [TDX_PLAY], 107312, 0, 0, [], [], []) 090955.083 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090955.099 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090955.099 001 ocxfn LineDrop(sLineId=1, sOpt=0) 090955.146 001 ev TDX_SETHOOK (SetHook Completed) 090955.146 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 090955.146 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 090955.146 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 090955.161 ocxev DoFireDialogic(dwIdx=52, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090955.271 001 ev TDX_CST (CST Event Received) 090955.271 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) 090955.271 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 090955.271 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 090955.271 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 090955.271 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 090955.271 dlgc caller id frame type: UNKNOWN [0x00] 090955.271 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 090955.302 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 090955.302 ocxev DoFireDialogic(dwIdx=55, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 090956.192 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091003.271 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1c8008) 091003.271 ev001 OnFireTimer_RingGoesAway (hli=0x1c8008) 091003.271 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091004.286 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 091004.364 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091004.380 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091004.396 001 ocxfn LineDrop(sLineId=1, sOpt=0) 091004.411 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091004.427 001 ev TDX_SETHOOK (SetHook Completed) 091004.442 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091004.489 ocxev DoFireDialogic(dwIdx=58, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 091005.536 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091006.458 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 091006.458 LineMakeCall using analog dialing as dti=[dxxxB1C1] 091006.458 dx_sethook(1, DX_OFFHOOK) ok 091007.005 001 ev TDX_SETHOOK (SetHook Completed) 091007.005 ocxev DoFireDialogic(dwIdx=59, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_OFFHOOK], [CALL_OUTBOUND], []) 091007.005 dx_dial([9,5167778], DX_CALLP) ok 091007.005 001 ev TDX_CST (CST Event Received) 091007.005 001 ev TDX_CST DE_LCON data=1186 091007.005 ocxev DoFireDialogic(dwIdx=60, 1, 134, [TDX_CST], 1186, 0, 0, [DE_LCON], [], []) 091021.208 001 ev TDX_CST (CST Event Received) 091021.208 001 ev TDX_CST DE_TONEON data=200 091021.208 ocxev DoFireDialogic(dwIdx=61, 1, 134, [TDX_CST], 200, 0, 0, [DE_TONEON], [TID_RNGBK2], []) 091021.255 001 ev TDX_CST (CST Event Received) 091021.255 001 ev TDX_CST DE_TONEON data=199 091021.255 ocxev DoFireDialogic(dwIdx=62, 1, 134, [TDX_CST], 199, 0, 0, [DE_TONEON], [TID_RNGBK1], []) 091026.396 001 ev TDX_CALLP (Call Progress Completed) 091026.396 001 TDX_CALLP CR_CNCT called line was connected 091026.396 001 TDX_CALLP CR_CNCT CON_PAMD connection due to Positive Answering Machine Detection 091026.396 ocxev DoFireDialogic(dwIdx=63, 1, 133, [TDX_CALLP], 10, 4, 0, [TDX_CALLP], [CR_CNCT], [CON_PAMD]) 091026.661 001 ocxfn PlayStart(hLine=1, strSoundFile=307, lPlayId=151390(0x24f5e), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091026.661 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091026.661 ocxfn Dial(1,151390,[307],,0,0,,) 091026.661 dx_dial(1,[307],off) as ca_intflg=2 091026.661 dx_dial(1,307) ok 091026.661 play .Dial returned (playing DTMF tones) 091027.255 001 ev TDX_DIAL (Dial Completed) 091027.255 001 ocxev PlayEnd(dwLineId=1, dwPlayId=151390) 091027.302 ocxev DoFireDialogic(dwIdx=64, 1, 132, [TDX_DIAL], 0, 0, 0, [], [], []) 091030.552 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=155281, strWavFilesIn=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091030.552 conv zStoreWavVoxTaskInfo_Add insert into index 7 end 091030.630 conv start on idx 7 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 091030.630 conv start on token 0 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 091030.630 conv not .vox 091030.630 conv get infile info 091030.630 conv scan for outfiles 091030.630 conv FindFirstFile call 091030.630 conv wav[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav][sat Nov 30 18:12:04 2002 ] same or older then vox[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox][Fri Aug 12 15:09:36 2005 ] (modified time comparison). not converting wav->vox. 091030.630 conv iSearchResult=2 091030.630 conv finished converting 091030.630 evt OnFireConvertWavVoxFinished wParam=7 lParam=0 091030.630 001 ocxev ConvertWavVoxFinished(1, 155281, C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, 1, 0, , ) 091030.630 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=155375(0x25eef), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091030.630 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091030.630 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=155375(0x25eef), lParam1=0, lParam2=0, strParam1=, strParam2=) 091030.630 play open(strFname[0]=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox) => 3, read_bytes=26726/26726, buff_addr=0x42fc0b0 (file_created_time=Fri Aug 12 15:09:36 2005 , file_modified_time=Fri Aug 12 15:09:36 2005 ) 091030.646 conv finished freeing and will now look at next entry for converting 091030.646 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=26726, io_nextp=0x0, io_prevp=0x0 091030.646 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091030.646 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091032.083 001 ev TDX_CST (CST Event Received) 091032.083 001 ev TDX_CST DE_DIGITS data=49 [1] 091032.083 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 091032.099 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091032.130 001 ev TDX_PLAY (Play Completed) 091032.130 play free(0x42fc0b0, 0x42f0768) eot 091032.130 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091032.161 ocxev DoFireDialogic(dwIdx=66, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 091032.177 001 ocxev PlayEnd(dwLineId=1, dwPlayId=155375) 091032.177 ocxev DoFireDialogic(dwIdx=67, 1, 129, [TDX_PLAY], 155375, 0, 0, [], [], []) 091032.208 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=156953, strWavFilesIn=c:\vg\enterPin.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091032.208 conv zStoreWavVoxTaskInfo_Add insert into index 8 end 091032.255 conv start on idx 8 [c:\vg\enterPin.wav] 091032.255 conv start on token 0 [c:\vg\enterPin.wav] 091032.255 conv not .vox 091032.255 conv get infile info 091032.255 conv scan for outfiles 091032.255 conv FindFirstFile call 091032.255 conv wav[c:\vg\enterPin.wav][Fri Sep 09 08:44:58 2005 ] same or older then vox[c:\vg\enterPin.vox][Fri Sep 09 08:46:19 2005 ] (modified time comparison). not converting wav->vox. 091032.255 conv iSearchResult=2 091032.255 conv finished converting 091032.255 evt OnFireConvertWavVoxFinished wParam=8 lParam=0 091032.255 001 ocxev ConvertWavVoxFinished(1, 156953, c:\vg\enterPin.vox, 1, 0, , ) 091032.255 001 ocxfn PlayStart(hLine=1, strSoundFile=c:\vg\enterPin.vox, lPlayId=157000(0x26548), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091032.255 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091032.255 play start (hli=0x1c8008, strSoundFile=c:\vg\enterPin.vox, lPlayId=157000(0x26548), lParam1=0, lParam2=0, strParam1=, strParam2=) 091032.255 play open(strFname[0]=c:\vg\enterPin.vox) => 3, read_bytes=23776/23776, buff_addr=0x42fc0b0 (file_created_time=Fri Sep 09 08:42:48 2005 , file_modified_time=Fri Sep 09 08:46:19 2005 ) 091032.255 conv finished freeing and will now look at next entry for converting 091032.271 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=23776, io_nextp=0x0, io_prevp=0x0 091032.271 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091032.271 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091034.192 001 ev TDX_CST (CST Event Received) 091034.192 001 ev TDX_CST DE_DIGITS data=50 [2] 091034.192 001 te LINE_MONITORDIGITS(1, 0x1, 0x32, 0x32) 091034.208 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091034.255 ocxev DoFireDialogic(dwIdx=69, 1, 134, [TDX_CST], 50, 0, 0, [DE_DIGITS], [], []) 091034.286 001 ev TDX_PLAY (Play Completed) 091034.286 play free(0x42fc0b0, 0x42f0768) eot 091034.286 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091034.286 001 ocxev PlayEnd(dwLineId=1, dwPlayId=157000) 091034.317 ocxev DoFireDialogic(dwIdx=70, 1, 129, [TDX_PLAY], 157000, 0, 0, [], [], []) 091034.474 001 ev TDX_CST (CST Event Received) 091034.474 001 ev TDX_CST DE_DIGITS data=49 [1] 091034.474 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 091034.521 ocxev DoFireDialogic(dwIdx=72, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 091035.177 001 ev TDX_CST (CST Event Received) 091035.177 001 ev TDX_CST DE_DIGITS data=48 [0] 091035.177 001 te LINE_MONITORDIGITS(1, 0x1, 0x30, 0x30) 091035.239 ocxev DoFireDialogic(dwIdx=74, 1, 134, [TDX_CST], 48, 0, 0, [DE_DIGITS], [], []) 091035.427 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=160171, strWavFilesIn=C:\Program Files\VoiceGuide\data\tts1.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091035.427 conv zStoreWavVoxTaskInfo_Add insert into index 9 end 091035.427 conv start on idx 9 [C:\Program Files\VoiceGuide\data\tts1.wav] 091035.427 conv start on token 0 [C:\Program Files\VoiceGuide\data\tts1.wav] 091035.427 conv not .vox 091035.427 conv get infile info 091035.427 conv scan for outfiles 091035.427 conv FindFirstFile call 091035.427 conv wav[C:\Program Files\VoiceGuide\data\tts1.wav][Mon Sep 12 09:10:35 2005 ] newer then vox[C:\Program Files\VoiceGuide\data\tts1.vox][Mon Sep 12 09:09:42 2005 ] (modified time comparison) will convert wav->vox. 091035.427 conv FindFirstFile call 091035.427 conv iSearchResult=1 091035.427 conv ms search no up-to-date vox, converting 091035.427 conv test opening output file 091035.427 conv wav2vox(C:\Program Files\VoiceGuide\data\tts1.wav, C:\Program Files\VoiceGuide\data\tts1.vox, ) 091035.427 conv WAV -> ADPCM 091035.427 conv C:\Program Files\VoiceGuide\data\tts1.wav (8000Hz, 16bit, mono) -> C:\Program Files\VoiceGuide\data\tts1.vox 091035.458 conv end iBytesRead=161370, iBytesWritten=40342 091035.458 conv finished converting 091035.458 evt OnFireConvertWavVoxFinished wParam=9 lParam=0 091035.458 001 ocxev ConvertWavVoxFinished(1, 160171, C:\Program Files\VoiceGuide\data\tts1.vox, 1, 0, , ) 091035.458 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=160203(0x271cb), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091035.458 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091035.458 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=160203(0x271cb), lParam1=0, lParam2=0, strParam1=, strParam2=) 091035.458 play open(strFname[0]=C:\Program Files\VoiceGuide\data\tts1.vox) => 3, read_bytes=40342/40342, buff_addr=0x42fc0b0 (file_created_time=Thu Aug 11 15:17:19 2005 , file_modified_time=Mon Sep 12 09:10:35 2005 ) 091035.458 conv finished freeing and will now look at next entry for converting 091035.458 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=40342, io_nextp=0x0, io_prevp=0x0 091035.458 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091035.474 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091045.630 001 ev TDX_PLAY (Play Completed) 091045.630 play free(0x42fc0b0, 0x42f0768) eot 091045.630 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091045.630 001 ocxev PlayEnd(dwLineId=1, dwPlayId=160203) 091045.661 ocxev DoFireDialogic(dwIdx=75, 1, 129, [TDX_PLAY], 160203, 0, 0, [], [], []) 091048.052 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091048.052 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091048.052 001 ocxfn LineDrop(sLineId=1, sOpt=0) 091048.099 001 ev TDX_SETHOOK (SetHook Completed) 091048.099 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091048.114 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091048.114 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091048.130 ocxev DoFireDialogic(dwIdx=77, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 091048.224 001 ev TDX_CST (CST Event Received) 091048.224 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) 091048.224 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 091048.224 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 091048.224 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 091048.239 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 091048.239 dlgc caller id frame type: UNKNOWN [0x00] 091048.239 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 091048.255 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 091048.255 ocxev DoFireDialogic(dwIdx=80, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 091049.161 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091056.224 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1c8008) 091056.224 ev001 OnFireTimer_RingGoesAway (hli=0x1c8008) 091056.224 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091056.646 001 ocxfn LineMakeCall(1, 0, 9,5167778, DX_PAMDOPTEN, 30, 0, 0, , ) 091056.755 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091056.755 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091056.771 001 ocxfn LineDrop(sLineId=1, sOpt=0) 091056.786 001 ev TDX_SETHOOK (SetHook Completed) 091056.786 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091056.817 ocxev DoFireDialogic(dwIdx=83, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 091057.442 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091058.849 001 ocxfn LineMakeCall(1, 0, 307, DX_PAMDOPTEN, 30, 0, 0, , ) 091058.849 LineMakeCall using analog dialing as dti=[dxxxB1C1] 091058.849 dx_sethook(1, DX_OFFHOOK) ok 091059.396 001 ev TDX_SETHOOK (SetHook Completed) 091059.396 ocxev DoFireDialogic(dwIdx=84, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_OFFHOOK], [CALL_OUTBOUND], []) 091059.411 dx_dial([307], DX_CALLP) ok 091059.411 001 ev TDX_CST (CST Event Received) 091059.411 001 ev TDX_CST DE_LCON data=1130 091059.411 ocxev DoFireDialogic(dwIdx=85, 1, 134, [TDX_CST], 1130, 0, 0, [DE_LCON], [], []) 091105.521 001 ev TDX_CALLP (Call Progress Completed) 091105.521 001 TDX_CALLP CR_CNCT called line was connected 091105.521 001 TDX_CALLP CR_CNCT CON_PAMD connection due to Positive Answering Machine Detection 091105.521 ocxev DoFireDialogic(dwIdx=86, 1, 133, [TDX_CALLP], 10, 4, 0, [TDX_CALLP], [CR_CNCT], [CON_PAMD]) 091105.771 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=190515, strWavFilesIn=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091105.771 conv zStoreWavVoxTaskInfo_Add insert into index 10 end 091105.802 conv start on idx 10 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 091105.802 conv start on token 0 [C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav] 091105.802 conv not .vox 091105.802 conv get infile info 091105.802 conv scan for outfiles 091105.802 conv FindFirstFile call 091105.802 conv wav[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.wav][sat Nov 30 18:12:04 2002 ] same or older then vox[C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox][Fri Aug 12 15:09:36 2005 ] (modified time comparison). not converting wav->vox. 091105.802 conv iSearchResult=2 091105.802 conv finished converting 091105.802 evt OnFireConvertWavVoxFinished wParam=10 lParam=0 091105.802 001 ocxev ConvertWavVoxFinished(1, 190515, C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, 1, 0, , ) 091105.802 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=190546(0x2e852), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091105.802 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091105.802 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox, lPlayId=190546(0x2e852), lParam1=0, lParam2=0, strParam1=, strParam2=) 091105.802 play open(strFname[0]=C:\Program Files\VoiceGuide\System\voice\AcceptAutoCall.vox) => 3, read_bytes=26726/26726, buff_addr=0x42fc0b0 (file_created_time=Fri Aug 12 15:09:36 2005 , file_modified_time=Fri Aug 12 15:09:36 2005 ) 091105.817 conv finished freeing and will now look at next entry for converting 091105.817 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=26726, io_nextp=0x0, io_prevp=0x0 091105.817 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091105.817 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091110.552 001 ev TDX_CST (CST Event Received) 091110.552 001 ev TDX_CST DE_DIGITS data=49 [1] 091110.552 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 091110.567 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091110.599 ocxev DoFireDialogic(dwIdx=88, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 091110.646 001 ev TDX_PLAY (Play Completed) 091110.646 play free(0x42fc0b0, 0x42f0768) eot 091110.646 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091110.677 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=195406, strWavFilesIn=c:\vg\enterPin.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091110.677 conv zStoreWavVoxTaskInfo_Add insert into index 11 end 091110.677 001 ocxev PlayEnd(dwLineId=1, dwPlayId=190546) 091110.677 ocxev DoFireDialogic(dwIdx=89, 1, 129, [TDX_PLAY], 190546, 0, 0, [], [], []) 091110.739 conv start on idx 11 [c:\vg\enterPin.wav] 091110.739 conv start on token 0 [c:\vg\enterPin.wav] 091110.739 conv not .vox 091110.739 conv get infile info 091110.739 conv scan for outfiles 091110.739 conv FindFirstFile call 091110.739 conv wav[c:\vg\enterPin.wav][Fri Sep 09 08:44:58 2005 ] same or older then vox[c:\vg\enterPin.vox][Fri Sep 09 08:46:19 2005 ] (modified time comparison). not converting wav->vox. 091110.739 conv iSearchResult=2 091110.739 conv finished converting 091110.739 conv finished freeing and will now look at next entry for converting 091110.739 evt OnFireConvertWavVoxFinished wParam=11 lParam=0 091110.739 001 ocxev ConvertWavVoxFinished(1, 195406, c:\vg\enterPin.vox, 1, 0, , ) 091110.739 001 ocxfn PlayStart(hLine=1, strSoundFile=c:\vg\enterPin.vox, lPlayId=195484(0x2fb9c), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091110.739 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091110.739 play start (hli=0x1c8008, strSoundFile=c:\vg\enterPin.vox, lPlayId=195484(0x2fb9c), lParam1=0, lParam2=0, strParam1=, strParam2=) 091110.739 play open(strFname[0]=c:\vg\enterPin.vox) => 3, read_bytes=23776/23776, buff_addr=0x42fc0b0 (file_created_time=Fri Sep 09 08:42:48 2005 , file_modified_time=Fri Sep 09 08:46:19 2005 ) 091110.755 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=23776, io_nextp=0x0, io_prevp=0x0 091110.755 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091110.755 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091116.771 001 ev TDX_PLAY (Play Completed) 091116.771 play free(0x42fc0b0, 0x42f0768) eot 091116.771 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091116.771 001 ocxev PlayEnd(dwLineId=1, dwPlayId=195484) 091116.802 ocxev DoFireDialogic(dwIdx=90, 1, 129, [TDX_PLAY], 195484, 0, 0, [], [], []) 091116.989 001 ev TDX_CST (CST Event Received) 091116.989 001 ev TDX_CST DE_DIGITS data=50 [2] 091116.989 001 te LINE_MONITORDIGITS(1, 0x1, 0x32, 0x32) 091117.036 ocxev DoFireDialogic(dwIdx=92, 1, 134, [TDX_CST], 50, 0, 0, [DE_DIGITS], [], []) 091117.224 001 ev TDX_CST (CST Event Received) 091117.224 001 ev TDX_CST DE_DIGITS data=49 [1] 091117.224 001 te LINE_MONITORDIGITS(1, 0x1, 0x31, 0x31) 091117.271 ocxev DoFireDialogic(dwIdx=94, 1, 134, [TDX_CST], 49, 0, 0, [DE_DIGITS], [], []) 091117.630 001 ev TDX_CST (CST Event Received) 091117.630 001 ev TDX_CST DE_DIGITS data=48 [0] 091117.630 001 te LINE_MONITORDIGITS(1, 0x1, 0x30, 0x30) 091117.708 ocxev DoFireDialogic(dwIdx=96, 1, 134, [TDX_CST], 48, 0, 0, [DE_DIGITS], [], []) 091117.927 001 ocxfn ConvertWav2Vox(hLine=1, lTaskID=202656, strWavFilesIn=C:\Program Files\VoiceGuide\data\tts1.wav, strConvertOptions=, lParam1=1, lParam2=0, strParam1=, strParam2=) 091117.927 conv zStoreWavVoxTaskInfo_Add insert into index 12 end 091117.974 conv start on idx 12 [C:\Program Files\VoiceGuide\data\tts1.wav] 091117.974 conv start on token 0 [C:\Program Files\VoiceGuide\data\tts1.wav] 091117.974 conv not .vox 091117.974 conv get infile info 091117.974 conv scan for outfiles 091117.974 conv FindFirstFile call 091117.974 conv wav[C:\Program Files\VoiceGuide\data\tts1.wav][Mon Sep 12 09:11:17 2005 ] newer then vox[C:\Program Files\VoiceGuide\data\tts1.vox][Mon Sep 12 09:10:35 2005 ] (modified time comparison) will convert wav->vox. 091117.974 conv FindFirstFile call 091117.974 conv iSearchResult=1 091117.974 conv ms search no up-to-date vox, converting 091117.974 conv test opening output file 091117.974 conv wav2vox(C:\Program Files\VoiceGuide\data\tts1.wav, C:\Program Files\VoiceGuide\data\tts1.vox, ) 091117.974 conv WAV -> ADPCM 091117.974 conv C:\Program Files\VoiceGuide\data\tts1.wav (8000Hz, 16bit, mono) -> C:\Program Files\VoiceGuide\data\tts1.vox 091118.005 conv end iBytesRead=159470, iBytesWritten=39867 091118.005 conv finished converting 091118.005 evt OnFireConvertWavVoxFinished wParam=12 lParam=0 091118.005 001 ocxev ConvertWavVoxFinished(1, 202656, C:\Program Files\VoiceGuide\data\tts1.vox, 1, 0, , ) 091118.005 001 ocxfn PlayStart(hLine=1, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=202750(0x317fe), lParam1=0, lParam2=0, strParam1=, strParam2=, 0,,,,,,,,,,) 091118.005 ocxfn PlaySetControlKeys(1,0,,,,,,,,,,) 091118.005 play start (hli=0x1c8008, strSoundFile=C:\Program Files\VoiceGuide\data\tts1.vox, lPlayId=202750(0x317fe), lParam1=0, lParam2=0, strParam1=, strParam2=) 091118.005 play open(strFname[0]=C:\Program Files\VoiceGuide\data\tts1.vox) => 3, read_bytes=39867/39867, buff_addr=0x42fc0b0 (file_created_time=Thu Aug 11 15:17:19 2005 , file_modified_time=Mon Sep 12 09:11:18 2005 ) 091118.005 conv finished freeing and will now look at next entry for converting 091118.005 iott 0x42f0768 io_type=14, io_fhandle=0, io_bufp=0x42fc0b0, io_offset=0, io_length=39867, io_nextp=0x0, io_prevp=0x0 091118.005 dlgc dx_playiottdata(1, 0x42f0768, 0x1c8cac, 0x1c8dec, EV_ASYNC) call 091118.021 dlgc play(1, 0x42f0768, 0x1c8cac, 0x1c8dec) => 0, hli=0x1c8008 091128.036 001 ev TDX_PLAY (Play Completed) 091128.036 play free(0x42fc0b0, 0x42f0768) eot 091128.036 cleared dwPlayId: hli=0x1c8008, hli->dwPlayId=0(0x0) 091128.036 001 ocxev PlayEnd(dwLineId=1, dwPlayId=202750) 091128.067 ocxev DoFireDialogic(dwIdx=97, 1, 129, [TDX_PLAY], 202750, 0, 0, [], [], []) 091130.427 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091130.427 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091130.442 001 ocxfn LineDrop(sLineId=1, sOpt=0) 091130.489 001 ev TDX_SETHOOK (SetHook Completed) 091130.489 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091130.489 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091130.505 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091130.505 ocxev DoFireDialogic(dwIdx=99, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 091130.614 001 ev TDX_CST (CST Event Received) 091130.614 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) 091130.614 dx_gtextcallid(1,CLIDINFO_CMPLT) error [128]:[Caller ID info/sub-msg not available] 091130.614 dx_gtextcallid(1,CLIDINFO_GENERAL) error [128]:[Caller ID info/sub-msg not available] 091130.614 dx_gtextcallid(1,CLIDINFO_CALLID) error [128]:[Caller ID info/sub-msg not available] 091130.614 dx_gtextcallid(1,CLIDINFO_FRAMETYPE) error [128]:[Caller ID info/sub-msg not available] 091130.614 dlgc caller id frame type: UNKNOWN [0x00] 091130.614 001 te LINE_CALLSTATE(1, 0x1, 0x2, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_OFFERING 091130.646 001 te LINE_LINEDEVSTATE(1, 0x1, 0x2, 0x0, 0x1) 091130.646 ocxev DoFireDialogic(dwIdx=2, 1, 134, [TDX_CST], 0, 0, 0, [DE_RINGS], [ET_RON], []) 091131.536 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091138.614 001 lTimer_RingsGoAway=0 => WM_FIRE_TIMER_RINGGOESAWAY (hli=0x1c8008) 091138.614 ev001 OnFireTimer_RingGoesAway (hli=0x1c8008) 091138.614 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091138.927 001 ocxfn LineMakeCall(1, 0, 307, DX_PAMDOPTEN, 30, 0, 0, , ) 091139.036 001 ocxfn RecStop(hLine=1, lRecId=0, lParam1=0, lParam2=0, strParam1=, strParam2=) 091139.036 001 ocxfn PlayStop(hLine=1, lPlayId=0(0x0), lParam1=0, lParam2=0, strParam1=, strParam2=) 091139.052 001 ocxfn LineDrop(sLineId=1, sOpt=0) 091139.067 001 ev TDX_SETHOOK (SetHook Completed) 091139.067 001 te LINE_CALLSTATE(1, 0x1, 0x1, 0x0, 0x0) LINE_CALLSTATE-LINECALLSTATE_IDLE 091139.099 ocxev DoFireDialogic(dwIdx=5, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 091139.849 ocxfn RingsBeforeAnswer(lLineId=1, lRings=0, lParam1=0, strParam2=) 091141.130 001 ocxfn LineMakeCall(1, 0, 307, DX_PAMDOPTEN, 30, 0, 0, , ) 091141.130 LineMakeCall using analog dialing as dti=[dxxxB1C1] 091141.130 dx_sethook(1, DX_OFFHOOK) ok 091141.677 001 ev TDX_SETHOOK (SetHook Completed Share this post Link to post
SupportTeam Report post Posted 09/14/2005 11:26 AM Trace shows that (as before) almost immediately after instructing the Dialogic card to make sure the line is hung up the Dialogic card is producing an event saying that it has detected an incoming call (a ring). 090753.849 ocxev DoFireDialogic(dwIdx=1, 1, 135, [TDX_SETHOOK], 0, 0, 0, [DX_ONHOOK], [], []) 090753.958 001 ev TDX_CST (CST Event Received) 090753.958 001 ev TDX_CST DE_RINGS data=0 ringcount=1 (hli=0x1c8008, hli->chdev=1, hli->linedev=1) We then see this happen more times further down the trace during thru initialization stages at end and start of calls. I'd say there is either some sort of a line mismatch between the PBX and the Dialogic card or the Dialogic card is faulty... Share this post Link to post
