Mysterious "Control Switched On" Notification. Gho

TJF1960 · March 10, 2011

Hey Illusion,

Your ghost came to my house over the weekend! Well, kind of.

I was wrapping up some things with programming and scenes and was in the midst of testing when the vent fan over my workbench turned on all by itself. I also noticed that the SL controllers and KPL buttons which were included in this new scene were randomly turning on then off a couple of times. This happened more times than not when activating the new scene, and was not part of the scene for the vent fan. I was in a bit of a hurry and didnâ€™t think to fire up the event viewer.

I have a program that monitors the devices in that scene and if the conditions are not right and the scene is manually turned on the program will immediately turn that scene off. If I disabled that program and activated the scene or ran the scene test everything was fine, as soon as the program was enabled and the scene was activated the malfunctions occurred. I figured what was happening was that when the scene was manually turned on, all controllers and responders of the scene turned on. Then 1 second later the program sent the scene Off command and all of the controllers and responders turned off but enough time hadnâ€™t expired from when the scene On command was sent that when the devices reported they were off the scene on command was re-applied. I really never did figure out exactly why the vent fan turned on during this process (I kick myself for not starting the event viewer). I assumed I guess that with the data collisions occurring that the vent switch thought it received an On command or a scene On command.

The fix was a 3 second wait before sending the scene Off command, which gave the scene On command enough time to finish its operation.

In the last couple of days I have tried to duplicate the problem but havenâ€™t been able to. I am not sure if it is because I had removed a couple of devices from that scene or what.

So, the long of it is that this experience led me to wonder if a similar situation might be occurring in your system, where the motion sends the on or off command and there is for whatever reason some sort of data collision occurring and the resulting data is received by the bathroom SL. I donâ€™t know why it would report as a local on though. But, also, keeping in mind that you have replaced the switch with a new one, and the old one is still in the system but just for monitoring purposes and has yet to ghost on, that the only real common denominator is the scene or scenes the SL is in. Out of curiosity how many scenes is the SL in and how many devices are in each of the scenes?

Thanks,

Tim

Illusion · March 12, 2011

Vanity is responder to nine scenes. Controller to none. That in and of itself is interesting. I think I will make this device a controller of some scene and see if it turns the scene on when it ghosts on. I assume it will as it is sending the command out as though it had been switched on.

Scene:Number of devices in scene

1:7

2:47

3:8

4:7

5:3

6:5

7:32

8:35

9:5

Illusion · May 1, 2011

Interesting data point: Since making the problem switch a controller of a scene I have not had it ghost on.

I wonder in the other cases where this has been reported (other forums) if the switch in question was not a controller of any scenes.

eximer168 · May 17, 2011

Hey Illusion,

Your ghost came to my house over the weekend! Well, kind of.

I was wrapping up some things with programming and scenes and was in the midst of testing when the vent fan over my workbench turned on all by itself.

I have seen a similar problem with RemoteLinc which is inexplicable yet relates to the initial problem (ie, enabling/disabling a program and having an unexplained event occur.). This is w/v3.1.2

The structure of the code that Illusion was enabling/disabling may also be similar in that it was an IF with a series of control-switched ons

Program: AmbientHHOff First Floor

if HHRemote 1.C is switched off

or HHRemote2.C is switched off

or HHRemote3.C is switched off

...

then turn AP modules off

When enabled, Remote Key C will be followed by phantom keypress A if the program is enabled. The problem with this is that keypress A is to raise/lower shades on second story.

What I see is the nominal DON 0 command becomes a spurious DON 3 command (see below) This is very repeatable; disabling the program gives the correct response. Enabling the program causes the Keypress A after a few clicks. There are no linked scenes on the remote-linc, I even tried linking to "null-scenes" w/no responders with the same result.

In his case, perhaps a 'nominal' event is being duplicated by the ISY and processed. I questioned whether this was an access point problem, reset all modules, but have run out of ideas until I saw this thread.

Q: Is it possible ISY is replicating a message somehow in conjunction with a conditional program being enabled?

Sat 05/14/2011 09:50:05 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:08 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:10 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:12 AM : [ 14 20 83 1] DON 3

Sat 05/14/2011 09:50:13 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:14 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:26 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:30 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:33 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:35 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:37 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:39 AM : [ 14 20 83 1] DON 3

Sat 05/14/2011 09:50:40 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:50:42 AM : [ 14 20 83 1] DON 3

Michel Kohanim · May 17, 2011

Hello eximer168,

It would be best to look at your logs (instead of event viewer) to see whether or not ISY is turning something on/off.

If you are looking at event viewer:

1. If you see INST-ACK followed by INST-SRX preceding every DON below, then ISY is sending the command (highly unlikely for an RL)

2. If you only see an INST-SRX prior to a DON, then the signal is coming from the device and ISY is not in the middle (just reporting it)

With kind regards,

Michel

eximer168 · May 17, 2011

Hello eximer168,

It would be best to look at your logs (instead of event viewer) to see whether or not ISY is turning something on/off.

If you are looking at event viewer:

1. If you see INST-ACK followed by INST-SRX preceding every DON below, then ISY is sending the command (highly unlikely for an RL)

2. If you only see an INST-SRX prior to a DON, then the signal is coming from the device and ISY is not in the middle (just reporting it)

Attached data logs, below. It shows no ISY logged event, because I did not want shades to go up and down, but program was activating. Were I to enable it, and the IO's to the EZIO8/Somfy would have been logged and repeated the situation being debugged. This was discovered during the process of high-rel, optimization of access-point-positions (I have 6 access points positioned, so that there is always at least 1-hop leftover).

The log does show ACK/SRX as expected because the remote-linc has no direct device links. Al activations go throught the ISY because it there are too-many (and it is too-difficult) to access the many battery powered devices part of this network. FWIW, testing was on a very-big-network which now operates w/better than 99.9% reliability; I just couldn't explain away this last artifact which looks like it is originating from the ISY.

iSolo Status 125.00W Sat 2011/05/14 09:49:01 PM System Log

iSolo Total Power Used 0.09250 kWh Sat 2011/05/14 09:49:01 PM System Log

1908 / TC Upstairs.Main Humidity 54% Sat 2011/05/14 09:49:14 PM System Log

1908 / TC Upstairs.Main Humidity 55% Sat 2011/05/14 09:50:21 PM System Log

1908 / TC Downstairs.Main Humidity 51% Sat 2011/05/14 09:52:40 PM System Log

1908 / TC Upstairs.Main Humidity 54% Sat 2011/05/14 09:53:35 PM System Log

1908 / TC Downstairs.Main Humidity 50% Sat 2011/05/14 09:53:46 PM System Log

iSolo Status Query Sat 2011/05/14 09:54:00 PM Program Log

Sat 05/14/2011 09:51:12 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Sat 05/14/2011 09:51:12 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:12 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:51:12 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:12 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 41 11 03 LTONRR (03)

