johnnyt

Okay, I get it. Sort of. I tested other devices and sure enough querying a device returns it's state and programs with the device will be evaluated even if the reported state is not a change. What confuses me, though, is why isn't the state of all 8 EZIO8SA relays reported back given that a query of node 1 is a query of all nodes? Or to ask a slightly different question, how would I get the same effect, i.e. cause a program evaluation from a query, for relays 2-8 of the EZIO8SA?

Well isn't that special! This is probably the 6th undocumented feature of the EZIO8SA I've encountered, and by far the most expensive one. This morning I changed the 3 scenes I had that contained multiple relays and adjusted the handful of programs that called them so they activated each relay separately. It's been about 12 hours and my inbox does not have a single notification of problems related to relay status surprises. While I've had the odd "good day" like this so I'm going to have to wait a few days to declare victory, I am quite optimistic. This could certainly explain the intermittent nature of the problem with the problems showing up only after some program had called one of the problematic scenes. I also have to wait to see if this solves the problems such as 5 and 6 on my list, which have happened only twice in ~3 months. I will confirm the one thing it has NOT fixed. ISY continues to be surprised by the state of relay 1. For example, the ISY GUI showed relay 1 was ON when I queried the device yet ISY recorded it as a status change to ON. Tue 03/18/2014 08:24:57 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Tue 03/18/2014 08:24:57 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Tue 03/18/2014 08:24:57 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 03 IOCTL (03) Tue 03/18/2014 08:24:57 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 03/18/2014 08:24:57 PM : [ 23 8D 7C 1] ST 255 Tue 03/18/2014 08:24:57 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Tue 03/18/2014 08:24:57 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Tue 03/18/2014 08:24:57 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Tue 03/18/2014 08:24:57 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 I'm not going to ask you if this can be fixed because 1) I don't have any programs that are triggered based only on relay 1 status and 2) if needed, I can work around the issue by picking a different relay and I'd rather see UDI work on 4.x and 5.0 releases. I did want to mention it in case it can help someone else. Perhaps I'll also publish a list of EZIO8SA undocumented features if this ends well to save others a lot of grief.

Yes I have at least three and they work under normal conditions. If I may I’d like to ask what you're thinking might need fixing because it’s not clear to me, particularly given the comment that you "do not see anything that strikes (you) as strange". From where I sit there a number of problems, some seeming to be ISY logic or something ISY could compensate for, others seeming to be problems with the EZIO8SA. The problem with the latter is explaining to Smartenit that the problem is not an ISY issue as right now they don’t seem to take any ownership for the behavior I’m seeing. Which of these problems do you think you might be able to fix or be able to rule out ISY as involved in it or be able to compensate for: 1) relays intermittently yet frequently surprising ISY even when they are already in the reported state (relay 1 being a pretty consistent offender) 2) on query, relays periodically surprising ISY with wrong state then if queried again reversing to actual state (because of extra messages?) 3) scene commands that often don't work during EZIO8SA queries 4) relays that end up stuck ON until EZIO8SA relay unit is power cycled 5) device link DB getting wiped out (according to ISY Device links query) but with some stuff still working 6) The bad sequence, seen when I was using Dual Band PLM viewtopic.php?f=27&t=13528#p106690 viewtopic.php?f=27&t=13528#p106758 7) this: viewtopic.php?f=27&t=13528&start=15#p108872, which I can't figure out how to summarize I’m trying to assess whether this actually has the potential to end well. If all you’re looking at and might fix is, say, number 1, it won’t be enough for me to think the EZIO8SA is going to work out for me.

I did the device restore until I got an identical compare with ISY links table. It took 2 attempts as the first one had 2 record mismatches. I think that's happen to me before with a KPL (lots of links) so I presume it's not an indication of a malfunctioning device. I then ran a few manual device queries of the EZIO8SA and it looks like a duplicate response occurred that may have set off some problems with the state ISY had internally for relays 1 then 5. Relay 1 repeatedly surprises ISY with its ON state. See attachment that ends in "DeviceQuery-relay1ONalready.txt" I did it again, this time watching the state of relay 1 in the GUI, which was ON to start. I would see the star come up beside it and it stayed ON. The trace shows ISY is actually surprised to hear the relay is ON.. twice! See attachment that ends in "DeviceQuery-Relay1ON-2.txt". A visual check of the dampers being controlled confirmed they were physically ON. I also ran the program that does 150 repeated queries. All seems fine until mid way through when the following extended response occurs: Sat 03/15/2014 02:44:31 PM : [iNST-ERX ] 02 51 23 8D 7C 24 1B FE 12 79 B8 CF 32 BD 43 FF FF 1F FF EF FF F0 00 00 AF Sat 03/15/2014 02:44:31 PM : [Ext-Direct ] 23.8D.7C-->ISY/PLM Group=0, Max Hops=2, Hops Left=0 Soon after the INST-ERX message appears a response (in red below) that's interrupting the normal flow of responses seen both before and again later. Sat 03/15/2014 02:44:32 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Sat 03/15/2014 02:44:32 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Sat 03/15/2014 02:44:32 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 4F 21 IOCTL (21) Sat 03/15/2014 02:44:32 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/15/2014 02:44:33 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Sat 03/15/2014 02:44:33 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/15/2014 02:44:33 PM : [ 23 8D 7C 1] ST 0 For the next several queries relays 1, 4, 6 and 7 are reported as having changed, with each changing back to (I assume) its actual state all along. Aside from wondering about what seems like a duplicate response that throws a lot of things off, and wondering why ISY seems to be surprised that relay 1 is ON when it shows it as ON in the GUI as well as in prior query responses, I'm wondering why the ERX message only shows up once in 150 queries. Or, maybe the question is why is there an ERX message at all? Any info would be appreciated. ISY-Events-Log.v4.1.2__150queries.txt ISY-Events-Log.v4.1.2__DeviceQuery-relay1ONalready.txt ISY-Events-Log.v4.1.2__DeviceQuery-Relay1ON-2.txt

Did three different scene tests, HRV Low, HRV High, and HRV Off. All succeeded according to event traces (2 of 3 attached), however the HRV does NOT turn on, so there's definitely something wrong I checked the device links three times and each time only got one record - see attached screenshot. I did see this when I first installed it (using "replace with" to copy from the original dual band PLM), but fixed it with a "restore device". So it seems the PLM loses it's links. But then how come the scene tests worked??? I'll do the restore device again, repeat a bunch of tests (later today) and report back. On a side note, strangely (to me) the scene test causes the event viewer to be cleared. I don't know if this is by design but I learned you have to save what's there before you run a test if you want to keep it. ISY-Events-Log.v4.1.2_SceneHRVHigh.txt ISY-Events-Log.v4.1.2__SceneHRVLow.txt

so what does that mean? could the EZIO PLM be overwhelmed by the query command? a few posts back the "query insteon engine" I ran trumped a device query I also ran while the first one was still running. Or is it because the insteon network was busy at that one moment and caused the scene command to have been completely missed by the EZIO PLM? I believe scene commands only go out once, right? Does collision avoidance not prevent scene commands from getting lost like that?

Hi Michel, it's just above the screenshot. I just successfully downloaded it. edit: it shows it's been downloaded 4 times as of 8:47 PM EDT on Mar 14th

Yes, with both PLM's, although LeeG pointed out an unexplained message as perhaps being the problem with the dual band that has not been seen with the single band. Attached is the event viewer trace from running the following program: If - No Conditions - (To add one, press 'Schedule' or 'Condition') Then Repeat 150 times Set '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' Query Else - No Actions - (To add one, press 'Action') You didn't say you wanted all other programs disabled so I left everything enabled. Let me know if you want me to run it with everything disabled. Repeatedly querying caused one of the self healing programs I have to repeatedly run to turn my HRV to Low (relay 5 of the EZIO8SA). Since it's supposed to be running at this time of the day, that's expected when ISY is surprised to see it isn't. I point to the program in the screenshot below. The four circled programs were stopping and starting repeatedly and should not have been doing that. I let it go for a bit but stopped it part way through the testing. Looking at the trace, relay 6 (HRV High) was also going on and off. It doesn't look to me as though a program was involved but it might have been. When there's a heat call, ISY turns the HRV to high (relay 6 on and relay 5 off). After the heat call ends, it sets the HRV back to low (relay 6 off and relay 5 on). A heat call change is recognizable in the trace because the X10 command K8 would have been heard by the PLM, or IO Linc sensor 15.BB.45 reports a change to OFF (although that's not been reliable, hence the need for X10 backup). I didn't find either of those events in the file. ISY-Events-Log.v4.1.2__Fri 2014.03.14 02.50.07 PM.txt

