HS Phone Line Lockup!

[danielperez]

Has anyone else had HS Phone stop interacting and continue to hold the line? It doesn't ever seam to time-out until I force it via the HS GUI. It will do it on good sequences of events, even ones that just worked the last time I tried. For example, I had this log sequence:
11/22/2005 12:40:36 AM Info Done loading internal VR
11/22/2005 12:40:36 AM HSPhone Line: Main Line Speak: C:\Program Files\HomeSeer 2\wave\help.wav
11/22/2005 12:40:38 AM HSPhone StartListening
11/22/2005 12:40:41 AM HSPhone Heard: messages
11/22/2005 12:40:41 AM HSPhone Disable Listen

I called HS Phone internally, it gave the prompt and I then responded "messages" to get my messages. It put in the log file that it heard "messages" and then did nothing, silence. I hung up and it held the line. If I went to use my phone it was dead. No key sequence will unlock HS Phone from the phone at this point. This has to be fixed or HS Phone will not be usable. If this is not something obvious I'd like to have an HS technician respond.

[danielperez]

Here is another sequence:
11/22/2005 12:57:01 AM HSPhone Heard: messages
11/22/2005 12:57:01 AM HSPhone Disable Listen
11/22/2005 12:57:36 AM Napco Gemini Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/22/2005 12:57:36 AM HSPhone DTMF: 0
11/22/2005 12:57:43 AM Napco Gemini Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/22/2005 12:57:46 AM Napco Gemini Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/22/2005 12:57:49 AM Napco Gemini Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/22/2005 12:57:56 AM HSPhone Line: Main Line Speak: C:\Program Files\HomeSeer 2\wave\nomessage.wav

Note the time stamps. It hears "messages" at 12:57:01 and does nothing. Then I hit the 0 key on the phone at 12:57:36 to try and get it back to the main menu, but that did nothing. Then at 12:57:56 it decides to play my "no message" wav file, which did not actually play. Why did it wait 55 seconds to play the wav file? Why didn't I hear the wav file? Keep in mind that this same sequence works many times, then stops working. It often also detects phone hangup and relinquishes the line, but not always.

[zap]

Danielperez,

Take a look at the HS Phone Threads, this seems to be a on going problem with HS phone lockups, one build seems too fix it than a new build comes out then is back two square one again. HS is aware of the Problem, and I think they are getting close to fixing it.

Mike

[Bryan Nystrom]

Daniel,

Your not alone. The last couple of builds do that same for me. If you monitor the CPU usage, it will spike at 100% when it goes "wacko" like this and holds your phone line hostage. If you watch the event log, you'll see HSP attempting to send voice/speech over the line, but you never hear it, etc. The only way I have out of the situation is to shut down HS2 and restart it.

Very annoying. Very buggy. Makes you almost want to not use it since it is much more important to have your phone line work 100%

[Rube Goldberg]

I too have been having "issues" with HSPhone with the PCI modem V2 and currently have on on-going dialog with HS via the help desk to work through them.

One of the things I found that helps somewhat is to make sure software silence detection is turned off and to set the inactivity timeout to 1 minute (the default setting is "0"). This seems to consistantly release the line on external calls. The slight downside with this is there is always a 1 minute delay from the end of the recorded message so that phone is "busy" for 1 minute longer than it should be. The good news is that it does not appear to hang forever.

As they say "your mileage may vary"....

[danielperez]

Yes, my symptoms are the same as described! The CPU goes to 100% on my homeseer machine and the threads for the phone app go to "running". The disabling "silence detection" and timeout did not help for an internal call, but that may be a "workaround" for an external call. I should not call that a "workaround" but a confirmation of a failure. It is not usable in this state.

This is so easy to debug from a software perspective that they must not be putting programming resources on the problem. It is totally repeatable, and the thread just goes into a loop, so all they have to do is put it in the debugger and break at the lockup. I know Windows threads are a pain, but anything this repeatable can be isolated. After Thanksgiving I'll have to put in a help desk request. I live in NH, so I can always offer to drop by for a debug session. :-)

[dkindred]

Guys, I don't know if you noticed, but recently HST placed advanced IVR logging capability into HS. You'll find it on the phone setup page as "Log IVR script processing". They're asking that those who are encountering problems enable the logging to help them track down the source(s).

--David

[rjh]

Yes, please enable the IVR logging and post a log snippet when it hangs. Also, is this with the Way2Call or PCI modem only? Please post your PC specs, especially if you have a hyperthreading processor. Hyperthreading issues should be fixed, but try turning this feature off.

[danielperez]

