About once a week, but then about 5 times yesterday, Homeseer misses my watchdog variable update.
I have a cycle that runs every five minutes that sets all my HVAC variables and then updates the watchdog variable.
My watchdog cycle gives it a second chance, so the watchdog usually works. It requires a failure twice in a row to actually have Homeseer think there is a loss of communications.
This is what is supposed to happen every five minutes:
8/12/2007 11:32:08 PM ~!~JDS StarGate~!~Load variable [94 (JDS HSCheck) with 4
Anyway, I noticed a series of failures on Saturday night in the middle of the night, so I turned on debug. It then caught another single failure last night at 11:45pm.
I have included a log section which shows it working fine, then another log section where the command to change the variable was received, but ignored?
RJ, can you tell me why the !!08/120852104004 was ignored in the second example? I see it at 11:42:08. Was there a followup "!!08/xxxxxx10415d" that got lost somehow?
The debug log used to show the entire string received and showed it getting broken down into pieces. It also showed sometimes a piece getting discarded.
Did somehow the polling interfere? Did the plug-in discard something that did not look like a polling result that happened to occur at the same time?
8/12/2007 11:32:05 PM ~!~JDS StarGate~!~Load variable [6 (JDS HVAC DormantOff) with 77
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [6 (77)
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846074055
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable event :: 055
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (85)
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846074106
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable event :: 106
8/12/2007 11:32:05 PM ~!~JDS StarGate~!~Load variable [7 (JDS HVAC EnergySave) with 85
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [7 (85)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846104004
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable event :: 004
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (4)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/12084610415d
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable event :: 15d
8/12/2007 11:32:08 PM ~!~JDS StarGate~!~Load variable [94 (JDS HSCheck) with 4
8/12/2007 11:32:08 PM ~!~Info~!~Event Trigger "JDS Homeseer Watchdog 4--3"
8/12/2007 11:32:08 PM ~!~Info~!~Delayed Trigger Event Removed: Stargate is Dead (delayed event)
8/12/2007 11:32:08 PM ~!~Info~!~Running script and waiting: TAL_variable_calcs.txt("main",104)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [94 (4)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846103131
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Flag event :: 131 -- Set flag \50
This time it failed to catch the variable change.. .
8/12/2007 11:42:05 PM ~!~JDS StarGate~!~Load variable [6 (JDS HVAC DormantOff) with 77
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [6 (77)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852074055
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable event :: 055
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (85)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852074106
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable event :: 106
8/12/2007 11:42:05 PM ~!~JDS StarGate~!~Load variable [7 (JDS HVAC EnergySave) with 85
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [7 (85)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3304
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Sending Command: ##%33800E
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Sending Command: ##%33800F
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3308
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338010
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3308
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338011
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338012
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%332c
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338013
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338014
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%334d
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338019
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3304
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R801Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R802Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R803Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R804Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = $R801Q00
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852104004
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~Variable event :: 004
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (4)
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = $R802Q00
I have a cycle that runs every five minutes that sets all my HVAC variables and then updates the watchdog variable.
My watchdog cycle gives it a second chance, so the watchdog usually works. It requires a failure twice in a row to actually have Homeseer think there is a loss of communications.
This is what is supposed to happen every five minutes:
8/12/2007 11:32:08 PM ~!~JDS StarGate~!~Load variable [94 (JDS HSCheck) with 4
Anyway, I noticed a series of failures on Saturday night in the middle of the night, so I turned on debug. It then caught another single failure last night at 11:45pm.
I have included a log section which shows it working fine, then another log section where the command to change the variable was received, but ignored?
RJ, can you tell me why the !!08/120852104004 was ignored in the second example? I see it at 11:42:08. Was there a followup "!!08/xxxxxx10415d" that got lost somehow?
The debug log used to show the entire string received and showed it getting broken down into pieces. It also showed sometimes a piece getting discarded.
Did somehow the polling interfere? Did the plug-in discard something that did not look like a polling result that happened to occur at the same time?
8/12/2007 11:32:05 PM ~!~JDS StarGate~!~Load variable [6 (JDS HVAC DormantOff) with 77
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [6 (77)
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846074055
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable event :: 055
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (85)
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846074106
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable event :: 106
8/12/2007 11:32:05 PM ~!~JDS StarGate~!~Load variable [7 (JDS HVAC EnergySave) with 85
8/12/2007 11:32:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [7 (85)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846104004
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable event :: 004
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (4)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/12084610415d
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable event :: 15d
8/12/2007 11:32:08 PM ~!~JDS StarGate~!~Load variable [94 (JDS HSCheck) with 4
8/12/2007 11:32:08 PM ~!~Info~!~Event Trigger "JDS Homeseer Watchdog 4--3"
8/12/2007 11:32:08 PM ~!~Info~!~Delayed Trigger Event Removed: Stargate is Dead (delayed event)
8/12/2007 11:32:08 PM ~!~Info~!~Running script and waiting: TAL_variable_calcs.txt("main",104)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [94 (4)
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120846103131
8/12/2007 11:32:08 PM ~!~JDS StarGate Comms~!~Flag event :: 131 -- Set flag \50
This time it failed to catch the variable change.. .
8/12/2007 11:42:05 PM ~!~JDS StarGate~!~Load variable [6 (JDS HVAC DormantOff) with 77
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [6 (77)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852074055
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable event :: 055
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (85)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852074106
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable event :: 106
8/12/2007 11:42:05 PM ~!~JDS StarGate~!~Load variable [7 (JDS HVAC EnergySave) with 85
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Variable Load(1) :: [7 (85)
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3304
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Sending Command: ##%33800E
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:05 PM ~!~JDS StarGate Comms~!~Sending Command: ##%33800F
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3308
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338010
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3308
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338011
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338012
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%332c
8/12/2007 11:42:06 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338013
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3300
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338014
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%334d
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%338019
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = ###%3304
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R801Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R802Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R803Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~Sending Command: ##%a507!R804Q
8/12/2007 11:42:07 PM ~!~JDS StarGate Comms~!~CurrentBuffer = $R801Q00
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = !!08/120852104004
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~Variable event :: 004
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~Variable Load(0) :: (4)
8/12/2007 11:42:08 PM ~!~JDS StarGate Comms~!~CurrentBuffer = $R802Q00