Sorry about a third post in a row but here's more data that I'm thinking (hoping) adds to the story. I manually initiated a number of device queries of the EZIO8SA in a row that occurred while an Office Damper scene was being called by a program. Each time the relay response portion of the queries surprised ISY, except for what looks like a duplicate response with max hops=3 remaining hops=0 (separated from the rest in the event viewer excerpt below) 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Thu 2014/03/13 05:00:22 PM Web Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 05:00:22 PM System Log Scene:1-MISC (Non Lighting) / HVAC / Office Damper for Prgs Off 0 Thu 2014/03/13 05:00:32 PM Program Log 1-MISC (Non Lighting) / HVAC / Old Office Damper (for LED) Status 0% Thu 2014/03/13 05:00:32 PM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 0% Thu 2014/03/13 05:00:32 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Thu 2014/03/13 05:00:33 PM Web Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 05:00:34 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Thu 2014/03/13 05:00:42 PM Web Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 05:00:43 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Thu 2014/03/13 05:00:50 PM Web Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 05:00:50 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Thu 2014/03/13 05:01:04 PM Web Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 05:01:04 PM System Log Thu 03/13/2014 05:00:18 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Thu 03/13/2014 05:00:18 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Thu 03/13/2014 05:00:19 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 8E IOCTL (8E) Thu 03/13/2014 05:00:19 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:19 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 05:00:19 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Thu 03/13/2014 05:00:19 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Thu 03/13/2014 05:00:19 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Thu 03/13/2014 05:00:19 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:29 PM : [iNST-TX-I1 ] 02 62 00 00 16 CF 13 00 Thu 03/13/2014 05:00:29 PM : [iNST-ACK ] 02 62 00.00.16 CF 13 00 06 LTOFFRR(00) Thu 03/13/2014 05:00:29 PM : [ 15 BB 45 2] ST 0 Thu 03/13/2014 05:00:29 PM : [ 23 8D 7C 4] ST 0 Thu 03/13/2014 05:00:30 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Thu 03/13/2014 05:00:30 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Thu 03/13/2014 05:00:30 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 86 IOCTL (86) Thu 03/13/2014 05:00:30 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:30 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 05:00:30 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Thu 03/13/2014 05:00:30 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Thu 03/13/2014 05:00:31 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Thu 03/13/2014 05:00:31 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:38 PM : [ Time] 17:00:41 0(0) Thu 03/13/2014 05:00:38 PM : [ Time] 17:00:41 0(0) Thu 03/13/2014 05:00:39 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Thu 03/13/2014 05:00:39 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Thu 03/13/2014 05:00:39 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 86 IOCTL (86) Thu 03/13/2014 05:00:39 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:40 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 05:00:40 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 4F 86 IOCTL (86) Thu 03/13/2014 05:00:40 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Thu 03/13/2014 05:00:40 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Thu 03/13/2014 05:00:40 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Thu 03/13/2014 05:00:40 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Thu 03/13/2014 05:00:40 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:41 PM : [ Time] 17:00:44 0(0) Thu 03/13/2014 05:00:41 PM : [ Time] 17:00:44 0(0) Thu 03/13/2014 05:00:45 PM : [VAR 2 66 ] 48 Thu 03/13/2014 05:00:46 PM : [VAR 2 12 ] 1 Thu 03/13/2014 05:00:47 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Thu 03/13/2014 05:00:47 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Thu 03/13/2014 05:00:47 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 86 IOCTL (86) Thu 03/13/2014 05:00:47 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:47 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 05:00:47 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Thu 03/13/2014 05:00:47 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Thu 03/13/2014 05:00:47 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Thu 03/13/2014 05:00:47 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:00:51 PM : [VAR 2 52 ] -820 Thu 03/13/2014 05:00:54 PM : [VAR 2 68 ] 5 Thu 03/13/2014 05:00:55 PM : [VAR 2 58 ] 1837 Thu 03/13/2014 05:00:55 PM : [VAR 2 70 ] 1987 Thu 03/13/2014 05:00:58 PM : [VAR 2 61 ] 2262 Thu 03/13/2014 05:00:58 PM : [ Time] 17:01:01 0(0) Thu 03/13/2014 05:01:00 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Thu 03/13/2014 05:01:00 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Thu 03/13/2014 05:01:01 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 86 IOCTL (86) Thu 03/13/2014 05:01:01 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:01:01 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 05:01:01 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Thu 03/13/2014 05:01:01 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Thu 03/13/2014 05:01:01 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Thu 03/13/2014 05:01:01 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 05:01:03 PM : [ Time] 17:01:05 0(0) Could other activity with error-free comms during a query of the EZIO8SA be at least part of what's causing problems? It would explain the intermittent yet daily occurrence of this kind of problem on a circuit that has only three IOLincs and one EZIO8SA and that is on the same phase as the ISY PLM hanging right off the electrical panel on its own circuit!! Is there anything that ISY firmware changes can fix or somehow accommodate if this is an EZIO8SA problem? Or should I just cut bait and give up on the idea of using insteon for HVAC controls? I've been struggling with this for I think 3 years now, first with IO Lincs (too many it turned out, at least for a solution that tries to be highly reliable and self healing) and now with the EZIO8SA, which, although much more insteon network friendly and efficient for ISY to process has been nothing but trouble (either itself or for ISY or both) from the beginning a short 3-4 months ago and through two units.