There is nothing regarding "IVR script processing" or "IVR logging" on any of the pages that I can find. I went under "Setup:Phone:" and then my phone modem configuration. I did find "Modem Used: HomeSeer PCI Voice Modem V2 Detail Logging: YES" and this was already enabled. On the "Setup:General" I have those logs enabled as well. When I search my computer I came up with these logs:
1) HomeSeer.log - General logging plus IVR interactions.
2) Callers.log - caller ID
3) Installation logs. - installation

I am running "HomeSeer version 2.0.2003.0" on "Windows 2000, 5.00.2195" SP-4.
I have the latest version of MDAC, 2.7 something. So Win 2000 is completely up to date with patches and MDAC. Microsoft.NET Framework is 2.0.

My Homeseer computer is a Pentium (Family 6, model 5) with 128MB RAM. There is only one processor.

I am using the PCI Voice Modem, V2 (BD0300-001).

[danielperez]

Log #1

Note that in both this and the following log, the same sequence can also work...

11/26/2005 5:50:20 PM ~!~HSPhone~!~Off Hook
11/26/2005 5:50:21 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/26/2005 5:50:21 PM ~!~HSPhone~!~Waiting for #...
11/26/2005 5:50:25 PM ~!~HSPhone~!~DTMF: #
11/26/2005 5:50:25 PM ~!~HSPhone~!~Connected line: Main
11/26/2005 5:50:25 PM ~!~Info~!~Read 364 lines from IVR file c:\program files\homeseer 2\ivr\internal.txt
11/26/2005 5:50:26 PM ~!~Info~!~Loading internal VR
11/26/2005 5:50:26 PM ~!~HSPhone~!~Building HomeSeer voice commands: 64226.037744
11/26/2005 5:50:26 PM ~!~HSPhone~!~Done build HomeSeer voice commands: 64226.2680752
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) O
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Rssf
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Ist
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) John Losee
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) A Ramirez
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) O
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Daniel Perez
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Daniel Perez's cell phone
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) To Lyndeborough
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Caller Wireless
11/26/2005 5:50:26 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Dianna Vaccaro
11/26/2005 5:50:28 PM ~!~Info~!~Done loading internal VR
11/26/2005 5:50:28 PM ~!~HSPhone~!~Line: Main Speak: C:\Program Files\HomeSeer 2\wave\help.wav
11/26/2005 5:50:30 PM ~!~HSPhone~!~StartListening
11/26/2005 5:50:33 PM ~!~HSPhone~!~Heard: messages
11/26/2005 5:50:33 PM ~!~HSPhone~!~Disable Listen
11/26/2005 5:51:13 PM ~!~Web Server Error 404~!~Cannot serve file: c:\program files\homeseer 2\html\tail.htm
11/26/2005 5:51:15 PM ~!~HSPhone~!~On Hook
11/26/2005 5:51:15 PM ~!~HSPhone~!~Resetting line due to handset going on hook
11/26/2005 5:51:15 PM ~!~HSPhone~!~Resetting line: Main
11/26/2005 5:51:45 PM ~!~HSPhone~!~Line: Main Speak: C:\Program Files\HomeSeer 2\wave\nomessage.wav
11/26/2005 5:51:53 PM ~!~HSPhone Error~!~Playing wave file: Thread was being aborted.
11/26/2005 5:51:54 PM ~!~Error~!~In PlayWav: Thread was being aborted.
11/26/2005 5:51:54 PM ~!~Error~!~In ProcessLine: Thread was being aborted.
11/26/2005 5:51:54 PM ~!~Error~!~In HandleIVRTimer: Thread was being aborted.
11/26/2005 5:51:54 PM ~!~Error~!~Resetting line: Exception has been thrown by the target of an invocation.
11/26/2005 5:51:54 PM ~!~Error~!~OnHook Error Thread was being aborted.
11/26/2005 5:51:57 PM ~!~Info~!~Initializing HomeSeer PCI Modem...
11/26/2005 5:51:58 PM ~!~Info~!~Using Com port 4 for modem HomeSeer PCI Voice Modem V2
11/26/2005 5:51:58 PM ~!~HSPhone~!~Enter WaitForCall, modem: 1
11/26/2005 5:51:59 PM ~!~HSPhone~!~Resetting line: Main
11/26/2005 5:52:00 PM ~!~HSPhone~!~Enter OpenLine, line: Main
11/26/2005 5:52:00 PM ~!~HSPhone~!~Enter WaitForCall, modem: 1
11/26/2005 5:52:01 PM ~!~HSPhone~!~Done Resetting line: Main

[danielperez]

