Help Deciphering Level 3

[zerop]

I have a scene containing 2 light switches as a 3-way (both switches are controllers in the scene).  Every so often the lights haven’t been turning off when a program has sent a “turn scene off” command.

 

Below is the level 3 of turning the scene on and then off manually through the ISY admin console.  Also below is the level 3 of turning the individual lights on and then off manually in the ISY admin console.  Could someone help me figure out what all this means as the wiki doesn't have much detail?

 

Also, why does it seem like the communication is good one time (hops 3, hops remaining 2) and then just a couple seconds later not good (hops 3, hops remaining 0)?

 

 

 

Turning the lights on then off via the scene.

What is this 1st line?
Fri 08/28/2015 06:34:53 AM : [INST-TX-I1  ] 02 62 00 00 14 CF 11 00

What is 00.00.14?  It's not a device.  LTONRR (00) is the on command in some form correct?  What does the LT and RR mean?
Fri 08/28/2015 06:34:53 AM : [INST-ACK    ] 02 62 00.00.14 CF 11 00 06          LTONRR (00)

30 B5 99 is the device, ST means status and 255 means on all the way (100%)
Fri 08/28/2015 06:34:53 AM : [D2D EVENT   ] Event [30 B5 99 1] [ST] [255] uom=0 prec=-1

This means it was actually turned on, correct?
Fri 08/28/2015 06:34:53 AM : [  30 B5 99 1]       ST 255

Fri 08/28/2015 06:34:54 AM : [INST-TX-I1  ] 02 62 00 00 2F CF 11 00
Fri 08/28/2015 06:34:55 AM : [INST-ACK    ] 02 62 00.00.2F CF 11 00 06          LTONRR (00)
Fri 08/28/2015 06:34:55 AM : [D2D EVENT   ] Event [30 BD BB 1] [ST] [255] uom=0 prec=-1
Fri 08/28/2015 06:34:55 AM : [  30 BD BB 1]       ST 255
Fri 08/28/2015 06:34:55 AM : [        Time] 06:34:44 3(0)
Fri 08/28/2015 06:34:55 AM : [D2D EVENT   ] Event [30 E7 1D 6] [ST] [255] uom=0 prec=-1
Fri 08/28/2015 06:34:55 AM : [  30 E7 1D 6]       ST 255
Fri 08/28/2015 06:34:55 AM : [        Time] 06:34:45 3(0)
Fri 08/28/2015 06:35:09 AM : [INST-TX-I1  ] 02 62 00 00 14 CF 13 00

This is the off command.
Fri 08/28/2015 06:35:10 AM : [INST-ACK    ] 02 62 00.00.14 CF 13 00 06          LTOFFRR(00)
Fri 08/28/2015 06:35:11 AM : [D2D EVENT   ] Event [30 B5 99 1] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [  30 B5 99 1]       ST   0
Fri 08/28/2015 06:35:11 AM : [D2D EVENT   ] Event [30 BD BB 1] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [  30 BD BB 1]       ST   0
Fri 08/28/2015 06:35:11 AM : [INST-TX-I1  ] 02 62 00 00 2F CF 13 00
Fri 08/28/2015 06:35:11 AM : [INST-ACK    ] 02 62 00.00.2F CF 13 00 06          LTOFFRR(00)
Fri 08/28/2015 06:35:11 AM : [D2D EVENT   ] Event [30 E7 1D 6] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [  30 E7 1D 6]       ST   0

Why do these time stamps show up sometimes but not others?
Fri 08/28/2015 06:35:11 AM : [        Time] 06:35:00 3(0)
Fri 08/28/2015 06:35:11 AM : [        Time] 06:35:01 3(0)

What is 02 50 20.48.24? 2F.B8.B2 is my PLM and what is 01 6E A5?
Fri 08/28/2015 06:35:12 AM : [INST-SRX    ] 02 50 20.48.24 2F.B8.B2 01 6E A5           (A5)

Why are the numbers of hops only shown once when turning on/off a scene rather than for each device?
Fri 08/28/2015 06:35:12 AM : [Std-Direct  ] 20.48.24-->ISY/PLM Group=0, Max Hops=1, Hops Left=0

What previous message was ignored and why?
Fri 08/28/2015 06:35:12 AM : [INST-DUP    ] Previous message ignored.

Turning the lights on then off individually

Fri 08/28/2015 06:41:40 AM : [INST-TX-I1  ] 02 62 30 BD BB 0F 11 FF
Fri 08/28/2015 06:41:40 AM : [INST-ACK    ] 02 62 30.BD.BB 0F 11 FF 06          LTONRR (FF)
Fri 08/28/2015 06:41:41 AM : [INST-SRX    ] 02 50 30.BD.BB 2F.B8.B2 2B 11 FF    LTONRR (FF)
Fri 08/28/2015 06:41:41 AM : [Std-Direct Ack] 30.BD.BB-->ISY/PLM Group=0, Max Hops=3, Hops Left=2
Fri 08/28/2015 06:41:41 AM : [D2D EVENT   ] Event [30 BD BB 1] [ST] [255] uom=0 prec=-1
Fri 08/28/2015 06:41:41 AM : [  30 BD BB 1]       ST 255
Fri 08/28/2015 06:41:41 AM : [INST-TX-I1  ] 02 62 00 00 2F CF 11 00
Fri 08/28/2015 06:41:42 AM : [INST-ACK    ] 02 62 00.00.2F CF 11 00 06          LTONRR (00)
Fri 08/28/2015 06:41:42 AM : [        Time] 06:41:27 3(0)
Fri 08/28/2015 06:41:42 AM : [D2D EVENT   ] Event [30 E7 1D 6] [ST] [255] uom=0 prec=-1
Fri 08/28/2015 06:41:42 AM : [  30 E7 1D 6]       ST 255
Fri 08/28/2015 06:41:42 AM : [        Time] 06:41:28 3(0)
Fri 08/28/2015 06:41:45 AM : [INST-TX-I1  ] 02 62 30 B5 99 0F 11 FF
Fri 08/28/2015 06:41:45 AM : [INST-ACK    ] 02 62 30.B5.99 0F 11 FF 06          LTONRR (FF)
Fri 08/28/2015 06:41:46 AM : [INST-SRX    ] 02 50 30.B5.99 2F.B8.B2 2B 11 FF    LTONRR (FF)
Fri 08/28/2015 06:41:46 AM : [Std-Direct Ack] 30.B5.99-->ISY/PLM Group=0, Max Hops=3, Hops Left=2
Fri 08/28/2015 06:41:46 AM : [D2D EVENT   ] Event [30 B5 99 1] [ST] [255] uom=0 prec=-1
Fri 08/28/2015 06:41:46 AM : [  30 B5 99 1]       ST 255
Fri 08/28/2015 06:41:53 AM : [INST-TX-I1  ] 02 62 30 BD BB 0F 13 00
Fri 08/28/2015 06:41:53 AM : [INST-ACK    ] 02 62 30.BD.BB 0F 13 00 06          LTOFFRR(00)
Fri 08/28/2015 06:41:54 AM : [INST-SRX    ] 02 50 30.BD.BB 2F.B8.B2 2B 13 00    LTOFFRR(00)
Fri 08/28/2015 06:41:54 AM : [Std-Direct Ack] 30.BD.BB-->ISY/PLM Group=0, Max Hops=3, Hops Left=2
Fri 08/28/2015 06:41:54 AM : [D2D EVENT   ] Event [30 BD BB 1] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:41:54 AM : [  30 BD BB 1]       ST   0
Fri 08/28/2015 06:41:54 AM : [INST-SRX    ] 02 50 30.BD.BB 2F.B8.B2 23 13 00    LTOFFRR(00)

This I presume is where my problems are?  Why 0 hops left when just a second ago I got 2 left (see above)
Fri 08/28/2015 06:41:54 AM : [Std-Direct Ack] 30.BD.BB-->ISY/PLM Group=0, Max Hops=3, Hops Left=0