I've been querying insteon engine at various times of the day and so far all have been the same flow of responses with max hops=3, remaining hops=2. This afternoon I ran the command BEFORE opening the event viewer and setting it to level 3. ISY was clearly taxed as response time was quite slow. As part of the responses this time, I got a lot of surprise state changes and two Max Hops=3, Hops Left=0. Could they have been because ISY was busy or is that a clear sign of comm problems, if only for a second or two? I added a space to highlight them. Thu 03/13/2014 04:57:08 PM : [All ] Writing 1 bytes to devices Thu 03/13/2014 04:57:08 PM : [23 8D 7C 0 ] May not fully support i2, reverting to i1 Thu 03/13/2014 04:57:12 PM : [ 23 8D 7C 1] ST 255 Thu 03/13/2014 04:57:12 PM : [ 23 8D 7C 5] ST 255 Thu 03/13/2014 04:57:12 PM : [ 23 8D 7C 6] ST 255 Thu 03/13/2014 04:57:12 PM : [ 23 8D 7C 8] ST 0 Thu 03/13/2014 04:57:15 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 29 02 Thu 03/13/2014 04:57:17 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 2B 00 PEEK (00) Thu 03/13/2014 04:57:17 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Thu 03/13/2014 04:57:18 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 29 02 06 POKE (02) Thu 03/13/2014 04:57:18 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 2B 00 PEEK (00) Thu 03/13/2014 04:57:18 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 1] ST 0 Thu 03/13/2014 04:57:18 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 29 02 POKE (02) Thu 03/13/2014 04:57:18 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 2] ST 0 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 3] ST 0 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 4] ST 0 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 5] ST 0 Thu 03/13/2014 04:57:18 PM : [ 23 8D 7C 6] ST 0 Thu 03/13/2014 04:57:18 PM : [VAR 2 33 ] 301 Thu 03/13/2014 04:57:18 PM : [VAR 2 32 ] 246 Thu 03/13/2014 04:57:19 PM : [VAR 2 12 ] 0 Thu 03/13/2014 04:57:19 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 3F Thu 03/13/2014 04:57:19 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 3F 06 SET-MSB(3F) Thu 03/13/2014 04:57:19 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 3F SET-MSB(3F) Thu 03/13/2014 04:57:19 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:19 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:19 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:20 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 00 PEEK (00) Thu 03/13/2014 04:57:20 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:20 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 29 03 Thu 03/13/2014 04:57:20 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 29 03 06 POKE (03) Thu 03/13/2014 04:57:20 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 29 03 POKE (03) Thu 03/13/2014 04:57:20 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:20 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 0F Thu 03/13/2014 04:57:20 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 0F 06 SET-MSB(0F) Thu 03/13/2014 04:57:21 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 0F SET-MSB(0F) Thu 03/13/2014 04:57:21 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:21 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:21 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:21 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 01 PEEK (01) Thu 03/13/2014 04:57:21 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:21 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 1F Thu 03/13/2014 04:57:21 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 1F 06 SET-MSB(1F) Thu 03/13/2014 04:57:22 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 1F SET-MSB(1F) Thu 03/13/2014 04:57:22 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:22 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:22 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:22 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 02 PEEK (02) Thu 03/13/2014 04:57:22 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:22 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 3F Thu 03/13/2014 04:57:22 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 3F 06 SET-MSB(3F) Thu 03/13/2014 04:57:23 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 3F SET-MSB(3F) Thu 03/13/2014 04:57:23 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:23 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:23 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:23 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 03 PEEK (03) Thu 03/13/2014 04:57:23 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:23 PM : [VAR 2 12 ] -1 Thu 03/13/2014 04:57:24 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 0F Thu 03/13/2014 04:57:24 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 0F 06 SET-MSB(0F) Thu 03/13/2014 04:57:24 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 0F SET-MSB(0F) Thu 03/13/2014 04:57:24 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:24 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:24 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:24 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 01 PEEK (01) Thu 03/13/2014 04:57:24 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:24 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 29 00 Thu 03/13/2014 04:57:25 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 29 00 06 POKE (00) Thu 03/13/2014 04:57:25 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 29 00 POKE (00) Thu 03/13/2014 04:57:25 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:25 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 1F Thu 03/13/2014 04:57:25 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 1F 06 SET-MSB(1F) Thu 03/13/2014 04:57:25 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 1F SET-MSB(1F) Thu 03/13/2014 04:57:25 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:25 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:26 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:26 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 02 PEEK (02) Thu 03/13/2014 04:57:26 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:26 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 29 00 Thu 03/13/2014 04:57:26 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 29 00 06 POKE (00) Thu 03/13/2014 04:57:26 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 29 00 POKE (00) Thu 03/13/2014 04:57:26 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:26 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 28 3F Thu 03/13/2014 04:57:27 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 28 3F 06 SET-MSB(3F) Thu 03/13/2014 04:57:27 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 28 3F SET-MSB(3F) Thu 03/13/2014 04:57:27 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:27 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 2B FF Thu 03/13/2014 04:57:27 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 2B FF 06 PEEK (FF) Thu 03/13/2014 04:57:27 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 2B 03 PEEK (03) Thu 03/13/2014 04:57:27 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:27 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 29 00 Thu 03/13/2014 04:57:28 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 29 00 06 POKE (00) Thu 03/13/2014 04:57:28 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 29 00 POKE (00) Thu 03/13/2014 04:57:28 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:30 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 45 01 Thu 03/13/2014 04:57:32 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 45 01 06 IOON (01) Thu 03/13/2014 04:57:32 PM : [ Time] 16:57:31 3(0) Thu 03/13/2014 04:57:32 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 45 8E IOON (8E) Thu 03/13/2014 04:57:33 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 03/13/2014 04:57:33 PM : [ 23 8D 7C 2] ST 255 Thu 03/13/2014 04:57:33 PM : [ 23 8D 7C 3] ST 255 Thu 03/13/2014 04:57:33 PM : [ 23 8D 7C 4] ST 255 Thu 03/13/2014 04:57:33 PM : [ 23 8D 7C 8] ST 255 The log from a few minutes before up to the final state changes tells me that a query of the IOLincs did occur at about the same time (added space before and after the relevant entry). This IOLinc scene being queried does NOT have any EZIO8SA nodes in it. 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Off 0 Thu 2014/03/13 04:47:42 PM Program Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 0% Thu 2014/03/13 04:47:43 PM System Log X10 K8 Thu 2014/03/13 04:48:15 PM System Log X10 K8 Off (11) Thu 2014/03/13 04:48:16 PM System Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Thu 2014/03/13 04:48:22 PM Program Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 0% Thu 2014/03/13 04:48:22 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) On 255 Thu 2014/03/13 04:48:32 PM Program Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 100% Thu 2014/03/13 04:48:32 PM System Log Scene:1-MISC (Non Lighting) / HVAC / Office Damper for Prgs On Thu 2014/03/13 04:48:35 PM Program Log 1-MISC (Non Lighting) / HVAC / Old Office Damper (for LED) Status 100% Thu 2014/03/13 04:48:35 PM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 100% Thu 2014/03/13 04:48:35 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Off 0 Thu 2014/03/13 04:49:05 PM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 04:49:06 PM System Log X10 K8 Thu 2014/03/13 04:56:21 PM System Log X10 K8 On (3) Thu 2014/03/13 04:56:22 PM System Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Thu 2014/03/13 04:56:28 PM Program Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 100% Thu 2014/03/13 04:56:28 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 100% Thu 2014/03/13 04:57:13 PM System Log 1-MISC (Non Lighting) / HRV / HRV Low-EZIO.5 Status 100% Thu 2014/03/13 04:57:13 PM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 100% Thu 2014/03/13 04:57:13 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 0% Thu 2014/03/13 04:57:13 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HVAC / Furnace Rm Damper-EZIO.3 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HRV / HRV Low-EZIO.5 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 0% Thu 2014/03/13 04:57:17 PM System Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 On 255 Thu 2014/03/13 04:57:26 PM Program Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 100% Thu 2014/03/13 04:57:31 PM System Log 1-MISC (Non Lighting) / HVAC / Furnace Rm Damper-EZIO.3 Status 100% Thu 2014/03/13 04:57:31 PM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 100% Thu 2014/03/13 04:57:31 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 100% Thu 2014/03/13 04:57:31 PM System Log Does this add anything to the story?

The one related to the most recent set of posts is only about a week or two old but it is using the older type (design anyway) single band PLM. The first one - at the start of this thread - was maybe a month or so old at that time and had a dual band PLM.

I don't have anything connected to the inputs other than 5&6 to gnd and I don't have the kind of busy ISY situation I used to see when I had to query 10 IO Lincs, pre-EZIO8SA. Incidentally, in case anyone is thinking "just stop querying and your problems will go away", the reason I do the querying is because whenever I've not done it in the past, I would sometimes find things that weren't working as they should. The 3AM "query all" would also end up reporting something wrong just about everyday. While one might be tempted to say that at 3AM the insteon network and ISY are busy so it's just that query problem misreporting things, the reality is there aren't that many problems at 3 AM the times where I'm querying throughout the day. Also, while nothing is such that an HA failure will cause damage to my HVAC system, I kind of want my HVAC controls to be reliable and "self healing" when something doesn't work for comms or other reasons, given that insteon does not promise the reliability of a hard wired solution. ISY for its part has been great for reliability and for the self healing aspect when the querying works but the latter does need to work properly for the self healing to work properly. Thinking something is OFF when it's ON causes the system to actually be "self breaking"... For testing purposes I will try disabling all querying for a few days to see what happens, then move to querying only a few times in a day. I'll combine this with some manual "query insteon engine" or scene tests at different times to see if I can uncover (or rule out?) comms issues as the probable cause of these chronic intermittent problems. In the meantime, any other insights and suggestions would be appreciated.

ELA, Thanks for trying to sift through my troubles and for the suggestion. I just ran about 4 query insteon engine commands against node 1 of the EZIO8SA. On the last one I first initiated my query testing "suite" with a 10 second delay before it queries the EZIO8SA. The thinking was that I would create a collision. The results were all similar and I was surprised to find no trace of the device query from my program within the query engine trace. I'm guessing the regular query command was trumped by the query insteon engine command. FWIW, I've attached the result of the last one here. I'll try again at other times of the day and look for other ways to hammer the insteon network when I do it. I presume there's no way to call for a "query insteon engine" from within a program. Otherwise I would add that into the mix. Would a scene test work? I would mention that the only things on the circuit are 3 IO Lincs and the EZIO8SA on a 14 AWG power bar that doesn't have any built in filtering. In the past I had an HRV, chest freezer and 24VAC power supply that were found to cause problems despite being on a 15A ACT filter but they were moved to another circuit. I'm sure I must be missing something so I'd like to tell you what I see when I look at the event viewer entries hoping you can help. I see that ISY sends out a query for the relays, gets a response 4 secs later, considers that too long so waits 5 secs and sends another query for relays. Rinse. Repeat. Until it gets a response quickly enough and then it queries the input. In this case, the response comes twice - perhaps a remnant from a previous query because of comm problems that don't show up in the max hops / remain hops - and ISY confuses it (or the variable that got updated while this is happening) as being a change in status for 4 of the 8 relays with relays 2 & 4 the wrong status. Were relays 5 & 6 ON all along or is that also a reverse status? I also noticed that an output status response came twice too (that one with an indication of a comms issue) but it was handled okay. I see this flow as problematic but figure I must be misinterpreting what's happening. Thanks again for your help.

Thanks for clarifying the querying. Still, why three queries of the relays and such a long wait to query the input? And why does the following summary update of the relays only occur after the input is queried and over 20 secs after the first query? Sun 03/09/2014 07:33:37 AM : [ 23 8D 7C 2] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 4] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 5] ST 255 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 6] ST 255 I've attached the log from 1PM 03/08 to 3:35PM 03/09 and the event viewer L3 log from 9PM 03/08 to ~8AM 03/09. Also, the attached screenshot shows how often a problem is caught by my automated testing. The arrow shows the device I chose to track for my post. Logs20140308-20140309.zip

