Announcement

Collapse
No announcement yet.

TI103 Stops Sending Commands

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

    TI103 Stops Sending Commands

    The X10 plugin used with my TI103 interface occasionally stops sending X10 commands. The problem has grown much more frequent over the last few weeks to months. I see no errors in the log (I turned on all X10 logging). The X10 commands show up in the log, but no X10 signals are sent. Shutting down the plugin and restarting it solves the problem. I downloaded the Jon00 enable/disable plugin, which I have scheduled to run once a day, but the problem persists. Any ideas on where I can look for clues?

    Thanks,
    Mike

    #2
    Hi Mike, a some questions:
    1) What OS are you running HS3 & the plugin under?
    2) Which version of the plugin are you running?
    3) What are the DIP switches set to inside the Ti103?
    4) Has anything changed in your X10 environment lately?
    5) When the HS3/Plugin system stops sending X10 to your devices does it still receive X10 commands?

    Also, please post some snippets of your log file showing:
    1) The X10 plugin starting up.
    2) A working transmission
    3) A failing transmission
    Best regards,
    -Mark-

    If you're not out on the edge, you're taking up too much room!
    Interested in 3D maps? Check out my company site: Solid Terrain Modeling

    Comment


      #3
      Mark,

      Thank you for the guidance on where to start looking. I will gather the information and post it.

      Thanks,
      Mike

      Comment


        #4
        Originally posted by mfisher View Post
        Hi Mike, a some questions:
        1) What OS are you running HS3 & the plugin under?
        Windows 10 Home
        Version 1709
        64 Bit
        8 GB RAM

        HS3 Pro Version 3.0.0.368

        Originally posted by mfisher View Post
        2) Which version of the plugin are you running?
        3.0.0.45

        Originally posted by mfisher View Post
        3) What are the DIP switches set to inside the Ti103?
        All OFF except #7 ON

        Originally posted by mfisher View Post
        4) Has anything changed in your X10 environment lately?
        Only the addition of some lamp and appliance modules for the Christmas season. As you might expect, this is a yearly occurrence that has been going on for many years. However, I cannot say with certainty whether my problem is more frequent during this seasonal addition of X10 modules or not.

        The problem has existed for quite a long time, years maybe. But was infrequent enough as to keep me from pursuing it - I just restarted HS3 when it occurred. I then discovered a few months ago that just restarting the X10 plugin solved the problem. That's what led me to the Jon00 plugin, which I scheduled for every night. While restarting the plugin seems to solve the problem, it does not prevent it.

        Originally posted by mfisher View Post
        5) When the HS3/Plugin system stops sending X10 to your devices does it still receive X10 commands?
        I believe the answer is no. I base this assumption on the fact that the log does not appear to show my B6 motion sensor commands when the plugin is in failure mode. However, more testing would be needed to establish this for sure.

        Originally posted by mfisher View Post
        Also, please post some snippets of your log file showing:
        1) The X10 plugin starting up.
        See 8:06:19 PM to 8:06:35 PM

        Originally posted by mfisher View Post
        2) A working transmission
        See 8:06:36 PM to 8:07:16 PM

        Originally posted by mfisher View Post
        3) A failing transmission
        See 4:22:34 PM to 8:01:00 PM


        The following log snippets represent the failed events and subsequent successful events after the Ti103 plugin was restarted. They are in reverse chronological order, just as they came from the log.


        The following group of commands are from me manually triggered events via HS3Touch after having disabled and re-enabled the X10 plugin. All of them were successful to the best of my knowledge. Note that the multitude of B6 commands are an X10 motion sensor.

        Dec-10 8:07:16 PM X10 INFO InParsed(0): $<2800!EDIM6A#
        Dec-10 8:07:16 PM X10 INFO Raw Poll: 72436.8076866 - $<2800!EDIM6A#
        Dec-10 8:07:16 PM X10 INFO ProcessX10: $<2800! EDIMEDIMA9#
        Dec-10 8:07:16 PM X10 INFO InParsed(0): $<2800! EDIMEDIMA9#
        Dec-10 8:07:16 PM X10 INFO Raw Poll: 72436.5070097 - $<2800! EDIMEDIMA9#
        Dec-10 8:07:16 PM X10 INFO ProcessX10: $<2800! E03E03BB#
        Dec-10 8:07:16 PM X10 INFO InParsed(0): $<2800! E03E03BB#
        Dec-10 8:07:16 PM X10 INFO Raw Poll: 72436.1745933 - $<2800! E03E03BB#
        Dec-10 8:07:16 PM X10 INFO Callback: Dev= E13, HC= E, Cmd= 5, 5/0
        Dec-10 8:07:16 PM X10 INFO ProcessX10: $<2800!EBGT6D#
        Dec-10 8:07:15 PM X10 INFO InParsed(0): $<2800!EBGT6D#
        Dec-10 8:07:15 PM X10 INFO Raw Poll: 72435.8217606 - $<2800!EBGT6D#
        Dec-10 8:07:15 PM X10 INFO ProcessX10: $<2800! EBGTEBGTAF#
        Dec-10 8:07:15 PM X10 INFO InParsed(0): $<2800! EBGTEBGTAF#
        Dec-10 8:07:15 PM X10 INFO Raw Poll: 72435.5212828 - $<2800! EBGTEBGTAF#
        Dec-10 8:07:15 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:15 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:15 PM X10 INFO Raw Poll: 72435.2047383 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:15 PM X10 INFO Output Dequeue: buf119F#
        Dec-10 8:07:14 PM X10 INFO Output Dequeue: $>28002EDIM7D#
        Dec-10 8:07:14 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO Output Dequeue: $>28002EDIMEDIMEDIMEDIMEDIMF9#
        Dec-10 8:07:14 PM X10 INFO Output Dequeue: $>28002EDIMEDIMEDIMEDIMEDIMF9#
        Dec-10 8:07:14 PM X10 INFO Output Dequeue: adr
        Dec-10 8:07:14 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO Raw Poll: 72434.555868 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO Output Dequeue: $>28001E03E03 CD#
        Dec-10 8:07:14 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO Raw Poll: 72434.2505903 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:14 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO Raw Poll: 72433.9334398 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO ProcessX10: $<2800!EBGT6D#
        Dec-10 8:07:13 PM X10 INFO InParsed(0): $<2800!EBGT6D#
        Dec-10 8:07:13 PM X10 INFO Raw Poll: 72433.6017217 - $<2800!EBGT6D#
        Dec-10 8:07:13 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO Raw Poll: 72433.3009019 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:13 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO Raw Poll: 72432.9698088 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO ProcessX10: $<2800!EBGT6D#
        Dec-10 8:07:12 PM X10 INFO InParsed(0): $<2800!EBGT6D#
        Dec-10 8:07:12 PM X10 INFO Raw Poll: 72432.6537605 - $<2800!EBGT6D#
        Dec-10 8:07:12 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO Raw Poll: 72432.3535115 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO ProcessX10: $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO InParsed(0): $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO Raw Poll: 72432.0311831 - $<2800!EBGTEBGT8F#
        Dec-10 8:07:12 PM X10 INFO ProcessX10: $<2800! EBGT8D#
        Dec-10 8:07:11 PM X10 INFO InParsed(0): $<2800! EBGT8D#
        Dec-10 8:07:11 PM X10 INFO Raw Poll: 72431.7151564 - $<2800! EBGT8D#
        Dec-10 8:07:11 PM X10 INFO ProcessX10: $<2800! E03E03BB#
        Dec-10 8:07:11 PM X10 INFO InParsed(0): $<2800! E03E03BB#
        Dec-10 8:07:11 PM X10 INFO Raw Poll: 72431.4143574 - $<2800! E03E03BB#
        Dec-10 8:07:11 PM X10 INFO Output Dequeue: buf22A1#
        Dec-10 8:07:11 PM X10 INFO Output Dequeue: $>28002EBGTEBGTA2#
        Dec-10 8:07:11 PM X10 INFO Output Dequeue: $>28002EBGTEBGTEBGTEBGTEBGT08#
        Dec-10 8:07:11 PM X10 INFO ProcessX10: $<2800!E[1]131331A5#
        Dec-10 8:07:11 PM X10 INFO Output Dequeue: $>28002EBGTEBGTEBGTEBGTEBGT08#
        Dec-10 8:07:10 PM X10 INFO Output Dequeue: $>28002EBGTEBGTEBGTEBGTEBGT08#
        Dec-10 8:07:10 PM X10 INFO Output Dequeue: $>28002EBGTEBGTEBGTEBGTEBGT08#
        Dec-10 8:07:10 PM X10 INFO InParsed(0): $<2800!E[1]131331A5#
        Dec-10 8:07:10 PM X10 INFO Raw Poll: 72430.7665532 - $<2800!E[1]131331A5#
        Dec-10 8:07:10 PM X10 INFO Output Dequeue: adr
        Dec-10 8:07:10 PM X10 INFO Output Dequeue: $>28001E03E03 CD#
        Dec-10 8:07:10 PM X10 INFO ProcessX10: $<2800! E[1]131331C5#
        Dec-10 8:07:10 PM X10 INFO InParsed(0): $<2800! E[1]131331C5#
        Dec-10 8:07:10 PM X10 INFO Raw Poll: 72430.1453788 - $<2800! E[1]131331C5#
        Dec-10 8:07:09 PM X10 INFO Output Dequeue: cmd1
        Dec-10 8:07:09 PM X10 INFO Output Dequeue: $>28001E[1]131331B7#
        Dec-10 8:07:09 PM X10 INFO ProcessX10: $<2800!EON2D#
        Dec-10 8:07:09 PM X10 INFO InParsed(0): $<2800!EON2D#
        Dec-10 8:07:09 PM X10 INFO Raw Poll: 72429.1789818 - $<2800!EON2D#
        Dec-10 8:07:09 PM X10 INFO ProcessX10: $<2800!E04 EONF6#
        Dec-10 8:07:08 PM X10 INFO InParsed(0): $<2800!E04 EONF6#
        Dec-10 8:07:08 PM X10 INFO Raw Poll: 72428.8628182 - $<2800!E04 EONF6#
        Dec-10 8:07:08 PM X10 INFO ProcessX10: $<2800! E0414#
        Dec-10 8:07:08 PM X10 INFO InParsed(0): $<2800! E0414#
        Dec-10 8:07:08 PM X10 INFO Raw Poll: 72428.5468261 - $<2800! E0414#
        Dec-10 8:07:08 PM X10 INFO Output Dequeue: cmd2
        Dec-10 8:07:08 PM X10 INFO Output Dequeue: $>28001EONEON 41#
        Dec-10 8:07:08 PM X10 INFO Output Dequeue: adr
        Dec-10 8:07:08 PM X10 INFO SendX10: E11 Cmd: 7 Dim: 19 Data1/2: 19/49
        Dec-10 8:07:08 PM X10 INFO SendX10: E9 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 8:07:08 PM X10 INFO SendX10: E3 Cmd: 4 Dim: 50 Data1/2: 0/0
        Dec-10 8:07:08 PM X10 INFO SendX10: E3 Cmd: 5 Dim: 100 Data1/2: 0/0
        Dec-10 8:07:08 PM X10 INFO SendX10: E13 Cmd: 7 Dim: 19 Data1/2: 19/49
        Dec-10 8:07:08 PM X10 INFO Output Dequeue: $>28001E04E04 CF#
        Dec-10 8:07:08 PM X10 INFO SendX10: E4 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 8:07:08 PM Device Control Device: 1st Outside Front Porch Lights to Brightness 30% (30)
        Dec-10 8:07:08 PM Device Control Device: 1st Outside Garage Back Door Light to On (100)
        Dec-10 8:07:08 PM Device Control Device: 2nd Outside Driveway Lights - Upper to Brightness 50% (50)
        Dec-10 8:07:08 PM Device Control Device: 2nd Outside Back Deck Lights - Upper to Brightness 30% (30)
        Dec-10 8:07:08 PM Device Control Device: 1st Outside Front Yard Light to On (100)
        Dec-10 8:07:08 PM Event Event Trigger "Lighting Scenes Evening Lights Outside - ON"
        Dec-10 8:07:02 PM X10 INFO ProcessX10: $<2800!BOFF68#
        Dec-10 8:07:02 PM X10 INFO InParsed(0): $<2800!BOFF68#
        Dec-10 8:07:02 PM X10 INFO Raw Poll: 72422.5378263 - $<2800!BOFF68#
        Dec-10 8:07:02 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 3, 0/0
        Dec-10 8:07:02 PM X10 INFO ProcessX10: $<2800! BOFF88#
        Dec-10 8:07:02 PM X10 INFO InParsed(0): $<2800! BOFF88#
        Dec-10 8:07:02 PM X10 INFO Raw Poll: 72422.18493 - $<2800! BOFF88#
        Dec-10 8:07:01 PM X10 INFO ProcessX10: $<2800! B06B06BB#
        Dec-10 8:07:01 PM X10 INFO InParsed(0): $<2800! B06B06BB#
        Dec-10 8:07:01 PM X10 INFO Raw Poll: 72421.552768 - $<2800! B06B06BB#
        Dec-10 8:06:59 PM X10 INFO Callback: Dev= E5+E6+E8, HC= E, Cmd= 2, 0/0
        Dec-10 8:06:59 PM X10 INFO ProcessX10: $<2800! EONEON2F#
        Dec-10 8:06:59 PM X10 INFO InParsed(0): $<2800! EONEON2F#
        Dec-10 8:06:59 PM X10 INFO Raw Poll: 72419.0284255 - $<2800! EONEON2F#
        Dec-10 8:06:58 PM X10 INFO ProcessX10: $<2800!E08F8#
        Dec-10 8:06:58 PM X10 INFO InParsed(0): $<2800!E08F8#
        Dec-10 8:06:58 PM X10 INFO Raw Poll: 72418.6951303 - $<2800!E08F8#
        Dec-10 8:06:58 PM X10 INFO ProcessX10: $<2800! E0818#
        Dec-10 8:06:58 PM X10 INFO InParsed(0): $<2800! E0818#
        Dec-10 8:06:58 PM X10 INFO Raw Poll: 72418.3787231 - $<2800! E0818#
        Dec-10 8:06:58 PM X10 INFO ProcessX10: $<2800! E06E06C1#
        Dec-10 8:06:58 PM X10 INFO InParsed(0): $<2800! E06E06C1#
        Dec-10 8:06:58 PM X10 INFO Raw Poll: 72418.0780249 - $<2800! E06E06C1#
        Dec-10 8:06:58 PM X10 INFO ProcessX10: $<2800!E06F6#
        Dec-10 8:06:57 PM X10 INFO InParsed(0): $<2800!E06F6#
        Dec-10 8:06:57 PM X10 INFO Raw Poll: 72417.7617062 - $<2800!E06F6#
        Dec-10 8:06:57 PM X10 INFO ProcessX10: $<2800!E05 E06C0#
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: cmd2
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: $>28001EONEON 41#
        Dec-10 8:06:57 PM X10 INFO InParsed(0): $<2800!E05 E06C0#
        Dec-10 8:06:57 PM X10 INFO Raw Poll: 72417.4459858 - $<2800!E05 E06C0#
        Dec-10 8:06:57 PM X10 INFO ProcessX10: $<2800! E0515#
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: adr
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: $>28001E08E08 D7#
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: adr
        Dec-10 8:06:57 PM X10 INFO InParsed(0): $<2800! E0515#
        Dec-10 8:06:57 PM X10 INFO Raw Poll: 72417.1448652 - $<2800! E0515#
        Dec-10 8:06:57 PM X10 INFO Output Dequeue: $>28001E06E06 D3#
        Dec-10 8:06:56 PM X10 INFO Output Dequeue: adr
        Dec-10 8:06:56 PM X10 INFO Output Dequeue: $>28001E05E05 D1#
        Dec-10 8:06:56 PM Sent Q Cleanup Secs=19 Count=7
        Dec-10 8:06:56 PM X10 INFO SendX10: E5+6+8 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 8:06:56 PM Event Running script statement immediately: &hs.SetDeviceValueByRef(157,100,True)
        Dec-10 8:06:56 PM Device Control Device: 1st Outside Holiday Porch Lights to On (100)
        Dec-10 8:06:56 PM Device Control Device: 1st Outside Front Porch Outlets East to On (100)
        Dec-10 8:06:56 PM Device Control Device: 1st Outside Front Porch Outlets West to On (100)
        Dec-10 8:06:56 PM Event Event Trigger "Holiday Holiday Exterior Lights - ON"
        Dec-10 8:06:51 PM Speaker Speaker host added, Name: HOMESEER Instance: iPhone IP address: 192.168.2.102
        Dec-10 8:06:51 PM HSTouch Server Connecting speaker client to HomeSeer IP 192.168.2.90
        Dec-10 8:06:51 PM HSTouch Server Client iPhone (192.168.2.102/60498) named iPhone has CONNECTED.
        Dec-10 8:06:51 PM HSTouch Server Opening connection from:192.168.2.102/60498
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 3, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 3, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 3, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= <28, HC= K, Cmd= 3, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:37 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:36 PM X10 INFO Callback: Dev= B6, HC= B, Cmd= 2, 0/0
        Dec-10 8:06:36 PM X10 INFO Supressed: Dev= , HC= B, Cmd= 2, 0/0
        Dec-10 8:06:36 PM X10 INFO ProcessX10: $<2800! BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 K01K01$<2800! KOFFKOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON $<2800! 0B#
        Dec-10 8:06:36 PM X10 INFO InParsed(0): $<2800! BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 K01K01$<2800! KOFFKOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON $<2800! 0B#
        Dec-10 8:06:36 PM X10 INFO Raw Poll: 72396.6742268 - $<2800! BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 K01K01$<2800! KOFFKOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BOFFBOFF B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON B06B06 BONBON $<2800! 0B#

        The following group of commands are me manually disabling, then re-enabling the X10 interface with the Jon00Plugin via the HS3 web interface.

        Dec-10 8:06:35 PM Plug-In Finished initializing plug-in X10
        Dec-10 8:06:35 PM Info TI103 Plug-in, Version 3.0.45
        Dec-10 8:06:35 PM ACT TI103 Calling initIO in TI103 with port:COM3
        Dec-10 8:06:34 PM Info Plugin X10 has connected. IP:127.0.0.1:50483
        Dec-10 8:06:34 PM Jon00Plugin Enabling Plugin: X10
        Dec-10 8:06:19 PM Info Plugin X10 with instance: has disconnected
        Dec-10 8:06:19 PM X10 ERROR Error in CommThreadProc:Thread was being aborted.
        Dec-10 8:06:19 PM X10 ERROR Error in CommThreadLoop:The port is closed.
        Dec-10 8:06:19 PM X10 INFO Output Dequeue: $>28001N[1]092F31D9#
        Dec-10 8:06:19 PM X10 ERROR error in MSComm1_OnComm :The I/O operation has been aborted because of either a thread exit or an application request.
        Dec-10 8:06:19 PM Plug-In Shutting down Plug-In: X10
        Dec-10 8:06:19 PM Jon00Plugin Disabling Plugin: X10

        The following group of commands show my manual attempt to run an event from HS3Touch. It was not successful.

        Dec-10 8:06:11 PM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\apple-touch-icon.png
        Dec-10 8:06:11 PM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\apple-touch-icon-precomposed.png
        Dec-10 8:06:11 PM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\apple-touch-icon-120x120.png
        Dec-10 8:06:11 PM Warning File does not exist: C:\Program Files (x86)\HomeSeer HS3\html\apple-touch-icon-120x120-precomposed.png
        Dec-10 8:05:49 PM HSTouch Server Client iPhone named iPhone has DISCONNECTED.
        Dec-10 8:05:49 PM ASR Speaker Client speech recognition stopped
        Dec-10 8:05:49 PM Speaker Speaker host disconnected, Name: HOMESEER IP address: 192.168.2.102
        Dec-10 8:05:49 PM HSTouch Server Client iPhone:iPhone (192.168.2.102/60373) has raised LineDisconnected or was forced closed.
        Dec-10 8:05:49 PM HSTouch Server Warning Client dropped connection 192.168.2.102/60373
        Dec-10 8:05:43 PM Sent Q Cleanup Secs=39881 Count=7
        Dec-10 8:05:42 PM X10 INFO SendX10: E5+6+8 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 8:05:42 PM Event Running script statement immediately: &hs.SetDeviceValueByRef(157,100,True)
        Dec-10 8:05:42 PM Device Control Device: 1st Outside Holiday Porch Lights to On (100)
        Dec-10 8:05:42 PM Device Control Device: 1st Outside Front Porch Outlets East to On (100)
        Dec-10 8:05:42 PM Device Control Device: 1st Outside Front Porch Outlets West to On (100)
        Dec-10 8:05:42 PM Event Event Trigger "Holiday Holiday Exterior Lights - ON"
        Dec-10 8:05:37 PM Speaker Speaker host added, Name: HOMESEER Instance: iPhone IP address: 192.168.2.102
        Dec-10 8:05:37 PM HSTouch Server Connecting speaker client to HomeSeer IP 192.168.2.90
        Dec-10 8:05:37 PM HSTouch Server Client iPhone (192.168.2.102/60373) named iPhone has CONNECTED.
        Dec-10 8:05:36 PM HSTouch Server Opening connection from:192.168.2.102/60373
        Dec-10 8:05:36 PM HSTouch Server Opening connection from:192.168.2.90/50454

        I beleive there should have been some B6 motion sensor commands received at this point in the log, but none exist, suggesting that the Ti103 was not recieving, or not processing received commands.

        The following group of commands are from automated events. I do not know if the X10 commands were successful or not (but based on past experience, I suspect not).

        Dec-10 8:01:00 PM Z-Wave Device: 2nd Hall Aquarium Light Set to 0
        Dec-10 8:01:00 PM Device Control Device: 2nd Hall Aquarium Light to Off (0)
        Dec-10 8:01:00 PM Event Event Trigger "Aquarium Aquarium Light - 2001 - OFF"
        Dec-10 8:00:00 PM Sent Q Cleanup Secs=39538 Count=13
        Dec-10 8:00:00 PM X10 INFO SendX10: N2 Cmd: 5 Dim: 100 Data1/2: 0/0
        Dec-10 8:00:00 PM Device Control Device: 2nd Hall Aquarium Lamp to Brightness 99% (99)
        Dec-10 8:00:00 PM Event Event Trigger "Aquarium Aquarium Lamp - 2000 - ON"
        Dec-10 7:15:00 PM Log Info The log database is currently 30.95MB in size.
        Dec-10 7:15:00 PM Log Maintenance 157 records, selected by date/time, were removed from the log database.
        Dec-10 5:06:00 PM Energy DB Info All energy database compacting routines completed.
        Dec-10 5:06:00 PM Energy DB Info No Records. Vacuuming database to condense.
        Dec-10 5:06:00 PM Energy DB Info Energy database compaction initiated.

        The following group of commands are from automated events. None of them were successful.

        Dec-10 4:52:34 PM Sent Q Cleanup Secs=28292 Count=29
        Dec-10 4:52:34 PM X10 INFO SendX10: E11 Cmd: 7 Dim: 19 Data1/2: 19/49
        Dec-10 4:52:34 PM X10 INFO SendX10: E9 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 4:52:34 PM X10 INFO SendX10: E3 Cmd: 4 Dim: 50 Data1/2: 0/0
        Dec-10 4:52:34 PM X10 INFO SendX10: E3 Cmd: 5 Dim: 100 Data1/2: 0/0
        Dec-10 4:52:34 PM X10 INFO SendX10: E13 Cmd: 7 Dim: 19 Data1/2: 19/49
        Dec-10 4:52:34 PM X10 INFO SendX10: E4 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 4:52:34 PM Device Control Device: 1st Outside Front Porch Lights to Brightness 30% (30)
        Dec-10 4:52:34 PM Device Control Device: 1st Outside Garage Back Door Light to On (100)
        Dec-10 4:52:34 PM Device Control Device: 2nd Outside Driveway Lights - Upper to Brightness 50% (50)
        Dec-10 4:52:34 PM Device Control Device: 2nd Outside Back Deck Lights - Upper to Brightness 30% (30)
        Dec-10 4:52:34 PM Device Control Device: 1st Outside Front Yard Light to On (100)
        Dec-10 4:52:34 PM Event Event Trigger "Lighting Scenes Evening Lights Outside - ON"
        Dec-10 4:52:34 PM Event Event Trigger "Lighting Scenes Evening Lights Outside - SUNSET - ON"
        Dec-10 4:37:34 PM Sent Q Cleanup Secs=27392 Count=10
        Dec-10 4:37:34 PM X10 INFO SendX10: E5+6+8 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 4:37:34 PM X10 INFO SendX10: N12 Cmd: 2 Dim: 0 Data1/2: 0/0
        Dec-10 4:37:34 PM Event Running script statement immediately: &hs.SetDeviceValueByRef(157,100,True)
        Dec-10 4:37:34 PM Device Control Device: 1st Outside Holiday Porch Lights to On (100)
        Dec-10 4:37:34 PM Device Control Device: 1st Outside Front Porch Outlets East to On (100)
        Dec-10 4:37:34 PM Device Control Device: 1st Outside Front Porch Outlets West to On (100)
        Dec-10 4:37:34 PM Event Event Trigger "Holiday Holiday Exterior Lights - ON"
        Dec-10 4:37:34 PM Event Event Trigger "Holiday Holiday Exterior Lights - SUNSET - ON"
        Dec-10 4:37:34 PM Device Control Device: 2nd Master Bedroom MBR Chest Lamp to On (100)
        Dec-10 4:37:34 PM Event Event Trigger "Lighting Groups MBR Chest Lamp - SUNSET - ON"
        Dec-10 4:22:34 PM Sent Q Cleanup Secs=26492 Count=9
        Dec-10 4:22:34 PM Z-Wave Device: 1st Music Room Floor Lamp Set to 30
        Dec-10 4:22:34 PM X10 INFO SendX10: D2 Cmd: 10 Dim: 9 Data1/2: 0/0
        Dec-10 4:22:34 PM X10 INFO SendX10: G2 Cmd: 7 Dim: 4 Data1/2: 4/49
        Dec-10 4:22:34 PM X10 INFO SendX10: L13 Cmd: 10 Dim: 9 Data1/2: 0/0
        Dec-10 4:22:34 PM X10 INFO SendX10: G1 Cmd: 7 Dim: 13 Data1/2: 13/49
        Dec-10 4:22:34 PM X10 INFO SendX10: N9 Cmd: 7 Dim: 47 Data1/2: 47/49
        Dec-10 4:22:34 PM Device Control Device: 1st Music Room Floor Lamp to Dim 30% (30)
        Dec-10 4:22:34 PM Device Control Device: 1st Foyer Foyer Light to Brightness 6% (6)
        Dec-10 4:22:34 PM Device Control Device: 1st Family Room Up Light to Brightness 30% (30)
        Dec-10 4:22:34 PM Device Control Device: 1st Back Hall Back Hall Light to Brightness 20% (20)
        Dec-10 4:22:34 PM Event Event Trigger "Lighting Scenes Evening Lights Inside - ON"
        Dec-10 4:22:34 PM Event Event Trigger "Lighting Scenes Evening Lights Inside - SUNSET - ON"


        Thank you very much for your assistance.

        Mike

        Comment


          #5
          Thanks for the log info. I'll study this and let you know what I find.
          Best regards,
          -Mark-

          If you're not out on the edge, you're taking up too much room!
          Interested in 3D maps? Check out my company site: Solid Terrain Modeling

          Comment


            #6
            Can you search your log and find the 1st occurrence of this statement: "Dec-10 4:22:34 PM Sent Q Cleanup Secs=26492 Count=9" where Secs is a value less than 20. Then post the commands that were issued prior to this entry and a little bit past.

            This log entry may indicate that the Ti103 is having problems sending commands on the powerline. The Secs entry is the number of seconds since the plugin last received any X10 traffic from the Ti and the Count value is the number of commands that the plugin sent to the Ti but the Ti did not verify that the commands were sent on the powerline.

            BTW, please verify that the Ti103 Polling Interval is set to 300. If it is not set to 300 then please change it, restart HS and let me know if the issue persists.
            Last edited by mfisher; December 13, 2017, 11:42 AM.
            Best regards,
            -Mark-

            If you're not out on the edge, you're taking up too much room!
            Interested in 3D maps? Check out my company site: Solid Terrain Modeling

            Comment


              #7
              Polling Interval: 300

              When you say "1st occurrence", do you mean earliest, or most recent?

              Here are all of the log entries for that statement. Let me know for which one you would like the adjacent commands.

              Also, note that my morning events failed this morning and I reset the plugin at 7:36:18 AM.

              Dec-13 7:45:00 AM Sent Q Cleanup Secs=6 Count=3
              Dec-13 7:35:54 AM Sent Q Cleanup Secs=13882 Count=3
              Dec-13 7:15:22 AM Sent Q Cleanup Secs=12649 Count=29
              Dec-13 7:15:22 AM Sent Q Cleanup Secs=12649 Count=11
              Dec-13 6:45:00 AM Sent Q Cleanup Secs=10827 Count=3
              Dec-13 6:40:00 AM Sent Q Cleanup Secs=10527 Count=13
              Dec-13 6:30:00 AM Sent Q Cleanup Secs=9927 Count=21
              Dec-13 6:25:00 AM Sent Q Cleanup Secs=9627 Count=3
              Dec-13 6:15:00 AM Sent Q Cleanup Secs=9027 Count=6
              Dec-13 6:00:00 AM Sent Q Cleanup Secs=8127 Count=6
              Dec-12 9:00:00 PM Sent Q Cleanup Secs=258 Count=3
              Dec-12 8:00:09 PM Sent Q Cleanup Secs=5 Count=6
              Dec-12 7:33:57 PM Sent Q Cleanup Secs=23 Count=3
              Dec-12 4:37:53 PM Sent Q Cleanup Secs=16 Count=10
              Dec-12 9:01:00 AM Sent Q Cleanup Secs=13 Count=3
              Dec-12 7:18:53 AM Sent Q Cleanup Secs=5 Count=1
              Dec-12 7:14:40 AM Sent Q Cleanup Secs=332 Count=11
              Dec-12 6:30:14 AM Sent Q Cleanup Secs=5 Count=1
              Dec-11 9:33:44 PM Sent Q Cleanup Secs=5 Count=4
              Dec-11 8:00:15 PM Sent Q Cleanup Secs=5 Count=5
              Dec-11 4:37:43 PM Sent Q Cleanup Secs=140 Count=10
              Dec-11 9:01:00 AM Sent Q Cleanup Secs=333 Count=3
              Dec-11 7:12:28 AM Sent Q Cleanup Secs=5 Count=3
              Dec-11 6:40:09 AM Sent Q Cleanup Secs=5 Count=2
              Dec-11 6:00:00 AM Sent Q Cleanup Secs=3201 Count=3
              Dec-10 10:23:49 PM Sent Q Cleanup Secs=5 Count=3
              Dec-10 10:13:03 PM Sent Q Cleanup Secs=5 Count=5
              Dec-10 10:00:00 PM Sent Q Cleanup Secs=2699 Count=3
              Dec-10 8:06:56 PM Sent Q Cleanup Secs=19 Count=7
              Dec-10 8:05:43 PM Sent Q Cleanup Secs=39881 Count=7
              Dec-10 8:00:00 PM Sent Q Cleanup Secs=39538 Count=13
              Dec-10 4:52:34 PM Sent Q Cleanup Secs=28292 Count=29
              Dec-10 4:37:34 PM Sent Q Cleanup Secs=27392 Count=10
              Dec-10 4:22:34 PM Sent Q Cleanup Secs=26492 Count=9
              Dec-10 7:24:57 AM Sent Q Cleanup Secs=5 Count=1
              Dec-10 7:13:32 AM Sent Q Cleanup Secs=5 Count=1
              Dec-10 6:40:09 AM Sent Q Cleanup Secs=5 Count=2
              Dec-10 12:00:33 AM Sent Q Cleanup Secs=5 Count=2
              Dec-09 8:30:00 PM Sent Q Cleanup Secs=898 Count=3
              Dec-09 8:00:00 PM Sent Q Cleanup Secs=611 Count=13
              Dec-09 4:52:28 PM Sent Q Cleanup Secs=147 Count=4
              Dec-09 4:37:28 PM Sent Q Cleanup Secs=419 Count=3
              Dec-09 1:22:47 PM Sent Q Cleanup Secs=5 Count=2
              Dec-09 12:08:58 PM Sent Q Cleanup Secs=5 Count=3
              Dec-09 8:19:23 AM Sent Q Cleanup Secs=5 Count=3
              Dec-09 6:45:00 AM Sent Q Cleanup Secs=294 Count=3
              Dec-09 12:00:36 AM Sent Q Cleanup Secs=5 Count=5
              Dec-08 11:30:00 PM Sent Q Cleanup Secs=17 Count=3
              Dec-08 8:30:00 PM Sent Q Cleanup Secs=36 Count=3
              Dec-08 8:19:59 PM Sent Q Cleanup Secs=5 Count=1
              Dec-08 6:12:48 PM Sent Q Cleanup Secs=5 Count=8
              Dec-08 4:22:23 PM Sent Q Cleanup Secs=174 Count=3
              Dec-08 2:23:11 PM Sent Q Cleanup Secs=5 Count=4
              Dec-08 8:45:13 AM Sent Q Cleanup Secs=5 Count=6
              Dec-08 6:00:00 AM Sent Q Cleanup Secs=309 Count=3
              Dec-08 12:00:33 AM Sent Q Cleanup Secs=5 Count=1
              Dec-07 11:00:00 PM Sent Q Cleanup Secs=211 Count=3
              Dec-07 10:00:00 PM Sent Q Cleanup Secs=388 Count=3
              Dec-07 9:14:55 PM Sent Q Cleanup Secs=69 Count=3
              Dec-07 9:13:44 PM Sent Q Cleanup Secs=23 Count=3
              Dec-07 9:12:02 PM Sent Q Cleanup Secs=314 Count=3
              Dec-07 9:11:50 PM Sent Q Cleanup Secs=303 Count=3
              Dec-07 9:11:10 PM Sent Q Cleanup Secs=262 Count=3
              Dec-07 9:09:48 PM Sent Q Cleanup Secs=180 Count=3
              Dec-07 9:09:25 PM Sent Q Cleanup Secs=158 Count=3
              Dec-07 9:05:35 PM Sent Q Cleanup Secs=5 Count=2
              Dec-07 8:30:07 PM Sent Q Cleanup Secs=5 Count=1
              Dec-07 6:01:09 PM Sent Q Cleanup Secs=5 Count=4
              Dec-07 8:10:22 AM Sent Q Cleanup Secs=5 Count=5
              Dec-07 6:40:00 AM Sent Q Cleanup Secs=590 Count=13
              Dec-07 6:25:00 AM Sent Q Cleanup Secs=597 Count=3
              Dec-07 6:15:00 AM Sent Q Cleanup Secs=897 Count=3
              Dec-06 11:01:00 PM Sent Q Cleanup Secs=58 Count=7
              Dec-06 9:57:47 PM Sent Q Cleanup Secs=5 Count=6
              Dec-06 5:22:07 PM Sent Q Cleanup Secs=5 Count=1
              Dec-06 5:16:53 PM Sent Q Cleanup Secs=13 Count=3
              Dec-06 4:52:20 PM Sent Q Cleanup Secs=16 Count=25
              Dec-06 4:22:20 PM Sent Q Cleanup Secs=152 Count=5
              Dec-06 4:08:43 PM Sent Q Cleanup Secs=5 Count=2
              Dec-06 9:01:00 AM Sent Q Cleanup Secs=29 Count=3
              Dec-06 7:18:00 AM Sent Q Cleanup Secs=5 Count=6
              Dec-06 6:45:00 AM Sent Q Cleanup Secs=294 Count=3
              Dec-06 6:30:00 AM Sent Q Cleanup Secs=207 Count=1
              Dec-05 10:34:04 PM Sent Q Cleanup Secs=5 Count=2
              Dec-05 6:07:33 PM Sent Q Cleanup Secs=5 Count=1
              Dec-05 4:55:14 PM Sent Q Cleanup Secs=8 Count=28
              Dec-05 4:52:21 PM Sent Q Cleanup Secs=27631 Count=26
              Dec-05 4:52:21 PM Sent Q Cleanup Secs=27631 Count=3
              Dec-05 4:37:21 PM Sent Q Cleanup Secs=26731 Count=10
              Dec-05 4:37:21 PM Sent Q Cleanup Secs=26731 Count=3
              Dec-05 4:22:21 PM Sent Q Cleanup Secs=25831 Count=12
              Dec-05 7:07:28 AM Sent Q Cleanup Secs=5 Count=5
              Dec-05 6:45:00 AM Sent Q Cleanup Secs=294 Count=3
              Dec-05 12:00:35 AM Sent Q Cleanup Secs=5 Count=2
              Dec-04 10:18:19 PM Sent Q Cleanup Secs=5 Count=1
              Dec-04 10:00:00 PM Sent Q Cleanup Secs=106 Count=9
              Dec-04 9:00:00 PM Sent Q Cleanup Secs=936 Count=3
              Dec-04 8:30:00 PM Sent Q Cleanup Secs=898 Count=6
              Dec-04 8:00:00 PM Sent Q Cleanup Secs=674 Count=13
              Dec-04 7:10:56 PM Sent Q Cleanup Secs=5 Count=7
              Dec-04 4:52:42 PM Sent Q Cleanup Secs=5 Count=2
              Dec-04 4:21:34 PM Sent Q Cleanup Secs=5 Count=6
              Dec-04 9:01:00 AM Sent Q Cleanup Secs=307 Count=3
              Dec-04 8:24:43 AM Sent Q Cleanup Secs=5 Count=2
              Dec-04 6:40:00 AM Sent Q Cleanup Secs=292 Count=13
              Dec-04 12:00:38 AM Sent Q Cleanup Secs=5 Count=1
              Dec-04 12:00:00 AM Sent Q Cleanup Secs=3533 Count=19
              Dec-03 8:00:00 PM Sent Q Cleanup Secs=680 Count=13
              Dec-03 4:55:46 PM Sent Q Cleanup Secs=5 Count=1
              Dec-03 12:19:58 PM Sent Q Cleanup Secs=5 Count=4
              Dec-03 6:57:08 AM Sent Q Cleanup Secs=5 Count=5
              Dec-03 6:15:00 AM Sent Q Cleanup Secs=898 Count=3
              Dec-03 2:00:00 AM Sent Q Cleanup Secs=211 Count=3
              Dec-03 12:00:31 AM Sent Q Cleanup Secs=5 Count=2
              Dec-02 11:00:00 PM Sent Q Cleanup Secs=702 Count=3
              Dec-02 10:00:00 PM Sent Q Cleanup Secs=217 Count=9
              Dec-02 4:37:37 PM Sent Q Cleanup Secs=207 Count=3
              Dec-02 2:27:04 PM Sent Q Cleanup Secs=5 Count=7
              Dec-02 9:27:52 AM Sent Q Cleanup Secs=5 Count=3
              Dec-02 9:27:45 AM Sent Q Cleanup Secs=27 Count=3
              Dec-02 8:18:47 AM Sent Q Cleanup Secs=5 Count=2
              Dec-02 6:00:00 AM Sent Q Cleanup Secs=4717 Count=3
              Dec-01 11:17:16 PM Sent Q Cleanup Secs=1034 Count=3
              Dec-01 10:50:12 PM Sent Q Cleanup Secs=5 Count=3
              Dec-01 10:38:56 PM Sent Q Cleanup Secs=5 Count=1
              Dec-01 9:15:47 PM Sent Q Cleanup Secs=5 Count=1
              Dec-01 8:00:00 PM Sent Q Cleanup Secs=1570 Count=13
              Dec-01 6:38:51 PM Sent Q Cleanup Secs=657 Count=7
              Dec-01 5:06:50 PM Sent Q Cleanup Secs=5 Count=3
              Dec-01 4:37:46 PM Sent Q Cleanup Secs=430 Count=6
              Dec-01 3:58:58 PM Sent Q Cleanup Secs=5 Count=7
              Dec-01 3:15:14 PM Sent Q Cleanup Secs=5 Count=2
              Dec-01 2:52:19 PM Sent Q Cleanup Secs=11 Count=3
              Dec-01 9:01:00 AM Sent Q Cleanup Secs=175 Count=3

              Comment


                #8
                Thanks Mike, this list is great. I would be curious to know if there is any correlation between either the Secs or Count getting above some value and the plugin appearing to stop working. Any chance you could do a quick scan of your log and see if this might be the case?

                At this point I am theorizing that there are 2 main issues:
                1) You have a noisy X10 environment that keeps your Ti103 from validating it has transmitted commands, or your Ti103 is failing in some way.
                2) There is a bug in the Ti section of the plugin code related to the Ti not validating it has transmitted commands that is causing the plugin to stop functioning properly, but does not cause it to throw any errors or crash.

                Here is how the plugin and Ti work together:
                • HS3 issues a command to the plugin.
                • The plugin converts this into Ti103 code and sends it to the Ti. It also keeps a 'sent comands' list of each command sent to the Ti.
                • The Ti attempts to send the commands onto the power line, and listens to the power line at the same time to verify that the full X10 message was sent. If there is noise on the power line, or if there are other X10 devices transmitting at the same time the Ti will attempt to retransmit 3 more times or indefinitely depending on the setting of DipSwitch #4.
                • When the Ti validates the successful transmission of a command it echoes the command back to the plugin.
                • The plugin then removes the acknowledged command from it's 'sent commands' list.
                • If there are stale commands in the 'sent commands' list the plugin is supposed to remove them if they have been there for some amount of time (I' don't recall how long) and when this happens the 'Sent Q Cleanup' entry is written to the HS log.
                This system provides feedback that commands are actually being sent on the power line. In your case the Ti is regularly not sending the 'ack' for every command and why you see the 'Sent Q Cleanup' entries in the log.
                Best regards,
                -Mark-

                If you're not out on the edge, you're taking up too much room!
                Interested in 3D maps? Check out my company site: Solid Terrain Modeling

                Comment


                  #9
                  Originally posted by mfisher View Post
                  Thanks Mike, this list is great. I would be curious to know if there is any correlation between either the Secs or Count getting above some value and the plugin appearing to stop working. Any chance you could do a quick scan of your log and see if this might be the case?
                  See below for the same log snippet as above, except I've inserted the log entries that show where I manually restarted the X10 plugin. I can't guarantee that every restart is because of a failure, but I'm pretty sure most, if not all, are indeed due to a failure.

                  Originally posted by mfisher View Post
                  At this point I am theorizing that there are 2 main issues:
                  1) You have a noisy X10 environment that keeps your Ti103 from validating it has transmitted commands, or your Ti103 is failing in some way.
                  I have an ELK ESM1 that I use to check the power line for signal strength and noise. I don't notice any noise at present, but with so many electronic devices, that can certainly change. Since I only know about a failure *after* it occurs, I'm not in a position to say whether or not there is noise at the time of failure.

                  Any device I have that is known to create noise (or attenuate the signal) is plugged into an ACT AF120 filter. Also note that I do have and ACT CR234 Coupler/Repeater at my main breaker panel (and the Ti103 is also near the breaker panel).

                  Originally posted by mfisher View Post
                  2) There is a bug in the Ti section of the plugin code related to the Ti not validating it has transmitted commands that is causing the plugin to stop functioning properly, but does not cause it to throw any errors or crash.
                  There are no errors, but the plugin does stop working until it is restarted. Or could it be that the Ti stops working and restarting the plugin re-initializes the Ti?

                  You should also know that I do have another Ti on the shelf. I have had no reason to suspect the current Ti as being bad (since rebooting the software fixes the problem), so I have not swapped them to see if the problem persists.

                  Originally posted by mfisher View Post
                  Here is how the plugin and Ti work together:
                  • HS3 issues a command to the plugin.
                  • The plugin converts this into Ti103 code and sends it to the Ti. It also keeps a 'sent comands' list of each command sent to the Ti.
                  • The Ti attempts to send the commands onto the power line, and listens to the power line at the same time to verify that the full X10 message was sent. If there is noise on the power line, or if there are other X10 devices transmitting at the same time the Ti will attempt to retransmit 3 more times or indefinitely depending on the setting of DipSwitch #4.
                  • When the Ti validates the successful transmission of a command it echoes the command back to the plugin.
                  • The plugin then removes the acknowledged command from it's 'sent commands' list.
                  • If there are stale commands in the 'sent commands' list the plugin is supposed to remove them if they have been there for some amount of time (I' don't recall how long) and when this happens the 'Sent Q Cleanup' entry is written to the HS log.
                  This system provides feedback that commands are actually being sent on the power line. In your case the Ti is regularly not sending the 'ack' for every command and why you see the 'Sent Q Cleanup' entries in the log.
                  Thanks for these additional details. It helps me get a better handle on what should be happening.

                  Mike

                  12/14/2017 18:07 Sent Q Cleanup Secs=8 Count=19
                  12/14/2017 16:53 Sent Q Cleanup Secs=5 Count=2
                  12/14/2017 9:01 Sent Q Cleanup Secs=396 Count=3
                  12/14/2017 7:45 Sent Q Cleanup Secs=212 Count=3
                  12/14/2017 6:30 Sent Q Cleanup Secs=5 Count=2
                  12/13/2017 23:00 Sent Q Cleanup Secs=1181 Count=3
                  12/13/2017 22:00 Sent Q Cleanup Secs=1109 Count=6
                  12/13/2017 21:41 Sent Q Cleanup Secs=6 Count=3
                  12/13/2017 21:40 Sent Q Cleanup Secs=9 Count=3
                  12/13/2017 21:40 Sent Q Cleanup Secs=144 Count=3
                  12/13/2017 16:53 Sent Q Cleanup Secs=5 Count=1
                  12/13/2017 16:06 Sent Q Cleanup Secs=5 Count=2
                  12/13/2017 13:10 Sent Q Cleanup Secs=5 Count=4
                  12/13/2017 7:45 Sent Q Cleanup Secs=6 Count=3
                  12/13/2017 7:36 Info Plugin X10 has connected. IP:127.0.0.1:54180
                  12/13/2017 7:36 Jon00Plugin Enabling Plugin: X10
                  12/13/2017 7:36 Info Plugin X10 with instance: has disconnected
                  12/13/2017 7:36 Jon00Plugin Disabling Plugin: X10
                  12/13/2017 7:35 Sent Q Cleanup Secs=13882 Count=3
                  12/13/2017 7:15 Sent Q Cleanup Secs=12649 Count=29
                  12/13/2017 7:15 Sent Q Cleanup Secs=12649 Count=11
                  12/13/2017 6:45 Sent Q Cleanup Secs=10827 Count=3
                  12/13/2017 6:40 Sent Q Cleanup Secs=10527 Count=13
                  12/13/2017 6:30 Sent Q Cleanup Secs=9927 Count=21
                  12/13/2017 6:25 Sent Q Cleanup Secs=9627 Count=3
                  12/13/2017 6:15 Sent Q Cleanup Secs=9027 Count=6
                  12/13/2017 6:00 Sent Q Cleanup Secs=8127 Count=6
                  12/12/2017 21:00 Sent Q Cleanup Secs=258 Count=3
                  12/12/2017 20:00 Sent Q Cleanup Secs=5 Count=6
                  12/12/2017 19:33 Sent Q Cleanup Secs=23 Count=3
                  12/12/2017 16:37 Sent Q Cleanup Secs=16 Count=10
                  12/12/2017 9:01 Sent Q Cleanup Secs=13 Count=3
                  12/12/2017 7:18 Sent Q Cleanup Secs=5 Count=1
                  12/12/2017 7:14 Sent Q Cleanup Secs=332 Count=11
                  12/12/2017 6:30 Sent Q Cleanup Secs=5 Count=1
                  12/11/2017 21:33 Sent Q Cleanup Secs=5 Count=4
                  12/11/2017 20:00 Sent Q Cleanup Secs=5 Count=5
                  12/11/2017 16:37 Sent Q Cleanup Secs=140 Count=10
                  12/11/2017 9:01 Sent Q Cleanup Secs=333 Count=3
                  12/11/2017 7:12 Sent Q Cleanup Secs=5 Count=3
                  12/11/2017 6:40 Sent Q Cleanup Secs=5 Count=2
                  12/11/2017 6:00 Sent Q Cleanup Secs=3201 Count=3
                  12/10/2017 22:23 Sent Q Cleanup Secs=5 Count=3
                  12/10/2017 22:13 Sent Q Cleanup Secs=5 Count=5
                  12/10/2017 22:00 Sent Q Cleanup Secs=2699 Count=3
                  12/10/2017 20:06 Sent Q Cleanup Secs=19 Count=7
                  12/10/2017 20:06 Info Plugin X10 has connected. IP:127.0.0.1:50483
                  12/10/2017 20:06 Jon00Plugin Enabling Plugin: X10
                  12/10/2017 20:06 Info Plugin X10 with instance: has disconnected
                  12/10/2017 20:06 Jon00Plugin Disabling Plugin: X10
                  12/10/2017 20:05 Sent Q Cleanup Secs=39881 Count=7
                  12/10/2017 20:00 Sent Q Cleanup Secs=39538 Count=13
                  12/10/2017 16:52 Sent Q Cleanup Secs=28292 Count=29
                  12/10/2017 16:37 Sent Q Cleanup Secs=27392 Count=10
                  12/10/2017 16:22 Sent Q Cleanup Secs=26492 Count=9
                  12/10/2017 7:24 Sent Q Cleanup Secs=5 Count=1
                  12/10/2017 7:13 Sent Q Cleanup Secs=5 Count=1
                  12/10/2017 6:40 Sent Q Cleanup Secs=5 Count=2
                  12/10/2017 0:00 Sent Q Cleanup Secs=5 Count=2
                  12/9/2017 20:30 Sent Q Cleanup Secs=898 Count=3
                  12/9/2017 20:00 Sent Q Cleanup Secs=611 Count=13
                  12/9/2017 16:52 Sent Q Cleanup Secs=147 Count=4
                  12/9/2017 16:37 Sent Q Cleanup Secs=419 Count=3
                  12/9/2017 13:22 Sent Q Cleanup Secs=5 Count=2
                  12/9/2017 12:08 Sent Q Cleanup Secs=5 Count=3
                  12/9/2017 8:19 Sent Q Cleanup Secs=5 Count=3
                  12/9/2017 6:45 Sent Q Cleanup Secs=294 Count=3
                  12/9/2017 0:00 Sent Q Cleanup Secs=5 Count=5
                  12/8/2017 23:30 Sent Q Cleanup Secs=17 Count=3
                  12/8/2017 20:30 Sent Q Cleanup Secs=36 Count=3
                  12/8/2017 20:19 Sent Q Cleanup Secs=5 Count=1
                  12/8/2017 18:12 Sent Q Cleanup Secs=5 Count=8
                  12/8/2017 16:22 Sent Q Cleanup Secs=174 Count=3
                  12/8/2017 14:23 Sent Q Cleanup Secs=5 Count=4
                  12/8/2017 8:45 Sent Q Cleanup Secs=5 Count=6
                  12/8/2017 6:00 Sent Q Cleanup Secs=309 Count=3
                  12/8/2017 0:00 Sent Q Cleanup Secs=5 Count=1
                  12/7/2017 23:00 Sent Q Cleanup Secs=211 Count=3
                  12/7/2017 22:00 Sent Q Cleanup Secs=388 Count=3
                  12/7/2017 21:14 Sent Q Cleanup Secs=69 Count=3
                  12/7/2017 21:13 Sent Q Cleanup Secs=23 Count=3
                  12/7/2017 21:12 Info Plugin X10 has connected. IP:127.0.0.1:50598
                  12/7/2017 21:12 Jon00Plugin Enabling Plugin: X10
                  12/7/2017 21:12 Info Plugin X10 with instance: has disconnected
                  12/7/2017 21:12 Jon00Plugin Disabling Plugin: X10
                  12/7/2017 21:12 Sent Q Cleanup Secs=314 Count=3
                  12/7/2017 21:11 Sent Q Cleanup Secs=303 Count=3
                  12/7/2017 21:11 Sent Q Cleanup Secs=262 Count=3
                  12/7/2017 21:09 Sent Q Cleanup Secs=180 Count=3
                  12/7/2017 21:09 Sent Q Cleanup Secs=158 Count=3
                  12/7/2017 21:05 Sent Q Cleanup Secs=5 Count=2
                  12/7/2017 20:30 Sent Q Cleanup Secs=5 Count=1
                  12/7/2017 18:01 Sent Q Cleanup Secs=5 Count=4
                  12/7/2017 8:10 Sent Q Cleanup Secs=5 Count=5
                  12/7/2017 6:40 Sent Q Cleanup Secs=590 Count=13
                  12/7/2017 6:25 Sent Q Cleanup Secs=597 Count=3
                  12/7/2017 6:15 Sent Q Cleanup Secs=897 Count=3
                  12/6/2017 23:01 Sent Q Cleanup Secs=58 Count=7
                  12/6/2017 21:57 Sent Q Cleanup Secs=5 Count=6
                  12/6/2017 17:22 Sent Q Cleanup Secs=5 Count=1
                  12/6/2017 17:16 Sent Q Cleanup Secs=13 Count=3
                  12/6/2017 16:52 Sent Q Cleanup Secs=16 Count=25
                  12/6/2017 16:22 Sent Q Cleanup Secs=152 Count=5
                  12/6/2017 16:08 Sent Q Cleanup Secs=5 Count=2
                  12/6/2017 9:01 Sent Q Cleanup Secs=29 Count=3
                  12/6/2017 7:18 Sent Q Cleanup Secs=5 Count=6
                  12/6/2017 6:45 Sent Q Cleanup Secs=294 Count=3
                  12/6/2017 6:30 Sent Q Cleanup Secs=207 Count=1
                  12/5/2017 22:34 Sent Q Cleanup Secs=5 Count=2
                  12/5/2017 18:07 Sent Q Cleanup Secs=5 Count=1
                  12/5/2017 16:55 Sent Q Cleanup Secs=8 Count=28
                  12/5/2017 16:53 Info Plugin X10 has connected. IP:127.0.0.1:57687
                  12/5/2017 16:53 Jon00Plugin Enabling Plugin: X10
                  12/5/2017 16:53 Info Plugin X10 with instance: has disconnected
                  12/5/2017 16:53 Jon00Plugin Disabling Plugin: X10
                  12/5/2017 16:52 Sent Q Cleanup Secs=27631 Count=26
                  12/5/2017 16:52 Sent Q Cleanup Secs=27631 Count=3
                  12/5/2017 16:37 Sent Q Cleanup Secs=26731 Count=10
                  12/5/2017 16:37 Sent Q Cleanup Secs=26731 Count=3
                  12/5/2017 16:22 Sent Q Cleanup Secs=25831 Count=12
                  12/5/2017 7:07 Sent Q Cleanup Secs=5 Count=5
                  12/5/2017 6:45 Sent Q Cleanup Secs=294 Count=3
                  12/5/2017 0:00 Sent Q Cleanup Secs=5 Count=2
                  12/4/2017 22:18 Sent Q Cleanup Secs=5 Count=1
                  12/4/2017 22:00 Sent Q Cleanup Secs=106 Count=9
                  12/4/2017 21:00 Sent Q Cleanup Secs=936 Count=3
                  12/4/2017 20:30 Sent Q Cleanup Secs=898 Count=6
                  12/4/2017 20:00 Sent Q Cleanup Secs=674 Count=13
                  12/4/2017 19:10 Sent Q Cleanup Secs=5 Count=7
                  12/4/2017 16:52 Sent Q Cleanup Secs=5 Count=2
                  12/4/2017 16:21 Sent Q Cleanup Secs=5 Count=6
                  12/4/2017 9:01 Sent Q Cleanup Secs=307 Count=3
                  12/4/2017 8:24 Sent Q Cleanup Secs=5 Count=2
                  12/4/2017 6:40 Sent Q Cleanup Secs=292 Count=13
                  12/4/2017 0:00 Sent Q Cleanup Secs=5 Count=1
                  12/4/2017 0:00 Sent Q Cleanup Secs=3533 Count=19
                  12/3/2017 20:00 Sent Q Cleanup Secs=680 Count=13
                  12/3/2017 16:55 Sent Q Cleanup Secs=5 Count=1
                  12/3/2017 12:19 Sent Q Cleanup Secs=5 Count=4
                  12/3/2017 6:57 Sent Q Cleanup Secs=5 Count=5
                  12/3/2017 6:15 Sent Q Cleanup Secs=898 Count=3
                  12/3/2017 2:00 Sent Q Cleanup Secs=211 Count=3
                  12/3/2017 0:00 Sent Q Cleanup Secs=5 Count=2
                  12/2/2017 23:00 Sent Q Cleanup Secs=702 Count=3
                  12/2/2017 22:00 Sent Q Cleanup Secs=217 Count=9
                  12/2/2017 16:37 Sent Q Cleanup Secs=207 Count=3
                  12/2/2017 14:27 Sent Q Cleanup Secs=5 Count=7
                  12/2/2017 9:27 Sent Q Cleanup Secs=5 Count=3
                  12/2/2017 9:27 Sent Q Cleanup Secs=27 Count=3
                  12/2/2017 8:18 Sent Q Cleanup Secs=5 Count=2
                  12/2/2017 6:00 Sent Q Cleanup Secs=4717 Count=3
                  12/1/2017 23:17 Sent Q Cleanup Secs=1034 Count=3
                  12/1/2017 22:50 Sent Q Cleanup Secs=5 Count=3
                  12/1/2017 22:38 Sent Q Cleanup Secs=5 Count=1
                  12/1/2017 21:15 Sent Q Cleanup Secs=5 Count=1
                  12/1/2017 20:00 Sent Q Cleanup Secs=1570 Count=13
                  12/1/2017 18:38 Sent Q Cleanup Secs=657 Count=7
                  12/1/2017 17:06 Sent Q Cleanup Secs=5 Count=3
                  12/1/2017 16:37 Sent Q Cleanup Secs=430 Count=6
                  12/1/2017 15:58 Sent Q Cleanup Secs=5 Count=7
                  12/1/2017 15:15 Sent Q Cleanup Secs=5 Count=2
                  12/1/2017 14:52 Sent Q Cleanup Secs=11 Count=3
                  12/1/2017 9:01 Sent Q Cleanup Secs=175 Count=3
                  12/1/2017 8:34 Info Plugin X10 has connected. IP:127.0.0.1:50884
                  12/1/2017 8:34 Info Plugin UltraGCIR3 has connected. IP:127.0.0.1:50882
                  12/1/2017 8:34 Info Plugin Z-Wave has connected. IP:127.0.0.1:50880
                  12/1/2017 8:30 Info Plugin X10 with instance: has disconnected
                  12/1/2017 8:30 Info Plugin UltraGCIR3 with instance: has disconnected
                  12/1/2017 8:24 Info Plugin X10 has connected. IP:127.0.0.1:50800
                  12/1/2017 8:24 Info Plugin X10 with instance: has disconnected
                  12/1/2017 8:24 Info Plugin X10 has connected. IP:127.0.0.1:50788
                  12/1/2017 8:24 Warning In InitSingleIOPlugin: Object reference not set to an instance of an object.
                  12/1/2017 8:24 Updater Re-initializing plugin after update.
                  12/1/2017 8:24 Warning Problem shutting down plugin Msg-B:X10
                  12/1/2017 8:24 Warning Problem shutting down plugin Msg-A:X10:Object reference not set to an instance of an object.
                  12/1/2017 8:24 Info Plugin X10 with instance: has disconnected
                  12/1/2017 8:24 Updater Plugin is loaded, unloading before updating.
                  12/1/2017 7:11 Info Plugin X10 has connected. IP:127.0.0.1:50657
                  12/1/2017 7:11 Jon00Plugin Enabling Plugin: X10
                  12/1/2017 7:11 Info Plugin X10 with instance: has disconnected
                  12/1/2017 7:11 Jon00Plugin Disabling Plugin: X10

                  Comment


                    #10
                    Thanks for the additional info and details added to the log. I'll need to dig deeper into this to try and understand what might be happening. In the interim you might try swapping your spare Ti in to see if it makes a difference. I have two units as well and one of them occasionally gets a little flaky in the summertime when it gets really hot in the garage (where the Ti is plugged in).
                    Best regards,
                    -Mark-

                    If you're not out on the edge, you're taking up too much room!
                    Interested in 3D maps? Check out my company site: Solid Terrain Modeling

                    Comment


                      #11
                      Originally posted by mfisher View Post
                      Thanks for the additional info and details added to the log. I'll need to dig deeper into this to try and understand what might be happening. In the interim you might try swapping your spare Ti in to see if it makes a difference. I have two units as well and one of them occasionally gets a little flaky in the summertime when it gets really hot in the garage (where the Ti is plugged in).
                      I have not done this yet, but I will. It may take a while to establish whether or not it's successful, so you may not hear from me for a while. But I will report back if and when I have something significant.

                      Thanks,
                      Mike

                      Comment


                        #12
                        No worries Mike! We're dealing with the Thomas Fire, which is about a mile from my shop, and I won't have much time to devote to this issue until things settle down a bit.
                        Best regards,
                        -Mark-

                        If you're not out on the edge, you're taking up too much room!
                        Interested in 3D maps? Check out my company site: Solid Terrain Modeling

                        Comment


                          #13

                          Comment

                          Working...
                          X