What previous message was ignored and why?
Fri 08/28/2015 06:41:54 AM : [INST-DUP    ] Previous message ignored.
Fri 08/28/2015 06:41:58 AM : [INST-TX-I1  ] 02 62 30 B5 99 0F 13 00
Fri 08/28/2015 06:41:58 AM : [INST-ACK    ] 02 62 30.B5.99 0F 13 00 06          LTOFFRR(00)
Fri 08/28/2015 06:41:59 AM : [INST-SRX    ] 02 50 30.B5.99 2F.B8.B2 2B 13 00    LTOFFRR(00)
Fri 08/28/2015 06:41:59 AM : [Std-Direct Ack] 30.B5.99-->ISY/PLM Group=0, Max Hops=3, Hops Left=2
Fri 08/28/2015 06:41:59 AM : [D2D EVENT   ] Event [30 B5 99 1] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:41:59 AM : [  30 B5 99 1]       ST   0
Fri 08/28/2015 06:41:59 AM : [INST-SRX    ] 02 50 30.B5.99 2F.B8.B2 23 13 00    LTOFFRR(00)
Fri 08/28/2015 06:41:59 AM : [Std-Direct Ack] 30.B5.99-->ISY/PLM Group=0, Max Hops=3, Hops Left=0
Fri 08/28/2015 06:41:59 AM : [INST-DUP    ] Previous message ignored.
Fri 08/28/2015 06:41:59 AM : [INST-TX-I1  ] 02 62 00 00 2F CF 13 00
Fri 08/28/2015 06:42:01 AM : [INST-ACK    ] 02 62 00.00.2F CF 13 00 06          LTOFFRR(00)
Fri 08/28/2015 06:42:01 AM : [D2D EVENT   ] Event [30 E7 1D 6] [ST] [0] uom=0 prec=-1
Fri 08/28/2015 06:42:01 AM : [  30 E7 1D 6]       ST   0
Fri 08/28/2015 06:42:01 AM : [        Time] 06:41:45 3(0)
Fri 08/28/2015 06:42:01 AM : [        Time] 06:41:46 3(0)

ISY994i/IR Pro

Firmware 4.2.18

UI 4.2.30

[LeeG]

Little can be learned from the ISY issuing a Scene command as far as what happened from an Insteon device perspective.

 

 

Turning the lights on then off via the scene.

What is this 1st line? - PLM Scene On command.   14 is hex Scene number; hex 11 is On command   

 

Fri 08/28/2015 06:34:53 AM : [INST-TX-I1 ] 02 62 00 00 14 CF 11 00

What is 00.00.14? It's not a device. LTONRR (00) is the on command in some form correct? What does the LT and RR mean?

This is the PLM echoing and ACKing the previous command.  hex 06 indicates command accepted by PLM.  LTONRR (00) is ISY interpretation of message, Light On Response

 

Fri 08/28/2015 06:34:53 AM : [iNST-ACK ] 02 62 00.00.14 CF 11 00 06 LTONRR (00)

30 B5 99 is the device, ST means status and 255 means on all the way (100%) - YES 
Fri 08/28/2015 06:34:53 AM : [D2D EVENT ] Event [30 B5 99 1] [sT] [255] uom=0 prec=-1

This means it was actually turned on, correct? - NO!  it means that is what the Scene should do, Insteon has provided no information about what Scene device(s) actually did. 
Fri 08/28/2015 06:34:53 AM : [ 30 B5 99 1] ST 255

 

The next statement is another ISY Scene On; Scene number hex 2F
Fri 08/28/2015 06:34:54 AM : [iNST-TX-I1 ] 02 62 00 00 2F CF 11 00
Fri 08/28/2015 06:34:55 AM : [iNST-ACK ] 02 62 00.00.2F CF 11 00 06 LTONRR (00)
Fri 08/28/2015 06:34:55 AM : [D2D EVENT ] Event [30 BD BB 1] [sT] [255] uom=0 prec=-1
Fri 08/28/2015 06:34:55 AM : [ 30 BD BB 1] ST 255
Fri 08/28/2015 06:34:55 AM : [ Time] 06:34:44 3(0)
Fri 08/28/2015 06:34:55 AM : [D2D EVENT ] Event [30 E7 1D 6] [sT] [255] uom=0 prec=-1
Fri 08/28/2015 06:34:55 AM : [ 30 E7 1D 6] ST 255
Fri 08/28/2015 06:34:55 AM : [ Time] 06:34:45 3(0)
Fri 08/28/2015 06:35:09 AM : [iNST-TX-I1 ] 02 62 00 00 14 CF 13 00

This is the off command. - Scene Off, Scene number hex 14, 13 is Off command   
Fri 08/28/2015 06:35:10 AM : [iNST-ACK ] 02 62 00.00.14 CF 13 00 06 LTOFFRR(00)
Fri 08/28/2015 06:35:11 AM : [D2D EVENT ] Event [30 B5 99 1] [sT] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [ 30 B5 99 1] ST 0
Fri 08/28/2015 06:35:11 AM : [D2D EVENT ] Event [30 BD BB 1] [sT] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [ 30 BD BB 1] ST 0
Fri 08/28/2015 06:35:11 AM : [iNST-TX-I1 ] 02 62 00 00 2F CF 13 00
Fri 08/28/2015 06:35:11 AM : [iNST-ACK ] 02 62 00.00.2F CF 13 00 06 LTOFFRR(00)
Fri 08/28/2015 06:35:11 AM : [D2D EVENT ] Event [30 E7 1D 6] [sT] [0] uom=0 prec=-1
Fri 08/28/2015 06:35:11 AM : [ 30 E7 1D 6] ST 0

Why do these time stamps show up sometimes but not others?
Fri 08/28/2015 06:35:11 AM : [ Time] 06:35:00 3(0)
Fri 08/28/2015 06:35:11 AM : [ Time] 06:35:01 3(0)

What is 02 50 20.48.24? 2F.B8.B2 is my PLM and what is 01 6E A5? - 02 50, inbound message; 20.48.24 is FROM device address.  01 is Hops information; 6E is command, probably from thermostat, A5 is temp in .5 degree increments
Fri 08/28/2015 06:35:12 AM : [iNST-SRX ] 02 50 20.48.24 2F.B8.B2 01 6E A5 (A5)

Why are the numbers of hops only shown once when turning on/off a scene rather than for each device? -  this message is not likely related to the Scene command
Fri 08/28/2015 06:35:12 AM : [std-Direct ] 20.48.24-->ISY/PLM Group=0, Max Hops=1, Hops Left=0

What previous message was ignored and why? -  the 02 50 was ignored as it did not come from the Scene command action

 

Fri 08/28/2015 06:35:12 AM : [iNST-DUP ] Previous message ignored.

[zerop]

LeeG,

 

Thank you so much for that!!  That's what I was hoping someone would do, go line by line like that.

 

Could you also help me out with the level 3 on where I seem to be having a problem?  The second posting of my level 3 shows hops 3, hops remaining 0.  It looks as if the command on was ok, hops left 2 but the command off wasn't, hops left 0.  Why would this change so drastically so quickly?  Is this a comm problem?  Why does the problem seem to be on the "off" command?  Could there be a reason for this?

[LeeG]

That duplicate response message I believe is the result of the message flowing on both 120v legs. The one with Hops Left=0 arrives so late to the PLM it does not see it as a duplicate and passes it on to the application (ISY in this case). This is seen during the 3AM query and can change the posted state of an I/O Linc during the query sequence. Not sure what type of problem it is creating during device On/Off sequence.

 

Can you expand on what the actual symptom is.

[Michel Kohanim]

Hi zerop,

 

You may also want to try right mouse click on the scene/Diagnostics / Scene Test.

 

With kind regards,

Michel

[zerop]

Hi zerop,

 

You may also want to try right mouse click on the scene/Diagnostics / Scene Test.

 

With kind regards,

Michel

 

Tried the scene test 3 times and it succeeded each time.  How many times should I try it?

[Michel Kohanim]

Hi zerop,

 

In that case, the issue might be time sensitive. Do you know whether or communication issues happen more frequently at certain times?

 

With kind regards,

Michel

[Jimbo.Automates]

This sounds like the IoLinc issue I have seen as well. If the sensor and relay are in different states, occasionally the 3am query will report incorrectly for the sensor. I now make sure they always are the same and I have not seen an issue since.