Thanks for looking through this stuff, LeeG. Here's what I thought were three queries without a response. I guess I meant three queries with only ONE response. I could certainly be misinterpreting what this is but I saw the word "query" three times and what I interpret to be a single response "RINPUT", although is the third instance of "RINPUT" a duplicate? Sun 03/09/2014 07:33:12 AM : [ Time] 07:33:23 0(0) Sun 03/09/2014 07:33:14 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:14 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:18 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:18 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:23 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:23 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:28 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:28 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:28 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:28 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sun 03/09/2014 07:33:32 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:32 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:32 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:32 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:32 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Sun 03/09/2014 07:33:32 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Sun 03/09/2014 07:33:33 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Sun 03/09/2014 07:33:33 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:34 AM : [ Time] 07:33:45 0(0) Sun 03/09/2014 07:33:34 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 49 30 RINPUT (30) Sun 03/09/2014 07:33:34 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sun 03/09/2014 07:33:37 AM : [ 23 8D 7C 2] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 4] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 5] ST 255 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 6] ST 255 The above events were the result of the query test program sequence that ran at 7:33:22, i.e. “the related one at 7:23:22†2014/03/09, 07:33:22, 1-Initiate Query Test ran True If needed, I can upload the complete log file and event viewer trace if you think I might have missed something. Thanks again.

Okay, so Smartenit sent me a new EZIO8SA/PLM Combo but this time with a single band PLM hoping it would solve the problems I'm seeing but it didn't. As the issue is intermittent and Smartenit can't reproduce it, I'm coming back here for advice on what I'm seeing and/or missing in troubleshooting this. I'll recap then build on what I've provided so far using up to date data generated by the single band PLM instead of the dual band one that came with the EZIO8SA initially. I won't repeat a desciption of the programs that I posted earlier to automate the testing in my attempts to catch this intermittent problem. Here's a typical winter time set of HVAC related events I control with three IO Lincs and the EZIO8SA. First the log entries below show a call for heat detected by an IOLinc (15.BB.45) that triggers a query scene made up of the three IOLinc sensors monitoring HVAC calls, one for heat, one for AC and one for Furnace Fan. (Someday I might separate the three but for now a change in any of the them triggers a query of all three). X10 K8 Sat 2014/03/08 09:12:48 PM System Log X10 K8 Off (11) Sat 2014/03/08 09:12:48 PM System Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Sat 2014/03/08 09:12:54 PM Program Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 0% Sat 2014/03/08 09:12:54 PM System Log 1-MISC (Non Lighting) / HVAC / Old Office Damper (for LED) Status 0% Sat 2014/03/08 09:12:55 PM System Log Notes: 1. The IO Lincs are configured to send X10 commands. This and the sensor query are safeguards for reliability, which are needed because ISY does not always see / act on an IO Linc sensor change. 2. Because the IOLinc sensor is ON when the stat call is OFF, the device name above shows that "Heat Off" has turned OFF, meaning heat is ON. 3. "Heat Off" device is the IO Linc sensor and the "Old Office Damper (for LED)" is the relay of the same IOLinc. As I learned not that long ago, an IOLinc query of either the sensor or the relay returns the value of both. I use the relay state only for its matching LED state to provide a visual indicator of the Office Damper (in a scene with the EZIO8SA relay that controls the damper). Noteworthy here, and what seems like a bug, is that there isn't a log entry for the sensor change. The event can be seen in the event viewer (see below) and does cause the query program to run (6 seconds later, as designed) so it seems that a log entry is simply not posted. This is consistent behavior. Here are the L3 event viewer entries from the IO Linc Sensor: Sat 03/08/2014 09:12:36 PM : [ Time] 21:12:42 1(0) Sat 03/08/2014 09:12:41 PM : [iNST-SRX ] 02 50 15.BB.45 00.00.01 CB 11 00 LTONRR (00) Sat 03/08/2014 09:12:41 PM : [std-Group ] 15.BB.45-->Group=1, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:41 PM : [ 15 BB 45 1] DON 0 Sat 03/08/2014 09:12:41 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 41 11 01 LTONRR (01) Sat 03/08/2014 09:12:41 PM : [std-Cleanup ] 15.BB.45-->ISY/PLM Group=1, Max Hops=1, Hops Left=0 Sat 03/08/2014 09:12:41 PM : [iNST-DUP ] Previous message ignored. And below are the event viewer entries aligned to the log entries above. The other two IO Lincs in the scene that is queried are 15.BB.62 and 17.71.EA. What seems a little weird to me is that the status response of those other sensors does NOT end up in the log. When I manually run a query, log entries are posted whether or not the status is a change but when queried here by a program, the only entry logged is for the heat call IO Linc (see above). Side question: 1) what does the INST-ERX message at 09:12:50 represent? Sat 03/08/2014 09:12:42 PM : [X10-RX ] 02 52 3D 00 Sat 03/08/2014 09:12:42 PM : [ X10] K8 Sat 03/08/2014 09:12:42 PM : [X10-RX ] 02 52 33 80 Sat 03/08/2014 09:12:42 PM : [ X10] K8/Off (11) Sat 03/08/2014 09:12:48 PM : [ Time] 21:12:53 1(0) Sat 03/08/2014 09:12:48 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 01 Sat 03/08/2014 09:12:48 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 01 06 LTSREQ (01) Sat 03/08/2014 09:12:48 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D 00 (00) Sat 03/08/2014 09:12:48 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:48 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 00 Sat 03/08/2014 09:12:48 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 00 06 LTSREQ (LIGHT) Sat 03/08/2014 09:12:48 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D 00 (00) Sat 03/08/2014 09:12:48 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:48 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 01 Sat 03/08/2014 09:12:49 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 01 06 LTSREQ (01) Sat 03/08/2014 09:12:49 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Sat 03/08/2014 09:12:49 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:49 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 00 Sat 03/08/2014 09:12:49 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 00 06 LTSREQ (LIGHT) Sat 03/08/2014 09:12:49 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Sat 03/08/2014 09:12:49 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:49 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 01 Sat 03/08/2014 09:12:50 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 01 06 LTSREQ (01) Sat 03/08/2014 09:12:50 PM : [iNST-ERX ] 02 51 15 BB 62 24 1B FE 10 F7 C0 66 DF C0 03 EE FE 81 44 01 0A 0D 3A AA 7D Sat 03/08/2014 09:12:50 PM : [Ext-Direct ] 15.BB.62-->ISY/PLM Group=0, Max Hops=0, Hops Left=0 Sat 03/08/2014 09:12:50 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Sat 03/08/2014 09:12:50 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:50 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 23 08 00 (00) Sat 03/08/2014 09:12:50 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/08/2014 09:12:51 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 01 (01) Sat 03/08/2014 09:12:51 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:51 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 00 Sat 03/08/2014 09:12:51 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 00 06 LTSREQ (LIGHT) Sat 03/08/2014 09:12:52 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 FF (FF) Sat 03/08/2014 09:12:52 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:12:52 PM : [ 15 BB 45 1] ST 0 Sat 03/08/2014 09:12:52 PM : [ 15 BB 45 2] ST 0 Moving to the EZIO8SA side of things. The above action resulted in my Humidifier going on as desired, however, setting the humidifer ON also reported the status of two other EZIO relays that surprised ISY. Relays 1 and 2 were found to be the opposite of what ISY thought. 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) On 255 Sat 2014/03/08 09:13:05 PM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Sat 2014/03/08 09:13:06 PM System Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 100% Sat 2014/03/08 09:13:06 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 100% Sat 2014/03/08 09:13:06 PM System Log Here is the event viewer L3 trace that aligns to the above log entries: Sat 03/08/2014 09:13:01 PM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 45 07 Sat 03/08/2014 09:13:01 PM : [ Time] 21:13:05 1(0) Sat 03/08/2014 09:13:01 PM : [iNST-ACK ] 02 62 23.8D.7C 0F 45 07 06 IOON (07) Sat 03/08/2014 09:13:01 PM : [ Time] 21:13:06 1(0) Sat 03/08/2014 09:13:01 PM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 45 82 IOON (82) Sat 03/08/2014 09:13:01 PM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/08/2014 09:13:01 PM : [ 23 8D 7C 1] ST 0 Sat 03/08/2014 09:13:01 PM : [ 23 8D 7C 2] ST 255 Sat 03/08/2014 09:13:01 PM : [ 23 8D 7C 8] ST 255 A little over a min later ISY initiates my query testing (the set of programs explained in an earlier post), which does a query of the EZIO8SA and confirms (or updates) the device status reported a minute earlier: 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Sat 2014/03/08 09:14:06 PM Program Log Overnight all worked well as the furnace cycled on and off, repeating the above pattern but without any surprises, and "Fan On-EZIO.2" remained ON throughout. At 7:30 AM the next day, however, the EZIO8SA query surprised ISY again. (A search of the log for "Fan On-EZIO.2" confirms there are no entries between 9:13:06 PM and 7:33:45 AM.) 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Sun 2014/03/09 07:33:25 AM Program Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 0% Sun 2014/03/09 07:33:45 AM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 0% Sun 2014/03/09 07:33:45 AM System Log 1-MISC (Non Lighting) / HRV / HRV Low-EZIO.5 Status 100% Sun 2014/03/09 07:33:45 AM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 100% Sun 2014/03/09 07:33:45 AM System Log Below are the related event viewer entries. What surprises me are the three queries, each within 10 secs of the previous one. Only one query is sent in my program logic unless the response surprises ISY, in which case a second round of testing is initiated about 13 secs later in the hope it might provide a clue as to what's going on. The second instance relies on the response to the first but there is no response here until the third query about 20 secs after the query is initiated and logged. There is also what appears to be a duplicate query message at 07:33:28 with Max Hops=3, Hops Left=0 in an environment that's otherwise pretty reliable comms-wise. Sun 03/09/2014 07:33:12 AM : [ Time] 07:33:23 0(0) Sun 03/09/2014 07:33:14 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:14 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:18 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:18 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:23 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:23 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:28 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:28 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:28 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:28 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sun 03/09/2014 07:33:32 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:33:32 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:33:32 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:33:32 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:32 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Sun 03/09/2014 07:33:32 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Sun 03/09/2014 07:33:33 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Sun 03/09/2014 07:33:33 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:33:34 AM : [ Time] 07:33:45 0(0) Sun 03/09/2014 07:33:34 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 23 49 30 RINPUT (30) Sun 03/09/2014 07:33:34 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sun 03/09/2014 07:33:37 AM : [ 23 8D 7C 2] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 4] ST 0 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 5] ST 255 Sun 03/09/2014 07:33:38 AM : [ 23 8D 7C 6] ST 255 Just to confirm it wasn't a program that runs/queries twice, I create a custom notification/log when it runs. Here are those entries including the one before and two after the related one at 7:23:22. This also shows the two rounds of automated testing that occured at 7:40 seen further below. 2014/03/09, 07:22:38, 1-Initiate Query Test ran True 2014/03/09, 07:33:22, 1-Initiate Query Test ran True 2014/03/09, 07:40:03, 1-Initiate Query Test ran True 2014/03/09, 07:40:15, 1-Run Query Test Again ran True About 6 mins later, when the heat call ends, ISY is again surprised for two of the EZIO8SA devices : X10 K8 Sun 2014/03/09 07:38:47 AM System Log X10 K8 Sun 2014/03/09 07:38:47 AM System Log X10 K8 On (3) Sun 2014/03/09 07:38:48 AM System Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Sun 2014/03/09 07:38:52 AM Program Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 100% Sun 2014/03/09 07:38:52 AM System Log Bedrooms / Boys Bedroom Table Lamp Status Query Sun 2014/03/09 07:40:00 AM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Sun 2014/03/09 07:40:05 AM Program Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 100% Sun 2014/03/09 07:40:05 AM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 0% Sun 2014/03/09 07:40:05 AM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Sun 2014/03/09 07:40:18 AM Program Log Here are the related event viewer L3 entries: Sun 03/09/2014 07:39:53 AM : [ Time] 07:40:05 0(0) Sun 03/09/2014 07:39:53 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:39:53 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:39:53 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:39:53 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:39:53 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Sun 03/09/2014 07:39:53 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Sun 03/09/2014 07:39:53 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Sun 03/09/2014 07:39:53 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:39:54 AM : [ 23 8D 7C 2] ST 255 Sun 03/09/2014 07:39:54 AM : [ 23 8D 7C 6] ST 0 Sun 03/09/2014 07:40:05 AM : [ Time] 07:40:17 0(0) Sun 03/09/2014 07:40:06 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 4F 02 Sun 03/09/2014 07:40:06 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 4F 02 06 IOCTL (QUERY) Sun 03/09/2014 07:40:06 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 4F 0A IOCTL (0A) Sun 03/09/2014 07:40:06 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sun 03/09/2014 07:40:06 AM : [iNST-TX-I1 ] 02 62 23 8D 7C 0F 49 00 Sun 03/09/2014 07:40:06 AM : [iNST-ACK ] 02 62 23.8D.7C 0F 49 00 06 RINPUT (00) Sun 03/09/2014 07:40:07 AM : [iNST-SRX ] 02 50 23.8D.7C 24.1B.FE 2B 49 30 RINPUT (30) Sun 03/09/2014 07:40:07 AM : [std-Direct Ack] 23.8D.7C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 While it would be nice to see log entries for IO Linc sensor changes (and that certainly would have helped me in the past), the only thing that concerns me at this point are the three queries in a row being sent out without responses. I'm also hoping without the dual band PLM in play, it's easy to pinpoint the problem for me to tell Smartnenit. Any suggestions?

Hi Michel, Was just looking back and and it seems there's something more than simply a duplicate message going on.

It's about 2 months old. Yes, it's dual band. Sent from my iPad using Tapatalk

38link, thanks for posting despite the trouble you ran into. After another week and a half of testing (mostly automated) I thought I would provide an update and mention what Smartenit has said about the issue. Below are examples of how ISY is being surprised on a daily basis by the EZIO8SA status following a query. While it doesn’t happen every time the device is queried - and never, so far, when I manually do it - it isn’t a rare occurrence as it happens to varying degrees every day. Just to recap, the programs outlined in my previous post do the following: 1) before a query, the status ISY has is recorded in a variable for all my EZIO8SA relays and programs that look for an unexpected state are enabled 2) the EZIO8SA is queried and for any device that isn't as expected, ISY sends an email 3) After sending the email a second round of query testing is run for that device after a short delay to see if it changes again following the subsequent query. I guesstimate that 10% - 20% of the time there is an unexpected state, the first email is followed by a second email indicating the was now the opposite state. In addition to sending an email, ISY also writes the notification to a custom log file, which augments notifications sent by another program when the relay controlling a humidifier is turned [on /off], as well as when the query test is initiated. Here are 2 sets of custom log entries aligned with the emails shown in my 1st screenshot below. I've highlighted the messages from the Query Test (prefixed by QT). 2014/02/14, 15:36:28, Notification of Humidifier OFF ran True 2014/02/14, 15:43:51, Query HVAC Devices on Sensor ran True 2014/02/14, 15:44:01, QT-Humidifier - Notify Change ON ran because device was showing OFF when it was ON 2014/02/14, 15:44:01, Notification of Humidifier ON ran True 2014/02/14, 15:44:15, QT-Humidifier - Notify Change OFF ran because device was showing ON when it was OFF 2014/02/14, 15:44:15, Notification of Humidifier OFF ran True The above shows: 1) The last recorded activity for the humidifier prior to the query (OFF) 2) ISY is surprised to learn the humidifier is ON 3) The notification that the humidifier is now recorded as ON 4) The query test is run again (although not logged) and again ISY is surprised to learn the humidifier is now OFF 5) The notification that the humidifier is now recorded as OFF Similarly, about an hour later the following was recorded: 2014/02/14, 16:46:51, Notifiication of Humidifier ON ran True 2014/02/14, 16:52:19, Query HVAC Devices on Sensor ran True 2014/02/14, 16:52:30, QT-Basement Damper- Notify Change ON ran because device was showing OFF when it was ON 2014/02/14, 16:52:31, QT-Humidifier - Notify Change OFF ran because device was showing ON when it was OFF 2014/02/14, 16:52:31, Notifiication of Humidifier OFF ran False 2014/02/14, 16:52:45, QT-Basement Damper- Notify Change OFF ran because device was showing ON when it was OFF 2014/02/14, 16:52:46, QT-Humidifier - Notify Change ON ran because device was showing OFF when it was ON 2014/02/14, 16:52:46, Notifiication of Humidifier ON ran True 2014/02/14, 16:52:47, QT-Humidifier - Notify Change ON ran because device was showing OFF when it was ON 2014/02/14, 16:52:47, QT-Basement Damper- Notify Change OFF ran because device was showing ON when it was OFF 2014/02/14, 16:58:06, Notifiication of Humidifier OFF ran True The 1st screenshot below shows a sampling of how frequently these unexpected statuses followed by the reversal can happen. The 2nd screenshot shows a sampling of the occurrences when ISY is surprised by the status and the second query does not see the reverse. A day can could go by without me seeing the first scenario but no day has gone by since I put these test programs in a few week ago where I haven’t had a message from ISY about an unexpected EZIO8SA status. If every time an entry changed it would change back, or if every time I queried the EZIO8SA ALL relay statuses were unexpected, I would think it's only a problem with querying (unexplained messages shown by LeeG) but because that's not the case, I'm thinking something is changing the EZIO8SA relays without ISY knowing. The problem now is that - unless I'm missing something - catching this would require having to sit there for probably hours at a time and watch devices controlled by the EZIO8SA (since there are no LED's on the EZIO) alongside an ISY console session running the event viewer at level 3 hoping I actually catch it happen during the query test window and before ISY takes action that might change the device back to what ISY thinks it's supposed to be. That's about as appealing to me as the idea of watching paint dry. Smartenit can't replicate the problem and has suggested an older model PLM (pre-dual band) would fix the problem. Have asked them to send me one to try that but am concerned that these older PLMs aren't or won't be supported for much longer. While they have some inventory today, are older PLM's still being made/licensed by smartlabs? What is the longer term fix? How will an older PLM hold up with i2CS and future changes? Asking UDI to ignore the extra messages occasionally sent by the newer PLMs would presumably fix the query problem, but what about the mysterious state changes that may not be about these extra messages? Also, I didn't see anything come out to fix the duplicate messages that sometimes come from an IO Linc. How different would ignoring extra messages from an EZIO8SA be from the perspective of UDI putting something in? I'm kind of stuck because there are problems with both IO Lincs and the EZIO8SA. Are there other options available today or on the horizon, e.g. zwave?

Here's an example of a query that surprised ISY with 6 incorrect statuses, followed by the related level 3 events. It looks like there are a couple of extra queries and other messages along the way before the answer arrives, a full 10 seconds after the query and 8-9 seconds after the log entries are posted. Sat 02/08/2014 12:16:15 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 4F 02 Sat 02/08/2014 12:16:15 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 4F 02 06 IOCTL (QUERY) Sat 02/08/2014 12:16:15 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 4F 07 IOCTL (07) Sat 02/08/2014 12:16:15 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 02/08/2014 12:16:15 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Sat 02/08/2014 12:16:15 PM : [iNST-ACK ] 02 62 28.AD.BD 00 4F 02 06 IOCTL (QUERY) Sat 02/08/2014 12:16:16 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 20 B0 C7 (C7) Sat 02/08/2014 12:16:16 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=0, Hops Left=0 Sat 02/08/2014 12:16:16 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 20 B0 C7 (C7) Sat 02/08/2014 12:16:16 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=0, Hops Left=0 Sat 02/08/2014 12:16:16 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 20 4F 02 IOCTL (QUERY) Sat 02/08/2014 12:16:16 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=0, Hops Left=0 Sat 02/08/2014 12:16:25 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Sat 02/08/2014 12:16:25 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 49 00 06 RINPUT (00) Sat 02/08/2014 12:16:25 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 49 30 RINPUT (30) Sat 02/08/2014 12:16:25 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 For comparison, here's what an event trace looks like when there are no surprises. Sat 02/08/2014 12:30:51 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 4F 02 Sat 02/08/2014 12:30:51 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 4F 02 06 IOCTL (QUERY) Sat 02/08/2014 12:30:52 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 4F 13 IOCTL (13) Sat 02/08/2014 12:30:52 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 02/08/2014 12:30:52 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Sat 02/08/2014 12:30:52 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 49 00 06 RINPUT (00) Sat 02/08/2014 12:30:52 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 49 30 RINPUT (30) Sat 02/08/2014 12:30:52 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2

I got lucky today and was able to catch a blast of the problem I see intermittently with the event viewer at level 3. First let me set up what I have in place. I have up to two rounds of device querying to catch/notify me when any of my EZIO relays report a status that ISY did not have in its records. At least that's what I think I'm doing. Below are the programs involved. This first one is called by another program that gets triggered every 15 mins or when an IOLinc sensor that monitors stat call changes (today a heat call, in the summer an AC call), or when an X10 command from an IO Linc is received. The X10 command was put in as a backup. If - No Conditions - (To add one, press 'Schedule' or 'Condition') Then $iRunQueryTestAgain = 0 $sHVAC.Query.Count = 0 Run Program 'QT-Basement Damper- Set Variable' (If) Run Program 'QT-Fan ON - Set Variable' (If) Run Program 'QT-Furnace Damper- Set Variable' (If) Run Program 'QT-Office Damper- Set Variable' (If) Run Program 'QT-HRV Low - Set Variable' (If) Run Program 'QT-HRV High - Set Variable' (If) Run Program 'QT-Humidifier - Set Variable' (If) Send Notification to 'me' content 'Generic Program Log' Wait 3 seconds Set '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' Query Wait 10 seconds Run Program '1-Run Query Test Again' (If) Else - No Actions - (To add one, press 'Action') There are then seven times each of the three following programs; one for each EZIO relay I use. All variables in the three programs below are integer variables. Program 'QT-Basement Damper- Set Variable' If Status '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' is 100% Then $a.StateOfBasementDamper = 1 Wait 1 second Enable Program 'QT-Basement Damper- Notify Change ON' Enable Program 'QT-Basement Damper- Notify Change OFF' Wait 8 seconds Disable Program 'QT-Basement Damper- Notify Change ON' Disable Program 'QT-Basement Damper- Notify Change OFF' Else $a.StateOfBasementDamper = 0 Wait 1 second Enable Program 'QT-Basement Damper- Notify Change ON' Enable Program 'QT-Basement Damper- Notify Change OFF' Wait 8 seconds Disable Program 'QT-Basement Damper- Notify Change ON' Disable Program 'QT-Basement Damper- Notify Change OFF' Program 'QT-Basement Damper- Notify Change ON' If Status '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' is 100% And $a.StateOfBasementDamper is 0 Then Send Notification to 'me' content 'Device Changed ON After Query' $iRunQueryTestAgain = 1 Else - No Actions - (To add one, press 'Action') Program 'QT-Basement Damper- Notify Change OFF' If Status '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' is Off And $a.StateOfBasementDamper is 1 Then Send Notification to 'Jean' content 'Device Changed OFF After Query' $iRunQueryTestAgain = 1 Else - No Actions - (To add one, press 'Action') Should one of the latter two above run, it set $iRunQueryTestAgain = 1 for the following second round of testing If $iRunQueryTestAgain > 0 Then $sHVAC.Query.Count = 0 Run Program 'QT-Basement Damper- Set Variable' (If) Run Program 'QT-Fan ON - Set Variable' (If) Run Program 'QT-Furnace Damper- Set Variable' (If) Run Program 'QT-Office Damper- Set Variable' (If) Run Program 'QT-HRV Low - Set Variable' (If) Run Program 'QT-HRV High - Set Variable' (If) Run Program 'QT-Humidifier - Set Variable' (If) Send Notification to 'Jean' content 'Generic Program Log' Wait 3 seconds Set '1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1' Query $iRunQueryTestAgain = 0 Else - No Actions - (To add one, press 'Action') This afternoon I got a flood of notifications showing an unexpected device status from the 1st query test followed by the reverse status from the second query test. See attached screenshot at the bottom. Below are the related log events. The first chunk shows the events from one heat call change / query test prior to the (second) one that resulted in the notifications getting sent. X10 K8 Fri 2014/02/07 02:17:14 PM System Log X10 K8 Fri 2014/02/07 02:17:15 PM System Log X10 K8 Off (11) Fri 2014/02/07 02:17:15 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Fri 2014/02/07 02:17:23 PM Program Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Fri 2014/02/07 02:17:30 PM Program Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 0% Fri 2014/02/07 02:17:31 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) On 255 Fri 2014/02/07 02:17:41 PM Program Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 100% Fri 2014/02/07 02:17:41 PM System Log Scene:1-MISC (Non Lighting) / HVAC / Office Damper for Prgs On Fri 2014/02/07 02:17:44 PM Program Log 1-MISC (Non Lighting) / HVAC / Old Office Damper (for LED) Status 100% Fri 2014/02/07 02:17:44 PM System Log 1-MISC (Non Lighting) / HVAC / Office Damper-EZIO.4 Status 100% Fri 2014/02/07 02:17:44 PM System Log Scene:1-MISC (Non Lighting) / HRV / HRV High for Prgs On Fri 2014/02/07 02:17:49 PM Program Log 1-MISC (Non Lighting) / HRV / Back KPL.G - HRV High (XLink) Status 100% Fri 2014/02/07 02:17:49 PM System Log 1-MISC (Non Lighting) / HRV / Back KPL.H - HRV High (XLink) Status 100% Fri 2014/02/07 02:17:49 PM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 100% Fri 2014/02/07 02:17:49 PM System Log Scene:1-MISC (Non Lighting) / HRV / HRV Low for Prgs Off 0 Fri 2014/02/07 02:18:00 PM Program Log 1-MISC (Non Lighting) / HRV / Back KPL.F - HRV Low Status 0% Fri 2014/02/07 02:18:00 PM System Log 1-MISC (Non Lighting) / HRV / HRV Low-EZIO.5 Status 0% Fri 2014/02/07 02:18:00 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Off 0 Fri 2014/02/07 02:18:14 PM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Fri 2014/02/07 02:18:14 PM System Log And here is the set of log entries related to the notifications. X10 K8 Fri 2014/02/07 02:24:06 PM System Log X10 K8 Fri 2014/02/07 02:24:06 PM System Log X10 K8 On (3) Fri 2014/02/07 02:24:06 PM System Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status Query Fri 2014/02/07 02:24:15 PM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 100% Fri 2014/02/07 02:24:16 PM System Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 100% Fri 2014/02/07 02:24:16 PM System Log 1-MISC (Non Lighting) / HVAC / Furnace Rm Damper-EZIO.3 Status 100% Fri 2014/02/07 02:24:16 PM System Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 0% Fri 2014/02/07 02:24:16 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 0% Fri 2014/02/07 02:24:16 PM System Log Scene:1-MISC (Non Lighting) / HRV / HRV High for Prgs On Fri 2014/02/07 02:24:22 PM Program Log Scene:1-MISC (Non Lighting) / HVAC / HVAC Sensors Status Query Fri 2014/02/07 02:24:22 PM Program Log 1-MISC (Non Lighting) / HRV / HRV High-EZIO.6 Status 100% Fri 2014/02/07 02:24:22 PM System Log 1-MISC (Non Lighting) / HVAC / Sensor - Heat Off - Office Da Status 100% Fri 2014/02/07 02:24:23 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) On 255 Fri 2014/02/07 02:24:26 PM Program Log 1-MISC (Non Lighting) / HVAC / Basement Dampers - EZIO8SA.1 Status 0% Fri 2014/02/07 02:24:26 PM System Log 1-MISC (Non Lighting) / HVAC / Fan On-EZIO.2 Status 0% Fri 2014/02/07 02:24:26 PM System Log 1-MISC (Non Lighting) / HVAC / Furnace Rm Damper-EZIO.3 Status 0% Fri 2014/02/07 02:24:26 PM System Log 1-MISC (Non Lighting) / HVAC / Humidifier-EZIO.8(really7) Status 100% Fri 2014/02/07 02:24:26 PM System Log Now the related level 3 event viewer entries, the first block being what ISY would end up with in its records when the second block of commands is sent/received. Fri 02/07/2014 02:17:13 PM : [ X10] K8 Fri 02/07/2014 02:17:13 PM : [X10-RX ] 02 52 3D 00 Fri 02/07/2014 02:17:13 PM : [ X10] K8 Fri 02/07/2014 02:17:13 PM : [X10-RX ] 02 52 33 80 Fri 02/07/2014 02:17:13 PM : [ X10] K8/Off (11) Fri 02/07/2014 02:17:20 PM : [ Time] 14:17:20 1(0) Fri 02/07/2014 02:17:21 PM : [VAR 2 68 ] 0 Fri 02/07/2014 02:17:21 PM : [ Time] 14:17:21 1(0) Fri 02/07/2014 02:17:21 PM : [VAR 2 78 ] 0 Fri 02/07/2014 02:17:21 PM : [VAR 2 79 ] 0 Fri 02/07/2014 02:17:21 PM : [VAR 2 77 ] 1 Fri 02/07/2014 02:17:21 PM : [VAR 2 76 ] 0 Fri 02/07/2014 02:17:21 PM : [VAR 2 73 ] 1 Fri 02/07/2014 02:17:21 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 4F 02 Fri 02/07/2014 02:17:21 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 4F 02 06 IOCTL (QUERY) Fri 02/07/2014 02:17:22 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 4F 11 IOCTL (11) Fri 02/07/2014 02:17:22 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:22 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Fri 02/07/2014 02:17:22 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 49 00 06 RINPUT (00) Fri 02/07/2014 02:17:23 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 49 30 RINPUT (30) Fri 02/07/2014 02:17:23 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:23 PM : [ Time] 14:17:24 1(0) Fri 02/07/2014 02:17:23 PM : [ Time] 14:17:25 1(0) Fri 02/07/2014 02:17:29 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 01 Fri 02/07/2014 02:17:29 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:17:29 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D 00 (00) Fri 02/07/2014 02:17:29 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:29 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 00 Fri 02/07/2014 02:17:29 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:17:29 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D 00 (00) Fri 02/07/2014 02:17:30 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:30 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 01 Fri 02/07/2014 02:17:30 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:17:30 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Fri 02/07/2014 02:17:30 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:30 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 00 Fri 02/07/2014 02:17:30 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:17:30 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Fri 02/07/2014 02:17:31 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:31 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 01 Fri 02/07/2014 02:17:31 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:17:31 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 01 (01) Fri 02/07/2014 02:17:31 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:31 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 00 Fri 02/07/2014 02:17:31 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:17:31 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 FF (FF) Fri 02/07/2014 02:17:32 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:32 PM : [ Time] 14:17:33 1(0) Fri 02/07/2014 02:17:32 PM : [ Time] 14:17:33 1(0) Fri 02/07/2014 02:17:33 PM : [ 15 BB 45 1] ST 0 Fri 02/07/2014 02:17:33 PM : [VAR 2 52 ] -670 Fri 02/07/2014 02:17:33 PM : [VAR 2 12 ] -1 Fri 02/07/2014 02:17:35 PM : [VAR 2 58 ] 1756 Fri 02/07/2014 02:17:35 PM : [VAR 2 70 ] 1912 Fri 02/07/2014 02:17:37 PM : [VAR 2 59 ] 1962 Fri 02/07/2014 02:17:37 PM : [VAR 2 70 ] 1910 Fri 02/07/2014 02:17:39 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 45 07 Fri 02/07/2014 02:17:39 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 45 07 06 IOON (07) Fri 02/07/2014 02:17:40 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 45 91 IOON (91) Fri 02/07/2014 02:17:40 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:17:40 PM : [ 28 AD BD 8] ST 255 Fri 02/07/2014 02:17:41 PM : [VAR 2 50 ] 2106 Fri 02/07/2014 02:17:43 PM : [iNST-TX-I1 ] 02 62 00 00 16 CF 11 00 Fri 02/07/2014 02:17:43 PM : [ Time] 14:17:44 1(0) Fri 02/07/2014 02:17:43 PM : [iNST-ACK ] 02 62 00.00.16 CF 11 00 06 LTONRR (00) Fri 02/07/2014 02:17:43 PM : [ 15 BB 45 2] ST 255 Fri 02/07/2014 02:17:43 PM : [ 28 AD BD 4] ST 255 Fri 02/07/2014 02:17:43 PM : [VAR 2 62 ] 481 Fri 02/07/2014 02:17:45 PM : [VAR 2 60 ] 2006 Fri 02/07/2014 02:17:45 PM : [VAR 2 70 ] 1908 Fri 02/07/2014 02:17:49 PM : [ Time] 14:17:48 1(0) Fri 02/07/2014 02:17:49 PM : [iNST-TX-I1 ] 02 62 00 00 20 CF 11 00 Fri 02/07/2014 02:17:49 PM : [iNST-ACK ] 02 62 00.00.20 CF 11 00 06 LTONRR (00) Fri 02/07/2014 02:17:49 PM : [VAR 2 42 ] 1 Fri 02/07/2014 02:17:49 PM : [ 20 43 49 7] ST 255 Fri 02/07/2014 02:17:49 PM : [ 20 43 49 8] ST 255 Fri 02/07/2014 02:17:49 PM : [ 28 AD BD 6] ST 255 Fri 02/07/2014 02:17:49 PM : [ Time] 14:17:50 1(0) Fri 02/07/2014 02:17:49 PM : [VAR 2 68 ] 1 Fri 02/07/2014 02:17:50 PM : [VAR 2 33 ] 152 Fri 02/07/2014 02:17:53 PM : [VAR 2 32 ] 56 Fri 02/07/2014 02:17:59 PM : [iNST-TX-I1 ] 02 62 00 00 1F CF 13 00 Fri 02/07/2014 02:17:59 PM : [VAR 2 38 ] 0 Fri 02/07/2014 02:17:59 PM : [iNST-ACK ] 02 62 00.00.1F CF 13 00 06 LTOFFRR(00) Fri 02/07/2014 02:17:59 PM : [ 20 43 49 6] ST 0 Fri 02/07/2014 02:17:59 PM : [ 28 AD BD 5] ST 0 Fri 02/07/2014 02:18:02 PM : [VAR 2 12 ] 1 Fri 02/07/2014 02:18:12 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 46 00 Fri 02/07/2014 02:18:12 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 46 00 06 IOOFF (00) Fri 02/07/2014 02:18:13 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 46 A8 IOOFF (A8) Fri 02/07/2014 02:18:13 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:18:13 PM : [ 28 AD BD 1] ST 0 Fri 02/07/2014 02:24:03 PM : [ X10] K8 Fri 02/07/2014 02:24:04 PM : [X10-RX ] 02 52 3D 00 Fri 02/07/2014 02:24:04 PM : [ X10] K8 Fri 02/07/2014 02:24:04 PM : [X10-RX ] 02 52 32 80 Fri 02/07/2014 02:24:04 PM : [ X10] K8/On (3) Fri 02/07/2014 02:24:13 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 4F 02 Fri 02/07/2014 02:24:13 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 4F 02 06 IOCTL (QUERY) Fri 02/07/2014 02:24:13 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 4F A8 IOCTL (A8) Fri 02/07/2014 02:24:13 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:13 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Fri 02/07/2014 02:24:13 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 49 00 06 RINPUT (00) Fri 02/07/2014 02:24:13 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 49 30 RINPUT (30) Fri 02/07/2014 02:24:13 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:15 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 21 89 0F (0F) Fri 02/07/2014 02:24:15 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=1, Hops Left=0 Fri 02/07/2014 02:24:15 PM : [ 28 AD BD 1] ST 255 Fri 02/07/2014 02:24:15 PM : [ 28 AD BD 2] ST 255 Fri 02/07/2014 02:24:15 PM : [ 28 AD BD 3] ST 255 Fri 02/07/2014 02:24:15 PM : [ 28 AD BD 6] ST 0 Fri 02/07/2014 02:24:15 PM : [ 28 AD BD 8] ST 0 Fri 02/07/2014 02:24:20 PM : [iNST-TX-I1 ] 02 62 00 00 20 CF 11 00 Fri 02/07/2014 02:24:20 PM : [iNST-ACK ] 02 62 00.00.20 CF 11 00 06 LTONRR (00) Fri 02/07/2014 02:24:20 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 01 Fri 02/07/2014 02:24:20 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:24:20 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D 01 (01) Fri 02/07/2014 02:24:20 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:20 PM : [iNST-TX-I1 ] 02 62 15 BB 45 0F 19 00 Fri 02/07/2014 02:24:20 PM : [iNST-ACK ] 02 62 15.BB.45 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:24:21 PM : [iNST-SRX ] 02 50 15.BB.45 24.1B.FE 2B 0D FF (FF) Fri 02/07/2014 02:24:21 PM : [std-Direct Ack] 15.BB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:21 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 01 Fri 02/07/2014 02:24:21 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:24:21 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Fri 02/07/2014 02:24:21 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:21 PM : [iNST-TX-I1 ] 02 62 15 BB 62 0F 19 00 Fri 02/07/2014 02:24:21 PM : [iNST-ACK ] 02 62 15.BB.62 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:24:22 PM : [iNST-SRX ] 02 50 15.BB.62 24.1B.FE 2B 08 00 (00) Fri 02/07/2014 02:24:22 PM : [std-Direct Ack] 15.BB.62-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:22 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 01 Fri 02/07/2014 02:24:22 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 01 06 LTSREQ (01) Fri 02/07/2014 02:24:22 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 01 (01) Fri 02/07/2014 02:24:22 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:22 PM : [iNST-TX-I1 ] 02 62 17 71 EA 0F 19 00 Fri 02/07/2014 02:24:22 PM : [iNST-ACK ] 02 62 17.71.EA 0F 19 00 06 LTSREQ (LIGHT) Fri 02/07/2014 02:24:23 PM : [iNST-SRX ] 02 50 17.71.EA 24.1B.FE 2B 00 FF (FF) Fri 02/07/2014 02:24:23 PM : [std-Direct Ack] 17.71.EA-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:23 PM : [ Time] 14:24:25 1(0) Fri 02/07/2014 02:24:23 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 45 07 Fri 02/07/2014 02:24:23 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 45 07 06 IOON (07) Fri 02/07/2014 02:24:24 PM : [ Time] 14:24:26 1(0) Fri 02/07/2014 02:24:24 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 45 A8 IOON (A8) Fri 02/07/2014 02:24:24 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:24 PM : [ 28 AD BD 6] ST 255 Fri 02/07/2014 02:24:25 PM : [ 15 BB 45 1] ST 255 Fri 02/07/2014 02:24:26 PM : [ 28 AD BD 1] ST 0 Fri 02/07/2014 02:24:26 PM : [ 28 AD BD 2] ST 0 Fri 02/07/2014 02:24:27 PM : [ 28 AD BD 3] ST 0 Fri 02/07/2014 02:24:27 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 4F 02 Fri 02/07/2014 02:24:27 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 4F 02 06 IOCTL (QUERY) Fri 02/07/2014 02:24:27 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 4F A8 IOCTL (A8) Fri 02/07/2014 02:24:27 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:27 PM : [iNST-TX-I1 ] 02 62 28 AD BD 0F 49 00 Fri 02/07/2014 02:24:27 PM : [iNST-ACK ] 02 62 28.AD.BD 0F 49 00 06 RINPUT (00) Fri 02/07/2014 02:24:27 PM : [iNST-SRX ] 02 50 28.AD.BD 24.1B.FE 2B 49 30 RINPUT (30) Fri 02/07/2014 02:24:27 PM : [std-Direct Ack] 28.AD.BD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Fri 02/07/2014 02:24:29 PM : [ 28 AD BD 8] ST 255 Any insight into what's happening would be appreciated. Hopefully I've provided everything that's available but if not, please let me know.