Sat 05/14/2011 09:51:12 AM : [standard-Cleanup][14.20.83-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Sat 05/14/2011 09:51:12 AM : [iNST-ACK ] 02 62 16.D5.99 0F 00 00 06 (00)

Sat 05/14/2011 09:51:12 AM : [iNST-ACK ] 02 62 16.D5.99 0F 00 00 06 (00): Received an ACK for a different device

Sat 05/14/2011 09:51:13 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 07 11 03 LTONRR (03)

Sat 05/14/2011 09:51:13 AM : [standard-Direct][14.20.83-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:13 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:13 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:13 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 4B 11 03 LTONRR (03)

Sat 05/14/2011 09:51:13 AM : [standard-Cleanup][14.20.83-->ISY/PLM Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:13 AM : [ 14 20 83 1] DON 3

Sat 05/14/2011 09:51:13 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:13 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:14 AM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 23 12 00 LTON-F (00)

Sat 05/14/2011 09:51:14 AM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Sat 05/14/2011 09:51:14 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:14 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:15 AM : [iNST-SRX ] 02 50 14.15.8F 14.85.AF 07 6F 42 (42)

Sat 05/14/2011 09:51:15 AM : [standard-Direct][14.15.8F-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:15 AM : [ 14 15 8F 1] CLIHUM 66

Michel Kohanim · May 18, 2011

Hello eximer168,

The only thing that is quite odd is that the PLM is responding with two ACKs for every command sent by ISY.

What is your PLM's firmware version (Tools | Diagnostics | PLM Status/Info)?

If I assume correctly, 14.20.83 is your RemoteLinc and basically ISY cannot even send any commands to it. What is happening is that the RemoteLinc itself (for some reason) is repeating the button press on button 3.

Do you have more than one RemoteLinc and if so do you have the problem with both?

Still the fact that you are getting two acks for each command does not look normal.

With kind regards,

Michel

eximer168 · May 18, 2011

The only thing that is quite odd is that the PLM is responding with two ACKs for every command sent by ISY.

What is your PLM's firmware version (Tools | Diagnostics | PLM Status/Info)?

If I assume correctly, 14.20.83 is your RemoteLinc and basically ISY cannot even send any commands to it. What is happening is that the RemoteLinc itself (for some reason) is repeating the button press on button 3.

Do you have more than one RemoteLinc and if so do you have the problem with both?

Still the fact that you are getting two acks for each command does not look normal.

I have 6 remotelinc's and they all do the same thing. Even after factory reset.

Q: How would the remotelinc 'know' that I'd enabled/disabled a program? That's what I cannot figure out, has to be something with programs, which is what caught my eye on this thread, the basic clause in the conditional was nearly identical. If the program is enabled/disabled, does it alter the PLM?

PLM is v92. I've also deleted, recreated each of the remote-linc entries from the ISY/device table with same results.

Two more captures from the time in question:

Sat 05/14/2011 09:52:10 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Sat 05/14/2011 09:52:10 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:52:10 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:52:10 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:10 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Sat 05/14/2011 09:52:10 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:52:10 AM : Duplicate: ignored

Sat 05/14/2011 09:52:10 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00): Process Message: failed

Sat 05/14/2011 09:52:10 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:52:10 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:10 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:10 AM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:10 AM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:11 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:11 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 47 11 03 LTONRR (03)

Sat 05/14/2011 09:52:11 AM : [standard-Cleanup][14.20.83-->ISY/PLM Group=3] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:11 AM : [ 14 20 83 1] DON 3

Sat 05/14/2011 09:52:11 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:11 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:12 AM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:12 AM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:12 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:12 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:12 AM : [iNST-SRX ] 02 50 14.06.24 14.85.AF 0B 6F 35 (35)

Sat 05/14/2011 09:52:12 AM : [standard-Direct][14.06.24-->ISY/PLM Group=0] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:52:12 AM : [ 14 6 24 1] CLIHUM 53

--------------------------------------------

and

--------------------------------------------

Sat 05/14/2011 09:51:57 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Sat 05/14/2011 09:51:57 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:57 AM : [ 14 20 83 3] DON 0

Sat 05/14/2011 09:51:57 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:57 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Sat 05/14/2011 09:51:57 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:57 AM : Duplicate: ignored

Sat 05/14/2011 09:51:57 AM : [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00): Process Message: failed

Sat 05/14/2011 09:51:57 AM : [standard-Group][14.20.83-->Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:57 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:57 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:57 AM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:51:57 AM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:58 AM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:51:58 AM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:58 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:58 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 07 11 03 LTONRR (03)

Sat 05/14/2011 09:51:58 AM : [standard-Direct][14.20.83-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:58 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:58 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:59 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 07 11 03 LTONRR (03)

Sat 05/14/2011 09:51:59 AM : [standard-Direct][14.20.83-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:59 AM : Duplicate: ignored

Sat 05/14/2011 09:51:59 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 07 11 03 LTONRR (03): Process Message: failed

Sat 05/14/2011 09:51:59 AM : [standard-Direct][14.20.83-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:51:59 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:59 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:51:59 AM : [iNST-SRX ] 02 50 14.20.83 14.85.AF 4B 11 03 LTONRR (03)

Sat 05/14/2011 09:51:59 AM : [standard-Cleanup][14.20.83-->ISY/PLM Group=3] Max Hops=3, Hops Left=2

Sat 05/14/2011 09:51:59 AM : [ 14 20 83 1] DON 3

Sat 05/14/2011 09:51:59 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:51:59 AM : [iNST-ACK ] 02 62 16.D5.99 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:00 AM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:00 AM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:00 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:00 AM : [iNST-ACK ] 02 62 14.FE.88 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:00 AM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:00 AM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:00 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00)

Sat 05/14/2011 09:52:00 AM : [iNST-ACK ] 02 62 16.D8.B3 0F 12 00 06 LTON-F (00): Received an ACK for a different device

Sat 05/14/2011 09:52:00 AM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:00 AM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Sat 05/14/2011 09:52:01 AM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 27 12 00 LTON-F (00)

Sat 05/14/2011 09:52:01 AM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

LeeG · May 18, 2011

eximer168

Q: How would the remotelinc 'know' that I'd enabled/disabled a program? That's what I cannot figure out, has to be something with programs, which is what caught my eye on this thread, the basic clause in the conditional was nearly identical. If the program is enabled/disabled, does it alter the PLM?

The PLM has no knowledge of ISY Programs. It is not the Program being enabled/disabled. It is the Insteon commands the Program issues when enabled.

For most devices when a button/paddle is pressed an Insteon Controller sends two messages.

Group Broadcast message

Group Cleanup Direct message

For RF devices the messages generated are different

Group Broadcast message [iNST-SRX ] 02 50 14.20.83 00.00.03 CB 11 00 LTONRR (00)

Group Broadcast message

Group Cleanup Direct message [iNST-SRX ] 02 50 14.20.83 14.85.AF 07 11 03 LTONRR (03)

Group Cleanup Direct message (sometimes)

Why the Group Broadcast message is sent multiple times has not been explained by Smartlabs that I have ever seen. It is normal with RemoteLincs, TriggerLincs, and Motion Sensors. The Group Cleanup Direct message being received multiple times varies. The most times I see this is when there is other Insteon traffic occurring at the same time. With RF devices it is possible for multiple RF receivers to process the message from the RemoteLinc. In theory only one will actually be propagated over the powerline to the application. In practice duplicate messages can be produced.

The condition is benign. The duplicate message might be avoided by putting a delay (Wait) at the beginning of the Program such that all the RemoteLinc inbound messages are processed before starting outbound traffic. I do not suggest that as a permanent solution. The message sequence is not causing any unwanted device behavior and the Wait would introduce an unnecessary delay.

From the trace it looks like multiple individual devices are being turned on with a Fast On. The devices will turn On in sequence when done that way. Defining a Scene with the same devices as Responders, turning the Scene Fast On, would turn all the Responders on nearly simultaneously,

Lee

Illusion · May 18, 2011

Interesting data point: Since making the problem switch a controller of a scene I have not had it ghost on.

I wonder in the other cases where this has been reported (other forums) if the switch in question was not a controller of any scenes.

Lee,

Still no more ghosting on instances since I made problem switch a controller for an arbitrary scene. Maybe this would work as a solution for the few others that have experienced this phenomenon.

Since this issue presented itself, this is the longest period, by far, that I have not had a ghost on incident.

eximer168 · May 18, 2011

eximer168

Q: How would the remotelinc 'know' that I'd enabled/disabled a program? That's what I cannot figure out, has to be something with programs, which is what caught my eye on this thread, the basic clause in the conditional was nearly identical. If the program is enabled/disabled, does it alter the PLM?

The PLM has no knowledge of ISY Programs. It is not the Program being enabled/disabled. It is the Insteon commands the Program issues when enabled.

...

From the trace it looks like multiple individual devices are being turned on with a Fast On. The devices will turn On in sequence when done that way. Defining a Scene with the same devices as Responders, turning the Scene Fast On, would turn all the Responders on nearly simultaneously,

Only one key was being pressed Remotelinc-C, however, other devices were doing their-thing in the background (ie. Thermostat-humidity message).

PLM being independent from the ISY, that's what I understood from the product description and architecture. But that being said, I can reproduce this symptom repeatably by enabling/disabling the program in question, and am still baffled as to why.

I intend to further look into this ghost-message along with two other anomalies(and will post here w/new findings if any):

1) ISY sends out an ON command, reports status as ON in the Java-Applet, but device does not go on (theoretical message lost). Need to determine if the ISY/internal ON-status actually affects program execution (since actual device status is inconsistent with status, workaround: send all INSTEON-commands twice, then query, but this busy's up the bus leading to second observable phenomena).

2) Separate programs which actively command on the same timeline (within 1second) interfere in unpredictable ways w/re to INSTEON/command-output. The effect is not as simple as the message-lost condition, which was why it was important to investigate this apart from any phantom-key issue if this were a known problem.

LeeG · May 18, 2011

eximer168

Enable the Program and delete all the statements in the Program that produce Insteon traffic. The result will be the same as when the Program is disabled. The PLM has no awareness of any application construct, be it ISY Program, Powerhome2 Macro, HouseLinc2 Event, etc. It is what the Program does when it is enabled, not the enable/disable state itself.

"1) ISY sends out an ON command, reports status as ON in the Java-Applet, but device does not go on (theoretical message lost)."

If the ON command is to the specific device this is not a lost message. Insteon Direct message requires an ACK from the device. If the ACK does not come back the device status is not changed. If the ON command is for a Scene it could be the message did not get to the Responder. If this is a Direct command look into the device itself. If a Direct command and the status changed the device ACKed the command indicating it was received and acted upon.

"2) Separate programs which actively command on the same timeline (within 1second) interfere in unpredictable ways w/re to INSTEON/command-output."

Here again it depends on whether a Direct command or Scene command is involved. Direct commands should be retried by the Controller (which would be the PLM in this case). The retry is automatic. Do you have a trace of this you can post?

Lee

eximer168 · May 19, 2011

Enable the Program and delete all the statements in the Program that produce Insteon traffic. ...

Lee, you are correct, taken-out produces no anomaly, however, with program content left-in (as I imagine original-poster: Illusion may have had) it produces anomalous result (captured below), program paraphrased here:

if control HHRemote1..7->C is switched off then

wait 1 second

set 4 appliance modules off

wait 1 second

query same 4 appliance modules

RemoteLinc #7 was pressed 3 times in rapid succession (ie, <<3seconds).

Summarizing we see:

14 20 EC 3] DOF 0

14 20 EC 1] DOF 3

14 20 EC 1] ST 0

INSTEON Device Control Value Time

1908 / AP Music Family Fast Off 0 Wed 2011/05/18 09:23:59 PM

1908 / AP Music Kitchen Fast Off 0 Wed 2011/05/18 09:23:59 PM

1908 / AP Music Dining Fast Off 0 Wed 2011/05/18 09:23:59 PM

1908 / AP Music Office Fast Off 0 Wed 2011/05/18 09:23:59 PM

1908 / AP Music Family Fast Off 0 Wed 2011/05/18 09:24:00 PM

1908 / AP Music Kitchen Fast Off 0 Wed 2011/05/18 09:24:00 PM

1908 / AP Music Dining Fast Off 0 Wed 2011/05/18 09:24:00 PM

1908 / AP Music Office Fast Off 0 Wed 2011/05/18 09:24:00 PM

1908 / HH Remote 7.A Status 0% Wed 2011/05/18 09:24:01 PM

1908 / AP Music Family Fast Off 0 Wed 2011/05/18 09:24:01 PM

1908 / AP Music Kitchen Fast Off 0 Wed 2011/05/18 09:24:01 PM

1908 / AP Music Dining Fast Off 0 Wed 2011/05/18 09:24:01 PM

1908 / AP Music Office Fast Off 0 Wed 2011/05/18 09:24:01 PM

1908 / AP Music Family Fast Off 0 Wed 2011/05/18 09:24:03 PM

1908 / AP Music Kitchen Fast Off 0 Wed 2011/05/18 09:24:03 PM

1908 / AP Music Dining Fast Off 0 Wed 2011/05/18 09:24:03 PM

1908 / AP Music Office Fast Off 0 Wed 2011/05/18 09:24:03 PM

1908 / AP Music Family Fast Off 0 Wed 2011/05/18 09:24:04 PM

1908 / AP Music Kitchen Fast Off 0 Wed 2011/05/18 09:24:04 PM

1908 / AP Music Dining Fast Off 0 Wed 2011/05/18 09:24:04 PM

1908 / AP Music Office Fast Off 0 Wed 2011/05/18 09:24:04 PM

Wed 05/18/2011 09:23:50 PM : CLI-WBug: Connecting to datafeed.weatherbug.com

Wed 05/18/2011 09:23:51 PM : CLI-WBug: Successfully Processed WBug Response

Wed 05/18/2011 09:23:53 PM : [ Time] 21:23:51 1(0)

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Wed 05/18/2011 09:23:59 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Wed 05/18/2011 09:23:59 PM : [ 14 20 EC 3] DOF 0

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Wed 05/18/2011 09:23:59 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Wed 05/18/2011 09:23:59 PM : Duplicate: ignored

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Wed 05/18/2011 09:23:59 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Wed 05/18/2011 09:23:59 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Wed 05/18/2011 09:24:00 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:00 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:00 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:00 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:01 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C7 13 00 LTOFFRR(00)

Wed 05/18/2011 09:24:01 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:01 PM : [ 14 20 EC 3] DOF 0

Wed 05/18/2011 09:24:01 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:01 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:01 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:01 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:01 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:01 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:02 PM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 23 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:02 PM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 09:24:02 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:02 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 4B 13 03 LTOFFRR(03)

Wed 05/18/2011 09:24:02 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=3, Hops Left=2

Wed 05/18/2011 09:24:02 PM : [ 14 20 EC 1] DOF 3

Wed 05/18/2011 09:24:02 PM : [ 14 20 EC 1] ST 0

Wed 05/18/2011 09:24:02 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:02 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Wed 05/18/2011 09:24:03 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 23 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:03 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 09:24:03 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C7 13 00 LTOFFRR(00)

Wed 05/18/2011 09:24:03 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:03 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:03 PM : [ 14 20 EC 3] DOF 0

Wed 05/18/2011 09:24:03 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:03 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:03 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:04 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:04 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Wed 05/18/2011 09:24:04 PM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:04 PM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:04 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:05 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Wed 05/18/2011 09:24:05 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:05 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:05 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:06 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:06 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Wed 05/18/2011 09:24:06 PM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 23 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:06 PM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 09:24:06 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:06 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 23 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:06 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 09:24:07 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:07 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:07 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:07 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:07 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:07 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:08 PM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:08 PM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:08 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:08 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:08 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:08 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:09 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Wed 05/18/2011 09:24:09 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Wed 05/18/2011 09:24:09 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:09 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 09:24:10 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 14 00 LTOFF-F(00)

Wed 05/18/2011 09:24:10 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

"1) ISY sends out an ON command, reports status as ON in the Java-Applet, but device does not go on (theoretical message lost)."

If the ON command is to the specific device this is not a lost message. Insteon Direct message requires an ACK from the device. If the ACK does not come back the device status is not changed. If the ON command is for a Scene it could be the message did not get to the Responder. If this is a Direct command look into the device itself. If a Direct command and the status changed the device ACKed the command indicating it was received and acted upon.

Data captured below, the more I look at this the more I can convince myself this is an INSTEON-protocol bug (as prior above issue), but, your opinion would be greatly appreciated.

Program-paraphased:

(turn on closet light when 'switch is off' and either motion sensor detects entry into closet or closet door is closed)

if status switch closet is off

and (

control Motion sensor is switched on

or triggerlinc sensor-open is switched off

) then

set scene closet on

wait 10 seconds

set scene closet query

INSTEON Device Control Value Time

1908 / MS Master Closet-Sensor Status 100% Wed 2011/05/18 09:35:54 PM

Scene:1908 / Master Closet Fast On 0 Wed 2011/05/18 09:35:54 PM

1908 / SW Master Closet Status 100% Wed 2011/05/18 09:35:54 PM

Wed 05/18/2011 09:35:53 PM : [iNST-SRX ] 02 50 14.18.CD 00.00.01 CB 11 01 LTONRR (01)

Wed 05/18/2011 09:35:53 PM : [standard-Group][14.18.CD-->Group=1] Max Hops=3, Hops Left=2

Wed 05/18/2011 09:35:53 PM : [ 14 18 CD 1] DON 1

Wed 05/18/2011 09:35:54 PM : [ 14 18 CD 1] ST 255

Wed 05/18/2011 09:35:54 PM : [iNST-ACK ] 02 62 00.00.35 CF 12 00 06 LTON-F (00)

Wed 05/18/2011 09:35:54 PM : [ 15 70 B6 1] ST 255

Wed 05/18/2011 09:35:54 PM : [iNST-SRX ] 02 50 14.18.CD 14.85.AF 41 11 01 LTONRR (01)

Wed 05/18/2011 09:35:54 PM : [standard-Cleanup][14.18.CD-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Wed 05/18/2011 09:35:54 PM : [iNST-SRX ] 02 50 14.18.CD 14.85.AF 66 88 87 (87)

Wed 05/18/2011 09:35:54 PM : [standard-Cleanup Ack][14.18.CD-->ISY/PLM Group=0] Max Hops=2, Hops Left=1

"2) Separate programs which actively command on the same timeline (within 1second) interfere in unpredictable ways w/re to INSTEON/command-output."

Here again it depends on whether a Direct command or Scene command is involved. Direct commands should be retried by the Controller (which would be the PLM in this case). The retry is automatic. Do you have a trace of this you can post?

This statistically harder to reproduce, but attached is an example which functioned correctly (the problem programs just happen to run at 10pm -- time very soon to this posting) Three programs tend to collide resulting in cat-feeder device not activating:

1-program #1, turn off series of appliance modules at 10pm

2-at various times including 10pm activate iolinc device to cat-feeder

3-every 5 minutes query status of greenwall + bug zapper socket.

When this fails, I see logged iolinc activation, but actual device does not activate (ie, no led flash-on), nor dispense of food. I will attempt to capture the non-functioning log, or set up equivalent test (ie. this is nontrivial, and could be related to #2).

INSTEON Device Control Value Time User Log Type

Scene:1908 / Ambient Off 0 Wed 2011/05/18 10:00:00 PM Program Log

1908 / Cat Feeder Trigger On 255 Wed 2011/05/18 10:00:00 PM Program Log

1908 / Cat Bell-Relay On 255 Wed 2011/05/18 10:00:00 PM Program Log

1908 / ES Greenwall Status Query Wed 2011/05/18 10:00:01 PM Program Log

1908 / ES Patio Rear Status Query Wed 2011/05/18 10:00:03 PM Program Log

Scene:1908 / Ambient Status Query Wed 2011/05/18 10:00:10 PM Program Log

1908 / Cat Feeder Trigger Status Query Wed 2011/05/18 10:00:14 PM Program Log

1908 / Cat Feeder Trigger Status 0% Wed 2011/05/18 10:00:15 PM System Log

1908 / Cat Bell-Relay Status Query Wed 2011/05/18 10:00:15 PM Program Log

1908 / Cat Bell-Relay Status 0% Wed 2011/05/18 10:00:15 PM System Log

Wed 05/18/2011 10:00:00 PM : [ Time] 22:00:00 0(0)

Wed 05/18/2011 10:00:00 PM : [iNST-ACK ] 02 62 00.00.10 CF 13 00 06 LTOFFRR(00)

Wed 05/18/2011 10:00:01 PM : [iNST-ACK ] 02 62 00.00.10 CF 13 00 06 LTOFFRR(00)

Wed 05/18/2011 10:00:01 PM : [iNST-ACK ] 02 62 15.E3.EE 0F 11 02 06 LTONRR (02)

Wed 05/18/2011 10:00:02 PM : [iNST-ACK ] 02 62 15.8F.CE 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:03 PM : [iNST-SRX ] 02 50 15.E3.EE 14.85.AF 23 11 02 LTONRR (02)

Wed 05/18/2011 10:00:03 PM : [standard-Direct Ack][15.E3.EE-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 10:00:03 PM : [iNST-SRX ] 02 50 15.8F.CE 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:03 PM : [standard-Direct Ack][15.8F.CE-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:03 PM : [iNST-ACK ] 02 62 15.99.E2 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:04 PM : [iNST-SRX ] 02 50 15.99.E2 14.85.AF 27 01 00 (00)

Wed 05/18/2011 10:00:04 PM : [standard-Direct Ack][15.99.E2-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:11 PM : [iNST-ACK ] 02 62 14.FE.88 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:11 PM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:11 PM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:11 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:12 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:12 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:12 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:12 PM : [iNST-SRX ] 02 50 16.D8.B3 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:12 PM : [standard-Direct Ack][16.D8.B3-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:12 PM : [iNST-ACK ] 02 62 16.D6.54 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:13 PM : [iNST-SRX ] 02 50 16.D6.54 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:13 PM : [standard-Direct Ack][16.D6.54-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:13 PM : [iNST-ACK ] 02 62 14.FE.60 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:14 PM : [iNST-SRX ] 02 50 14.FE.60 14.85.AF 27 3E 00 (00)

Wed 05/18/2011 10:00:14 PM : [standard-Direct Ack][14.FE.60-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:14 PM : [iNST-ACK ] 02 62 16.D5.99 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:14 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:14 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:14 PM : [iNST-ACK ] 02 62 16.D5.53 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:14 PM : [iNST-SRX ] 02 50 16.D5.53 14.85.AF 27 00 00 (00)

Wed 05/18/2011 10:00:14 PM : [standard-Direct Ack][16.D5.53-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Wed 05/18/2011 10:00:14 PM : [iNST-ACK ] 02 62 16.DA.53 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:15 PM : [iNST-SRX ] 02 50 16.DA.53 14.85.AF 23 00 00 (00)

Wed 05/18/2011 10:00:15 PM : [standard-Direct Ack][16.DA.53-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 10:00:15 PM : [iNST-ACK ] 02 62 15.E3.EE 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:15 PM : [iNST-SRX ] 02 50 16.DA.53 14.85.AF 20 FF C0 (C0)

Wed 05/18/2011 10:00:15 PM : [standard-Direct Ack][16.DA.53-->ISY/PLM Group=0] Max Hops=0, Hops Left=0

Wed 05/18/2011 10:00:15 PM : [iNST-SRX ] 02 50 15.E3.EE 14.85.AF 23 00 00 (00)

Wed 05/18/2011 10:00:15 PM : [standard-Direct Ack][15.E3.EE-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 10:00:15 PM : [iNST-ACK ] 02 62 15.E5.18 0F 19 00 06 LTSREQ (LIGHT)

Wed 05/18/2011 10:00:16 PM : [iNST-SRX ] 02 50 15.E5.18 14.85.AF 23 00 00 (00)

Wed 05/18/2011 10:00:16 PM : [standard-Direct Ack][15.E5.18-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Wed 05/18/2011 10:00:16 PM : [ 15 E3 EE 2] ST 0

Wed 05/18/2011 10:00:16 PM : [ 15 E5 18 2] ST 0

LeeG · May 19, 2011

eximer168

I cannot explain why what looks to be 5 iterations of the Program from 3 button presses of the RemoteLinc. Pressing the button that quickly does change the messages that are flowing. The normal 3 Group Broadcast message sequence for press 2 and 3 are cut off. Looks like the RemoteLinc does not stack the outbound commands. Simply aborts the currently working Group for the next Group. This would be consistent in some respects with Insteon architecture as multiple Groups are not permitted to be active at the same time.

I see no Query operations in that first trace. Also created a Program that matches the first example and cannot reproduce the sequence you are seeing. Perhaps my RemoteLinc is at a different firmware level.

What ISY image are you running?

Iâ€™m looking at the next sequence in the last post.

Lee

LeeG · May 19, 2011

eximer168

All the tests I run against the IO Linc relay show a -2 error if the IO Linc does not respond with an ACK. This is logged using the Sensor node in the Log but it is always there. Of course if the IO Linc ACKs the On command the -2 entry is not logged.

IO Linc1-Relay On Thu 2011/05/19 02:44:02 PM Web Log

IO Linc1-Sensor Thu 2011/05/19 02:44:06 PM System -2

The next time the IO Linc relay does not activate the cat-feeder I think the Log will differentiate whether the IO Linc ACKed the On command. If it did but the cat-feeder did not cycle the IO Linc and associated equipment is the place to look. If the -2 entry against the Sensor node is in the Log then the IO Linc did not receive the On command. I donâ€™t think it can be a failure of the ISY to receive the ACK as the IO Linc relay would drive the cat-feeder regardless.

Lee

LeeG · May 19, 2011

eximer168

Iâ€™ve done more testing and analysis of the RemoteLinc multiple button press trace. The RemoteLinc button press events cover a 4 second period which is not consistent with pressing the button 3 times in less than 1 second. Is there any chance this trace is not related to the test where the RemoteLinc button was pressed 3 times in under 1 second.

The Group Broadcast message is what triggers a Program with an If Control. Because the RemoteLinc generates so many Group Broadcast messages a problem with the ISY recognizing the multiple Group Broadcast messages as duplicates could theoretically cause a Program to be triggered more times than the button is pressed. There is no indication this happened as the trace is labeling the extra Group Broadcast messages a Duplicates and Process Message: failed. Also I would have thought I would be able to reproduce the situation. I am running 3.1.2.

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Wed 05/18/2011 09:23:59 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Wed 05/18/2011 09:24:01 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C7 13 00 LTOFFRR(00)

Wed 05/18/2011 09:24:02 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 4B 13 03 LTOFFRR(03)

Wed 05/18/2011 09:24:03 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C7 13 00 LTOFFRR(00)

Lee

eximer168 · May 20, 2011

eximer168

Iâ€™ve done more testing and analysis of the RemoteLinc multiple button press trace. The RemoteLinc button press events cover a 4 second period which is not consistent with pressing the button 3 times in less than 1 second. Is there any chance this trace is not related to the test where the RemoteLinc button was pressed 3 times in under 1 second.

The Group Broadcast message is what triggers a Program with an If Control. Because the RemoteLinc generates so many Group Broadcast messages a problem with the ISY recognizing the multiple Group Broadcast messages as duplicates could theoretically cause a Program to be triggered more times than the button is pressed.

The image is 3.1.2, I believe the query does not occur because the 'condition' of the 'if' is no longer valid and the clause ceases to execute.

The trace was from the time period in question, must have been closer to 4 seconds than 1. And there were 3 presses that showed up as 4 commands (edit problems with the forum post). There is something statistical about it; the duplicate message could just has easily occurred on the first press -- which it often does.

I don't disagree that it shouldn't happen, but the fact that it does, leads me to believe there is an unhandled/under-handled race condition or cascade-failure somewhere in the protocol or system-system message handling. I plan to deactivate all access-points except the one relevant to the test, and then slowly re-integrate access points to see if:

1) the symptom disappears when there is only one access point (actually 2, since the PLM counts as one).

2) does the problem reappear with network complexity, if so, does the nature of the failure change in a way which makes it more apparent/debuggable.

Q: Was there any indication as to why the closet scene does not turn on reliably unless issued twice? Doing so increases the success from ~60% to >95%

LeeG · May 20, 2011

eximer168

The Event Viewer is expected to show 3 Group Broadcast messages and 1 Group Cleanup message when a RemoteLinc button is pressed without generating multiple button presses. The first Group Cleanup message triggers the Program with an If Control. The remaining 2 Group Broadcast messages and the 1 Group Cleanup message should not cause a Program to be triggered. A simple 1 button press should show a single Program trigger even though multiple Group Broadcast messages are received for that single button press.

I initially added the Queries to my test Program and see the Query activity in the Event Trace. That is why I mentioned it. Eventually deleted the Queries thinking maybe it made a difference but it did not change the overall result.

The Closet scenario involves a Set Scene as the Action statement. A Set Scene can be less reliable when there powerline issues because the Scene command is not ACKed by any of the Responders so the ISY does not know if the Responder(s) actually reacted. The change in Responder device Status is what should have happened based on the Scene definition rather than a positive ACK from each Responder.

There is no indication in the Event Viewer trace whether the Set Scene was received by the Responder(s). The trace entries showing resultant device status is what should have happened rather than a knowledge that it did happen.

Lee

LeeG · May 20, 2011

eximer168

An alternative to the current Closet arrangement would be to make the motion sensor and the TriggerLinc controllers of the closet scene. As with the RemoteLinc, the motion sensor and TriggerLinc also send multiple Group Broadcast messages to the linked Responders. This would provide several Group Broadcast commands from both devices which would improve reliability. The motion sensor would probably have to be in On Only mode so it did not turn off the closet light if motion stopped. The TriggerLinc Off would be okay if someone closed the closet door.

Should also see what else electrically is on the closet circuit. Could be some specific appliance that is reducing the reliability of that particular circuit.

Lee

LeeG · May 20, 2011

eximer168

Can you post the actual Program that is being triggered by the RemoteLinc button press. I keep going over this and with the number of tests Iâ€™ve run here that do not reproduce the results you are seeing, Iâ€™m thinking there is another variable involved that I do not have in my test Program.

Lee

eximer168 · May 20, 2011

eximer168

An alternative to the current Closet arrangement would be to make the motion sensor and the TriggerLinc controllers of the closet scene. As with the RemoteLinc, the motion sensor and TriggerLinc also send multiple Group Broadcast messages to the linked Responders. This would provide several Group Broadcast commands from both devices which would improve reliability. The motion sensor would probably have to be in On Only mode so it did not turn off the closet light if motion stopped. The TriggerLinc Off would be okay if someone closed the closet door.

Should also see what else electrically is on the closet circuit. Could be some specific appliance that is reducing the reliability of that particular circuit.

Lee

I considered having them as controllers, the problem is that you don't want to have either triggerlinc or remote go on automatically at certain times of day, like night, when people are sleeping, or if so, it comes only only at a dimmed level. This is the True power of the ISY the ability to do that.

The second drawback to making it a scene controller is that if you ever have to restore or update the links, you need to be physically present to push the button on the battery operated device. This becomes problematic when you have more than 10-20 of these devices all over the house -- some in very inconvenient places to get to. Again, it is the True power of the ISY to be able to handle the generic device and make it more powerful and reliable.

Whole house power is clean w/all LED interior lights, new wiring, and Eaton two-tier whole house surge suppression (T1/meter-input and T2/breaker mains). The entire system currently consists of 110 devices 59 scenes and 102 programs which detect and react to house activity without the need for switches, so, yes, there's a lot of activity going on in the background.

What you've related w/re to scenes makes sense, to which again, I have to credit the ISY for giving me the power to take a 'kind-of' reliable operation (scene on, 1sigma) and make it more powerful with the logic (2sigma) which if you double the scene-on command w/query follow-up gets you in to 3&4 sigma reliability -- stuff you can depend on!

TJF1960 · May 20, 2011

I considered having them as controllers, the problem is that you don't want to have either triggerlinc or remote go on automatically at certain times of day, like night, when people are sleeping, or if so, it comes only only at a dimmed level.

For what its worth it is possible to use a triggerlinc or ms as a controller of a scene (linked to and controller of a SL relay or dimmer) and use the adjust scene in programs to control the on levels and ramp rate when turned on by the ms or triggerlinc. Similar to using adjust scene in programs for local control.

Tim

eximer168 · May 20, 2011

eximer168

Can you post the actual Program that is being triggered by the RemoteLinc button press. I keep going over this and with the number of tests Iâ€™ve run here that do not reproduce the results you are seeing, Iâ€™m thinking there is another variable involved that I do not have in my test Program.

Lee

Program is as follows; additional data-captures of the DOF3 and Cat-Feeder are included after the program.

If

Control '1908 / HH Remote 1.A / HH Remote 1.C' is switched Off

Or Control '1908 / HH Remote 2.A / HH Remote 2.C' is switched Off

Or Control '1908 / HH Remote 3.A / HH Remote 3.C' is switched Off

Or Control '1908 / HH Remote 4.A / HH Remote 4.C' is switched Off

Or Control '1908 / HH Remote 5.A / HH Remote 5.C' is switched Off

Or Control '1908 / HH Remote 6.A / HH Remote 6.C' is switched Off

Or Control '1908 / HH Remote 7.A / HH Remote 7.C' is switched Off

Then

Wait 1 second

Set 'AP Music Family' Fast Off

Set 'AP Music Kitchen' Fast Off

Set 'AP Music Dining' Fast Off

Set 'AP Music Office' Fast Off

Wait 1 second

Set 'AP Music Family' Fast Off

Set 'AP Music Kitchen' Fast Off

Set 'AP Music Dining' Fast Off

Set 'AP Music Office' Fast Off

Else

- No Actions - (To add one, press 'Action')

I've removed all the access points and used the PLM as the sole access point to the Remote Linc, and the same symptom appeared with both remotelincs#6 and #7 (actually happens with all of them).

One thing I did notice, is the java banner comes up asking not to change anything with the system, this did not occur at the point where the DOF 3 happened, but did occur during the instance where the cat-feeder failed to activate (capture below first data-listing).

Q: What does this message mean? I've had the feeder-failure and phantom key occur even without the java browser active.

This is the log from 9 C-key presses, not particularly quickly, something you can expect from a spouse or family member; DOF 3 occurred between press 8 and 9 in this case.

INSTEON Device Control Value Time

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:28:45 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:28:45 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:28:45 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:28:45 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:28:52 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:28:52 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:28:52 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:28:52 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:28:53 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:28:53 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:28:53 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:28:53 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:28:56 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:28:56 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:28:56 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:28:56 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:28:57 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:28:57 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:28:57 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:28:57 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:02 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:02 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:02 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:02 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:03 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:03 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:03 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:03 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:08 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:08 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:08 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:08 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:09 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:09 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:09 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:09 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:15 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:15 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:15 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:15 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:16 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:16 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:16 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:16 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:20 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:20 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:20 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:20 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:21 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:21 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:21 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:21 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:22 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:22 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:22 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:22 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:23 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:23 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:23 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:23 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:27 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:27 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:27 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:27 PM

1908 / AP Music Family Fast Off 0 Fri 2011/05/20 12:29:28 PM

1908 / AP Music Kitchen Fast Off 0 Fri 2011/05/20 12:29:28 PM

1908 / AP Music Dining Fast Off 0 Fri 2011/05/20 12:29:28 PM

1908 / AP Music Office Fast Off 0 Fri 2011/05/20 12:29:28 PM

Fri 05/20/2011 12:28:43 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:28:43 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:43 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:28:43 PM : CLI-WBug: Connecting to datafeed.weatherbug.com

Fri 05/20/2011 12:28:43 PM : CLI-WBug: Successfully Processed WBug Response

Fri 05/20/2011 12:28:43 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Fri 05/20/2011 12:28:43 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Fri 05/20/2011 12:28:44 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:44 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:44 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:28:44 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:28:44 PM : [ 16 D7 2E 1] ST 0

Fri 05/20/2011 12:28:44 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:45 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:45 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:46 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:28:46 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:28:46 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:51 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:28:51 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:51 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:28:51 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:28:51 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:51 PM : Duplicate: ignored

Fri 05/20/2011 12:28:51 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Fri 05/20/2011 12:28:51 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:51 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Fri 05/20/2011 12:28:51 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Fri 05/20/2011 12:28:52 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:52 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Process ACK: duplicate ignored

Fri 05/20/2011 12:28:52 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:52 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:53 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:53 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:53 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:28:53 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:28:54 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:54 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:55 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:28:55 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:55 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:28:55 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:55 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:56 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 4B 13 03 LTOFFRR(03)

Fri 05/20/2011 12:28:56 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:28:57 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:57 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:58 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:28:58 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:28:58 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:58 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:28:59 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:28:59 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:01 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:01 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:01 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:29:01 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:01 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Fri 05/20/2011 12:29:02 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:02 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:03 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:03 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:05 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:05 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Process ACK: duplicate ignored

Fri 05/20/2011 12:29:06 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:06 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:07 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:07 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:07 PM : Duplicate: ignored

Fri 05/20/2011 12:29:07 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00): Process Message: failed

Fri 05/20/2011 12:29:07 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:07 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:07 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=1

Fri 05/20/2011 12:29:07 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:29:07 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:07 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:07 PM : Duplicate: ignored

Fri 05/20/2011 12:29:07 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Fri 05/20/2011 12:29:07 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:08 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:08 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Fri 05/20/2011 12:29:08 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:08 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:09 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:09 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:11 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:11 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Process ACK: duplicate ignored

Fri 05/20/2011 12:29:11 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:11 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:11 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:11 PM : [iNST-ACK ] 02 62 14.FE.88 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:12 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:12 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:12 PM : Duplicate: ignored

Fri 05/20/2011 12:29:12 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00): Process Message: failed

Fri 05/20/2011 12:29:12 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:14 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:14 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:14 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:29:15 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 41 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:15 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=1, Hops Left=0

Fri 05/20/2011 12:29:15 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:15 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:16 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:16 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:18 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:18 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Process ACK: duplicate ignored

Fri 05/20/2011 12:29:19 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:19 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:19 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:29:19 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 07 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:19 PM : [standard-Direct][14.20.EC-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 12:29:19 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:19 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:20 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 07 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:20 PM : [standard-Direct][14.20.EC-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 12:29:20 PM : Duplicate: ignored

Fri 05/20/2011 12:29:20 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 07 13 03 LTOFFRR(03): Process Message: failed

Fri 05/20/2011 12:29:20 PM : [standard-Direct][14.20.EC-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 12:29:20 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:20 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:20 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:20 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:22 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 C3 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:22 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:22 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:22 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:22 PM : [ 14 20 EC 3] DOF 0

Fri 05/20/2011 12:29:22 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:22 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:22 PM : Duplicate: ignored

Fri 05/20/2011 12:29:22 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Fri 05/20/2011 12:29:22 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:22 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:22 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:23 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:23 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:23 PM : Duplicate: ignored

Fri 05/20/2011 12:29:23 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00): Process Message: failed

Fri 05/20/2011 12:29:23 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:24 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:24 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Process ACK: duplicate ignored

Fri 05/20/2011 12:29:24 PM : [iNST-SRX ] 02 50 16.D7.2E 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:24 PM : [standard-Direct Ack][16.D7.2E-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:24 PM : [iNST-ACK ] 02 62 16.D5.99 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:24 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 07 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:24 PM : [standard-Direct][14.20.EC-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 12:29:25 PM : [iNST-SRX ] 02 50 16.D5.99 14.85.AF 23 14 00 LTOFF-F(00)

Fri 05/20/2011 12:29:25 PM : [standard-Direct Ack][16.D5.99-->ISY/PLM Group=0] Max Hops=3, Hops Left=0

Fri 05/20/2011 12:29:25 PM : [iNST-ACK ] 02 62 16.D8.B3 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:26 PM : [iNST-SRX ] 02 50 14.20.EC 14.85.AF 4B 13 03 LTOFFRR(03)

Fri 05/20/2011 12:29:26 PM : [standard-Cleanup][14.20.EC-->ISY/PLM Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:26 PM : [ 14 20 EC 1] DOF 3

Fri 05/20/2011 12:29:26 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00)

Fri 05/20/2011 12:29:26 PM : [iNST-ACK ] 02 62 16.D7.2E 0F 14 00 06 LTOFF-F(00): Received an ACK for a different device

Fri 05/20/2011 12:29:26 PM : [iNST-SRX ] 02 50 14.20.EC 00.00.03 CB 13 00 LTOFFRR(00)

Fri 05/20/2011 12:29:26 PM : [standard-Group][14.20.EC-->Group=3] Max Hops=3, Hops Left=2

Fri 05/20/2011 12:29:26 PM : [ 14 20 EC 3] DOF 0

The Cat Feeder problem is more mysterious, here we have a log which says it occured, yet, the device did not activate. This was a case also where I saw the java-banner asking not to change anything (which wouldn't be apparent unless you had a browser open, prior failures had no browser open).

You can see the cat-feeder activate at 11:45:00 and what should be a query nominally 10 seconds later occur at 11:46:04, but there was no device activation. To busy up the bus, I turned a light switch on/off every second or so as two programs activated (one to turn off a series of appliance modules), the second to feed cats.

Q: Is it possible it is overshooting schedule grace period? if so, why is it logging device active? Is there anything below that gives better detail on what is going on?

If

Time is 2:00:00AM

Or Time is 6:00:00AM

Or Time is 10:00:00AM

Or Time is 6:00:00PM

Or Time is 11:45:00AM

Then

Set '1908 / Cat Feeder Trigger' On

Set '1908 / Cat Bell-Relay' On

Wait 10 seconds

Set '1908 / Cat Feeder Trigger' Query

Set '1908 / Cat Bell-Relay' Query

Else

- No Actions - (To add one, press 'Action')

INSTEON Device Control Value Time

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:35 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:35 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:35 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:37 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:37 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:37 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:38 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:38 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:38 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:40 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:40 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:40 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:42 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:42 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:42 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:44 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:44 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:44 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:46 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:46 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:46 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:48 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:48 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:48 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:49 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:49 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:49 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:51 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:51 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:51 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:52 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:52 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:52 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:54 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:54 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:54 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:56 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:56 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:56 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:44:58 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:44:58 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:44:58 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:44:59 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:44:59 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:44:59 AM

Scene:1908 / Ambient Off 0 Fri 2011/05/20 11:45:00 AM

1908 / Cat Feeder Trigger On 255 Fri 2011/05/20 11:45:00 AM

1908 / Cat Bell-Relay On 255 Fri 2011/05/20 11:45:00 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:04 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:04 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:04 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:06 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:06 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:06 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:08 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:08 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:08 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:10 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:10 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:10 AM

Scene:1908 / Ambient Status Query Fri 2011/05/20 11:45:10 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:11 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:11 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:11 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:13 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:13 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:13 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:15 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:15 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:15 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:16 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:16 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:16 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:18 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:18 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:18 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:19 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:19 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:19 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:21 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:21 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:21 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:23 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:23 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:23 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:25 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:25 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:25 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:26 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:26 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:26 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:28 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:28 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:28 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:30 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:30 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:30 AM

1908 / TC Downstairs.Main Humidity 54% Fri 2011/05/20 11:45:30 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:31 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:31 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:31 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:33 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:33 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:33 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:35 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:35 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:35 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:37 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:37 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:37 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:38 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:38 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:38 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:41 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:41 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:41 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:42 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:42 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:42 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:43 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:43 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:43 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:45 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:45 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:45 AM

1908 / AP Blue Room Light Status 100% Fri 2011/05/20 11:45:46 AM

1908 / ES Blueroom SE Status 100% Fri 2011/05/20 11:45:46 AM

1908 / SW Blue Room Status 100% Fri 2011/05/20 11:45:46 AM

1908 / SW Blue Room Fri 2011/05/20 11:45:46 AM

1908 / AP Blue Room Light Status 0% Fri 2011/05/20 11:45:48 AM

1908 / ES Blueroom SE Status 0% Fri 2011/05/20 11:45:48 AM

1908 / SW Blue Room Status 0% Fri 2011/05/20 11:45:48 AM

1908 / Cat Feeder Trigger Status Query Fri 2011/05/20 11:46:04 AM

1908 / Cat Bell-Relay Status Query Fri 2011/05/20 11:46:04 AM

Scene:1908 / Greenwall Fast Off 0 Fri 2011/05/20 11:46:04 AM

Scene:1908 / Greenwall Status Query Fri 2011/05/20 11:46:04 AM

1908 / ES Greenwall Status 0% Fri 2011/05/20 11:46:05 AM

Fri 05/20/2011 11:44:35 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:35 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:35 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:35 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:35 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:35 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:35 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:35 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:35 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:35 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:35 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:36 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:36 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:36 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:36 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:36 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:36 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:36 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:37 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:37 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:37 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:37 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:38 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:38 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:38 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:38 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:38 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:38 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:38 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:38 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:38 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:39 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:39 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:39 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:39 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:39 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:39 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01)

Fri 05/20/2011 11:44:39 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:39 AM : Duplicate: ignored

Fri 05/20/2011 11:44:39 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01): Process Message: failed

Fri 05/20/2011 11:44:39 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:39 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01)

Fri 05/20/2011 11:44:39 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:39 AM : Duplicate: ignored

Fri 05/20/2011 11:44:40 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01): Process Message: failed

Fri 05/20/2011 11:44:40 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:40 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:40 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:44:40 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:40 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:40 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:40 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:40 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:40 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:40 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:41 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:41 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:41 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:41 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:41 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:44:41 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:41 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:41 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:41 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:42 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:42 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:42 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:42 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:42 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:42 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:42 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:42 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:42 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:42 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:42 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:42 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:42 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:42 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:42 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:43 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:43 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:43 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:43 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:44:43 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:43 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:43 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:43 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:43 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:43 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:45 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:45 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:45 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:45 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:45 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:45 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:47 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:47 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:47 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:47 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:47 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:47 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:48 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:48 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:48 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:48 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:48 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:48 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:48 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:48 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:48 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:49 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:49 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:49 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:49 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:49 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:49 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:49 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:49 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:49 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:49 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 11 00 LTONRR (00)

Fri 05/20/2011 11:44:49 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:44:49 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:49 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:49 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:49 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:50 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:50 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:50 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:50 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:50 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:50 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:50 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:50 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:51 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:51 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:51 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:51 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:51 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:51 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:52 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:52 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:52 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:52 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:52 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:52 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:52 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:52 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:52 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:52 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:52 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:54 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:54 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:54 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:54 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:54 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:54 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:54 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:54 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:54 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:54 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:54 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:54 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:54 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:44:54 AM : Duplicate: ignored

Fri 05/20/2011 11:44:54 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 13 01 LTOFFRR(01): Process Message: failed

Fri 05/20/2011 11:44:54 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:44:56 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:56 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:56 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:56 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:56 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:56 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:56 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:56 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:56 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:56 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:56 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:56 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:56 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:56 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:58 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:58 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:58 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:58 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:44:58 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:44:58 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:44:58 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:44:58 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:58 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:44:58 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:58 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:44:58 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 13 01 LTOFFRR(01)

Fri 05/20/2011 11:44:58 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:44:58 AM : Duplicate: ignored

Fri 05/20/2011 11:44:58 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 13 01 LTOFFRR(01): Process Message: failed

Fri 05/20/2011 11:44:58 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:44:59 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:59 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:59 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:59 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:44:59 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:44:59 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:44:59 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:44:59 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:44:59 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:44:59 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:44:59 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:00 AM : [ Time] 11:45:00 0(0)

Fri 05/20/2011 11:45:00 AM : [iNST-ACK ] 02 62 00.00.10 CF 13 00 06 LTOFFRR(00)

Fri 05/20/2011 11:45:01 AM : [iNST-ACK ] 02 62 00.00.10 CF 13 00 06 LTOFFRR(00)

Fri 05/20/2011 11:45:01 AM : [iNST-ACK ] 02 62 15.E5.18 0F 11 02 06 LTONRR (02)

Fri 05/20/2011 11:45:02 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 11 00 LTONRR (00)

Fri 05/20/2011 11:45:02 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:45:02 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:02 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:02 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:02 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:03 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:03 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:03 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:03 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:03 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:03 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:03 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:03 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:03 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:04 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:04 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:04 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:45:04 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:45:04 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:45:04 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:45:04 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:04 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:06 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:06 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:06 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:06 AM : [ 14 FC 24 1] ST 255

Fri 05/20/2011 11:45:06 AM : [ 15 94 C9 1] ST 255

Fri 05/20/2011 11:45:06 AM : [ 16 51 78 1] ST 255

Fri 05/20/2011 11:45:06 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:06 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:06 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:06 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:06 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:06 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:06 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:06 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:08 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:08 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:08 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:45:08 AM : [ 14 FC 24 1] ST 0

Fri 05/20/2011 11:45:08 AM : [ 15 94 C9 1] ST 0

Fri 05/20/2011 11:45:08 AM : [ 16 51 78 1] ST 0

Fri 05/20/2011 11:45:08 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:08 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:45:08 AM : [ 16 51 78 1] DOF 0

Fri 05/20/2011 11:45:09 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:09 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:09 AM : [ 16 51 78 1] DON 0

Fri 05/20/2011 11:45:09 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:09 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:09 AM : [iNST-ACK ] 02 62 14.FE.88 0F 19 00 06 LTSREQ (LIGHT)

Fri 05/20/2011 11:45:10 AM : [iNST-SRX ] 02 50 14.FE.88 14.85.AF 27 00 00 (00)

Fri 05/20/2011 11:45:10 AM : [standard-Direct Ack][14.FE.88-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:10 AM : [iNST-ACK ] 02 62 16.D7.2E 0F 19 00 06 LTSREQ (LIGHT)

Fri 05/20/2011 11:45:10 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 07 11 01 LTONRR (01)

Fri 05/20/2011 11:45:10 AM : [standard-Direct][16.51.78-->ISY/PLM Group=0] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:11 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:11 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:11 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:11 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:11 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:11 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:12 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:12 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:12 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:12 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:14 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:14 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:14 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:14 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:15 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 22 EC C1 (C1)

Fri 05/20/2011 11:45:15 AM : [standard-Direct Ack][16.51.78-->ISY/PLM Group=0] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:45:16 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:16 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:17 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:17 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:18 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:18 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:18 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:18 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:18 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:18 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:19 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:19 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:19 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 11 00 LTONRR (00)

Fri 05/20/2011 11:45:19 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:45:19 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:19 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:19 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 22 EE C1 (C1)

Fri 05/20/2011 11:45:19 AM : [standard-Direct Ack][16.51.78-->ISY/PLM Group=0] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:45:19 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01)

Fri 05/20/2011 11:45:19 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:21 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:21 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:21 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:21 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:22 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:22 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:22 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:22 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:22 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:22 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:22 AM : Duplicate: ignored

Fri 05/20/2011 11:45:22 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 13 01 LTOFFRR(01): Process Message: failed

Fri 05/20/2011 11:45:22 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:23 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:23 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:23 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:23 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:23 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:23 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:23 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:23 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:24 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:24 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:24 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:24 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:24 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 11 01 LTONRR (01)

Fri 05/20/2011 11:45:24 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:45:24 AM : Duplicate: ignored

Fri 05/20/2011 11:45:24 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 42 11 01 LTONRR (01): Process Message: failed

Fri 05/20/2011 11:45:24 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=2, Hops Left=0

Fri 05/20/2011 11:45:24 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:24 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:25 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:25 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:26 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:26 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:26 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:26 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:26 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:26 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:26 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:26 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:27 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:27 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:27 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:27 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:27 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01)

Fri 05/20/2011 11:45:27 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:27 AM : Duplicate: ignored

Fri 05/20/2011 11:45:28 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 47 11 01 LTONRR (01): Process Message: failed

Fri 05/20/2011 11:45:28 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:28 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:28 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:28 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:28 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:29 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:29 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:29 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:29 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:29 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C3 11 00 LTONRR (00)

Fri 05/20/2011 11:45:29 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=0

Fri 05/20/2011 11:45:29 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:29 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:30 AM : [iNST-SRX ] 02 50 14.06.24 14.85.AF 26 6F 36 (36)

Fri 05/20/2011 11:45:30 AM : [standard-Direct Ack][14.06.24-->ISY/PLM Group=0] Max Hops=2, Hops Left=1

Fri 05/20/2011 11:45:30 AM : [iNST-SRX ] 02 50 14.06.24 14.85.AF 2B 6F 36 (36)

Fri 05/20/2011 11:45:30 AM : [standard-Direct Ack][14.06.24-->ISY/PLM Group=0] Max Hops=3, Hops Left=2

Fri 05/20/2011 11:45:30 AM : Duplicate: ignored

Fri 05/20/2011 11:45:30 AM : [iNST-SRX ] 02 50 14.06.24 14.85.AF 2B 6F 36 (36): Process Message: failed

Fri 05/20/2011 11:45:30 AM : [standard-Direct Ack][14.06.24-->ISY/PLM Group=0] Max Hops=3, Hops Left=2

Fri 05/20/2011 11:45:31 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:31 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:31 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:31 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:31 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 13 00 LTOFFRR(00)

Fri 05/20/2011 11:45:31 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:31 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 13 01 LTOFFRR(01)

Fri 05/20/2011 11:45:31 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:33 AM : [iNST-SRX ] 02 50 16.51.78 00.00.01 C7 11 00 LTONRR (00)

Fri 05/20/2011 11:45:33 AM : [standard-Group][16.51.78-->Group=1] Max Hops=3, Hops Left=1

Fri 05/20/2011 11:45:33 AM : [iNST-SRX ] 02 50 16.51.78 14.85.AF 41 11 01 LTONRR (01)

Fri 05/20/2011 11:45:33 AM : [standard-Cleanup][16.51.78-->ISY/PLM Group=1] Max Hops=1, Hops Left=0

Fri 05/20/2011 11:45:35 AM : [iNST-

eximer168 · May 20, 2011

eximer168

Can you post the actual Program that is being triggered by the RemoteLinc button press. I keep going over this and with the number of tests Iâ€™ve run here that do not reproduce the results you are seeing, Iâ€™m thinking there is another variable involved that I do not have in my test Program.

Lee

Program is as follows; additional data-captures of the DOF3 and Cat-Feeder are included after the program.

Looks like I hit a message size limit, just when the listing started to get interesting!

Cat-feeder listing: