wierd log entry's

[jaycanter]

I have been trying to find out why once a week i have 1 failure on my scheduled runs. i have been watching the log everyday for my sunset & sunrise times. today i got a couple of x10 lines in there that i have never seen before and i don't have any x10. any ideas where it might have come from?

 

also have some entries i don't know what they are it shows as

 

Tue 01/25/2011 05:25:08 PM : (INST-ACK ) 02 62 00.00.17 CF 13 00 06 Does anybody know what this is cause i don't have any devices those #s

 

I have been printing the logs and going thru them and have found that when the isy tries to contact the brultech ecm there is a pause up to 5 seconds before it gets it's response and if a motion sensor triggers in that time period the isy doesn't register the ms command. just for your info if your having ms problems intermitinly with a ecm.

[Michel Kohanim]

Hello jcanter,

 

INST-ACK means that someone issues a scene command to ISY and this the ACK from the PLM that the command was received and executed.

 

The ECM issue is a new one! Does it interfere with all your RF devices or only the MS?

 

With kind regards,

Michel

[jaycanter]

but what are the digits after that they don't match any addresses i have.and the only rf devices i have are the ms and access points so i don't know if it affects anything else

Jay

[Michel Kohanim]

Hi jcanter,

 

They are group address (scene) 00 00 nn where nn is the INSTEON group number.

 

With kind regards,

Michel

[jaycanter]

so i shouldn't worry about those when i am tracing the event viewer?

[LeeG]

Jcanter

 

As Michel has already indicated that is a Scene (Group) Off for Scene (Group) 0x17 decimal 23. The 00 00 17 is the Scene (Group) number.

 

You can issue the following command in a browser and get a list of the Scenes in the ISY. Look for an entry with a 23. The devices listed below it will be the devices in the Scene. If MS messages are being block during this Scene for 5 seconds it normally indicates one or more of the responders is not responding to the Scene (Group) Off. It takes a few seconds for the Controller to retry the Off command multiple times to a non responding device. A Scene normally completes very quickly.

 

http://192.168.2.2/rest/nodes/scenes

 

Example of a Scene displayed by the above Rest command

 

16833

SceneKPL3Ramp30

59

K07

-

14 71 3C 1

B 4A AD 4

 

I issued the following Rest command

 

http://192.168.2.2/rest/nodes/16833/cmd/DOF

 

which turns Off the above Scene SceneKPL3Ramp30. This Scene has a deviceGroup 59 hex 0x3B which produced the following Event entry

 

Wed 01/26/2011 02:14:10 PM : [iNST-ACK ] 02 62 00.00.3B CF 13 00 06 LTOFFRR(00)

 

By knowing the Scene you should be able to determine what is turning the Scene Off. Scene On/Off activity is normal. Just depends on whether that Scene should be turning Off at that point.

 

Lee

[jaycanter]

thanks i am trying to understand this at a deeper level cause once a week i have 1 set out of three sets of blinds fail to open or close and can't find out why. the ms problem is with the brultech communication when the isy tries to read the brultech it takes several seconds for the response and any ms during that time does not go thru to the isy. i use to have it sent every 5 mins playing with it and have since changed it to 30 mins and the motions became more reliable.

[Michel Kohanim]

jcanter,

 

If the problems are scene related, I strongly recommend doing a scene test on your scenes.

 

The MS/Brultech issue is quite a mystery and it does not make sense UNLESS the Zigbee communications are impacting/interfering with INSTEON which is also quite doubtful.

 

With kind regards,

Michel

[jaycanter]

Sorry i am not real good at explaing problems the blinds are not part of any scenes i just have them as a program. and the brultech isn't wirless the are wired to my router

[Michel Kohanim]

Hello jcanter,

 

Don't you have ECM1240 where the box that is next to the breaker panel communicates with EP-1 which is connected to your router? If that's the case, then they are communicating via Zigbee. I am not sure how Zigbee could interfere with INSTEON RF ... this is the first time I am hearing it.

 

With kind regards,

Michel

[bpwwer]

If you are seeing a pause of 5 seconds between when the ISY starts to query the ECM-1240 and when it gets a response from the ECM-1240, that seems excessive. I'm able to query my ECM-1240 every 2 seconds without a problem. I don't have any MS and my ECM-1240 is hardwired to the EP-1 but even so the EP-1 should respond almost instantly when it's queried for data.

 

Michel, Is it possible that the ISY is blocked while a TCP connection is trying to be established and that the serial buffer for the PCM connection overflows, losing any messages that were sent at that time?

[jaycanter]

i have a EP-1 EtherPort Ethernet to RS-232 Gateway thats hardwired to the router. am also running the brultech software engine g that forwards my data to google power meter, and saving the usage to a database on my computer

[jaycanter]

heres event viewer log for the brulech the 1st 1 was 3 sec 2nd was 4 sec and the last was 5 sec

 

Thu 01/27/2011 08:34:17 AM : MOD-EM-BRULTECH: Connecting to http://xxx.xxx.x.xxx:xxxx

 

Thu 01/27/2011 08:34:20 AM : MOD-EM-BRULTECH: Successfully Processed Response

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 1 2] 1701.500000 Electricity - Channel 1 - Power = 1701.500 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 1 5] 1701.500000 Electricity - Channel 1 - Polarized Power = 1701.500 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 1 3] 332.658000 Electricity - Channel 1 - Energy Usage = 332.658 kWh

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 1 6] 12.460000 Electricity - Channel 1 - Current = 12.46 Amps

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 2 2] 718.700000 Electricity - Channel 2 - Power = 718.700 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 2 5] 718.700000 Electricity - Channel 2 - Polarized Power = 718.700 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 2 3] 133.521000 Electricity - Channel 2 - Energy Usage = 133.521 kWh

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 2 6] 5.160000 Electricity - Channel 2 - Current = 5.16 Amps

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 3 2] 142.400000 Electricity - Channel 3 - Power = 142.400 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 4 2] 124.300000 Electricity - Channel 4 - Power = 124.300 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 5 2] 0.300000 Electricity - Channel 5 - Power = 0.300 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 6 2] 506.200000 Electricity - Channel 6 - Power = 506.200 Watts

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 6 3] 75.243000 Electricity - Channel 6 - Energy Usage = 75.243 kWh

 

Thu 01/27/2011 08:34:20 AM : [MOD 3 3 7 2] 129.900000 Electricity - Channel 7 - Power = 129.900 Watts

 

Thu 01/27/2011 08:34:27 AM : MOD-EM-BRULTECH: Connecting to http://xxx.xxx.x.xxx:xxxx

 

Thu 01/27/2011 08:34:31 AM : MOD-EM-BRULTECH: Successfully Processed Response

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 1 2] 1697.273000 Electricity - Channel 1 - Power = 1697.273 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 1 5] 1697.273000 Electricity - Channel 1 - Polarized Power = 1697.273 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 1 3] 332.663000 Electricity - Channel 1 - Energy Usage = 332.663 kWh

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 1 6] 12.280000 Electricity - Channel 1 - Current = 12.28 Amps

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 2 2] 712.455000 Electricity - Channel 2 - Power = 712.455 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 2 5] 712.455000 Electricity - Channel 2 - Polarized Power = 712.455 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 2 3] 133.523000 Electricity - Channel 2 - Energy Usage = 133.523 kWh

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 2 6] 4.990000 Electricity - Channel 2 - Current = 4.99 Amps

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 3 2] 141.909000 Electricity - Channel 3 - Power = 141.909 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 3 3] 41.744000 Electricity - Channel 3 - Energy Usage = 41.744 kWh

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 4 2] 124.364000 Electricity - Channel 4 - Power = 124.364 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 4 3] 29.884000 Electricity - Channel 4 - Energy Usage = 29.884 kWh

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 5 2] 0.182000 Electricity - Channel 5 - Power = 0.182 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 6 2] 509.273000 Electricity - Channel 6 - Power = 509.273 Watts

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 6 3] 75.245000 Electricity - Channel 6 - Energy Usage = 75.245 kWh

 

Thu 01/27/2011 08:34:31 AM : [MOD 3 3 7 2] 130.000000 Electricity - Channel 7 - Power = 130.000 Watts

 

Thu 01/27/2011 08:34:37 AM : MOD-EM-BRULTECH: Connecting to http://xxx.xxx.x.xxx:xxxx

 

Thu 01/27/2011 08:34:42 AM : MOD-EM-BRULTECH: Successfully Processed Response

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 1 2] 62318692.000000 Electricity - Channel 1 - Power = 62318692.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 1 5] 62318848.000000 Electricity - Channel 1 - Polarized Power = 62318848.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 1 3] 3.759000 Electricity - Channel 1 - Energy Usage = 3.759 kWh

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 1 6] 0.140000 Electricity - Channel 1 - Current = 0.14 Amps

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 1 4] 122.200000 Electricity - Channel 1 - Voltage = 122.20 volts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 2 2] 25289840.000000 Electricity - Channel 2 - Power = 25289840.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 2 5] 25299098.000000 Electricity - Channel 2 - Polarized Power = 25299098.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 2 3] 0.049000 Electricity - Channel 2 - Energy Usage = 0.049 kWh

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 2 6] 0.090000 Electricity - Channel 2 - Current = 0.09 Amps

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 2 4] 122.200000 Electricity - Channel 2 - Voltage = 122.20 volts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 3 2] 7849532.000000 Electricity - Channel 3 - Power = 7849532.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 3 3] 0.316000 Electricity - Channel 3 - Energy Usage = 0.316 kWh

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 3 4] 122.200000 Electricity - Channel 3 - Voltage = 122.20 volts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 4 2] 5660778.000000 Electricity - Channel 4 - Power = 5660778.000 Watts

 

Thu 01/27/2011 08:34:42 AM : [MOD 3 3 4 3] 0.007000 Electricity - Channel 4 - Energy Usage = 0.007 kWh

[Michel Kohanim]

Hi Bob,

 

ISY blocks on making connections but this means that all other tasks start running while the TCP task is blocked. So, there's absolutely no way that the serial buffer (4K) would overflow.

 

With kind regards,

Michel