Why then does the event log have a status entry for Front Light Companion Sw, one of the devices in the scene, right after calling that scene? Scene:All ON/OFF Downstairs Off 0 Mon 2014/02/03 10:50:50 PM Program Log Main Floor / Front Light Companion Sw Status 0% Mon 2014/02/03 10:50:50 PM System Log

wait, I take that back. The device that made me look into this (master bedroom ceiling light) and the boys bedroom ceiling light are not part of that scene.

Yes, I did, and it explains (or likely explains) the situation when the response is related to IO Lincs. I also have an explanation for the Front Inside Light That leaves the following unexplained: From my original post: Scene:My Lighting Status Query Sun 2014/02/02 03:00:03 AM Program Log Main Floor / Front Light Companion Sw Status 100% Sun 2014/02/02 03:00:30 AM System Log From my more recent post: Scene:My Lighting Status Query Sun 2014/01/19 04:45:13 PM Program Log X10 G9 Status = On ( Sun 2014/01/19 04:45:14 PM System Log X10 Transceiver Module Outlet Status 100% Sun 2014/01/19 04:45:14 PM System Log Now let me add to that what happened for the query all the night before last: Scene:My Lighting Status Query Mon 2014/02/03 03:00:03 AM Program Log Kitchen / Family Rm Wall Light Status 0% Mon 2014/02/03 03:00:24 AM System Log Kitchen / Kitchen Sink Area Light Status 0% Mon 2014/02/03 03:00:24 AM System Log Main Floor / Front Light Companion Sw Status 0% Mon 2014/02/03 03:00:31 AM System Log Kitchen / Bookcase Lights Status 0% Mon 2014/02/03 03:00:45 AM System Log X10 G9 Mon 2014/02/03 03:00:47 AM Program Log X10 G9 Status Request (10) Mon 2014/02/03 03:00:47 AM Program Log All the above, except for the X10 devices, were turned off by the All ON/OFF Downstairs scene earlier that evening. Here's the log showing that event Main Floor / FrontKPL.H-Sleep Status 100% Mon 2014/02/03 10:50:49 PM System Log Scene:All ON/OFF Downstairs Off 0 Mon 2014/02/03 10:50:50 PM Program Log Main Floor / Front Light Companion Sw Status 0% Mon 2014/02/03 10:50:50 PM System Log If I read this right, the only device that was turned off was Front Light Companion Sw and all the other devices in the scene were already OFF and therefore not logged. (The scene has 10 devices in it.) Other than for X10 devices, a common thread so far for the unexpected/unexplained statuses seems to be devices that are turned off by the scene All ON/OFF Downstairs. I suppose the other thing left unexplained is why ISY rebooted itself but I guess that really should be a separate thread (that I'm going to pass on at this point since I didn't catch it when it happened and there's little to go on other than what I've provided in my original post.)

Xathros, thanks for the screenshot. Although I was confused about the two query all's when I expected only one, it dawned on me from your screenshot why that happened. I have "Catch up schedules at Restart". See screenshot below. I guess if I wanted to prevent the query all from running a second time after a restart, I would have to either schedule it for 11:59:59 PM since the catch up is from 00:00:00 the day of the restart. That or turn off "Query at restart" and schedule the query all for shortly after midnight so a restart before 3AM - although unlikely - won't miss running it. The second thing that confused me was the fact that a second query all 3 minutes after the first returned some different statuses. While I know the 1D.5B.9E-Relay is my problematic v41 IO Linc that always responds (and is neither trigger reversed, nor in momentary mode), the status of the other devices should have been the same for the two queries. Scene:My Lighting Status Query Sun 2014/01/19 04:45:13 PM Program Log X10 G9 Status = On ( Sun 2014/01/19 04:45:14 PM System Log X10 Transceiver Module Outlet Status 100% Sun 2014/01/19 04:45:14 PM System Log 15 B9 70 2 Status 0% Sun 2014/01/19 04:45:24 PM System Log 1D.5B.9E-Relay Status 100% Sun 2014/01/19 04:45:49 PM System Log