Announcement

Collapse
No announcement yet.

ISY Insteon Beta 1.0.3.17

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

    #46
    Thanks for letting me know it's working.

    Originally posted by MasterMog View Post
    The status fields no longer show the 56.8F as mentioned previously but they still seem to be very slow updating to the actual status.

    Thanks
    Mack
    Do you have an example and/or logs? Some devices will get updated when you make a change via the status page and some don't. I'm not sure it is consistent either. The main problem is that there isn't any way to synchronize the web page reload with the device status update message from the ISY. They are both asynchronous events and one is in HomeSeer and the other in my plug-in. So it is possible for the web page to reload before the status update arrives and is processed. When this happens, the status on the web page is one update behind. Typically forcing a reload of the status page brings it back up-to-date. I don't know if this is what you're experiencing but if it's not, please send more details and I'll try to track it down.
    --
    Bob Paauwe
    ISYInsteon Plug-in
    http://www.bobsplace.com/ISYInsteon/

    Comment


      #47
      Originally posted by bpwwer View Post
      Thanks for letting me know it's working.



      Do you have an example and/or logs? Some devices will get updated when you make a change via the status page and some don't. I'm not sure it is consistent either. The main problem is that there isn't any way to synchronize the web page reload with the device status update message from the ISY. They are both asynchronous events and one is in HomeSeer and the other in my plug-in. So it is possible for the web page to reload before the status update arrives and is processed. When this happens, the status on the web page is one update behind. Typically forcing a reload of the status page brings it back up-to-date. I don't know if this is what you're experiencing but if it's not, please send more details and I'll try to track it down.
      Forcing the reload seems to have works so you may be correct regarding web page reloading before status arrives. I will let you know if I have any other problems but everything seems to be working now.

      Thanks
      Mack

      Comment


        #48
        Multiple Homeseer Event Trigger when program status changes

        I have started seeing multiple event triggers when my garage door has been opened. This has been working fine in 1.03.8 but has started to generate duplicate events.

        See the log file entries below:


        Code:
         
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: ISY is idle. 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event Queue is now empty. 
        3/17/2012 6:40:43 PM  ISY Insteon  COMM: Received ISY Notice. 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [5120317 18:40:45120317 18:40:4521] 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: /My Programs/Test Program now at 21 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Update Test Program to True 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Setting Program Status from 31 to 21. 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  Event  Event Trigger "Garage Door Opened" 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  TTS  Speak ():Someone has opened the garage door 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
        3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event Queue is now empty. 
        3/17/2012 6:40:43 PM  Event  Event Trigger "Garage Door Opened" 
        3/17/2012 6:40:43 PM  TTS  Speak ():Someone has opened the garage door 
        3/17/2012 6:40:44 PM  Event  Event Trigger "Garage Door Opened" 
        3/17/2012 6:40:44 PM  TTS  Speak ():Someone has opened the garage door 
        3/17/2012 6:40:44 PM  Event  Event Trigger "Garage Door Opened" 
        3/17/2012 6:40:44 PM  TTS  Speak ():Someone has opened the garage door
        The test program is one I created on the ISY to remove any possiblity that the device itself (an IoLinc) was sending multiple on notifications.

        In the log above, I manually trigger the test program from the ISY admin console by choosing 'Run If' on the admin console.

        A TriggerLinc I have monitoring the door to a room doesn't exhibit this behavior. Any ideas to help diagnose this issue would be appreciated!

        Mitch

        Comment


          #49
          Hi Mitch,

          First question, I'm not clear on if you're using 1.0.3.8 or the latest 1.0.3.12 beta. I was able to reproduce the problem with the latest 1.0.3.12 version.

          The event processing change that happened after 1.0.3.8 was in part prompted by a the poor handling of the various program states. The new code does a better job of that but isn't complete (and has a bug). The goal was to be able to detect programs starts and stopping independent of the true/false state. In it's current state, it can detect that but the HomeSeer triggers haven't been updated so the multiple events that are sent are not getting properly mapped into HomeSeer events.

          And there is a bug that causes it to send state (enable / disable) change events that look just like status change events. It also isn't tracking the state properly so these are getting sent every time.

          My 2nd question is about the actual garage IOLinc, I'm assuming that you are using that to change an ISY program and it's the ISY program that generates multiple events in HomeSeer. Is that right? If not, then that means the IOLinc trigger code may be broken too.

          I have the program bugs fixed and will update a new version soon. I'd like to try it with the latest ISY firmware that was just released first.

          Originally posted by mitchmitchell1616 View Post
          I have started seeing multiple event triggers when my garage door has been opened. This has been working fine in 1.03.8 but has started to generate duplicate events.

          See the log file entries below:


          Code:
           
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: ISY is idle. 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event Queue is now empty. 
          3/17/2012 6:40:43 PM  ISY Insteon  COMM: Received ISY Notice. 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [5120317 18:40:45120317 18:40:4521] 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: /My Programs/Test Program now at 21 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Update Test Program to True 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Setting Program Status from 31 to 21. 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  Event  Event Trigger "Garage Door Opened" 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  TTS  Speak ():Someone has opened the garage door 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP521 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
          3/17/2012 6:40:43 PM  ISY Insteon  EVNT: Event Queue is now empty. 
          3/17/2012 6:40:43 PM  Event  Event Trigger "Garage Door Opened" 
          3/17/2012 6:40:43 PM  TTS  Speak ():Someone has opened the garage door 
          3/17/2012 6:40:44 PM  Event  Event Trigger "Garage Door Opened" 
          3/17/2012 6:40:44 PM  TTS  Speak ():Someone has opened the garage door 
          3/17/2012 6:40:44 PM  Event  Event Trigger "Garage Door Opened" 
          3/17/2012 6:40:44 PM  TTS  Speak ():Someone has opened the garage door
          The test program is one I created on the ISY to remove any possiblity that the device itself (an IoLinc) was sending multiple on notifications.

          In the log above, I manually trigger the test program from the ISY admin console by choosing 'Run If' on the admin console.

          A TriggerLinc I have monitoring the door to a room doesn't exhibit this behavior. Any ideas to help diagnose this issue would be appreciated!

          Mitch
          --
          Bob Paauwe
          ISYInsteon Plug-in
          http://www.bobsplace.com/ISYInsteon/

          Comment


            #50
            Bob,

            Thank you for looking at this so quickly.

            To answer your question, I am now using 1.0.3.12 beta. My reference to 1.0.3.8 was that it was the last version I used before this one and I did not see the problem in 1.0.3.8

            I normally have the IoLinc run an ISY program that I then have the homeseer event watch for.

            I will check to see if the problem occurs when the homeseer event watches the IoLinc status directly. I'll get back to you in a bit.

            Thanks!

            Mitch

            Comment


              #51
              Bob,

              I reconfigured the homerseer event to use the IoLinc 'sends on' directly rather than examining the program flag in the ISY -- still see repetition.

              Best Regards,

              Mitch

              Comment


                #52
                Hi Mitch,

                I haven't been able to reproduce this with other devices, but I haven't tried yet with an IOLinc. I'll try that soon. If you have a section of the log for this, that would help a bit too.

                Bob


                Originally posted by mitchmitchell1616 View Post
                Bob,

                I reconfigured the homerseer event to use the IoLinc 'sends on' directly rather than examining the program flag in the ISY -- still see repetition.

                Best Regards,

                Mitch
                --
                Bob Paauwe
                ISYInsteon Plug-in
                http://www.bobsplace.com/ISYInsteon/

                Comment


                  #53
                  Bob,

                  That tracks with what I am seeing -- I have a triggerlinc set up on one of my doors, and it does *not* repeat the event.

                  I also have an In-LineLinc with sense #2475S2 that *does* generate repeated events whenever my motion sensor driveway spotlight is triggered.


                  Below is a log of opening and closing my garage door (I open and close it twice in this log snippet). Note that I have some actions the ISY takes on its own when this happen including turning on and off two lamps in the house.

                  Best Regards,

                  Mitch



                  Code:
                  3/18/2012 3:54:20 PM  ISY Insteon  EVNT: ISY Heartbeat message. 
                  3/18/2012 3:54:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:20 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:37 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [E BE 53 1] eventinfo = [] 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Utility Room/Utility Room Overhead Light now set to 255 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: SceneTracking Enabled: E BE 53 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Update First Floor Utility Room Utility Room Overhead Light to 255 (100%) 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Setting Utility Room Overhead Light DeviceValue from 0 to 100 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Setting Utility Room Overhead Light DeviceStatus from 3 to 2. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [14 12 41 1] eventinfo = [] 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE BE 53 1Status 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE BE 53 1Status 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor now set to 255 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: SceneTracking Enabled: 14 12 41 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Setting Utility Room Motion-Sensor DeviceValue from 0 to 255 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Setting Utility Room Motion-Sensor DeviceStatus from 3 to 2. 
                  3/18/2012 3:54:37 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ 14 12 41 1] DON 1] 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update Trigger14 12 41 1Status 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update Trigger14 12 41 1Status 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor sent 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Trigger14 12 41 1on 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Trigger14 12 41 1on 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:37 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E BE 53 1] ST 255] 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Utility Room/Utility Room Overhead Light sent 255 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE BE 53 1status 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE BE 53 1status 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:37 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ 14 12 41 1] ST 255] 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor sent 255 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Trigger14 12 41 1status 
                  3/18/2012 3:54:37 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Trigger14 12 41 1status 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:37 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:44 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:44 PM  ISY Insteon  EVNT: control = [_0] action = [120] node = [] eventinfo = [] 
                  3/18/2012 3:54:44 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:44 PM  ISY Insteon  EVNT: ISY Heartbeat message. 
                  3/18/2012 3:54:44 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:44 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:51 PM  Vista Alarm  FAULT 005 HOUSE UTILITY DOOR 
                  3/18/2012 3:54:59 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [F 8A 4A 1] eventinfo = [] 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor now set to 255 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 8A 4A 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceValue from 0 to 255 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceStatus from 3 to 2. 
                  3/18/2012 3:54:59 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0] 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:59 PM  Event  Event Trigger "Garage Door Opened" 
                  3/18/2012 3:54:59 PM  TTS  Speak ():Someone has opened the garage door 
                  3/18/2012 3:54:59 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] ST 255] 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 255 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:59 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:00120318 15:52:1722] 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 22 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to Running True 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Setting Program Status from 31 to 22. 
                  3/18/2012 3:54:59 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:54:59 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:00120318 15:55:0021] 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 21 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to True 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Setting Program Status from 22 to 21. 
                  3/18/2012 3:55:00 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0] 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:00 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:00 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:00 PM  Event  Event Trigger "Garage Door Opened" 
                  3/18/2012 3:55:00 PM  TTS  Speak ():Someone has opened the garage door 
                  3/18/2012 3:55:01 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [F 6F FA 1] eventinfo = [] 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp now set to 255 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 6F FA 1 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Update First Floor Family Room Family Room Lamp to 255 (100%) 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceValue from 0 to 100 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceStatus from 3 to 2. 
                  3/18/2012 3:55:01 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 6F FA 1] ST 255] 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp sent 255 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:01 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:01 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:02 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [E 7F 2C 1] eventinfo = [] 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp now set to 255 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: SceneTracking Enabled: E 7F 2C 1 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Update First Floor Master Bedroom Master Bedroom Desk Lamp to 255 (100%) 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceValue from 0 to 100 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceStatus from 3 to 2. 
                  3/18/2012 3:55:02 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E 7F 2C 1] ST 255] 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp sent 255 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:02 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:02 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:09 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:09 PM  ISY Insteon  EVNT: control = [_0] action = [120] node = [] eventinfo = [] 
                  3/18/2012 3:55:09 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:09 PM  ISY Insteon  EVNT: ISY Heartbeat message. 
                  3/18/2012 3:55:09 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:09 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:10 PM  Vista Alarm  FAULT 014 GARAGEDOOR 
                  3/18/2012 3:55:13 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [F 8A 4A 1] eventinfo = [] 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor now set to 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 8A 4A 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceValue from 255 to 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:13 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DOF 0] 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] ST 0] 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 2 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1off 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1off 
                  3/18/2012 3:55:13 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:13120318 15:55:0033] 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 33 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to Running False 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Setting Program Status from 21 to 33. 
                  3/18/2012 3:55:13 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:13 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:14 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:14 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:14 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:14 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:13120318 15:55:1331] 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 31 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to False 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Setting Program Status from 33 to 31. 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:14 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:14 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:15 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [F 6F FA 1] eventinfo = [] 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp now set to 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 6F FA 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Update First Floor Family Room Family Room Lamp to 0 (0%) 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceValue from 100 to 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:15 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 6F FA 1] ST 0] 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp sent 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:15 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [E 7F 2C 1] eventinfo = [] 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp now set to 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: SceneTracking Enabled: E 7F 2C 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Update First Floor Master Bedroom Master Bedroom Desk Lamp to 0 (0%) 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceValue from 100 to 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:15 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E 7F 2C 1] ST 0] 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp sent 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:15 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:15 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:18 PM  Vista Alarm  FAULT 004 HOUSE KITCHEN DOOR 
                  3/18/2012 3:55:20 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [F 8A 4A 1] eventinfo = [] 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor now set to 255 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 8A 4A 1 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceValue from 0 to 255 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceStatus from 3 to 2. 
                  3/18/2012 3:55:20 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0] 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:20 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] ST 255] 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 2 
                  3/18/2012 3:55:20 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:21120318 15:55:1322] 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 3 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:20 PM  Event  Event Trigger "Garage Door Opened" 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 2 
                  3/18/2012 3:55:20 PM  TTS  Speak ():Someone has opened the garage door 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 255 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 1 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 22 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to Running True 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Setting Program Status from 31 to 22. 
                  3/18/2012 3:55:20 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP422 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:20 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:21120318 15:55:2121] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 21 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to True 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Setting Program Status from 22 to 21. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP421 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1on 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  Event  Event Trigger "Garage Door Opened" 
                  3/18/2012 3:55:22 PM  TTS  Speak ():Someone has opened the garage door 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [F 6F FA 1] eventinfo = [] 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp now set to 255 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 6F FA 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Update First Floor Family Room Family Room Lamp to 255 (100%) 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceValue from 0 to 100 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceStatus from 3 to 2. 
                  3/18/2012 3:55:22 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 6F FA 1] ST 255] 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp sent 255 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:22 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:22 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:23 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [E 7F 2C 1] eventinfo = [] 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp now set to 255 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: SceneTracking Enabled: E 7F 2C 1 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Update First Floor Master Bedroom Master Bedroom Desk Lamp to 255 (100%) 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceValue from 0 to 100 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceStatus from 3 to 2. 
                  3/18/2012 3:55:23 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E 7F 2C 1] ST 255] 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp sent 255 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:23 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:23 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [F 8A 4A 1] eventinfo = [] 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor now set to 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 8A 4A 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DOF 0] 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceValue from 255 to 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Setting Garage Door Sensor DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 8A 4A 1Status 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1off 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1off 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] ST 0] 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Garage/Garage Door Sensor sent 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 8A 4A 1status 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:32120318 15:55:2133] 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 33 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to Running False 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Setting Program Status from 21 to 33. 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP433 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:32 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: control = [_0] action = [120] node = [] eventinfo = [] 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: ISY Heartbeat message. 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:32 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [_5] action = [1] node = [] eventinfo = [] 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: ISY is busy. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [_5] action = [0] node = [] eventinfo = [] 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: ISY is idle. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:32120318 15:55:3231] 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: /My Programs/Garage Door Open Indication now at 31 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Update Garage Door Open Indication to False 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Setting Program Status from 33 to 31. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [F 6F FA 1] eventinfo = [] 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Notify subscribers of program trigger events. 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Program TriggerP431 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp now set to 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: SceneTracking Enabled: F 6F FA 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Update First Floor Family Room Family Room Lamp to 0 (0%) 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceValue from 100 to 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Setting Family Room Lamp DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:33 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 6F FA 1] ST 0] 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerF 6F FA 1Status 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Family Room/Family Room Lamp sent 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:33 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerF 6F FA 1status 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:33 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:34 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [E 7F 2C 1] eventinfo = [] 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp now set to 0 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: SceneTracking Enabled: E 7F 2C 1 
                  3/18/2012 3:55:34 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E 7F 2C 1] ST 0] 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Update First Floor Master Bedroom Master Bedroom Desk Lamp to 0 (0%) 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceValue from 100 to 0 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Setting Master Bedroom Desk Lamp DeviceStatus from 2 to 3. 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE 7F 2C 1Status 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Master Bedroom/Master Bedroom Desk Lamp sent 0 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:34 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE 7F 2C 1status 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:34 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [5] node = [] eventinfo = [-0.3 F/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Temperature rate now at -0.3 F/h 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [6] node = [] eventinfo = [71 %] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Temperature rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Temperature rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Humidity now at 71 % 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [7] node = [] eventinfo = [1 %/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Humidity** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Humidity** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Humidity rate now at 1 %/h 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Humidity rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Humidity rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [9] node = [] eventinfo = [-0.02 inches/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Pressure rate now at -0.02 inches/h 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Pressure rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [11] node = [] eventinfo = [7 mph] 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Pressure rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Wind speed now at 7 mph 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [18] node = [] eventinfo = [3.7 %] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Wind speed** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Wind speed** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Light now at 3.7 % 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_11] action = [19] node = [] eventinfo = [-9 %/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Light** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Light** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Weather Information weather Light rate now at -9 %/h 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: A Weather value was updated. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 5] -3000.000000 Weather - Temperature Rate = -0.3 °F/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Notify subscribers of weatherbug trigger event. 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Weather Trigger21020Light rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Weather Trigger21020Light rate** 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 5  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 6] 710000.000000 Weather - Humidity = 71 %] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 6  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 7] 10000.000000 Weather - Humidity Rate = 1 %/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 7  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 9] -200.000000 Weather - Pressure Rate = -0.02 inches/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 9  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 11] 70000.000000 Weather - Wind Speed = 7 mph] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 11  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 18] 37000.000000 Weather - Light = 3.7 %] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 18  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[MOD 2 2 1 19] -90000.000000 Weather - Light Rate = -9 %/h] 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:55 PM  ISY Insteon  COMM: NOT HANDLED: Unsupported ISY message: C=_1 A=3 N=MOD 2 2 1 19  
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:55 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:55:57 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:55:57 PM  ISY Insteon  EVNT: control = [_0] action = [120] node = [] eventinfo = [] 
                  3/18/2012 3:55:57 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:55:57 PM  ISY Insteon  EVNT: ISY Heartbeat message. 
                  3/18/2012 3:55:57 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:55:57 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:56:04 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [E BE 53 1] eventinfo = [] 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Utility Room/Utility Room Overhead Light now set to 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: SceneTracking Enabled: E BE 53 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Update First Floor Utility Room Utility Room Overhead Light to 0 (0%) 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Setting Utility Room Overhead Light DeviceValue from 100 to 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Setting Utility Room Overhead Light DeviceStatus from 2 to 3. 
                  3/18/2012 3:56:04 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [14 12 41 1] eventinfo = [] 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update TriggerE BE 53 1Status 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update TriggerE BE 53 1Status 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor now set to 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: SceneTracking Enabled: 14 12 41 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Setting Utility Room Motion-Sensor DeviceValue from 255 to 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Setting Utility Room Motion-Sensor DeviceStatus from 2 to 3. 
                  3/18/2012 3:56:04 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ 14 12 41 1] DOF 1] 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Update Trigger14 12 41 1Status 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Update Trigger14 12 41 1Status 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor sent 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Trigger14 12 41 1off 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Trigger14 12 41 1off 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:56:04 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ E BE 53 1] ST 0] 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Utility Room/Utility Room Overhead Light sent 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device TriggerE BE 53 1status 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device TriggerE BE 53 1status 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event Queue is now empty. 
                  3/18/2012 3:56:04 PM  ISY Insteon  COMM: Received ISY Notice. 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ 14 12 41 1] ST 0] 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event added to queue. Queue size = 1 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Utility Room/Utility Room Motion-Sensor sent 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Notify subscribers of device trigger event. 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Chk Trigger: ISY InsteonISY Device Trigger14 12 41 1status 
                  3/18/2012 3:56:04 PM  ISY Insteon  MAIN: Checking trigger: ISY InsteonISY Device Trigger14 12 41 1status 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event removed from queue. Queue size = 0 
                  3/18/2012 3:56:04 PM  ISY Insteon  EVNT: Event Queue is now empty.

                  Comment


                    #54
                    Originally posted by mitchmitchell1616 View Post
                    Bob,

                    That tracks with what I am seeing -- I have a triggerlinc set up on one of my doors, and it does *not* repeat the event.

                    I also have an In-LineLinc with sense #2475S2 that *does* generate repeated events whenever my motion sensor driveway spotlight is triggered.


                    Below is a log of opening and closing my garage door (I open and close it twice in this log snippet). Note that I have some actions the ISY takes on its own when this happen including turning on and off two lamps in the house.

                    Best Regards,

                    Mitch
                    Hi Mitch,

                    My IOLinc has apparently died. Maybe 3 years in the garage is all they can take. I've ordered a new one but in the meantime, all I have to go on is your logs. Based on what I see, the plug-in is working correctly given the inputs from the ISY (with the exception of the program trigger bugs). I've pulled the relevant parts from you log and described what they mean and what I believe is going on. Starting with the first message from the ISY about the IOLinc.

                    First message from ISY:
                    Code:
                    3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [ST] action = [255] node = [F 8A 4A 1] eventinfo = []
                    This causes the plug-in to update the device status from 0 to 255 (on).

                    Second message from ISY:
                    Code:
                    3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0]
                    This message is telling the plug-in that the IOLinc sent an ON command. This results in two trigger events getting sent to HomeSeer, device status change and device ON. The device ON trigger causes your Speak event to run.

                    Third message from ISY:
                    Code:
                    3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] ST 255]
                    This is a status trigger message saying the device is now has status 255 (which we already know from the first status message. This causes another device status change trigger, but is otherwise ignored.

                    Fourth message from ISY:
                    Code:
                    3/18/2012 3:54:59 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:00120318 15:52:1722]
                    This is a notification that your ISY Program has changed to running the "then" portion of the program. This generates mutliple program triggers because of the bug I mentioned earlier.

                    Fifth message from ISY:
                    Code:
                    3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_1] action = [0] node = [] eventinfo = [4120318 15:55:00120318 15:55:0021]
                    The program has stopped running the "then" portion and is now "True". This also generates multiple program triggers.

                    Sixth message from ISY:
                    Code:
                    3/18/2012 3:55:00 PM  ISY Insteon  EVNT: control = [_1] action = [3] node = [] eventinfo = [[ F 8A 4A 1] DON 0]
                    This is the same as the second message. This triggers your speak event to run again (about 1 second after the first time). The plug-in is not repeating the event, it is the ISY that is sending two messages notifying clients that the device sent an ON command. It's either the IOLinc sending the ON twice, or the ISY repeating it.

                    There are a number of messages for other devices.

                    Seventh message from ISY:
                    Code:
                    3/18/2012 3:55:13 PM  ISY Insteon  EVNT: control = [ST] action = [0] node = [F 8A 4A 1] eventinfo = []
                    This comes 13 seconds later and is the IOLinc sending a status value of 0.

                    At this point the OFF messages proceed with the same sequence as the ON did except that the we only see one notification that an DOF (OFF) was sent instead of two.

                    I don't think there's anything that the plug-in can do to change this. You could look at the ISY diagnostic logs to determine if it's the ISY or the IOLinc that doing the duplication. One solution would be to use Device Status Trigger, instead of the ISY Device Trigger. Have the event fire when the status changes to 255 instead of when the device sends an ON.

                    I'll be uploading a new version with the program bugs fixed shortly so you should be able to go back to using the program trigger with this version.
                    --
                    Bob Paauwe
                    ISYInsteon Plug-in
                    http://www.bobsplace.com/ISYInsteon/

                    Comment


                      #55
                      Version 1.0.3.13 uploaded. See the first post in this thread for details
                      --
                      Bob Paauwe
                      ISYInsteon Plug-in
                      http://www.bobsplace.com/ISYInsteon/

                      Comment


                        #56
                        Bob,

                        I ran the .12 plugin today with the device trigger and did not get repeats -- it looks like the repeat I got yesterday was due to 'keybounce' so to speak.

                        I will try out your .13 version tomorrow.

                        Best Regards,

                        Mitch

                        Comment


                          #57
                          I have been running .13 now for several days and have seen no repetition of program triggers at all. So looks like the fix worked.

                          The device trigger repetition I was seeing on my IoLinc is definitely some form of swtch bounce on the part of the sensor. Nothing I would expect the plugin to handle in that case.

                          Thanks!

                          Mitch

                          Comment


                            #58
                            Thanks for the update!


                            Originally posted by mitchmitchell1616 View Post
                            I have been running .13 now for several days and have seen no repetition of program triggers at all. So looks like the fix worked.

                            The device trigger repetition I was seeing on my IoLinc is definitely some form of swtch bounce on the part of the sensor. Nothing I would expect the plugin to handle in that case.

                            Thanks!

                            Mitch
                            --
                            Bob Paauwe
                            ISYInsteon Plug-in
                            http://www.bobsplace.com/ISYInsteon/

                            Comment


                              #59
                              Hi Bob since i Upgraded to your 1.0.3.13 and isy newest 3.2.3 I am seeing this error any thoughts where i may go to clean this up

                              Thanks

                              Robert



                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR0A BC 8E 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR07 F1 B0 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR018 28 5B 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR014 AD 5F 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR014 20 AE 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR013 F9 AC 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR013 EF 13 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 A1 13 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 90 33 1
                              4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 8C 29 1

                              Comment


                                #60
                                Hi Robert,

                                The ISY firmware added a new message that it sends:

                                3.2.0 WSDL NEW Err Control (Added Err Control ... for device comm errors)

                                The plug-in doesn't yet know how to parse that message. That's all the failure messages mean. I'll probably add support for those in the next release, but in the meantime, it should not be causing any problems.

                                I haven't really looked through my logs to see if I have any of those messages, but if you get a chance, can you send me one without the xml formatting stripped out?

                                Originally posted by Wingo View Post
                                Hi Bob since i Upgraded to your 1.0.3.13 and isy newest 3.2.3 I am seeing this error any thoughts where i may go to clean this up

                                Thanks

                                Robert



                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR0A BC 8E 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR07 F1 B0 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR018 28 5B 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR014 AD 5F 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR014 20 AE 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR013 F9 AC 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR013 EF 13 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 A1 13 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 90 33 1
                                4/9/2012 4:24:37 PM ISY Insteon COMM: Parse failure: ERR011 8C 29 1
                                --
                                Bob Paauwe
                                ISYInsteon Plug-in
                                http://www.bobsplace.com/ISYInsteon/

                                Comment

                                Working...
                                X