Log #2

For this log I took out the use of a wav file for stating that there were no messages and put back the TTS. The same result.

11/26/2005 6:00:10 PM ~!~Info~!~Initializing HomeSeer PCI Modem...
11/26/2005 6:00:10 PM ~!~Info~!~Using Com port 4 for modem HomeSeer PCI Voice Modem V2
11/26/2005 6:00:11 PM ~!~HSPhone~!~Enter WaitForCall, modem: 1
11/26/2005 6:00:12 PM ~!~HSPhone~!~Resetting line: Main
11/26/2005 6:00:12 PM ~!~HSPhone~!~Enter OpenLine, line: Main
11/26/2005 6:00:12 PM ~!~HSPhone~!~Enter WaitForCall, modem: 1
11/26/2005 6:00:13 PM ~!~HSPhone~!~Done Resetting line: Main
11/26/2005 6:00:21 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/26/2005 6:00:22 PM ~!~HSPhone~!~Off Hook
11/26/2005 6:00:23 PM ~!~HSPhone~!~Waiting for #...
11/26/2005 6:00:24 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/26/2005 6:00:29 PM ~!~HSPhone~!~DTMF: #
11/26/2005 6:00:29 PM ~!~HSPhone~!~Connected line: Main
11/26/2005 6:00:29 PM ~!~Info~!~Read 364 lines from IVR file c:\program files\homeseer 2\ivr\internal.txt
11/26/2005 6:00:29 PM ~!~Info~!~Loading internal VR
11/26/2005 6:00:29 PM ~!~HSPhone~!~Building HomeSeer voice commands: 64829.6256608
11/26/2005 6:00:29 PM ~!~HSPhone~!~Done build HomeSeer voice commands: 64829.6256608
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) O
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Rssf
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Ist
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) John Losee
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) A Ramirez
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) O
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Daniel Perez
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Daniel Perez's cell phone
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) To Lyndeborough
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Caller Wireless
11/26/2005 6:00:29 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Dianna Vaccaro
11/26/2005 6:00:29 PM ~!~Info~!~Done loading internal VR
11/26/2005 6:00:29 PM ~!~HSPhone~!~Line: Main Speak: C:\Program Files\HomeSeer 2\wave\help.wav
11/26/2005 6:00:32 PM ~!~HSPhone~!~StartListening
11/26/2005 6:00:34 PM ~!~HSPhone~!~Heard: messages
11/26/2005 6:00:34 PM ~!~HSPhone~!~Disable Listen

[zap]

IVR Logging

Danielperez,

I think is was only in higher test builds, I do know it is in 2014 under the phone -setting-Line setting.

Mike

[danielperez]

I updated to HS 2014 and found the IVR logging in that version. Here is my log. Note that when the "On Hook" is done the CPU is already at 100% by that point and no further responses are given:

11/27/2005 9:12:03 PM ~!~HSPhone~!~Off Hook
11/27/2005 9:12:04 PM ~!~HSPhone~!~Waiting for #...
11/27/2005 9:12:05 PM ~!~HSPhone~!~DTMF: #
11/27/2005 9:12:05 PM ~!~HSPhone~!~Connected line: Main
11/27/2005 9:12:05 PM ~!~Info~!~Read 364 lines from IVR file c:\program files\homeseer 2\ivr\internal.txt
11/27/2005 9:12:05 PM ~!~Info~!~Processing line: 12->LABEL:START
11/27/2005 9:12:05 PM ~!~Info~!~Processing line: 18->LABEL:INTERNAL
11/27/2005 9:12:05 PM ~!~Info~!~Processing line: 19->WAIT_TIMEOUT:10
11/27/2005 9:12:05 PM ~!~Info~!~Processing line: 22->LOADVR:#VR_INTERNAL
11/27/2005 9:12:05 PM ~!~Info~!~Loading internal VR
11/27/2005 9:12:06 PM ~!~Info~!~Processing line: 23->CLEAR_KEY:
11/27/2005 9:12:06 PM ~!~Info~!~Processing line: 24->SPEAK:$INTERNAL_PROMPT
11/27/2005 9:12:06 PM ~!~HSPhone~!~Line: Main Speak: C:\Program Files\HomeSeer 2\wave\help.wav
11/27/2005 9:12:06 PM ~!~HSPhone~!~Disable Listen
11/27/2005 9:12:08 PM ~!~Info~!~Processing line: 25->GOTO:WAITVR
11/27/2005 9:12:08 PM ~!~Info~!~Processing line: 34->LABEL:WAITVR
11/27/2005 9:12:08 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:08 PM ~!~HSPhone~!~StartListening
11/27/2005 9:12:08 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:08 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:12:09 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:09 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:09 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:09 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:10 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:10 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:10 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:10 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:10 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:11 PM ~!~HSPhone~!~Heard: call Daniel Perez
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 36->CHECKVR:#VRCMD_HELP;GOTO:VRHELP;NEXT:
11/27/2005 9:12:11 PM ~!~HSPhone~!~Disable Listen
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 37->CHECKVR:#VRCMD_EXIT;GOTO:VREXIT;NEXT:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 38->CHECKVR:#VRCMD_LEAVE_MESSAGE;GOTO:VRLEAVE_MESSAGE;NEXT:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 39->CHECKVR:#VRCMD_DEVICE;GOTO:VRDEVICE;NEXT:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 40->CHECKVR:#VRCMD_DIAL;GOTO:VRDIAL;NEXT:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 40->CHECKVR:#VRCMD_DIAL;GOTO:VRDIAL;NEXT:
11/27/2005 9:12:11 PM ~!~Info~!~Processing line: 150->SPEAK:Shall I $VR_PHRASE
11/27/2005 9:12:11 PM ~!~HSPhone~!~Line: Main Speak: Shall I call Daniel Perez
11/27/2005 9:12:14 PM ~!~Info~!~Processing line: 151->LOADVR:Yes,No
11/27/2005 9:12:14 PM ~!~HSPhone~!~Adding custom voice command: Yes
11/27/2005 9:12:14 PM ~!~HSPhone~!~Adding custom voice command: No
11/27/2005 9:12:14 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:14 PM ~!~HSPhone~!~StartListening
11/27/2005 9:12:15 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:15 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:15 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:15 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:16 PM ~!~HSPhone~!~Heard: Yes
11/27/2005 9:12:16 PM ~!~Info~!~Processing line: 152->WAITVR:
11/27/2005 9:12:16 PM ~!~Info~!~Processing line: 153->IF:$VR_PHRASE=No;GOTO:VRDIAL_NO;NEXT:
11/27/2005 9:12:16 PM ~!~Info~!~Processing line: 153->IF:$VR_PHRASE=No;GOTO:VRDIAL_NO;NEXT:
11/27/2005 9:12:16 PM ~!~Info~!~Processing line: 155->LABELIAL
11/27/2005 9:12:16 PM ~!~Info~!~Processing line: 156->SPEAKialing
11/27/2005 9:12:16 PM ~!~HSPhone~!~Line: Main Speak: Dialing
11/27/2005 9:12:16 PM ~!~HSPhone~!~Disable Listen
11/27/2005 9:13:00 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/27/2005 9:13:03 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:13:05 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/27/2005 9:13:07 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:13:25 PM ~!~HSPhone~!~On Hook
11/27/2005 9:13:25 PM ~!~HSPhone~!~Resetting line due to handset going on hook
11/27/2005 9:13:25 PM ~!~HSPhone~!~Resetting line: Main
11/27/2005 9:14:43 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/27/2005 9:14:49 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:15:02 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/27/2005 9:15:15 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:15:21 PM ~!~Napco Gemini~!~Status event received: 192, 15, 5 (Attic Motion): Zone Opened.
11/27/2005 9:15:29 PM ~!~Napco Gemini~!~Status event received: 194, 0, 5 (Attic Motion): Zone Closed.
11/27/2005 9:16:16 PM ~!~HSPhone~!~Enter OpenLine, line: Main
11/27/2005 9:16:16 PM ~!~HSPhone~!~Enter WaitForCall, modem: 1

[danielperez]

Another sequence. In this one I say "Leave message" and it then goes bad. (I cut some lines in the log "Adding DIAL VC" that had personal info from incoming calls).

