jph2658 Report post Posted 07/02/2004 02:44 PM Everything works great for a while then the application stops working. Here's where it stops. 203446.77 4 RVreplace end: [Thank you!] 203446.77 4 tts generate start[Thank you!] 203446.77 4 tts generate wait 203446.77 4 RunModule PLAY end 203446.80 4 callstate DISCONNECTED 65554,0,0 203446.80 4 ScriptEventCode 9250 iLineState=1100 203446.80 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 203446.80 4 rv add [Hangup Time]{7/1/2004 8:34:46 PM} 203446.80 4 Hanging up call... 203446.80 4 RecSoundStop ok 203446.80 4 PlaySoundStop err=0 203446.80 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 203446.85 4 fnHangupCall end 203446.85 4 tts generate finish 203446.85 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 203447.88 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 203447.91 4 TimeoutClear 203447.91 4 tapie PlayEnd old line=4 0 917984 203447.91 4 wa(4433,91798400) 203447.91 4 linedevstate 2048 0 0 203447.91 4 callstate IDLE 65554 0 0 203447.91 4 WorkingMode@Idle= 203447.91 4 TimeoutClear 203448.01 4 TimeoutSet 1 EV_TIMEOUT_TIMETOREINITLINE 203448.01 4 tapi Reply (LineEvReply) ok 66371 0 203448.01 4 Play End line[4] (id=917984) 203448.01 4 ScriptEventCode 8001 iLineState=900 203448.01 4 LsAwaitingCalls EV_PLAY_FINISHED 203448.99 4 Timer fired EV_TIMEOUT_TIMETOREINITLINE 203448.99 4 ScriptEventCode 9008 iLineState=900 203448.99 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 203448.99 4 ReinitTelephony due to IDLE 203448.99 4 tapic lineDeallocateCall(MainCall:65554) 0 At that point nothing else is ever written to the log file. When you call in, the phone rings twice and then there is silence. A reboot will fix whatever is wrong and the log file picks up where it left of with... 212020.39 0 ApiMode=[TAPI] 212021.75 0 Tapi device 7 is: Analog Line 4(dxxxB1C4) 212021.75 0 Tapi device 6 is: Analog Line 3(dxxxB1C3) 212021.75 0 Tapi device 5 is: Analog Line 2(dxxxB1C2) 212021.77 0 Tapi device 4 is: Analog Line 1(dxxxB1C1) 212021.77 0 Tapi device 3 is: H323 Line 212021.77 0 Tapi device 2 is: IPCONF LINE 212021.77 0 Tapi device 1 is: WAN Miniport (L2TP) 212021.77 0 Tapi device 0 is: RAS VPN Line 0 212021.97 0 Inband Signaling Config file not specified 212022.02 0 Loading VMB data begin 212022.05 0 Loading VMB data - about to delete old entries 212022.05 0 Loading VMB data - sorting/ordering 212022.05 0 Loading VMB data end 212022.48 0 init clear settings on 4 devices. 212022.53 4 Initialising... 212022.53 5 Initialising... 212022.53 6 Initialising... 212022.53 7 Initialising... 212022.53 0 init attach vgs on 4 devices. 212022.55 0 Script Load C:\Program Files\VoiceGuide\Scripts\PTR_phone\PTR.vgs 212023.36 0 init lineopen on 4 devices. 212023.36 0 Calling .lineOpen on device 1 212023.50 4 lineOpen(4)=> 212023.50 0 Calling .GetLineHandle on LineId 4 212023.50 4 hLine=65945 212023.53 0 Calling .GetProviderInfo on LineId 4 212023.56 4 Line 4 Provider: D41MT.TSP: Dialogic D41 Service Provider 212023.56 0 Calling lineSetNumRings on lLineHandle 65945 212023.56 4 Waiting for a call... 212023.56 4 dlgc bus switching not supported 212023.56 0 Calling .lineOpen on device 2 212023.69 5 lineOpen(5)=> 212023.69 0 Calling .GetLineHandle on LineId 5 212023.69 5 hLine=65911 212023.70 0 Calling .GetProviderInfo on LineId 5 212023.70 5 Line 5 Provider: D41MT.TSP: Dialogic D41 Service Provider 212023.70 0 Calling lineSetNumRings on lLineHandle 65911 212023.70 5 Waiting for a call... 212023.72 5 dlgc bus switching not supported 212023.72 0 Calling .lineOpen on device 3 212023.84 6 lineOpen(6)=> 212023.84 0 Calling .GetLineHandle on LineId 6 212023.84 6 hLine=65877 212023.84 0 Calling .GetProviderInfo on LineId 6 212023.86 6 Line 6 Provider: D41MT.TSP: Dialogic D41 Service Provider 212023.86 0 Calling lineSetNumRings on lLineHandle 65877 212023.86 6 Waiting for a call... 212023.86 6 dlgc bus switching not supported 212023.86 0 Calling .lineOpen on device 4 212024.00 7 lineOpen(7)=> 212024.00 0 Calling .GetLineHandle on LineId 7 212024.00 7 hLine=65843 212024.00 0 Calling .GetProviderInfo on LineId 7 212024.02 7 Line 7 Provider: D41MT.TSP: Dialogic D41 Service Provider 212024.02 0 Calling lineSetNumRings on lLineHandle 65843 212024.02 7 Waiting for a call... 212024.02 7 dlgc bus switching not supported At that point it begins answering calls again and works for great for a while until it happens again. Normal logging of the point where logging stops looks like the following... 074042.96 4 RVreplace end: [Thank you!] 074042.96 4 tts generate start[Thank you!] 074042.96 4 tts generate wait 074042.96 4 RunModule PLAY end 074042.99 4 tts generate finish 074042.99 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 074043.01 4 PlaySoundStart ok [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 074043.01 4 TimeoutClear 074043.01 4 wa(1044,47407800) 074043.01 4 callinfo MONITORMODES 074044.10 4 wb(47407800) 074044.16 4 Play End line[4] (id=474078) 074044.16 4 ScriptEventCode 8001 iLineState=1100 074044.16 4 LsPlayMsg EV_PLAY_FINISHED 074044.16 4 TimeoutSet 10 EV_TIMEOUT_HANGUP 074044.16 4 TimeoutSet 2 EV_TIMEOUT_GOTOMODULE 074046.24 4 Timer fired EV_TIMEOUT_GOTOMODULE 074046.24 4 ScriptEventCode 9002 iLineState=1101 074046.24 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE 074046.24 4 TimeoutClear 074046.24 4 rv add [Hangup Time]{7/1/2004 7:40:46 AM} 074046.24 4 Hanging up call... [Hangup Module] 074046.24 4 RecSoundStop ok 074046.24 4 PlaySoundStop err=0 074046.24 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 074046.24 4 fnHangupCall end 074046.27 4 linedevstate 2048 0 0 074046.27 4 callstate IDLE 66423 0 0 074046.27 4 WorkingMode@Idle= 074046.27 4 TimeoutClear 074046.29 4 TimeoutSet 1 EV_TIMEOUT_TIMETOREINITLINE 074046.29 4 tapi Reply (LineEvReply) ok 66341 0 074047.34 4 Timer fired EV_TIMEOUT_TIMETOREINITLINE 074047.34 4 ScriptEventCode 9008 iLineState=900 074047.34 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 074047.34 4 ReinitTelephony due to IDLE 074047.34 4 tapic lineDeallocateCall(MainCall:66423) 0 074047.49 4 lineOpen(4)=> 074047.49 4 Waiting for a call... 074047.49 4 LineHandle=65895 074047.49 4 TimeoutSet 3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 074050.77 4 Timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 074050.77 4 ScriptEventCode 9013 iLineState=900 074139.96 4 linedevstate 2048 0 0 074139.96 4 callstate OFFERING 66307 0 4 074139.96 4 No old processes from last call (could not obtain handle) 59636, 59484 074139.96 4 Answer the call at 7/1/2004 7:41:39 AM 074139.97 4 lineAnswer(66307) => 65861 074139.97 4 callinfo CALLEDID 074139.97 4 callinfo ORIGIN 074139.97 4 ring 0 The differences I see are that the user doesn't hangup the call during that time, which I assume adds the... 203446.80 4 callstate DISCONNECTED 65554,0,0 203446.80 4 ScriptEventCode 9250 iLineState=1100 203446.80 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 203446.80 4 rv add [Hangup Time]{7/1/2004 8:34:46 PM} 203446.80 4 Hanging up call... 203446.80 4 RecSoundStop ok 203446.80 4 PlaySoundStop err=0 203446.80 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP section, and the second difference is the 203446.85 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 203447.88 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] as apposed to 074042.99 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 074043.01 4 PlaySoundStart ok [C:\PROGRA~1\VOICEG~1\data\tts4.wav] We can't keep rebooting to fix this problem. Any ideas on what is going on and what we could do to fix it? Thanks in advance! Share this post Link to post
SupportTeam Report post Posted 07/03/2004 03:55 AM Which version of VoiceGuide are you using? v5.2.2 or some earlier version? the phone rings twice and then there is silence. This suggests the call is answered but then VG does not play anything. It's strange that the log file is not updated with information if VG does answer the call though... Can you please enable logging to file and then after encountering the problem again just shutdown VG and then .ZIP up and post log files from VG's \log\ subdirectory. Please zip up the current day's files ending in "tw.txt" and "vgm.txt". Share this post Link to post
jph2658 Report post Posted 07/06/2004 01:43 PM We are using v5.2.2. Yes, it is strange that nothing is written to the log file when somebody calls in after 203448.99 4 tapic lineDeallocateCall(MainCall:65554) 0 It's possible somebody called in after that point and nothing was written, but more likely that nobody called in after that point. So, we're going to call in a few times after the error is encountered to see what happens and post whatever the log file gives us. Logging to file is enabled, that's where we got the logs from. The vgm.txt are 40+ mb in length, since the application runs all day and is pretty busy. We posted the sections that we felt were causing the problem, which we believe is 203447.88 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] What does this waveOutWrite failed section mean, and what can we do to prevent it? Thanks for your help. Share this post Link to post
SupportTeam Report post Posted 07/06/2004 09:23 PM What does this waveOutWrite failed section mean, and what can we do to prevent it? It basically means that the wave drivers for the card returned an error - more info on what was the error would be in the "MMDDtw.txt" log file. Please zip up the file ending in "tw.txt" for the day on which the error occurred (or just the porting for a few minutes before the error) and email it to support@voiceguide.com Share this post Link to post
Guest Guest Report post Posted 07/07/2004 01:33 PM I've included the zipped MMDDtw file for the corresponding MMDDvgm file. In it I see the following for the wave MMDDvgm 203446.85 4 fnHangupCall end 203446.85 4 tts generate finish 203446.85 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 203447.88 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 203447.91 4 TimeoutClear 203447.91 4 tapie PlayEnd old line=4 0 917984 203447.91 4 wa(4433,91798400) 203447.91 4 linedevstate 2048 0 0 203447.91 4 callstate IDLE 65554 0 0 203447.91 4 WorkingMode@Idle= 203447.91 4 TimeoutClear MMDDtw 203446.850 tapic lineDrop(0x10012, , 0) => async:66371 (66371) 203446.850 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\data\tts4.wav, lPlayId=0xe01e0, lOptions=0x0, strOptions=) 203446.850 tr 5 ResetWavOutAndContinuePlay start 203446.850 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.850 play FileMmioOpenAndAscend end 203446.850 fn play wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203446.850 wavec waveOutOpen(0x0, 0x0, 0x2165f0, 0x0, 0x0, 0x1) => 0 (MMSYSERR_OK) - format query 203446.850 wavec waveOutOpen(0x216624, 0x0, 0x2165f0, 0x50062, 0x2165c8, 0x10002) => 0 (MMSYSERR_OK) ... hli->hWaveOut=0xc4d6804 203446.850 wavec waveOutReset(0xc4d6804) => 0(MMSYSERR_OK) fn:PlaySoundStart 203446.850 tapic lineMonitorDigits(0x10012, 0x2) => 0x8000001c 203446.850 fn TAPI_ERR:LINEERR_INVALCALLSTATE 203446.850 tapic lineMonitorMedia(0x10012, 0x7c) => 0 203446.866 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.866 play FileMmioOpenAndAscend end 203446.866 play LoadCurrFileToPlay_IntoOneMainBuffer start 203446.866 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.866 play FileMmioOpenAndAscend end 203446.866 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203446.866 wavec waveOutWrite HeapAllocs bytes: DataToPlay=11491, WaveHdr=32, UserData=12 203446.866 wavehdr: lpData=0xc567168, dwBufferLength=11491, dwBytesRecorded=0, dwUser=0xc445788, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 203446.866 play LoadCurrFileToPlay_IntoOneMainBuffer end 203446.866 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 203446.866 wavec waveOutPrepareHeader(0xc4d6804, 0x9d22368, 0x20) => 0 203447.881 wavec waveOutWrite(0xc4d6804, 0x9d22368, 0x20) => 3 203447.881 play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 203447.881 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 203447.881 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav, lPlayId=0xe01e0, lOptions=0x0, strOptions=) 203447.881 tr 5 ResetWavOutAndContinuePlay start 203447.881 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.897 play FileMmioOpenAndAscend end 203447.897 tapic lineMonitorDigits(0x10012, 0x2) => 0x8000001c 203447.897 fn TAPI_ERR:LINEERR_INVALCALLSTATE 203447.897 tapic lineMonitorMedia(0x10012, 0x7c) => 0 203447.897 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.897 play FileMmioOpenAndAscend end 203447.897 play LoadCurrFileToPlay_IntoOneMainBuffer start 203447.897 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.913 play FileMmioOpenAndAscend end 203447.913 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203447.913 wavec waveOutWrite HeapAllocs bytes: DataToPlay=48772, WaveHdr=32, UserData=12 203447.913 wavehdr: lpData=0xc574ff8, dwBufferLength=48772, dwBytesRecorded=0, dwUser=0x9e3c920, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 203447.913 play LoadCurrFileToPlay_IntoOneMainBuffer end 203447.913 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 203447.913 wavec waveOutPrepareHeader(0xc4d6804, 0x9d22368, 0x20) => 0 203447.913 wavec waveOutWrite(0xc4d6804, 0x9d22368, 0x20) => 0 203447.913 tr fn PostMessage WM_FIRE_PLAY_WDOG_SET_EVENT (all in 1 buffer) params: 4 91798400 203447.913 wavee WOM_DONE(0xc4d6804, 0x9d22368) (callback window message) 203447.913 wavee WOM_DONE(0xc4d6804, 0x3bd, 0x2165c8, 0x9d22368, 0x0) psch 203447.913 fn psch lpUserData=0x9e3c920 : dwPlayId=917984, hli=0x2165c8, iFileIdx=0x0 203447.913 fn psch hli=0x2165c8 : dwPlayId=917984, iPlayListIdxCurr=0x0 203447.913 fn psch clearing watchdog 203447.913 tr fn PlaySoundCallBackHandler calling PostMessage WM_FIRE_PLAY_WDOG_CLEAR_EVENT 203447.913 wavec waveOutUnprepareHeader(0xc4d6804, 0x9d22368, 0x20) call non-unimodem 203447.913 wavec waveOutUnprepareHeader(0xc4d6804, 0x9d22368, 0x20) => 33 (WAVERR_STILLPLAYING) non-unimodem 203447.913 ocxev PlayEnd(dwLineId=4, dwPlayId=0x0) 203447.913 fn OnFirePlayPlayWdogSetEvent start 203447.913 ocxev PlayWdogSet(dwLineId=4, dwPlayId=0x578bb80, LenMsecs=4433) 203447.913 tapie LINE_LINEDEVSTATE(4, 0x103ab, 0x800, 0x0, 0x0) 203447.913 tapie LINE_CALLSTATE(4, 0x10012, 0x1, 0x0, 0x0) Hopefully this helps in locating the problem. If you need anymore information please let me know. Thanks for all you help. 0701tw.zip Share this post Link to post
SupportTeam Report post Posted 07/08/2004 02:07 AM Look like this problem occurs only with the sound files created by the Text-To-Speech engine - correct? Which TTS engine are you using? Share this post Link to post
jph2658 Report post Posted 07/08/2004 02:32 PM All voice output is generated as Text-To-Speech throughout the application. The module [say Final Message] is the last module in the application that generates TTS before the hangup module. Which TTS engine are you using? We are using Microsoft Text-To-Speech engine for SAPI version 5.1.4324.00, with Microsoft Mary, Mike, and Sam 5.0. The problem occured again last night and here is the vgm 124907.81 4 RVreplace end: [Thank you!] 124907.81 4 tts generate start[Thank you!] 124907.81 4 tts generate wait 124907.81 4 RunModule PLAY end 124907.86 4 callstate DISCONNECTED 65713,0,0 124907.86 4 ScriptEventCode 9250 iLineState=1100 124907.86 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 124907.86 4 rv add [Hangup Time]{7/7/2004 12:49:07 PM} 124907.86 4 Hanging up call... 124907.86 4 RecSoundStop ok 124907.86 4 PlaySoundStop err=0 124907.86 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 124907.87 4 fnHangupCall end 124907.87 4 tts generate finish 124907.87 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 124908.90 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 124908.93 4 TimeoutClear 124908.93 4 tapie PlayEnd old line=4 0 467859 124908.93 4 wa(4433,46785900) 124908.93 4 linedevstate 2048 0 0 124908.93 4 callstate IDLE 65713 0 0 124908.93 4 WorkingMode@Idle= 124908.93 4 TimeoutClear 124908.93 4 TimeoutSet 1 EV_TIMEOUT_TIMETOREINITLINE 124908.93 4 tapi Reply (LineEvReply) ok 66498 0 124908.93 4 Play End line[4] (id=467859) 124908.93 4 ScriptEventCode 8001 iLineState=900 124908.93 4 LsAwaitingCalls EV_PLAY_FINISHED 124909.92 4 Timer fired EV_TIMEOUT_TIMETOREINITLINE 124909.92 4 ScriptEventCode 9008 iLineState=900 124909.92 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 124909.92 4 ReinitTelephony due to IDLE 124909.92 4 tapic lineDeallocateCall(MainCall:65713) 0 as you can see the same waveOutWrite failed error occured. To confirm what I stated earlier. Yes, it is strange that nothing is written to the log file when somebody calls in after 203448.99 4 tapic lineDeallocateCall(MainCall:65554) 0 It's possible somebody called in after that point and nothing was written, but more likely that nobody called in after that point. So, we're going to call in a few times after the error is encountered to see what happens and post whatever the log file gives us. We did call in three times after the application stopped logging, the phone rings twice and then there is silence, and nothing was written. The error ONLY occurs when the user hangs up the call between the time the TTS is generated 124907.81 4 RVreplace end: [Thank you!] 124907.81 4 tts generate start[Thank you!] 124907.81 4 tts generate wait 124907.81 4 RunModule PLAY end and the time the sound file attempts to play. 124908.90 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] Look like this problem occurs only with the sound files created by the Text-To-Speech engine - correct? Yes, the problem only occurs when the sound files are generated through TTS, but the hangup must also be there in order for the error to occur. I"m led to believe that the hangup directly after the TTS is generated modifies something which leads to the sound file failing to play. Since this is the only time this occurs, do you believe that the hangup during that time has something to do with it? Thank you for all your help. Share this post Link to post
SupportTeam Report post Posted 07/09/2004 07:27 AM We've tried to replicate the problem with v5.2.3 of VoiceGuide and did not find this problem happening with this versionYou can download v5.2.3 from: [old link removed]Please uninstall v5.2.2 (backup and scripts you need first!) then install v5.2.3 and try again. (your registration code will work - all v5.0 onwards codes will work with v5.2.3) Share this post Link to post
jph2658 Report post Posted 07/09/2004 03:56 PM I'm confindent the error will still be there, but we'll try it anyway. From my last post... Since this is the only time this occurs, do you believe that the hangup during that time has something to do with it? Can we concentrate on the errors present and try to figure out exactly what is happening? Replicating the problem on your machines isn't probable, since there are thousands of configurations. The error only occurs inbetween 124907.81 4 tts generate start[Thank you!] 124907.81 4 tts generate wait and 124907.87 4 tts generate finish So, I'm led to believe that the remote hangup does something that it shouldn't while the TTS is being generated. Here is another complete log of the error "vgm.txt" with the corresponding "tw.txt" vgm.txt 203446.77 4 RVreplace end: [Thank you!] 203446.77 4 tts generate start[Thank you!] 203446.77 4 tts generate wait 203446.77 4 RunModule PLAY end 203446.80 4 callstate DISCONNECTED 65554,0,0 203446.80 4 ScriptEventCode 9250 iLineState=1100 203446.80 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 203446.80 4 rv add [Hangup Time]{7/1/2004 8:34:46 PM} 203446.80 4 Hanging up call... 203446.80 4 RecSoundStop ok 203446.80 4 PlaySoundStop err=0 203446.80 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 203446.85 4 fnHangupCall end 203446.85 4 tts generate finish 203446.85 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 203447.88 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 203447.91 4 TimeoutClear 203447.91 4 tapie PlayEnd old line=4 0 917984 203447.91 4 wa(4433,91798400) 203447.91 4 linedevstate 2048 0 0 203447.91 4 callstate IDLE 65554 0 0 203447.91 4 WorkingMode@Idle= 203447.91 4 TimeoutClear 203448.01 4 TimeoutSet 1 EV_TIMEOUT_TIMETOREINITLINE 203448.01 4 tapi Reply (LineEvReply) ok 66371 0 203448.01 4 Play End line[4] (id=917984) 203448.01 4 ScriptEventCode 8001 iLineState=900 203448.01 4 LsAwaitingCalls EV_PLAY_FINISHED 203448.99 4 Timer fired EV_TIMEOUT_TIMETOREINITLINE 203448.99 4 ScriptEventCode 9008 iLineState=900 203448.99 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 203448.99 4 ReinitTelephony due to IDLE 203448.99 4 tapic lineDeallocateCall(MainCall:65554) 0 tw.txt entire tw.txt in zip file further down in post 203446.803 tapie LINE_CALLSTATE(4, 0x10012, 0x4000, 0x0, 0x0) 203446.803 ocxfn RecStop(sLineId:4) 203446.803 wavec waveInReset(0x0) => 5 203446.803 linec lineMonitorTones(0x10012, 0x0, 1) => 0x8000001c (disabling monitoring for silence/tones) 203446.803 ocxfn PlayStop(sLineId=4) 203446.803 fn PlaySoundStop start 203446.803 fn PlaySoundStop line not playing a sound file. 0x9d5a23c 203446.803 ocxfn LineDrop(sLineId=4, sOpt=0) 203446.803 wavec waveOutReset(0x9d5a23c) call, caller: LineDrop TSP_DIALOGIC 203446.803 wavec waveOutReset(0x9d5a23c) => 0 (MMSYSERR_OK), caller: LineDrop TSP_DIALOGIC 203446.803 wavec waveOutClose(0x9d5a23c) call, caller: LineDrop TSP_DIALOGIC 203446.803 wavec waveOutClose(0x9d5a23c) => 0 (MMSYSERR_OK), caller: LineDrop TSP_DIALOGIC 203446.803 tapic lineDrop(0x10012, , 0) call in ocxfn_LineDrop 203446.850 tapic lineDrop(0x10012, , 0) => async:66371 (66371) 203446.850 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\data\tts4.wav, lPlayId=0xe01e0, lOptions=0x0, strOptions=) 203446.850 tr 5 ResetWavOutAndContinuePlay start 203446.850 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.850 play FileMmioOpenAndAscend end 203446.850 fn play wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203446.850 wavec waveOutOpen(0x0, 0x0, 0x2165f0, 0x0, 0x0, 0x1) => 0 (MMSYSERR_OK) - format query 203446.850 wavec waveOutOpen(0x216624, 0x0, 0x2165f0, 0x50062, 0x2165c8, 0x10002) => 0 (MMSYSERR_OK) ... hli->hWaveOut=0xc4d6804 203446.850 wavec waveOutReset(0xc4d6804) => 0(MMSYSERR_OK) fn:PlaySoundStart 203446.850 tapic lineMonitorDigits(0x10012, 0x2) => 0x8000001c 203446.850 fn TAPI_ERR:LINEERR_INVALCALLSTATE 203446.850 tapic lineMonitorMedia(0x10012, 0x7c) => 0 203446.866 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.866 play FileMmioOpenAndAscend end 203446.866 play LoadCurrFileToPlay_IntoOneMainBuffer start 203446.866 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 203446.866 play FileMmioOpenAndAscend end 203446.866 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203446.866 wavec waveOutWrite HeapAllocs bytes: DataToPlay=11491, WaveHdr=32, UserData=12 203446.866 wavehdr: lpData=0xc567168, dwBufferLength=11491, dwBytesRecorded=0, dwUser=0xc445788, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 203446.866 play LoadCurrFileToPlay_IntoOneMainBuffer end 203446.866 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 203446.866 wavec waveOutPrepareHeader(0xc4d6804, 0x9d22368, 0x20) => 0 203447.881 wavec waveOutWrite(0xc4d6804, 0x9d22368, 0x20) => 3 203447.881 play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 203447.881 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 203447.881 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav, lPlayId=0xe01e0, lOptions=0x0, strOptions=) 203447.881 tr 5 ResetWavOutAndContinuePlay start 203447.881 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.897 play FileMmioOpenAndAscend end 203447.897 tapic lineMonitorDigits(0x10012, 0x2) => 0x8000001c 203447.897 fn TAPI_ERR:LINEERR_INVALCALLSTATE 203447.897 tapic lineMonitorMedia(0x10012, 0x7c) => 0 203447.897 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.897 play FileMmioOpenAndAscend end 203447.897 play LoadCurrFileToPlay_IntoOneMainBuffer start 203447.897 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 203447.913 play FileMmioOpenAndAscend end 203447.913 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 203447.913 wavec waveOutWrite HeapAllocs bytes: DataToPlay=48772, WaveHdr=32, UserData=12 203447.913 wavehdr: lpData=0xc574ff8, dwBufferLength=48772, dwBytesRecorded=0, dwUser=0x9e3c920, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 203447.913 play LoadCurrFileToPlay_IntoOneMainBuffer end 203447.913 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 203447.913 wavec waveOutPrepareHeader(0xc4d6804, 0x9d22368, 0x20) => 0 203447.913 wavec waveOutWrite(0xc4d6804, 0x9d22368, 0x20) => 0 203447.913 tr fn PostMessage WM_FIRE_PLAY_WDOG_SET_EVENT (all in 1 buffer) params: 4 91798400 203447.913 wavee WOM_DONE(0xc4d6804, 0x9d22368) (callback window message) 203447.913 wavee WOM_DONE(0xc4d6804, 0x3bd, 0x2165c8, 0x9d22368, 0x0) psch 203447.913 fn psch lpUserData=0x9e3c920 : dwPlayId=917984, hli=0x2165c8, iFileIdx=0x0 203447.913 fn psch hli=0x2165c8 : dwPlayId=917984, iPlayListIdxCurr=0x0 203447.913 fn psch clearing watchdog 203447.913 tr fn PlaySoundCallBackHandler calling PostMessage WM_FIRE_PLAY_WDOG_CLEAR_EVENT 203447.913 wavec waveOutUnprepareHeader(0xc4d6804, 0x9d22368, 0x20) call non-unimodem 203447.913 wavec waveOutUnprepareHeader(0xc4d6804, 0x9d22368, 0x20) => 33 (WAVERR_STILLPLAYING) non-unimodem 203447.913 ocxev PlayEnd(dwLineId=4, dwPlayId=0x0) 203447.913 fn OnFirePlayPlayWdogSetEvent start 203447.913 ocxev PlayWdogSet(dwLineId=4, dwPlayId=0x578bb80, LenMsecs=4433) 203447.913 tapie LINE_LINEDEVSTATE(4, 0x103ab, 0x800, 0x0, 0x0) 203447.913 tapie LINE_CALLSTATE(4, 0x10012, 0x1, 0x0, 0x0) 203448.006 tapie LINE_REPLY(4, 0x0, 0x10343, 0x0) 203448.006 fn OnFirePlayPlayWdogClearEvent start 203448.006 ocxev PlayWdogClear(dwLineId=4, dwPlayId=0x578bb80) 203448.006 ocxev PlayEnd(dwLineId=4, dwPlayId=0xe01e0) 203448.991 ocxfn LineClose(sLineId:4) 203448.991 wavec waveOutReset(0xc4d6804) call, caller: OneLineClose_Dialogic Here is a walkthrough of what I see is happening. After the remote hangup a call to waveOutClose is made. 203446.803 wavec waveOutClose(0x9d5a23c) call, caller: LineDrop TSP_DIALOGIC 203446.803 wavec waveOutClose(0x9d5a23c) => 0 (MMSYSERR_OK), caller: LineDrop TSP_DIALOGIC The application still wants to play the generated tts, so during the call to 203446.850 tr 5 ResetWavOutAndContinuePlay start the application doesn't find an open hWaveOut handle so it creates a new one with 203446.850 wavec waveOutOpen(0x0, 0x0, 0x2165f0, 0x0, 0x0, 0x1) => 0 (MMSYSERR_OK) - format query 203446.850 wavec waveOutOpen(0x216624, 0x0, 0x2165f0, 0x50062, 0x2165c8, 0x10002) => 0 (MMSYSERR_OK) ... hli->hWaveOut=0xc4d6804 so it can play the generated *.wav. Then when it gets to 203447.881 wavec waveOutWrite(0xc4d6804, 0x9d22368, 0x20) => 3 The error value returned is 3, which I believe is MMSYSERR_NOTENABLED, indicating that the driver failed to enable. Since this waveOutWrite falied the application tries to play ErrorPlayingFile.wav which it succeeds in doing. Then on a call to 203447.913 wavec waveOutUnprepareHeader(0xc4d6804, 0x9d22368, 0x20) => 33 (WAVERR_STILLPLAYING) non-unimodem the WAVERR_STILLPLAYING error is received. Then on a call to 203448.991 wavec waveOutReset(0xc4d6804) call, caller: OneLineClose_Dialogic the application just hangs... and no further logging takes place. Things that I see different from normal execution are this. I believe only one hWaveOut handle is created for each phone call. This is the only time two waveOutOpen calls are made during a call. Before each waveOpen and between each call to waveOutClose and waveOutOpen, a call is made to waveOutGetDevCaps. This call may or may not be necessary. The last waveOutReset before the application stops logging the call is made by OneLineClose_Dialogic. This is the only time this happens. A call to lineMonitorDigits after a call to lineDrop and before a lineOpen. This returns the LINEERR_INVALCALLSTATE value. Why is this function call being made on a DISCONNECTED line? Would you please have a developer look through the logs. I believe the solution is action taken in the VoiceGuide code itself. Thank you for your help. Share this post Link to post
SupportTeam Report post Posted 07/09/2004 09:38 PM Then on a call to 203448.991 wavec waveOutReset(0xc4d6804) call, caller: OneLineClose_Dialogic the application just hangs... and no further logging takes place. I'm told the above is basically the correct interpretation of the bug that you were finding in v5.2.2 when call was finished during TTS generation. This is the exact scenario that we replicated in v5.2.3 and confirmed that hanging up while TTS is in the process of generating sound file no longer causes the problem. There were changes made to that area of code in v5.2.3 to fix this bug. Please download v5.2.3 and you'll be able to test this out for yourself. Share this post Link to post
jph2658 Report post Posted 07/14/2004 04:34 AM Okay, uninstalled 5.2.2 and installed 5.2.3 Now the script that was working doesn't... here's where it stops logging and does nothing.... vgm.txt 001617.31 4 db [FirstMenu] row count=1 001617.31 4 rv add [FirstMenu_RowCount]{1} 001617.31 4 db get RecordsetGetRowsMax 001617.31 4 db get 999 records. (row count=1) 001617.31 4 db update RVs 001617.31 4 db about to close dbDbqDatabase 001617.31 4 db going down path [True] 001617.31 4 TimeoutClear 001617.31 4 [say First Menu] Playing 001617.31 4 rv replace start: [After the beep. $RV[FirstMenu_1_1]] 001617.31 4 rv ns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[scriptPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone}[$RV_STARTTIME]{7/14/2004 12:16:17 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number} 001617.31 4 rv replace end: [After the beep. Please Enter your 7 digits pager number] 001617.31 4 tts generate start[After the beep. Please Enter your 7 digits pager number] 001617.31 4 tts generate wait 001617.31 4 RunModule PLAY end 001617.36 4 tts generate finish 001617.36 4 [say First Menu] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 001617.36 4 play set playid=883593 001617.38 4 PlaySoundStart ok [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 001617.38 4 TimeoutClear 001617.38 4 wa(3708,88359300) 001617.38 4 callinfo MONITORMODES 001621.17 4 play end current play (playid=883593) 001621.17 4 ScriptEventCode 8001 iLineState=1100 001621.17 4 LsPlayMsg EV_PLAY_FINISHED 001621.17 4 TimeoutSet 10 EV_TIMEOUT_HANGUP 001621.17 4 TimeoutSet 0 EV_TIMEOUT_GOTOMODULE 001621.17 4 ScriptEventCode 9002 iLineState=1101 001621.17 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE 001621.17 4 TimeoutClear 001621.17 4 [PagerNumber] Number Input 001621.17 4 [PagerNumber] Playing (C:\Program Files\VoiceGuide\system\voice\beep1.wav) 001621.17 4 play set playid=887406 001621.19 4 PlaySoundStart ok [C:\Program Files\VoiceGuide\system\voice\beep1.wav] 001621.19 4 TimeoutClear 001621.19 4 wa(186,88740600) 001621.19 4 callinfo MONITORMODES 001621.39 4 wb(88740600) 001621.44 4 play end current play (playid=887406) 001621.44 4 ScriptEventCode 8001 iLineState=1300 001621.44 4 LsGetNbrsPlayWelcMsg EV_PLAY_FINISHED 001621.44 4 TimeoutSet 10 EV_TIMEOUT_HANGUP 001621.44 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001623.11 4 dtmf 2 (65877,50,2) 001623.11 4 ScriptEventCode 50 iLineState=1301 001623.11 4 LsGetNbrsRxDigits 2 001623.11 4 [PagerNumber] Number Input 2 001623.11 4 path {2} not found 001623.11 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001623.44 4 dtmf 2 (65877,50,2) 001623.44 4 ScriptEventCode 50 iLineState=1301 001623.44 4 LsGetNbrsRxDigits 2 001623.44 4 [PagerNumber] Number Input 22 001623.44 4 path {22} not found 001623.44 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001624.30 4 dtmf 0 (65877,48,2) 001624.30 4 ScriptEventCode 48 iLineState=1301 001624.30 4 LsGetNbrsRxDigits 0 001624.30 4 [PagerNumber] Number Input 220 001624.30 4 path {220} not found 001624.30 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001625.92 4 dtmf 5 (65877,53,2) 001625.92 4 ScriptEventCode 53 iLineState=1301 001625.92 4 LsGetNbrsRxDigits 5 001625.92 4 [PagerNumber] Number Input 2205 001625.92 4 path {2205} not found 001625.92 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001626.22 4 dtmf 6 (65877,54,2) 001626.22 4 ScriptEventCode 54 iLineState=1301 001626.22 4 LsGetNbrsRxDigits 6 001626.22 4 [PagerNumber] Number Input 22056 001626.22 4 path {22056} not found 001626.22 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001626.56 4 dtmf 7 (65877,55,2) 001626.56 4 ScriptEventCode 55 iLineState=1301 001626.56 4 LsGetNbrsRxDigits 7 001626.56 4 [PagerNumber] Number Input 220567 001626.56 4 path {220567} not found 001626.56 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001626.89 4 dtmf 2 (65877,50,2) 001626.91 4 ScriptEventCode 50 iLineState=1301 001626.91 4 LsGetNbrsRxDigits 2 001626.91 4 [PagerNumber] Number Input 2205672 001626.91 4 path {2205672} not found 001626.91 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 001626.91 4 TimeoutClear 001626.91 4 rv add [PagerNumber]{2205672} 001626.91 4 path {2205672} not found 001626.91 4 AddRVns [PagerNumber_PathTaken]{success} 001626.91 4 TimeoutClear 001626.91 4 [userID] DB Query 001626.91 4 db ODBC mode used 001626.92 4 rv replace start: [sELECT userid FROM TA_on_duty WHERE pagerNum=$RV[PagerNumber];] 001626.92 4 rv ns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[scriptPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone}[$RV_STARTTIME]{7/14/2004 12:16:17 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205672}[PagerNumber_PathTaken]{success} 001626.92 4 rv replace end: [sELECT userid FROM TA_on_duty WHERE pagerNum=2205672;] 001626.92 4 db About to execute a Retrieve type operation [sELECT userid FROM TA_on_duty WHERE pagerNum=2205672;] 001626.92 4 db [userID] row count=1 001626.92 4 rv add [userID_RowCount]{1} 001626.92 4 db get RecordsetGetRowsMax 001626.92 4 db get 999 records. (row count=1) 001626.92 4 db update RVs 001626.92 4 db about to close dbDbqDatabase 001626.92 4 db going down path [True] 001626.92 4 TimeoutClear 001626.92 4 [userID Found] Running VB Script... 001626.92 4 rv replace start: [set vg = CreateObject("VoiceGuide.CommandLink") vg.Run_ResultReturn $RV_LINEID, "[gMenu]{3}[gOptionID]{0}[gUserID]{$RV[userID_1_1]}" Set vg = Nothing ] 001626.92 4 rv ns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[scriptPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone}[$RV_STARTTIME]{7/14/2004 12:16:17 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205672}[PagerNumber_PathTaken]{success}[userID_RowCount]{1 [userID_1_1]{129} 001626.92 4 eng copy of script ran: vbs_4_1.vbs 001626.94 4 Run Script waiting... (shellid=1884, process=1460) 001626.94 4 TimeoutSet 1 EV_TIMEOUT_CHECKONSTATE 001626.95 4 cl Run_ResultReturn >>[gMenu]{3}[gOptionID]{0}[gUserID]{129}<< 001626.95 4 AddRVns [gMenu]{3} 001626.95 4 AddRVns [gOptionID]{0} 001626.95 4 AddRVns [gUserID]{129} 001626.95 4 cl module's runwait=1, WavPlayHasNowFinished=0 001626.95 4 cl wait till WAV finished then goto [userName] (idx=29) 001628.02 4 Timer fired EV_TIMEOUT_CHECKONSTATE 001628.02 4 ScriptEventCode 9007 iLineState=1501 001628.02 4 LsRunWaitTillFinished EV_TIMEOUT_CHECKONSTATE 001628.02 4 task completed (shellid=1884, process=1460) vgm=5, iRunWait_ExeResult_NextVgm=29 001628.02 4 eng wait till WAV finished (runwait=1) then goto [userName] (idx=29) tw.txt 001617.359 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\data\tts4.wav, lPlayId=0xd7b89, lOptions=0x0, strOptions=) 001617.359 tr 5 ResetWavOutAndContinuePlay start 001617.359 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 001617.359 play FileMmioOpenAndAscend end 001617.359 fn play wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 001617.359 wavec waveOutOpen(0x0, 0x0, 0x1af408, 0x0, 0x0, 0x1) => 0 (MMSYSERR_OK) - format query 001617.359 wavec waveOutOpen(0x1af43c, 0x0, 0x1af408, 0x100de, 0x1af3e0, 0x10002) => 0 (MMSYSERR_OK) ... hli->hWaveOut=0x1e7184 001617.359 wavec waveOutReset(0x1e7184) => 0(MMSYSERR_OK) fn:PlaySoundStart 001617.359 tapic lineMonitorDigits(0x10155, 0x2) => 0x0 001617.375 tapic lineMonitorMedia(0x10155, 0x7c) => 0 001617.375 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 001617.375 play FileMmioOpenAndAscend end 001617.375 play LoadCurrFileToPlay_IntoOneMainBuffer start 001617.375 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 001617.375 play FileMmioOpenAndAscend end 001617.375 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 001617.375 wavec waveOutWrite HeapAllocs bytes: DataToPlay=40798, WaveHdr=32, UserData=12 001617.375 wavehdr: lpData=0xaa5e488, dwBufferLength=40798, dwBytesRecorded=0, dwUser=0xaa6caf0, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 001617.375 play LoadCurrFileToPlay_IntoOneMainBuffer end 001617.375 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 001617.375 wavec waveOutPrepareHeader(0x1e7184, 0xaa7ed70, 0x20) => 0 001617.375 wavec waveOutWrite(0x1e7184, 0xaa7ed70, 0x20) => 0 001617.375 tr fn PostMessage WM_FIRE_PLAY_WDOG_SET_EVENT (all in 1 buffer) params: 4 88359300 001617.375 fn OnFirePlayPlayWdogSetEvent start 001617.375 ocxev PlayWdogSet(dwLineId=4, dwPlayId=0x5444184, LenMsecs=3708) 001617.375 tapie LINE_CALLINFO(4, 0x10155, 0x8000000) 001621.171 wavee WOM_DONE(0x1e7184, 0xaa7ed70) (callback window message) 001621.171 wavee WOM_DONE(0x1e7184, 0x3bd, 0x1af3e0, 0xaa7ed70, 0x0) psch 001621.171 fn psch lpUserData=0xaa6caf0 : dwPlayId=883593, hli=0x1af3e0, iFileIdx=0x0 001621.171 fn psch hli=0x1af3e0 : dwPlayId=883593, iPlayListIdxCurr=0x0 001621.171 fn psch clearing watchdog 001621.171 tr fn PlaySoundCallBackHandler calling PostMessage WM_FIRE_PLAY_WDOG_CLEAR_EVENT 001621.171 wavec waveOutUnprepareHeader(0x1e7184, 0xaa7ed70, 0x20) call non-unimodem 001621.171 wavec waveOutUnprepareHeader(0x1e7184, 0xaa7ed70, 0x20) => 0 (MMSYSERR_OK) non-unimodem 001621.171 fn OnFirePlayPlayWdogClearEvent start 001621.171 ocxev PlayWdogClear(dwLineId=4, dwPlayId=0x5444184) 001621.171 ocxev PlayEnd(dwLineId=4, dwPlayId=0xd7b89) 001621.171 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\Program Files\VoiceGuide\system\voice\beep1.wav, lPlayId=0xd8a6e, lOptions=0x0, strOptions=) 001621.171 tr 5 ResetWavOutAndContinuePlay start 001621.171 play FileMmioOpenAndAscend start C:\Program Files\VoiceGuide\system\voice\beep1.wav 001621.171 play FileMmioOpenAndAscend end 001621.171 tapic lineMonitorDigits(0x10155, 0x2) => 0x0 001621.171 tapic lineMonitorMedia(0x10155, 0x7c) => 0 001621.171 play FileMmioOpenAndAscend start C:\Program Files\VoiceGuide\system\voice\beep1.wav 001621.187 play FileMmioOpenAndAscend end 001621.187 play LoadCurrFileToPlay_IntoOneMainBuffer start 001621.187 play FileMmioOpenAndAscend start C:\Program Files\VoiceGuide\system\voice\beep1.wav 001621.187 play FileMmioOpenAndAscend end 001621.187 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 001621.187 wavec waveOutWrite HeapAllocs bytes: DataToPlay=2053, WaveHdr=32, UserData=12 001621.187 wavehdr: lpData=0xaa79008, dwBufferLength=2053, dwBytesRecorded=0, dwUser=0xaa5e470, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 001621.187 play LoadCurrFileToPlay_IntoOneMainBuffer end 001621.187 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 001621.187 wavec waveOutPrepareHeader(0x1e7184, 0xaa7ed70, 0x20) => 0 001621.187 wavec waveOutWrite(0x1e7184, 0xaa7ed70, 0x20) => 0 001621.187 tr fn PostMessage WM_FIRE_PLAY_WDOG_SET_EVENT (all in 1 buffer) params: 4 88740600 001621.187 fn OnFirePlayPlayWdogSetEvent start 001621.187 ocxev PlayWdogSet(dwLineId=4, dwPlayId=0x54a12f8, LenMsecs=186) 001621.187 tapie LINE_CALLINFO(4, 0x10155, 0x8000000) 001621.390 ocxfn PlayStopSpecial(sLineId:4, lParam1:0x54a12f8, lParam2(idcode):0, giving: dwPlayId=887406, iPlayIdx=0) 001621.390 wavec waveOutReset(0x1e7184) call fn:PlayStopSpecial 001621.437 wavec waveOutReset(0x1e7184) => 0 (MMSYSERR_OK) fn:PlayStopSpecial 001621.437 wavec waveOutReset(0x1e7184) call, caller: PlayStopSpecial 001621.437 wavec waveOutReset(0x1e7184) => 0 (MMSYSERR_OK), caller: PlayStopSpecial 001621.437 wavec will wait with waveOutClose until after WOM_DONE 001621.437 wavee WOM_DONE(0x1e7184, 0xaa7ed70) (callback window message) 001621.437 wavee WOM_DONE(0x1e7184, 0x3bd, 0x1af3e0, 0xaa7ed70, 0x0) psch 001621.437 fn psch lpUserData=0xaa5e470 : dwPlayId=887406, hli=0x1af3e0, iFileIdx=0x0 001621.437 fn psch hli=0x1af3e0 : dwPlayId=887406, iPlayListIdxCurr=0x0 001621.437 fn psch clearing watchdog 001621.437 tr fn PlaySoundCallBackHandler calling PostMessage WM_FIRE_PLAY_WDOG_CLEAR_EVENT 001621.437 wavec waveOutUnprepareHeader(0x1e7184, 0xaa7ed70, 0x20) call non-unimodem 001621.437 wavec waveOutUnprepareHeader(0x1e7184, 0xaa7ed70, 0x20) => 0 (MMSYSERR_OK) non-unimodem 001621.437 fn OnFirePlayPlayWdogClearEvent start 001621.437 ocxev PlayWdogClear(dwLineId=4, dwPlayId=0x54a12f8) 001621.437 ocxev PlayEnd(dwLineId=4, dwPlayId=0xd8a6e) 001623.109 tapie LINE_MONITORDIGITS(4, 0x10155, 0x32) 001623.437 tapie LINE_MONITORDIGITS(4, 0x10155, 0x32) 001624.296 tapie LINE_MONITORDIGITS(4, 0x10155, 0x30) 001625.921 tapie LINE_MONITORDIGITS(4, 0x10155, 0x35) 001626.218 tapie LINE_MONITORDIGITS(4, 0x10155, 0x36) 001626.562 tapie LINE_MONITORDIGITS(4, 0x10155, 0x37) 001626.890 tapie LINE_MONITORDIGITS(4, 0x10155, 0x32) normal execution when the script did work in 5.2.2 looks like this vgm.txt 070906.84 4 [say First Menu] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 070906.86 4 PlaySoundStart ok [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 070906.87 4 TimeoutClear 070906.87 4 wa(3712,45306200) 070906.87 4 callinfo MONITORMODES 070910.65 4 Play End line[4] (id=453062) 070910.65 4 ScriptEventCode 8001 iLineState=1100 070910.65 4 LsPlayMsg EV_PLAY_FINISHED 070910.65 4 TimeoutSet 10 EV_TIMEOUT_HANGUP 070910.65 4 TimeoutSet 0 EV_TIMEOUT_GOTOMODULE 070910.65 4 ScriptEventCode 9002 iLineState=1101 070910.65 4 LsPlayMsgFinished EV_TIMEOUT_GOTOMODULE 070910.65 4 TimeoutClear 070910.65 4 [PagerNumber] Number Input 070910.65 4 [PagerNumber] Playing (C:\Program Files\VoiceGuide\system\voice\beep1.wav) 070910.67 4 PlaySoundStart ok [C:\Program Files\VoiceGuide\system\voice\beep1.wav] 070910.67 4 TimeoutClear 070910.67 4 wa(186,45689000) 070910.67 4 callinfo MONITORMODES 070910.87 4 wb(45689000) 070910.92 4 Play End line[4] (id=456890) 070910.92 4 ScriptEventCode 8001 iLineState=1300 070910.92 4 LsGetNbrsPlayWelcMsg EV_PLAY_FINISHED 070910.92 4 TimeoutSet 10 EV_TIMEOUT_HANGUP 070910.92 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070911.42 4 dtmf 2 (66374,50,2) 070911.42 4 ScriptEventCode 50 iLineState=1301 070911.42 4 LsGetNbrsRxDigits 2 070911.42 4 [PagerNumber] Number Input 2 070911.42 4 path {2} not found 070911.42 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070911.64 4 dtmf 2 (66374,50,2) 070911.64 4 ScriptEventCode 50 iLineState=1301 070911.64 4 LsGetNbrsRxDigits 2 070911.64 4 [PagerNumber] Number Input 22 070911.64 4 path {22} not found 070911.64 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070912.28 4 dtmf 0 (66374,48,2) 070912.28 4 ScriptEventCode 48 iLineState=1301 070912.28 4 LsGetNbrsRxDigits 0 070912.28 4 [PagerNumber] Number Input 220 070912.28 4 path {220} not found 070912.28 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070912.67 4 dtmf 5 (66374,53,2) 070912.67 4 ScriptEventCode 53 iLineState=1301 070912.67 4 LsGetNbrsRxDigits 5 070912.67 4 [PagerNumber] Number Input 2205 070912.67 4 path {2205} not found 070912.67 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070912.94 4 dtmf 6 (66374,54,2) 070912.94 4 ScriptEventCode 54 iLineState=1301 070912.94 4 LsGetNbrsRxDigits 6 070912.94 4 [PagerNumber] Number Input 22056 070912.94 4 path {22056} not found 070912.94 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070913.20 4 dtmf 7 (66374,55,2) 070913.20 4 ScriptEventCode 55 iLineState=1301 070913.20 4 LsGetNbrsRxDigits 7 070913.20 4 [PagerNumber] Number Input 220567 070913.20 4 path {220567} not found 070913.20 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070913.59 4 dtmf 5 (66374,53,2) 070913.59 4 ScriptEventCode 53 iLineState=1301 070913.59 4 LsGetNbrsRxDigits 5 070913.59 4 [PagerNumber] Number Input 2205675 070913.59 4 path {2205675} not found 070913.59 4 TimeoutSet 10 EV_TIMEOUT_GOTOMODULE 070913.59 4 TimeoutClear 070913.59 4 rv add [PagerNumber]{2205675} 070913.59 4 path {2205675} not found 070913.59 4 AddRVns [PagerNumber_PathTaken]{success} 070913.59 4 TimeoutClear 070913.59 4 [userID] DB Query 070913.59 4 db ODBC mode used 070913.61 4 RVreplace start: [sELECT userid FROM TA_on_duty WHERE pagerNum=$RV[PagerNumber];] 070913.61 4 RVns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[$RV_STARTTIME]{7/11/2004 7:09:06 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205675}[PagerNumber_PathTaken]{success} 070913.61 4 RVreplace end: [sELECT userid FROM TA_on_duty WHERE pagerNum=2205675;] 070913.61 4 db About to execute a Retrieve type operation [sELECT userid FROM TA_on_duty WHERE pagerNum=2205675;] 070913.61 4 db [userID] row count=1 070913.61 4 rv add [userID_RowCount]{1} 070913.61 4 db get RecordsetGetRowsMax 070913.61 4 db get 100 records. (row count=1) 070913.61 4 db update RVs 070913.61 4 db about to close dbDbqDatabase 070913.61 4 db going down path [True] 070913.61 4 TimeoutClear 070913.61 4 [userID Found] Running VB Script... 070913.61 4 RVreplace start: [set vg = CreateObject("VoiceGuide.CommandLink") vg.Run_ResultReturn $RV_LINEID, "[gMenu]{3}[gOptionID]{0}[gUserID]{$RV[userID_1_1]}" Set vg = Nothing ] 070913.61 4 RVns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[$RV_STARTTIME]{7/11/2004 7:09:06 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205675}[PagerNumber_PathTaken]{success}[userID_RowCount]{1 [userID_1_1]{131} 070913.61 4 eng copy of script ran: vbs_4_1.vbs 070913.62 4 Run Script waiting... (shellid=28352, process=39072) 070913.62 4 TimeoutSet 1 EV_TIMEOUT_CHECKONSTATE 070913.64 4 cl Run_ResultReturn >>[gMenu]{3}[gOptionID]{0}[gUserID]{131}<< 070913.64 4 AddRVns [gMenu]{3} 070913.64 4 AddRVns [gOptionID]{0} 070913.64 4 AddRVns [gUserID]{131} 070913.64 4 TimeoutClear 070913.64 4 [userName] DB Query 070913.64 4 db ODBC mode used 070913.65 4 RVreplace start: [sELECT firstname + ', ' + lastname AS username FROM Users where userID=$RV[gUserID];] 070913.65 4 RVns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[$RV_STARTTIME]{7/11/2004 7:09:06 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205675}[PagerNumber_PathTaken]{success}[userID_RowCount]{1 [userID_1_1]{131}[gMenu]{3}[gOptionID]{0}[gUserID]{131} 070913.65 4 RVreplace end: [sELECT firstname + ', ' + lastname AS username FROM Users where userID=131;] 070913.65 4 db About to execute a Retrieve type operation [sELECT firstname + ', ' + lastname AS username FROM Users where userID=131;] 070913.65 4 db [userName] row count=1 070913.65 4 rv add [userName_RowCount]{1} 070913.67 4 db get RecordsetGetRowsMax 070913.67 4 db get 100 records. (row count=1) 070913.67 4 db update RVs 070913.67 4 db about to close dbDbqDatabase 070913.67 4 db going down path [True] 070913.67 4 TimeoutClear 070913.67 4 [Confirm] Playing 070913.67 4 RVreplace start: [This number is assigned to $RV[userName_1_1]] 070913.67 4 RVns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[$RV_STARTTIME]{7/11/2004 7:09:06 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number}[PagerNumber]{2205675}[PagerNumber_PathTaken]{success}[userID_RowCount]{1 [userID_1_1]{131}[gMenu]{3}[gOptionID]{0}[gUserID]{131}[userName_RowCount]{1}[us rName_1_1]{Derek , Williams} 070913.67 4 RVreplace end: [This number is assigned to Derek , Williams] 070913.67 4 tts generate start[This number is assigned to Derek , Williams] 070913.67 4 tts generate wait 070913.67 4 RunModule PLAY end Why does the application stop there? Is 5.2.3 a stable release? Share this post Link to post
jph2658 Report post Posted 07/14/2004 04:39 AM Is there some other way to get support? E-mail, phone, anything other than the forums? Share this post Link to post
SupportTeam Report post Posted 07/14/2004 05:52 AM Please place the attached .exe in VoiceGuide directory and try running your script again. This version fixes the bug which you have encountered. (the downloadable install will now also be updated). The support forum is probably the best place for resolving most issues. Phone support can be purchased - please see the WWW based order form. VgMulti_5.2.3013.zip Share this post Link to post
jph2658 Report post Posted 07/16/2004 07:29 PM Okay, uninstalled 5.2.3 and installed 5.2.3013. The application froze again... Here's where it stops 0715vgm.txt 190846.09 4 tts generate start[Thank you!] 190846.09 4 tts generate wait 190846.09 4 RunModule PLAY end 190846.13 4 callstate DISCONNECTED 65994 0 0 190846.13 4 ScriptEventCode 9250 iLineState=1100 190846.13 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 190846.13 4 rv add [Hangup Time]{7/15/2004 7:08:46 PM} 190846.13 4 Hanging up call... 190846.13 4 RecSoundStop ok 190846.13 4 PlaySoundStop err=0 190846.13 4 TimeoutSet 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 190846.13 4 fnHangupCall end 190846.14 4 tts generate finish 190846.14 4 [say Final Message] Playing (C:\PROGRA~1\VOICEG~1\data\tts4.wav) 190846.14 4 play set playid=42187 190847.17 4 PlaySoundStart err=[0 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer] [C:\PROGRA~1\VOICEG~1\data\tts4.wav] 190847.17 4 play set playid=42187 190847.22 4 TimeoutClear 0715.tw.txt 190846.126 tapie LINE_CALLSTATE(4, 0x101ca, 0x4000, 0x0, 0x0) 190846.126 ocxfn RecStop(sLineId:4) 190846.126 wavec waveInReset(0x0) => 5 190846.126 linec lineMonitorTones(0x101ca, 0x0, 1) => 0x8000001c (disabling monitoring for silence/tones) 190846.126 ocxfn PlayStop(sLineId=4) 190846.126 fn PlaySoundStop start 190846.126 fn PlaySoundStop line not playing a sound file. 0xabb721c 190846.126 ocxfn LineDrop(sLineId=4, sOpt=0) 190846.126 wavec waveOutReset(0xabb721c) call, caller: LineDrop TSP_DIALOGIC 190846.126 wavec waveOutReset(0xabb721c) => 0 (MMSYSERR_OK), caller: LineDrop TSP_DIALOGIC 190846.126 wavec waveOutClose(0xabb721c) call, caller: LineDrop TSP_DIALOGIC 190846.126 wavec waveOutClose(0xabb721c) => 0 (MMSYSERR_OK), caller: LineDrop TSP_DIALOGIC 190846.126 tapic lineDrop(0x101ca, , 0) call in ocxfn_LineDrop 190846.126 tapic lineDrop(0x101ca, , 0) => async:65652 (65652) 190846.157 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\data\tts4.wav, lPlayId=0xa4cb, lOptions=0x0, strOptions=) 190846.157 tr 5 ResetWavOutAndContinuePlay start 190846.157 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 190846.157 play FileMmioOpenAndAscend end 190846.157 fn play wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 190846.157 wavec waveOutOpen(0x0, 0x0, 0x1af408, 0x0, 0x0, 0x1) => 0 (MMSYSERR_OK) - format query 190846.157 wavec waveOutOpen(0x1af43c, 0x0, 0x1af408, 0x10128, 0x1af3e0, 0x10002) => 0 (MMSYSERR_OK) ... hli->hWaveOut=0xabb721c 190846.157 wavec waveOutReset(0xabb721c) => 0(MMSYSERR_OK) fn:PlaySoundStart 190846.157 tapic lineMonitorDigits(0x101ca, 0x2) => 0x8000001c 190846.157 fn TAPI_ERR:LINEERR_INVALCALLSTATE 190846.157 tapic lineMonitorMedia(0x101ca, 0x7c) => 0 190846.157 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 190846.157 play FileMmioOpenAndAscend end 190846.157 play LoadCurrFileToPlay_IntoOneMainBuffer start 190846.157 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\data\tts4.wav 190846.157 play FileMmioOpenAndAscend end 190846.157 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 190846.157 wavec waveOutWrite HeapAllocs bytes: DataToPlay=11491, WaveHdr=32, UserData=12 190846.157 wavehdr: lpData=0xd6687f0, dwBufferLength=11491, dwBytesRecorded=0, dwUser=0xaa556d8, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 190846.157 play LoadCurrFileToPlay_IntoOneMainBuffer end 190846.157 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 190846.157 wavec waveOutPrepareHeader(0xabb721c, 0xaaa8338, 0x20) => 0 190847.173 wavec waveOutWrite(0xabb721c, 0xaaa8338, 0x20) => 3 190847.173 play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 190847.173 play PlaySoundStart ResetWavOutAndContinuePlay=>3,play waveOutWrite failed after LoadCurrFileToPlay_IntoOneMainBuffer 190847.173 ocxfn PlayMultiDelimited(sLineId=4, strFile1=C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav, lPlayId=0xa4cb, lOptions=0x0, strOptions=) 190847.173 tr 5 ResetWavOutAndContinuePlay start 190847.173 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 190847.204 play FileMmioOpenAndAscend end 190847.204 tapic lineMonitorDigits(0x101ca, 0x2) => 0x8000001c 190847.204 fn TAPI_ERR:LINEERR_INVALCALLSTATE 190847.204 tapic lineMonitorMedia(0x101ca, 0x7c) => 0 190847.204 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 190847.219 play FileMmioOpenAndAscend end 190847.219 play LoadCurrFileToPlay_IntoOneMainBuffer start 190847.219 play FileMmioOpenAndAscend start C:\PROGRA~1\VOICEG~1\system\voice\ErrorPlayingFile.wav 190847.219 play FileMmioOpenAndAscend end 190847.219 iPlayListIdxCurr 0 wav format: wFormatTag=0x1, nChannels=1, nSamplesPerSec=11025, nAvgBytesPerSec=11025, nBlockAlign=1, wBitsPerSample=8, cbSize=0 190847.219 wavec waveOutWrite HeapAllocs bytes: DataToPlay=48772, WaveHdr=32, UserData=12 190847.219 wavehdr: lpData=0xd682e30, dwBufferLength=48772, dwBytesRecorded=0, dwUser=0xaa556d8, dwFlags=0x0, dwLoops=0x0, lpNext=0x0, reserved=0x0 190847.219 play LoadCurrFileToPlay_IntoOneMainBuffer end 190847.219 tr fn ResetWavOutAndContinuePlay LoadCurrFileToPlay_IntoOneMainBuffer returned 190847.219 wavec waveOutPrepareHeader(0xabb721c, 0xd651198, 0x20) => 0 190847.219 wavec waveOutWrite(0xabb721c, 0xd651198, 0x20) => 0 190847.219 tr fn PostMessage WM_FIRE_PLAY_WDOG_SET_EVENT (all in 1 buffer) params: 4 4218700 190847.219 wavee WOM_DONE(0xabb721c, 0xaaa8338) (callback window message) 190847.219 wavee WOM_DONE(0xabb721c, 0x3bd, 0x0, 0xaaa8338, 0x0) psch Clearly the bug we've been encountering still exists, granted it freezes at a different point this time, but it still freezes. Is 5.2.3 a stable release? When will a stable release come out without this bug? Share this post Link to post
SupportTeam Report post Posted 07/16/2004 08:00 PM So that we better understand what exactly is happening on this system could you please .ZIP up and post the section of the trace which stretches some time before and after the problem (10-20 seconds each direction). We will be looking into this immediately. Share this post Link to post
SupportTeam Report post Posted 07/16/2004 08:41 PM Looks like we may not have replicated the exact sequence of events during our testing and it looks like it may actually take some time for us to replicate this exact set of events on our test machines. I'm attaching another update to vgmulti.exe. This .exe specifically protects against going ahead with playing of any TTS messages once "callstate DISCONNECTED" arrives on the line. If TTS generation completes after line changes state to DISCONNECTED the you should see TTS play being aborted and this message showing up in log: tts play abort as linestate not connected Previous version only aborted TTS play after "callstate IDLE" arrived on the lines (which usually should be arriving pretty soon after "callstate DISCONNECTED") VgMulti_5.2.3018.zip Share this post Link to post
Guest Guest Report post Posted 07/16/2004 10:47 PM Here are the *vgm.txt and *tw.txt They both end where the application freezes. *tw.txt is the complete file and i only included the beginning and end of *vgm.txt, with a large section in the middle missing because it's about 43mb in size. Hopefully these help. In the meantime we'll try the latest version. 0715vgm.zip Share this post Link to post
jph2658 Report post Posted 07/20/2004 03:34 AM any progress with duplicating the error, and confirming a fix? Share this post Link to post
SupportTeam Report post Posted 07/20/2004 09:26 AM Did the errors still occur when you use v5.2.3018 of vgmulti.exe? The traces provided in 2nds last and 3rd last post were still from an older version of vgmulti.exe If you still experience same problems when using v5.2.3018 of vgmulti.exe then please post the trace from that version. We are finding it hard to reproduce this problem here - the timings of events sent from Dialogic card back to VG at disconnect time seem a bit different. Which Dialogic card are you using? Which version of Windows and Dialogic drivers are you using? Share this post Link to post
Guest Guest Report post Posted 07/23/2004 04:28 PM Here are the traces from recent log files... 114909.09 4 tts generate start[Thank you!] 114909.20 4 tts generate wait 114909.20 4 RunModule PLAY end 114909.50 4 callstate DISCONNECTED 65580 0 0 114910.36 4 ScriptEventCode 9250 iLineState=1100 114910.36 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 114910.36 4 rv add [Hangup Time]{7/22/2004 11:49:10 AM} 114910.36 4 Hanging up call... 114910.36 4 RecSoundStop ok 114910.36 4 PlaySoundStop err=0 114910.36 4 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 114910.75 4 fnHangupCall end 114910.75 4 tts generate finish. lineCallState=DISCONNECTED 114910.75 4 tts play abort as linestate not connected 114910.80 4 linedevstate 2048 0 0 114910.80 4 callstate IDLE 65580 0 0 114910.80 4 WorkingMode@Idle= 114910.80 4 timer clear 114911.00 4 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 114911.02 4 tapi Reply (LineEvReply) ok 65716 0 114912.00 4 timer fired EV_TIMEOUT_TIMETOREINITLINE 114912.00 4 ScriptEventCode 9008 iLineState=900 114912.00 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 114912.00 4 ReinitTelephony due to IDLE 114912.00 4 tapic lineDeallocateCall(MainCall:65580) 0 114913.09 4 lineOpen(4)=> 114913.09 4 Waiting for a call... 164221.51 5 tts generate start[After the beep. Please Enter your 7 digits pager number] 164221.76 5 tts generate wait 164221.76 5 RunModule PLAY end 164221.86 5 callstate DISCONNECTED 65640 0 0 164221.86 5 ScriptEventCode 9250 iLineState=1100 164221.86 5 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 164221.86 5 rv add [Hangup Time]{7/22/2004 4:42:21 PM} 164221.86 5 Hanging up call... 164221.89 5 RecSoundStop ok 164221.93 5 PlaySoundStop err=0 164221.93 5 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 164222.48 5 fnHangupCall end 164222.50 5 tts generate finish. lineCallState=DISCONNECTED 164222.50 5 tts play abort as linestate not connected 164222.51 0 dial start any summary:|4:rdy=0|5:hc>0|6:idx=0|7:idx=0| 164222.81 5 linedevstate 2048 0 0 164222.81 5 callstate IDLE 65640 0 0 164222.81 5 WorkingMode@Idle= 164222.81 5 timer clear 164222.91 5 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 164222.93 5 tapi Reply (LineEvReply) ok 66013 0 164223.51 0 dial start any summary:|4:rdy=0|5:hc>0|6:idx=0|7:idx=0| 164223.91 5 timer fired EV_TIMEOUT_TIMETOREINITLINE 164223.91 5 ScriptEventCode 9008 iLineState=900 164223.91 5 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 164223.91 5 ReinitTelephony due to IDLE 164224.81 5 tapic lineDeallocateCall(MainCall:65640) 0 164224.82 0 dial start any summary:|4:rdy=0|5:hc>0|6:idx=0|7:idx=0| 164227.15 4 linedevstate 2048 0 0 164227.18 4 callstate OFFERING 66269 0 4 164227.20 4 Answer the call at 7/22/2004 4:42:27 PM 164227.24 4 lineAnswer(66269) => 65908 164227.26 4 callinfo CALLEDID 164227.26 4 callinfo ORIGIN 164227.29 4 ring 0 164230.71 5 lineOpen(5)=> 164230.78 5 Waiting for a call... The application no longer freezes as it has been. Thanks for your help. We're using... Dialogic D4PCI Dialogic System Release 5.1.1 with Service Pack 1 Windows 2k with SP2 Share this post Link to post
SupportTeam Report post Posted 07/23/2004 08:02 PM Glad to hear it's all sorted out. Just as a note to other readers the v5.2.3 download now has been updated with the v5.2.3018 of vgmulti.exe as well. Share this post Link to post
jph2658 Report post Posted 08/02/2004 02:08 PM Alright... I lied, it's not fixed and working. It took about a week this time, but it went down again. Here are the logs when the application froze. *vgmtxt [\b] 113519.30 4 rv replace start: [After the beep. $RV[FirstMenu_1_1]] 113519.30 4 rv ns [PathSysVoice]{C:\PROGRA~1\VOICEG~1\system\voice\}[PathApp]{C:\PROGRA~1\VOICEG~1\}[PathDataVm]{C:\PROGRA~1\VOICEG~1\data\}[PathVgSys]{C:\PROGRA~1\VOICEG~1\system\}[scriptsPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone\}[scriptPath]{C:\Program Files\VoiceGuide\Scripts\PTR_phone}[$RV_STARTTIME]{7/29/2004 11:35:13 AM}[$RV_DEVICEID]{4}[$RV_CIDNAME]{}[PathApp]{C:\PROGRA~1\VOICEG~1\}[$RV_CIDNUMBER]{}[FirstMenu_RowCount]{1}[FirstMenu_1_1]{Please Enter your 7 digits pager number} 113519.30 4 rv replace end: [After the beep. Please Enter your 7 digits pager number] 113519.30 4 tts generate start[After the beep. Please Enter your 7 digits pager number] 113519.56 4 tts generate wait 113519.63 4 RunModule PLAY end 113522.86 4 callstate DISCONNECTED 65957 0 0 113524.89 4 ScriptEventCode 9250 iLineState=1100 113524.89 4 LsPlayMsg EV_REMOTEPARTY_DISCONNECT 113524.89 4 rv add [Hangup Time]{7/29/2004 11:35:24 AM} 113524.89 4 Hanging up call... 113525.05 4 RecSoundStop ok 113525.08 4 PlaySoundStop err=0 113525.08 4 timer set 2 EV_TIMEOUT_WAITFORIDLEAFTERLINEDROP 113525.77 4 fnHangupCall end 113525.77 4 tts generate finish. lineCallState=DISCONNECTED 113525.77 4 tts play abort as linestate not connected 113525.77 4 linedevstate 2048 0 0 113525.81 4 callstate IDLE 65957 0 0 113525.81 4 WorkingMode@Idle= 113525.81 4 timer clear 113526.50 4 timer set 1 EV_TIMEOUT_TIMETOREINITLINE 113526.70 4 tapi Reply (LineEvReply) ok 66180 0 113527.78 4 timer fired EV_TIMEOUT_TIMETOREINITLINE 113527.78 4 ScriptEventCode 9008 iLineState=900 113527.78 4 LsAwaitingCalls EV_TIMEOUT_TIMETOREINITLINE 113527.78 4 ReinitTelephony due to IDLE 113528.05 4 tapic lineDeallocateCall(MainCall:65957) 0 113536.92 4 lineOpen(4)=> 113538.14 4 Waiting for a call... 113538.17 4 LineHandle=66230 113538.17 4 timer set 3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 113538.69 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113539.75 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113540.78 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113541.84 4 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 113541.92 4 ScriptEventCode 9013 iLineState=900 *tw.txt [\b] 113522.375 tapie LINE_CALLSTATE(4, 0x101a5, 0x4000, 0x0, 0x0) 113524.890 ocxfn RecStop(sLineId:4) 113524.937 wavec waveInReset(0x0) => 5 113525.015 linec lineMonitorTones(0x101a5, 0x0, 1) => 0x8000001c (disabling monitoring for silence/tones) 113525.046 ocxfn PlayStop(sLineId=4) 113525.078 fn PlaySoundStop start 113525.078 fn PlaySoundStop line not playing a sound file. 0x0 113525.078 ocxfn LineDrop(sLineId=4, sOpt=0) 113525.078 tapic lineDrop(0x101a5, , 0) call in ocxfn_LineDrop 113525.671 tapic lineDrop(0x101a5, , 0) => async:66180 (66180) 113525.765 tapie LINE_LINEDEVSTATE(4, 0x1010a, 0x800, 0x0, 0x0) 113525.812 tapie LINE_CALLSTATE(4, 0x101a5, 0x1, 0x0, 0x0) 113526.687 tapie LINE_REPLY(4, 0x0, 0x10284, 0x0) 113528.078 ocxfn LineClose(sLineId:4) 113529.265 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12f158) 113529.343 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113529.968 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113529.968 tapic lineGetIcon(4, 0, 0x1af3f8) 113530.374 ocxfn LineOpen(4, 0, 0, , ) 113530.437 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12e498) 113530.452 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113530.609 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113530.656 tapic lineGetIcon(4, 0, 0x1af3f8) 113530.656 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113530.656 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113530.656 tapic lineOpen(0x800003ff, 4, 0x1af3ec, 0x10004, 0, 0x1af3e0, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113534.984 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113536.062 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113536.062 tapic lineGetID => 0 113536.093 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113536.109 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113536.109 tapic lineGetID => 0 113536.109 ocxfn GetLineName(sLineId=4) 113536.109 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113536.905 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113536.905 wavec waveInGetDevCaps(0x0, 0x12e500, 0x30) => 0 113536.921 wavec waveOutGetDevCaps(0x0, 0x12e4cc, 0x34) => 0 113536.921 wave IN:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1, support=0x4] 113538.171 ocxfn SilenceDetectSet(sLineId=4, sTimeLen=30, sVolumeLevel=10) 113719.028 tapie LINE_LINEDEVSTATE(4, 0x102b6, 0x800, 0x0, 0x0) 113720.981 tapie LINE_CALLSTATE(4, 0x103b5, 0x2, 0x0, 0x4) 113721.871 tapic lineMonitorDigits(0x103b5, 0x2) => 0x0 113721.871 tapic lineMonitorMedia(0x103b5, 0xfc) => 0 113722.418 tapic lineGetCallInfo(0x103b5, 0xd956030) 113722.481 eng No CallerId info available at this time 113722.481 tapie LINE_CALLINFO(4, 0x103b5, 0x10000) 113722.481 tapie LINE_CALLINFO(4, 0x103b5, 0x100) 113722.481 tapie LINE_LINEDEVSTATE(4, 0x102b6, 0x2, 0x0, 0x0) 113723.496 tapie LINE_LINEDEVSTATE(5, 0x100e8, 0x800, 0x0, 0x0) 113723.512 tapie LINE_CALLSTATE(5, 0x10354, 0x2, 0x0, 0x4) 113724.059 tapic lineMonitorDigits(0x10354, 0x2) => 0x0 113724.075 tapic lineMonitorMedia(0x10354, 0xfc) => 0 113724.137 tapic lineGetCallInfo(0x10354, 0xd964020) 113724.137 eng No CallerId info available at this time 113724.137 tapie LINE_CALLINFO(5, 0x10354, 0x10000) 113724.137 tapie LINE_CALLINFO(5, 0x10354, 0x100) 113724.137 tapie LINE_LINEDEVSTATE(5, 0x100e8, 0x2, 0x0, 0x0) 113724.153 tapie LINE_CALLSTATE(4, 0x103b5, 0x100, 0x1, 0x0) 113724.153 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113724.200 tapic lineGetID => 0 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113724.200 tapic lineGetID => 0 113724.215 tapie LINE_REPLY(4, 0x0, 0x1004f, 0x0) 113724.215 tapie LINE_CALLINFO(4, 0x103b5, 0x8000000) 113726.340 ocxfn RecStop(sLineId:4) 113726.481 wavec waveInReset(0x0) => 5 113726.762 linec lineMonitorTones(0x103b5, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113726.762 ocxfn PlayStop(sLineId=4) 113726.762 fn PlaySoundStop start 113726.762 fn PlaySoundStop line not playing a sound file. 0x0 113726.762 ocxfn LineDrop(sLineId=4, sOpt=0) 113726.762 tapic lineDrop(0x103b5, , 0) call in ocxfn_LineDrop 113726.965 tapic lineDrop(0x103b5, , 0) => async:65842 (65842) 113727.153 tapie LINE_CALLSTATE(5, 0x10354, 0x100, 0x1, 0x0) 113727.168 tapic lineGetID(0x100e8, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113727.246 tapic lineGetID(0x100e8, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113727.262 tapic lineGetID => 1 113727.262 tapic lineGetID(0x100e8, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113727.262 tapic lineGetID(0x100e8, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113727.262 tapic lineGetID => 1 113727.262 tapie LINE_REPLY(5, 0x0, 0x101b6, 0x0) 113727.262 tapie LINE_CALLINFO(5, 0x10354, 0x8000000) 113727.309 tapie LINE_LINEDEVSTATE(4, 0x102b6, 0x800, 0x0, 0x0) 113727.309 tapie LINE_CALLSTATE(4, 0x103b5, 0x1, 0x0, 0x0) 113727.621 tapie LINE_REPLY(4, 0x0, 0x10132, 0x0) 113727.809 ocxfn RecStop(sLineId:5) 113727.809 wavec waveInReset(0x0) => 5 113727.809 linec lineMonitorTones(0x10354, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113727.809 ocxfn PlayStop(sLineId=5) 113727.809 fn PlaySoundStop start 113727.809 fn PlaySoundStop line not playing a sound file. 0x0 113727.824 ocxfn LineDrop(sLineId=5, sOpt=0) 113727.824 tapic lineDrop(0x10354, , 0) call in ocxfn_LineDrop 113727.824 tapic lineDrop(0x10354, , 0) => async:65703 (65703) 113727.856 tapie LINE_LINEDEVSTATE(5, 0x100e8, 0x800, 0x0, 0x0) 113727.856 tapie LINE_CALLSTATE(5, 0x10354, 0x1, 0x0, 0x0) 113727.871 tapie LINE_REPLY(5, 0x0, 0x100a7, 0x0) 113728.699 ocxfn LineClose(sLineId:4) 113728.949 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12f158) 113728.996 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113729.090 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113729.090 tapic lineGetIcon(4, 0, 0x1af3f8) 113729.090 ocxfn LineOpen(4, 0, 0, , ) 113729.090 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12e498) 113729.090 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113729.106 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113729.106 tapic lineGetIcon(4, 0, 0x1af3f8) 113729.121 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113729.121 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113729.121 tapic lineOpen(0x800003ff, 4, 0x1af3ec, 0x10004, 0, 0x1af3e0, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113730.418 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113730.559 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113730.574 tapic lineGetID => 0 113730.574 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113730.574 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113730.574 tapic lineGetID => 0 113730.574 ocxfn GetLineName(sLineId=4) 113730.574 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113730.621 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113730.637 wavec waveInGetDevCaps(0x0, 0x12e500, 0x30) => 0 113730.637 wavec waveOutGetDevCaps(0x0, 0x12e4cc, 0x34) => 0 113730.653 wave IN:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1, support=0x4] 113730.653 ocxfn SilenceDetectSet(sLineId=4, sTimeLen=30, sVolumeLevel=10) 113731.043 ocxfn LineClose(sLineId:5) 113731.246 tapic lineNegotiateAPIVersion(0x800003ff, 5, 0x10003, 0x20001, 0x1bc5f8, 0x12f158) 113731.277 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113731.277 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113731.652 tapic lineGetIcon(5, 0, 0x1bc600) 113731.652 ocxfn LineOpen(5, 0, 0, , ) 113731.652 tapic lineNegotiateAPIVersion(0x800003ff, 5, 0x10003, 0x20001, 0x1bc5f8, 0x12e498) 113731.652 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113731.652 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113731.652 tapic lineGetIcon(5, 0, 0x1bc600) 113731.652 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113731.652 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113731.652 tapic lineOpen(0x800003ff, 5, 0x1bc5f4, 0x10004, 0, 0x1bc5e8, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113732.684 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113732.699 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113732.699 tapic lineGetID => 1 113732.699 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113732.699 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113732.699 tapic lineGetID => 1 113732.699 ocxfn GetLineName(sLineId=5) 113732.699 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113732.699 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113732.699 wavec waveInGetDevCaps(0x1, 0x12e500, 0x30) => 0 113732.699 wavec waveOutGetDevCaps(0x1, 0x12e4cc, 0x34) => 0 113732.699 wave IN:[Dialogic Wave[dxxxB1C2] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C2] v16.0 format=0x1, channels=0x1, support=0x4] 113732.715 ocxfn SilenceDetectSet(sLineId=5, sTimeLen=30, sVolumeLevel=10) 113748.230 tapie LINE_LINEDEVSTATE(4, 0x10392, 0x800, 0x0, 0x0) 113749.996 tapie LINE_CALLSTATE(4, 0x10386, 0x2, 0x0, 0x4) 113750.058 tapic lineMonitorDigits(0x10386, 0x2) => 0x0 113750.105 tapic lineMonitorMedia(0x10386, 0xfc) => 0 113750.574 tapic lineGetCallInfo(0x10386, 0xd957180) 113750.652 eng No CallerId info available at this time 113751.105 tapie LINE_CALLINFO(4, 0x10386, 0x10000) 113751.183 tapie LINE_CALLINFO(4, 0x10386, 0x100) 113751.261 tapie LINE_LINEDEVSTATE(4, 0x10392, 0x2, 0x0, 0x0) 113751.793 tapie LINE_CALLSTATE(4, 0x10386, 0x100, 0x1, 0x0) 113751.793 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113751.949 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113751.964 tapic lineGetID => 0 113751.996 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113752.168 tapic lineGetID(0x10392, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113752.199 tapic lineGetID => 0 113752.339 tapie LINE_REPLY(4, 0x0, 0x101d9, 0x0) 113752.339 tapie LINE_CALLINFO(4, 0x10386, 0x8000000) 113755.871 ocxfn RecStop(sLineId:4) 113755.980 wavec waveInReset(0x0) => 5 113755.980 linec lineMonitorTones(0x10386, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113755.980 ocxfn PlayStop(sLineId=4) 113755.980 fn PlaySoundStop start 113756.074 fn PlaySoundStop line not playing a sound file. 0x0 113756.074 ocxfn LineDrop(sLineId=4, sOpt=0) 113756.074 tapic lineDrop(0x10386, , 0) call in ocxfn_LineDrop 113756.261 tapic lineDrop(0x10386, , 0) => async:66330 (66330) 113756.808 tapie LINE_LINEDEVSTATE(4, 0x10392, 0x800, 0x0, 0x0) 113756.839 tapie LINE_CALLSTATE(4, 0x10386, 0x1, 0x0, 0x0) 113757.964 tapie LINE_REPLY(4, 0x0, 0x1031a, 0x0) 113759.027 ocxfn LineClose(sLineId:4) 113759.464 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12f158) 113759.480 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113759.621 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113759.621 tapic lineGetIcon(4, 0, 0x1af3f8) 113759.621 ocxfn LineOpen(4, 0, 0, , ) 113759.621 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12e498) 113759.621 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113759.621 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113759.636 tapic lineGetIcon(4, 0, 0x1af3f8) 113759.636 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113759.636 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113759.636 tapic lineOpen(0x800003ff, 4, 0x1af3ec, 0x10004, 0, 0x1af3e0, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113800.277 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113800.308 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113800.308 tapic lineGetID => 0 113800.308 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113800.308 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113800.308 tapic lineGetID => 0 113800.308 ocxfn GetLineName(sLineId=4) 113800.308 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113800.324 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113800.339 wavec waveInGetDevCaps(0x0, 0x12e500, 0x30) => 0 113800.339 wavec waveOutGetDevCaps(0x0, 0x12e4cc, 0x34) => 0 113800.339 wave IN:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1, support=0x4] 113800.339 ocxfn SilenceDetectSet(sLineId=4, sTimeLen=30, sVolumeLevel=10) 113802.980 tapie LINE_LINEDEVSTATE(4, 0x10141, 0x800, 0x0, 0x0) 113803.011 tapie LINE_CALLSTATE(4, 0x10163, 0x2, 0x0, 0x4) 113803.027 tapic lineMonitorDigits(0x10163, 0x2) => 0x0 113803.042 tapic lineMonitorMedia(0x10163, 0xfc) => 0 113803.074 tapic lineGetCallInfo(0x10163, 0xd9582d0) 113803.074 eng No CallerId info available at this time 113803.074 tapie LINE_CALLINFO(4, 0x10163, 0x10000) 113803.074 tapie LINE_CALLINFO(4, 0x10163, 0x100) 113803.074 tapie LINE_LINEDEVSTATE(4, 0x10141, 0x2, 0x0, 0x0) 113803.620 tapie LINE_CALLSTATE(4, 0x10163, 0x100, 0x1, 0x0) 113803.620 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113803.620 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113803.620 tapic lineGetID => 0 113803.620 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113803.620 tapic lineGetID(0x10141, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113803.620 tapic lineGetID => 0 113803.683 tapie LINE_REPLY(4, 0x0, 0x10330, 0x0) 113803.683 tapie LINE_CALLINFO(4, 0x10163, 0x8000000) 113805.589 ocxfn RecStop(sLineId:4) 113805.620 wavec waveInReset(0x0) => 5 113805.652 linec lineMonitorTones(0x10163, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113805.745 ocxfn PlayStop(sLineId=4) 113805.745 fn PlaySoundStop start 113805.745 fn PlaySoundStop line not playing a sound file. 0x0 113805.745 ocxfn LineDrop(sLineId=4, sOpt=0) 113805.745 tapic lineDrop(0x10163, , 0) call in ocxfn_LineDrop 113805.933 tapic lineDrop(0x10163, , 0) => async:65539 (65539) 113806.370 tapie LINE_LINEDEVSTATE(4, 0x10141, 0x800, 0x0, 0x0) 113806.370 tapie LINE_CALLSTATE(4, 0x10163, 0x1, 0x0, 0x0) 113806.480 tapie LINE_REPLY(4, 0x0, 0x10003, 0x0) 113807.464 ocxfn LineClose(sLineId:4) 113808.136 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12f158) 113808.214 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113808.448 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113808.448 tapic lineGetIcon(4, 0, 0x1af3f8) 113808.511 ocxfn LineOpen(4, 0, 0, , ) 113808.511 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12e498) 113808.542 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113808.542 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113808.542 tapic lineGetIcon(4, 0, 0x1af3f8) 113808.542 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113808.542 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113808.542 tapic lineOpen(0x800003ff, 4, 0x1af3ec, 0x10004, 0, 0x1af3e0, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113811.136 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113812.776 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113812.776 tapic lineGetID => 0 113812.776 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113812.776 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113812.776 tapic lineGetID => 0 113812.776 ocxfn GetLineName(sLineId=4) 113812.776 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113813.308 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113813.308 wavec waveInGetDevCaps(0x0, 0x12e500, 0x30) => 0 113813.308 wavec waveOutGetDevCaps(0x0, 0x12e4cc, 0x34) => 0 113813.308 wave IN:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1, support=0x4] 113813.308 ocxfn SilenceDetectSet(sLineId=4, sTimeLen=30, sVolumeLevel=10) 113813.308 tapie LINE_LINEDEVSTATE(4, 0x1008f, 0x800, 0x0, 0x0) 113813.323 tapie LINE_CALLSTATE(4, 0x10365, 0x2, 0x0, 0x4) 113813.370 tapic lineMonitorDigits(0x10365, 0x2) => 0x0 113813.433 tapic lineMonitorMedia(0x10365, 0xfc) => 0 113813.448 tapic lineGetCallInfo(0x10365, 0xd959420) 113813.448 eng No CallerId info available at this time 113813.448 tapie LINE_CALLINFO(4, 0x10365, 0x10000) 113813.448 tapie LINE_CALLINFO(4, 0x10365, 0x100) 113813.448 tapie LINE_LINEDEVSTATE(4, 0x1008f, 0x2, 0x0, 0x0) 113814.292 tapie LINE_CALLSTATE(4, 0x10365, 0x100, 0x1, 0x0) 113814.292 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113814.292 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113814.292 tapic lineGetID => 0 113814.292 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113814.292 tapic lineGetID(0x1008f, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113814.292 tapic lineGetID => 0 113814.355 tapie LINE_REPLY(4, 0x0, 0x10194, 0x0) 113814.386 tapie LINE_CALLINFO(4, 0x10365, 0x8000000) 113817.011 ocxfn RecStop(sLineId:4) 113817.339 wavec waveInReset(0x0) => 5 113817.401 linec lineMonitorTones(0x10365, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113817.401 ocxfn PlayStop(sLineId=4) 113817.401 fn PlaySoundStop start 113817.401 fn PlaySoundStop line not playing a sound file. 0x0 113817.401 ocxfn LineDrop(sLineId=4, sOpt=0) 113817.401 tapic lineDrop(0x10365, , 0) call in ocxfn_LineDrop 113817.729 tapic lineDrop(0x10365, , 0) => async:65594 (65594) 113818.261 tapie LINE_LINEDEVSTATE(4, 0x1008f, 0x800, 0x0, 0x0) 113818.386 tapie LINE_CALLSTATE(4, 0x10365, 0x1, 0x0, 0x0) 113819.058 tapie LINE_REPLY(4, 0x0, 0x1003a, 0x0) 113820.620 ocxfn LineClose(sLineId:4) 113821.682 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12f158) 113821.729 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113821.932 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113821.979 tapic lineGetIcon(4, 0, 0x1af3f8) 113821.979 tapie LINE_LINEDEVSTATE(5, 0x1021d, 0x800, 0x0, 0x0) 113821.979 tapie LINE_CALLSTATE(5, 0x1001d, 0x2, 0x0, 0x4) 113821.979 tapic lineMonitorDigits(0x1001d, 0x2) => 0x0 113821.979 tapic lineMonitorMedia(0x1001d, 0xfc) => 0 113821.995 tapic lineGetCallInfo(0x1001d, 0xd95a570) 113821.995 eng No CallerId info available at this time 113821.995 tapie LINE_CALLINFO(5, 0x1001d, 0x10000) 113821.995 tapie LINE_CALLINFO(5, 0x1001d, 0x100) 113822.011 tapie LINE_LINEDEVSTATE(5, 0x1021d, 0x2, 0x0, 0x0) 113822.026 ocxfn LineOpen(4, 0, 0, , ) 113822.026 tapic lineNegotiateAPIVersion(0x800003ff, 4, 0x10003, 0x20001, 0x1af3f0, 0x12e498) 113822.026 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113822.026 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113822.026 tapic lineGetIcon(4, 0, 0x1af3f8) 113822.026 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113822.026 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113822.026 tapic lineOpen(0x800003ff, 4, 0x1af3ec, 0x10004, 0, 0x1af3e0, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113823.136 tapic lineGetID(0x103c7, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113823.261 tapic lineGetID(0x103c7, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113823.261 tapic lineGetID => 0 113823.261 tapic lineGetID(0x103c7, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113823.276 tapic lineGetID(0x103c7, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113823.276 tapic lineGetID => 0 113823.276 ocxfn GetLineName(sLineId=4) 113823.276 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) call 113823.386 tapic lineGetDevCaps(0x800003ff, 4, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113823.401 wavec waveInGetDevCaps(0x0, 0x12e500, 0x30) => 0 113823.401 wavec waveOutGetDevCaps(0x0, 0x12e4cc, 0x34) => 0 113823.401 wave IN:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C1] v16.0 format=0x1, channels=0x1, support=0x4] 113823.417 ocxfn SilenceDetectSet(sLineId=4, sTimeLen=30, sVolumeLevel=10) 113823.417 tapie LINE_CALLSTATE(5, 0x1001d, 0x100, 0x1, 0x0) 113823.417 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113823.417 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113823.417 tapic lineGetID => 1 113823.417 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113823.526 tapic lineGetID(0x1021d, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113823.761 tapic lineGetID => 1 113823.964 tapie LINE_REPLY(5, 0x0, 0x10051, 0x0) 113823.964 tapie LINE_CALLINFO(5, 0x1001d, 0x8000000) 113828.104 ocxfn RecStop(sLineId:5) 113828.135 wavec waveInReset(0x0) => 5 113828.245 linec lineMonitorTones(0x1001d, 0x0, 1) => 0x0 (disabling monitoring for silence/tones) 113828.339 ocxfn PlayStop(sLineId=5) 113828.354 fn PlaySoundStop start 113828.354 fn PlaySoundStop line not playing a sound file. 0x0 113828.604 ocxfn LineDrop(sLineId=5, sOpt=0) 113828.604 tapic lineDrop(0x1001d, , 0) call in ocxfn_LineDrop 113829.354 tapic lineDrop(0x1001d, , 0) => async:66348 (66348) 113829.963 tapie LINE_LINEDEVSTATE(5, 0x1021d, 0x800, 0x0, 0x0) 113829.979 tapie LINE_CALLSTATE(5, 0x1001d, 0x1, 0x0, 0x0) 113830.635 tapie LINE_REPLY(5, 0x0, 0x1032c, 0x0) 113832.042 ocxfn LineClose(sLineId:5) 113833.104 tapic lineNegotiateAPIVersion(0x800003ff, 5, 0x10003, 0x20001, 0x1bc5f8, 0x12f158) 113833.198 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113833.510 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113833.510 tapic lineGetIcon(5, 0, 0x1bc600) 113833.713 ocxfn LineOpen(5, 0, 0, , ) 113833.713 tapic lineNegotiateAPIVersion(0x800003ff, 5, 0x10003, 0x20001, 0x1bc5f8, 0x12e498) 113833.729 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113833.807 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113833.901 tapic lineGetIcon(5, 0, 0x1bc600) 113833.901 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113833.901 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113833.901 tapic lineOpen(0x800003ff, 5, 0x1bc5f4, 0x10004, 0, 0x1bc5e8, 0x4, LINEMEDIAMODE_INTERACTIVEVOICE | LINEMEDIAMODE_AUTOMATEDVOICE, 0) 113836.401 tapic lineGetID(0x10296, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113836.557 tapic lineGetID(0x10296, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113836.620 tapic lineGetID => 1 113836.635 tapic lineGetID(0x10296, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113836.635 tapic lineGetID(0x10296, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113836.635 tapic lineGetID => 1 113836.651 ocxfn GetLineName(sLineId=5) 113836.651 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) call 113836.729 tapic lineGetDevCaps(0x800003ff, 5, 0x10004, 0, 0xabcd3c0) => NO_ERROR (0) 113836.729 wavec waveInGetDevCaps(0x1, 0x12e500, 0x30) => 0 113836.760 wavec waveOutGetDevCaps(0x1, 0x12e4cc, 0x34) => 0 113836.776 wave IN:[Dialogic Wave[dxxxB1C2] v16.0 format=0x1, channels=0x1] OUT:[Dialogic Wave[dxxxB1C2] v16.0 format=0x1, channels=0x1, support=0x4] 113836.776 ocxfn SilenceDetectSet(sLineId=5, sTimeLen=30, sVolumeLevel=10) What's going on here? Share this post Link to post
jph2658 Report post Posted 08/02/2004 08:17 PM As you can see the tts play abort as linestate not connected did show, however the application still froze. However, there are other logs, on other days where the tts play abort as linestate not connected occured and normal execution of the application continued, as you said it should. Perhaps there is another problem related to the tts generation? When will a stable build be released, that has been tested with this problem fixed? Share this post Link to post
SupportTeam Report post Posted 08/02/2004 08:20 PM The trace logs show that the call was completed and the line correctly re-initialized and marked as waiting for another call. These traces looks very different to the ones that were relating to the previous problem (Text-To-Speech generation at end of call), and I'd say at this stage that this is a totally different problem that you are seeing here. Do you know how long after that last entry in the log did the application freeze? Did you have a chance to look at the general state of the machine at the time when the application froze (memory availability, how much memory and CPU time was taken up by VG etc)? Did you receive any error messages etc on the screen? Were you able to run any other applications while VG was frozen? How many calls were taken by this system during the week when it was running without restarts? A common problem encountered is that if you create objects in the VB Script modules without releasing them correctly then this can be responsible for chewing up memory on the system - maybe this applies here? Can you .ZIP up the script which you are using along with any associated sound files etc? We can then run it up on out test server and put it under repeated calls stress test to see if we can replicate the problem. Share this post Link to post
jph2658 Report post Posted 08/04/2004 02:58 PM Yes, the line correctly re-initialized and marked as waiting for another call. Perhaps this is a different problem, but perhaps not. I was led to believe that it is because it happened while trying to answer another call... 113525.77 4 tts play abort as linestate not connected so it may or not not be related. Please look at the *tw.txt I sent... After the lines in the *vgm.txt to... 113538.14 4 Waiting for a call... 113538.17 4 LineHandle=66230 113538.17 4 timer set 3 EV_TIMEOUT_ATERIDLE_ALLOWOUT 113538.69 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113539.75 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113540.78 0 dial start any summary:|4:rdy=0|5:idx=0|6:idx=0|7:idx=0| 113541.84 4 timer fired EV_TIMEOUT_ATERIDLE_ALLOWOUT 113541.92 4 ScriptEventCode 9013 iLineState=900 the application tries to answer another call that is coming in. Usually normal execution in the *tw.txt looks like the following... 071318.618 tapie LINE_LINEDEVSTATE(4, 0x101dd, 0x800, 0x0, 0x0) 071318.618 tapie LINE_CALLSTATE(4, 0x103ca, 0x2, 0x0, 0x4) 071318.618 tapic lineMonitorDigits(0x103ca, 0x2) => 0x0 071318.618 tapic lineMonitorMedia(0x103ca, 0xfc) => 0 071318.681 tapic lineGetCallInfo(0x103ca, 0xd8d4808) 071318.681 eng No CallerId info available at this time 071318.681 tapie LINE_CALLINFO(4, 0x103ca, 0x10000) 071318.681 tapie LINE_LINEDEVSTATE(4, 0x101dd, 0x2, 0x0, 0x0) 071318.681 tapie LINE_CALLINFO(4, 0x103ca, 0x100) 071319.243 tapie LINE_CALLSTATE(4, 0x103ca, 0x100, 0x1, 0x0) 071319.243 tapic lineGetID(0x101dd, 0, 0, 0x1, 0xd8a7e78, wave/in) LINECALLSELECT_LINE hLineInfo 071319.290 tapic lineGetID(0x101dd, 0, 0, 0x1, 0xd8a7e78, wave/in) => 0x0 071319.290 tapic lineGetID => 0 071319.290 tapic lineGetID(0x101dd, 0, 0, 0x1, 0xd8a7e78, wave/out) LINECALLSELECT_LINE hLineInfo 071319.290 tapic lineGetID(0x101dd, 0, 0, 0x1, 0xd8a7e78, wave/out) => 0x0 071319.290 tapic lineGetID => 0 however, in this case, the *tw.txt looks like this... 113719.028 tapie LINE_LINEDEVSTATE(4, 0x102b6, 0x800, 0x0, 0x0) 113720.981 tapie LINE_CALLSTATE(4, 0x103b5, 0x2, 0x0, 0x4) 113721.871 tapic lineMonitorDigits(0x103b5, 0x2) => 0x0 113721.871 tapic lineMonitorMedia(0x103b5, 0xfc) => 0 113722.418 tapic lineGetCallInfo(0x103b5, 0xd956030) 113722.481 eng No CallerId info available at this time 113722.481 tapie LINE_CALLINFO(4, 0x103b5, 0x10000) 113722.481 tapie LINE_CALLINFO(4, 0x103b5, 0x100) 113722.481 tapie LINE_LINEDEVSTATE(4, 0x102b6, 0x2, 0x0, 0x0) 113723.496 tapie LINE_LINEDEVSTATE(5, 0x100e8, 0x800, 0x0, 0x0) 113723.512 tapie LINE_CALLSTATE(5, 0x10354, 0x2, 0x0, 0x4) 113724.059 tapic lineMonitorDigits(0x10354, 0x2) => 0x0 113724.075 tapic lineMonitorMedia(0x10354, 0xfc) => 0 113724.137 tapic lineGetCallInfo(0x10354, 0xd964020) 113724.137 eng No CallerId info available at this time 113724.137 tapie LINE_CALLINFO(5, 0x10354, 0x10000) 113724.137 tapie LINE_CALLINFO(5, 0x10354, 0x100) 113724.137 tapie LINE_LINEDEVSTATE(5, 0x100e8, 0x2, 0x0, 0x0) 113724.153 tapie LINE_CALLSTATE(4, 0x103b5, 0x100, 0x1, 0x0) 113724.153 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) LINECALLSELECT_LINE hLineInfo 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/in) => 0x0 113724.200 tapic lineGetID => 0 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) LINECALLSELECT_LINE hLineInfo 113724.200 tapic lineGetID(0x102b6, 0, 0, 0x1, 0xd8dc138, wave/out) => 0x0 113724.200 tapic lineGetID => 0 Should this be happening? There is also a similar repeating pattern in the *tw.txt while the application is trying to answer the call... is this normal? Here are the answers to your questions. Do you know how long after that last entry in the log did the application freeze? That was the last entry in the log file. When the application freezes, the log files are recovered and the application is restarted. Did you have a chance to look at the general state of the machine at the time when the application froze (memory availability, how much memory and CPU time was taken up by VG etc)? Did you receive any error messages etc on the screen? Were you able to run any other applications while VG was frozen? The state of the machine was fine at that point. VG was taking a normal amount of memory. VG took up a minimal amount of CPU time. No error messages. Other applications ran while VG was frozen. How many calls were taken by this system during the week when it was running without restarts? Around 300 or more calls are made throughout the day. That's a rough estimate. A common problem encountered is that if you create objects in the VB Script modules without releasing them correctly then this can be responsible for chewing up memory on the system - maybe this applies here? Can you .ZIP up the script which you are using along with any associated sound files etc? During script development, we made sure to release all objects correctly and tested the system thoroughly for memory leaks. I believe the answer to this problem lies in the *tw.txt that I sent you. Please have a developer look through it and let us know if you notice anything unusual. Share this post Link to post
SupportTeam Report post Posted 08/08/2004 04:57 AM The *tw.txt log file shows calls still arriving after the last entry in the *vgm.txt log (at 11:35:41 AM) The *tw.txt log file even shows that VoiceGuide was still working and issuing some commands to the Dialogic TAPI drivers, as we can see "ocxfn" entries in the log - indicating a call to the tapiwrap.ocx was made by VoiceGuide - and indeed for the call to be answered in the first place VG must issue some commands internally to the drivers as well. It is strange that nothing was getting saved in *vgm.txt log file when it appears that VG was still functioning. Were you able to shutdown the application using either the "Exit" button or using the "X" in the top right corner of the window, or did you have to use the Task Manager to shutdown the application? Did you may be notice if VG's screen repainted itself if you moved another window over the top of it and then moved it away - or was it just a white panel after you moved the other window away? Coming back to the *tw.txt log file we can see in it that for each call that arrived VG ended up deciding to hangup the call soon after - about 3 to 5 seconds after the call was reported as connected. The tw.txt log does not show why VG decided that the call is to be hung up on (that sort of info would be in the vgm.txt log) but it is pretty strange to see the decision to do the hangup occurring a few seconds after the call has arrived... If you still have the log files for that day could you please .ZIP up and post here the entire vgm.txt and tw.txt log for that day - maybe looking through some previous calls on the system we will be able to trace down what could have happened here. Also, the two highlighted lines in the tw log extract seem normal, any reason why they are highlighted? Share this post Link to post