11/27/2005 9:35:47 PM ~!~HSPhone~!~Off Hook
11/27/2005 9:35:48 PM ~!~HSPhone~!~Waiting for #...
11/27/2005 9:35:52 PM ~!~HSPhone~!~DTMF: #
11/27/2005 9:35:52 PM ~!~HSPhone~!~Connected line: Main
11/27/2005 9:35:53 PM ~!~Info~!~Read 364 lines from IVR file c:\program files\homeseer 2\ivr\internal.txt
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 12->LABEL:START
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 18->LABEL:INTERNAL
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 19->WAIT_TIMEOUT:10
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 22->LOADVR:#VR_INTERNAL
11/27/2005 9:35:53 PM ~!~Info~!~Loading internal VR
11/27/2005 9:35:53 PM ~!~HSPhone~!~Building HomeSeer voice commands: 77753.1558832
11/27/2005 9:35:53 PM ~!~HSPhone~!~Done build HomeSeer voice commands: 77753.1658976
11/27/2005 9:35:53 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) O
11/27/2005 9:35:53 PM ~!~HSPhone~!~Adding DIAL VC: (dial | call) Rssf
11/27/2005 9:35:53 PM ~!~Info~!~Done loading internal VR
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 23->CLEAR_KEY:
11/27/2005 9:35:53 PM ~!~Info~!~Processing line: 24->SPEAK:$INTERNAL_PROMPT
11/27/2005 9:35:53 PM ~!~HSPhone~!~Line: Main Speak: C:\Program Files\HomeSeer 2\wave\help.wav
11/27/2005 9:35:55 PM ~!~Info~!~Processing line: 25->GOTO:WAITVR
11/27/2005 9:35:55 PM ~!~Info~!~Processing line: 34->LABEL:WAITVR
11/27/2005 9:35:55 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:55 PM ~!~HSPhone~!~StartListening
11/27/2005 9:35:56 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:56 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:56 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:56 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:57 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:57 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:57 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:57 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:58 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:58 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:58 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:58 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:58 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:59 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:59 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:59 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:35:59 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:00 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:00 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:00 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:00 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:01 PM ~!~HSPhone~!~Heard: leave a message
11/27/2005 9:36:01 PM ~!~Info~!~Processing line: 35->WAITVR:
11/27/2005 9:36:01 PM ~!~Info~!~Processing line: 36->CHECKVR:#VRCMD_HELP;GOTO:VRHELP;NEXT:
11/27/2005 9:36:01 PM ~!~HSPhone~!~Disable Listen

At this point I went to SETUP:PHONE and did a save which resets the Line. After I reset it the log went on:
11/27/2005 9:37:15 PM Info Processing line: 37->CHECKVR:#VRCMD_EXIT;GOTO:VREXIT;NEXT:
11/27/2005 9:37:15 PM Info Processing line: 38->CHECKVR:#VRCMD_LEAVE_MESSAGE;GOTO:VRLEAVE_MESSAGE;NEXT:
11/27/2005 9:37:15 PM Info Processing line: 38->CHECKVR:#VRCMD_LEAVE_MESSAGE;GOTO:VRLEAVE_MESSAGE;NEXT:
11/27/2005 9:37:15 PM Info Processing line: 102->IF:$MAILBOX_NAME=default;SPEAK:At the beep, leave a message. Press pound when finished;SPEAK:At the beep, leave a message for $MAILBOX_NAME. Press pound when finished
11/27/2005 9:37:15 PM Info Processing line: 102->IF:$MAILBOX_NAME=default;SPEAK:At the beep, leave a message. Press pound when finished;SPEAK:At the beep, leave a message for $MAILBOX_NAME. Press pound when finished
11/27/2005 9:37:15 PM HSPhone Line: Main Speak: At the beep, leave a message. Press pound when finished
11/27/2005 9:38:13 PM HSPhone On Hook
11/27/2005 9:38:13 PM HSPhone Resetting line due to handset going on hook
11/27/2005 9:38:13 PM HSPhone Resetting line: Main
11/27/2005 9:39:20 PM HSPhone Enter OpenLine, line: Main
11/27/2005 9:39:20 PM HSPhone Enter WaitForCall, modem: 1
11/27/2005 9:40:53 PM Error In ProcessLine: Thread was being aborted.
11/27/2005 9:40:53 PM Error In HandleIVRTimer: Thread was being aborted.
11/27/2005 9:40:53 PM Error Resetting line: Exception has been thrown by the target of an invocation.
11/27/2005 9:40:53 PM Error OnHook Error Thread was being aborted.
11/27/2005 9:40:53 PM HSPhone Error ProcessEVQ for PCI Modem error Thread was being aborted.
11/27/2005 9:40:56 PM Info Initializing HomeSeer PCI Modem...
11/27/2005 9:40:56 PM Info Using Com port 4 for modem HomeSeer PCI Voice Modem V2
11/27/2005 9:40:57 PM HSPhone Enter WaitForCall, modem: 1
11/27/2005 9:40:58 PM HSPhone Resetting line: Main
11/27/2005 9:40:58 PM HSPhone Enter OpenLine, line: Main
11/27/2005 9:40:58 PM HSPhone Enter WaitForCall, modem: 1
11/27/2005 9:40:59 PM HSPhone Done Resetting line: Main

[zap]

Danielperez,
I would put in a Help ticket to HS, and be sure to include that log file.

mike