LeeG Posted February 21, 2013 Posted February 21, 2013 Swnewman That is actually good news that both I/O Lincs show the same out of sync Sensor state. That tends to eliminate a wiring problem to the magnetic switches since each is wired independently. Also that a Query of each I/O Linc did fix both Sensor states. At least when the manual Query was issued both I/O Lincs reported accurate Sensor state information. Although not providing the answer to the cause itself both I/O Linc Sensors being out of sync tends to eliminate wiring and an individual I/O Linc failure. Can you post the ISY Log that covers the time from before the automatic Query was issued to the time where the KPL button LED was found On. Indicate the Insteon addresses of the I/O Linc.
swnewman Posted February 21, 2013 Author Posted February 21, 2013 Yeah, what's the best way to do that? I suppose there is "event viewer"-type data logged already on the ISY device itself?
LeeG Posted February 21, 2013 Posted February 21, 2013 swnewman The ISY Log is a function separate from the event viewer. Under Tools | Log select Log. A popup will prompt for displaying in a spread sheet. Select No. Another popup will solicit the location to store the ISY Log file as .txt Each line is time stamped. Select some lines starting before the automatic Query through the time when the KPL button was found On. Paste those lines to a forum post.
swnewman Posted February 21, 2013 Author Posted February 21, 2013 Got it. I'm not at home so I wasn't able to look at the menu options. I'll try to do this tonight.
swnewman Posted February 22, 2013 Author Posted February 22, 2013 Below you can see the log from this morning. I bolded the two IOLincs in question. You can see the query all, as well as the manual queries I did after I noticed the problem. The last time one of these IOLincs changed value by physical interaction was this the night before: GarageDoorMain_Sensor Status 100% Wed 2013/02/20 07:42:57 PM System Log ... MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 03:07:32 AM System Log Scene:1st Floor Scenes / MasterBathWC On 255 Thu 2013/02/21 03:07:32 AM Program Log MasterBathWC_Dimmer Status 35% Thu 2013/02/21 03:07:33 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 03:07:44 AM System Log Scene:1st Floor Scenes / MasterBathWC Off 0 Thu 2013/02/21 03:07:44 AM Program Log MasterBathWC_Dimmer Status 0% Thu 2013/02/21 03:07:44 AM System Log MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 03:08:45 AM System Log Scene:1st Floor Scenes / MasterBathWC On 255 Thu 2013/02/21 03:08:45 AM Program Log MasterBathWC_Dimmer Status 35% Thu 2013/02/21 03:08:46 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 03:08:48 AM System Log Scene:1st Floor Scenes / MasterBathWC Off 0 Thu 2013/02/21 03:08:48 AM Program Log MasterBathWC_Dimmer Status 0% Thu 2013/02/21 03:08:49 AM System Log Scene:ISY Status Query Thu 2013/02/21 04:00:00 AM Program Log GarageDoor3rd_Sensor Status 0% Thu 2013/02/21 04:00:10 AM System Log GarageDoorMain_Sensor Status 0% Thu 2013/02/21 04:00:12 AM System Log Scene:1st Floor Scenes / GarageDoorStatus On 255 Thu 2013/02/21 04:00:40 AM Program Log Foyer_KeypadSw_EaveLights / Foyer_KeypadSw - B Status 100% Thu 2013/02/21 04:00:40 AM System Log MainHallway_KeypadSw_Lights / MainHallway_KeypadSw - B Status 100% Thu 2013/02/21 04:00:40 AM System Log Scene:1st Floor Scenes / GarageDoorStatus On 255 Thu 2013/02/21 04:00:40 AM Program Log AtticFans_InlineSwitch Off 0 Thu 2013/02/21 06:27:57 AM Program Log Scene:1st Floor Scenes / MasterBedroomLampHis On 255 Thu 2013/02/21 06:50:05 AM Program Log MasterBedroom_KeypadDm_Lights / MasterBedroom_KeypadDm_D Status 100% Thu 2013/02/21 06:50:05 AM System Log MasterBedroomLampHis_OutletDm Status 100% Thu 2013/02/21 06:50:05 AM System Log BedsideRemote_Keypad / BedsideRemote_D Status 100% Thu 2013/02/21 06:50:05 AM System Log Scene:1st Floor Scenes / MasterBedroomButton On 255 Thu 2013/02/21 06:50:05 AM Program Log MainHallway_KeypadSw_Lights / MainHallway_KeypadSw - D Status 100% Thu 2013/02/21 06:50:06 AM System Log FamilyRoom_KeypadDm_Lights / FamilyRoom_KeypadDm - H Status 100% Thu 2013/02/21 06:50:06 AM System Log MasterBedroom_KeypadDm_Lights / MasterBedroom_KeypadDm_D Status 0% Thu 2013/02/21 07:08:52 AM System Log MasterBedroomLampHis_OutletDm Status 0% Thu 2013/02/21 07:08:52 AM System Log BedsideRemote_Keypad / BedsideRemote_D Status 0% Thu 2013/02/21 07:08:52 AM System Log Scene:1st Floor Scenes / MasterBedroomButton Off 0 Thu 2013/02/21 07:08:52 AM Program Log MainHallway_KeypadSw_Lights / MainHallway_KeypadSw - D Status 0% Thu 2013/02/21 07:08:53 AM System Log FamilyRoom_KeypadDm_Lights / FamilyRoom_KeypadDm - H Status 0% Thu 2013/02/21 07:08:53 AM System Log MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 07:09:38 AM System Log MasterWalkinCloset_Dimmer Status 100% Thu 2013/02/21 07:14:19 AM System Log MasterCloset_Motion-Sensor Status 100% Thu 2013/02/21 07:14:19 AM System Log MasterWalkinCloset_Dimmer Status 0% Thu 2013/02/21 07:14:49 AM System Log MasterCloset_Motion-Sensor Status 0% Thu 2013/02/21 07:14:49 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 07:16:50 AM System Log MasterWalkinCloset_Dimmer Status 100% Thu 2013/02/21 07:17:06 AM System Log MasterCloset_Motion-Sensor Status 100% Thu 2013/02/21 07:17:06 AM System Log MasterWalkinCloset_Dimmer Status 0% Thu 2013/02/21 07:18:11 AM System Log MasterCloset_Motion-Sensor Status 0% Thu 2013/02/21 07:18:11 AM System Log Scene:1st Floor Scenes / MasterBedroomLampHis Off 0 Thu 2013/02/21 07:20:18 AM Program Log MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 07:20:44 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 07:20:48 AM System Log MasterBathWC_Dimmer Status 100% Thu 2013/02/21 07:21:00 AM System Log MasterBathWC_Dimmer Status 0% Thu 2013/02/21 07:23:52 AM System Log MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 07:23:57 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 07:24:00 AM System Log MasterBathDoor_Sensor-Opened Status 100% Thu 2013/02/21 07:29:50 AM System Log MasterBathroomVanity_Dimmer Status 100% Thu 2013/02/21 07:30:30 AM System Log MasterWalkinCloset_Dimmer Status 100% Thu 2013/02/21 07:38:18 AM System Log MasterCloset_Motion-Sensor Status 100% Thu 2013/02/21 07:38:18 AM System Log MasterWalkinCloset_Dimmer Status 0% Thu 2013/02/21 07:39:34 AM System Log MasterCloset_Motion-Sensor Status 0% Thu 2013/02/21 07:39:34 AM System Log MasterWalkinCloset_Dimmer Status 100% Thu 2013/02/21 07:41:20 AM System Log MasterCloset_Motion-Sensor Status 100% Thu 2013/02/21 07:41:20 AM System Log MasterWalkinCloset_Dimmer Status 0% Thu 2013/02/21 07:42:55 AM System Log MasterCloset_Motion-Sensor Status 0% Thu 2013/02/21 07:42:55 AM System Log MasterWalkinCloset_Dimmer Status 100% Thu 2013/02/21 07:43:28 AM System Log MasterCloset_Motion-Sensor Status 100% Thu 2013/02/21 07:43:28 AM System Log MasterWalkinCloset_Dimmer Status 0% Thu 2013/02/21 07:43:58 AM System Log MasterCloset_Motion-Sensor Status 0% Thu 2013/02/21 07:43:58 AM System Log MasterBathroomVanity_Dimmer Status 0% Thu 2013/02/21 07:53:00 AM System Log MasterBathDoor_Sensor-Opened Status 0% Thu 2013/02/21 07:53:05 AM System Log GarageEntry_Sensor-Opened Status 100% Thu 2013/02/21 07:55:22 AM System Log GarageEntry_Sensor-Opened Status 0% Thu 2013/02/21 07:55:56 AM System Log GarageDoor3rd_Sensor Status Query Thu 2013/02/21 07:58:20 AM Web Log Scene:1st Floor Scenes / GarageDoorStatus On 255 Thu 2013/02/21 07:58:20 AM Program Log GarageDoor3rd_Sensor Status 100% Thu 2013/02/21 07:58:20 AM System Log GarageDoorMain_Sensor Status Query Thu 2013/02/21 07:58:53 AM Web Log Scene:1st Floor Scenes / GarageDoorStatus Off 0 Thu 2013/02/21 07:58:53 AM Program Log GarageDoorMain_Sensor Status 100% Thu 2013/02/21 07:58:54 AM System Log Foyer_KeypadSw_EaveLights / Foyer_KeypadSw - B Status 0% Thu 2013/02/21 07:58:54 AM System Log MainHallway_KeypadSw_Lights / MainHallway_KeypadSw - B Status 0% Thu 2013/02/21 07:58:54 AM System Log
LeeG Posted February 22, 2013 Posted February 22, 2013 The next step is to have the Event Viewer running at LEVEL 3 covering from when the doors were last closed even if opened/closed to generate Sensor messages when Event Viewer started through the 4AM Query so the actual I/O Linc Query and response can be seen. The ISY Log certainly indicates the both I/O Linc Sensors reported Off during that Query and the KPL button turned On..
swnewman Posted February 22, 2013 Author Posted February 22, 2013 Ok. I guess I'll have to set up my PC so it doesn't hibernate. Of course I'll have it set up to record and it won't happen again for a week.
LeeG Posted February 22, 2013 Posted February 22, 2013 I traced the 3AM Query on my system this morning. Had one I/O Linc Sensor On and the other I/O Linc Sensor Off just to see what the trace of Query produced. No unexpected state results. The only thing I noticed is the Query is done twice, once for each I/O Linc node. Since a Query of either I/O Linc node produces a physical query command to both Sensor and Relay nodes the effect is the I/O Linc Sensor node along with the Relay node are queried twice. Nothing wrong with that as both queries produced the same state information. I note it because it is the only thing I saw in the trace that was not expected. There is some law in nature that when the event is watched it will not fail. Let’s hope yours will fail sooner than later. I'll run my trace again tonight but I don't see the change in Sensor state so I don't expect the 3AM trace here to show anything new.
Michel Kohanim Posted February 23, 2013 Posted February 23, 2013 Hi LeeG, Thanks so very much! BugID #58. With kind regards, Michel
swnewman Posted February 28, 2013 Author Posted February 28, 2013 Ok, I was finally able to record this event during the Query All. I have attached the log. During this particular one, the anomaly only happened to one of the IOLincs. But as we have discovered it is possible to have them both switch. In the log I have indented the lines where the offending IOLinc's address (1F.C4.AE) is found. The other IOLinc is 1F.C5.CF. Around 10:15 last night, I manually queried both devices. All good. At 4am the Query All fires, and a little while after 1F.C4.AE switches values. It looks like it happened about a minute after the initial communication for some reason, but I have seen it occurring closer to 4am as well. At around 7:15 this morning, I notice the anomaly and do a manual query on 1F.C4.AE. It corrects itself and I save the log. Thanks to anyone who can take a look at this and tell me what's going on. I am about ready to kill the Query All because it seems to only cause this headache. ISY-Events-Log.v4.0.1__Thu 2013.02.28 07.16.48 AM.txt
LeeG Posted February 28, 2013 Posted February 28, 2013 swnewman Thanks for that trace. It provided the answer. The Insteon mesh network is creating multiple response messages from a single Query request. The later duplicates come back with a Hops Left=0 suggesting it took longer for them to arrive at the PLM. Normally the PLM will not pass the duplicates but we have seen cases where it does and this is one of those cases. The extra messages come back from the Query of the Relay so they show Off. However, the first duplicate with Hops Left=0 comes back after the ISY has queried the Sensor. The duplicate Relay Query response looks like it is the response to the Sensor Query so the Sensor is marked Off. Thu 02/28/2013 04:00:10 AM : [iNST-TX-I1 ] 02 62 1F C4 AE 0F 19 01 QUERY SENSOR Thu 02/28/2013 04:00:11 AM : [iNST-ACK ] 02 62 1F.C4.AE 0F 19 01 06 LTSREQ (01) Thu 02/28/2013 04:00:11 AM : [iNST-SRX ] 02 50 1F.C4.AE 21.31.26 2B 28 01 SET-MSB(01) Thu 02/28/2013 04:00:11 AM : [std-Direct Ack] 1F.C4.AE-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 02/28/2013 04:00:11 AM : [iNST-TX-I1 ] 02 62 1F C4 AE 0F 19 00 QUERY RELAY Thu 02/28/2013 04:00:11 AM : [iNST-ACK ] 02 62 1F.C4.AE 0F 19 00 06 LTSREQ (LIGHT) Thu 02/28/2013 04:00:11 AM : [iNST-SRX ] 02 50 1F.C4.AE 21.31.26 2B 28 00 SET-MSB(00) FIRST RELAY QUERY RESPONSE Thu 02/28/2013 04:00:11 AM : [std-Direct Ack] 1F.C4.AE-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 02/28/2013 04:00:11 AM : [iNST-TX-I1 ] 02 62 1F C4 AE 0F 19 01 QUERY SENSOR Thu 02/28/2013 04:00:12 AM : [iNST-ACK ] 02 62 1F.C4.AE 0F 19 01 06 LTSREQ (01) Thu 02/28/2013 04:00:12 AM : [iNST-SRX ] 02 50 1F.C4.AE 21.31.26 23 28 00 SET-MSB(00) DUPLICATE RELAY QUERY RESPONSE AFTER QUERY SENSOR ISSUED Thu 02/28/2013 04:00:12 AM : [std-Direct Ack] 1F.C4.AE-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 The other I/O Linc also has these duplicate responses. In this trace the duplicate Query Relay responses from the other I/O Linc came back after the correct responses had been processed so they caused no harm. Thu 02/28/2013 04:00:10 AM : [iNST-TX-I1 ] 02 62 1F C5 CF 0F 19 00 RELAY QUERY Thu 02/28/2013 04:00:10 AM : [iNST-ACK ] 02 62 1F.C5.CF 0F 19 00 06 LTSREQ (LIGHT) Thu 02/28/2013 04:00:10 AM : [iNST-SRX ] 02 50 1F.C5.CF 21.31.26 2B 08 00 (00) RELAY QUERY FIRST RESPONSE Thu 02/28/2013 04:00:10 AM : [std-Direct Ack] 1F.C5.CF-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Thu 02/28/2013 04:00:11 AM : [iNST-SRX ] 02 50 1F.C5.CF 21.31.26 23 08 00 (00) RELAY QUERY DUPLICATE RESPONSE Thu 02/28/2013 04:00:11 AM : [std-Direct Ack] 1F.C5.CF-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Thu 02/28/2013 04:00:11 AM : [iNST-SRX ] 02 50 1F.C5.CF 21.31.26 23 08 00 (00) RELAY QUERY DUPLICATE RESPONSE Thu 02/28/2013 04:00:11 AM : [std-Direct Ack] 1F.C5.CF-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Of course the question is how can these duplicates be eliminated. Could try plugging an Access Point at the same location as the I/O Lincs. I do not know if this will help or hurt.
swnewman Posted February 28, 2013 Author Posted February 28, 2013 Lee, Interesting! Thanks for your help. So is this not something that could be weeded out with an ISY update? I agree it sounds like a bug in the mesh/insteon protocol, but that may be more difficult to get a solution for. Seems like ISY could be updated to ignore responses from devices that it has already gotten a response from (at least during the query all function). Thoughts? -Seth Edit: Shouldn't ISY know the difference between Sensor and Relay node responses? I thought each was appended with a 1 or 2 respectively.
LeeG Posted February 28, 2013 Posted February 28, 2013 "Seems like ISY could be updated to ignore responses from devices that it has already gotten a response from (at least during the query all function)." That is exactly what the ISY did. What I can see as a duplicate Relay Query response by visual analysis of the entirety of the event trace the ISY took as the Query Sensor response. They look exactly the same. "Shouldn't ISY know the difference between Sensor and Relay node responses? " This is the Query response from the Query of the Sensor Wed 02/27/2013 10:15:39 PM : [iNST-SRX ] 02 50 1F.C4.AE 21.31.26 2B 28 01 SET-MSB(01) This is the Query response from the Query of the Relay Wed 02/27/2013 10:15:39 PM : [iNST-SRX ] 02 50 1F.C4.AE 21.31.26 2B 28 00 SET-MSB(00) Except for one indicating On and the other indicating Off they are exactly the same. The following real Sensor Query response is ignored, as you suggest it should, as from the ISY perspective the Sensor Query response has already been processed. EDIT: it is possible if the ISY stops issuing a query for both the Sensor Node and the Relay node during the Query All the duplicates may not cause a problem. Michel did open bug ID #58 (see earlier post this same topic). This would not eliminate the duplicate responses so what other problems the duplicate messages might cause will still exist.
swnewman Posted February 28, 2013 Author Posted February 28, 2013 Ok, I see what you're saying. I just remembered seeing this this morning and figured it was differentiating the Sensor and the Relay in all the messages. Thu 02/28/2013 07:16:51 AM : [ 1F C4 AE 1] ST 255 Thu 02/28/2013 07:16:52 AM : [ 1F C4 AE 2] ST 0 So it seems there is a legitimate problem here, in that the PLM should not be passing these dupe messages on to the ISY. That seems like basic mesh networking. The messages bounce around from device to device potentially reaching the PLM at multiple times and at different times. The PLM only needs one of them and should ignore the others. Aside from that, the issue in ISY is that two distinct services (the Relay and the Sensor) have the same identifier, and only differentiate from each other by time, ie query one, get response, query the next, get response. If this delicate balance gets disrupted, the ISY no longer knows what's what. -Seth Edit: I think Bug ID#58 should resolve the problem. Just query the physical device once, makes sense to me. Not sure if maybe there might be a better way to differentiate the two nodes in ISY. I guess it can only work with the messages it is given though. Where should the duplicate message issue be raised? Smarthome? Maybe they could revisit how they differentiate the two nodes in the Insteon protocol?
LeeG Posted February 28, 2013 Posted February 28, 2013 "So it seems there is a legitimate problem here, in that the PLM should not be passing these dupe messages on to the ISY. That seems like basic mesh networking. The messages bounce around from device to device potentially reaching the PLM at multiple times and at different times. The PLM only needs one of them and should ignore the others." I think so. What is the PLM firmware level? Tools | Diagnostics | PLM Info/Status displayed the PLM firmware level. Could be an issue a later PLM firmware has addressed, or not. SmartLabs does not publish a list of defects and fixes so this would not be something generally known. Also this may not be easily resolved by the PLM as it has been given the next serial command so the duplicate message appears as a valid response from its perspective. "Aside from that, the issue in ISY is that two distinct services (the Relay and the Sensor) have the same identifier, and only differentiate from each other by time, ie query one, get response, query the next, get response. If this delicate balance gets disrupted, the ISY no longer knows what's what." That is accurate. Normally though messages do not come out of sequence. In this case they are not actually out of sequence. The Relay Query response comes as it should, after the device has been asked to provide that status, and the Sensor Query response comes as it should, after the device has been asked to provide that status. This issue here is that an additional message is being received rather than the real responses coming out of sequence. See the EDIT is added to my previous post.
swnewman Posted February 28, 2013 Author Posted February 28, 2013 See my edit as well I'll check the firmware version tonight. Thanks for all your help. I think I'll disable the Query All until #58 is resolved. -Seth
LeeG Posted February 28, 2013 Posted February 28, 2013 Direct feedback to SmartLabs/Smarthome is difficult. The Smarthome forum is a user-to-user forum, not meant to be a mechanism for communication with either company. There is an Insteon Gold Line phone number which Smarthome provides for assistance with Insteon products. Changing the basic Query response content is very unlikely. That command response structure has existed since the beginning of Insteon. The impact on all the applications that are written to that structure would make changes for all practical purposes impossible. Also there is no unused space in the Standard ACK structure for additional information. There are folks who have disabled the Query All. If the Insteon Mesh network is otherwise providing accurate device state information throughout the day the Query All is redundant.
belias Posted March 4, 2013 Posted March 4, 2013 I'm having the same issue with IOLinc's configured for garage door monitoring / controlling. The Query All (at 3:00am) will occasionally cause one of the IOLinc's to report "OFF" (indicating an open garage door) even when the sensor is ON. Opening the admin console and manually querying the IOLinc corrects the sensor status. I'm running 4.0.2 and my PLM is v.92 Not sure if it helps at this point, but here's the Level 3 trace for the manual query that I ran this morning to correct the sensor status: Mon 03/04/2013 07:09:13 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 01 Mon 03/04/2013 07:09:13 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 01 06 LTSREQ (01) Mon 03/04/2013 07:09:13 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 27 00 01 (01) Mon 03/04/2013 07:09:13 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=1 Mon 03/04/2013 07:09:13 AM : [ 13 32 2D 1] ST 255 Mon 03/04/2013 07:09:14 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 00 Mon 03/04/2013 07:09:14 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 00 06 LTSREQ (LIGHT) Mon 03/04/2013 07:09:14 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 00 (00) Mon 03/04/2013 07:09:14 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Mon 03/04/2013 07:09:14 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 23 00 00 (00) Mon 03/04/2013 07:09:14 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Mon 03/04/2013 07:09:15 AM : [iNST-TX-I1 ] 02 62 00 00 17 CF 13 00 Mon 03/04/2013 07:09:15 AM : [iNST-ACK ] 02 62 00.00.17 CF 13 00 06 LTOFFRR(00) Mon 03/04/2013 07:09:15 AM : [ 12 3C A5 8] ST 0 Mon 03/04/2013 07:09:15 AM : [ 14 73 2 6] ST 0 Also, I noticed the "Hops Left = 0" on the last response, which is a bit strange since I usually have no comm. issues in that part of the house, so I ran a few more manual queries from the admin console. They all resulted in the following: Mon 03/04/2013 07:31:13 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 01 Mon 03/04/2013 07:31:13 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 01 06 LTSREQ (01) Mon 03/04/2013 07:31:14 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 01 (01) Mon 03/04/2013 07:31:14 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Mon 03/04/2013 07:31:14 AM : [ 13 32 2D 1] ST 255 Mon 03/04/2013 07:31:14 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 00 Mon 03/04/2013 07:31:14 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 00 06 LTSREQ (LIGHT) Mon 03/04/2013 07:31:14 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 00 (00) Mon 03/04/2013 07:31:14 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Mon 03/04/2013 07:31:15 AM : [iNST-TX-I1 ] 02 62 00 00 17 CF 13 00 Mon 03/04/2013 07:31:15 AM : [iNST-ACK ] 02 62 00.00.17 CF 13 00 06 LTOFFRR(00) Thanks.
LeeG Posted March 4, 2013 Posted March 4, 2013 belias Thanks for that trace data. If that happened at the 3AM Query it could have affected what state the Sensor was marked as. This looks so much like the other user I am wondering if this is an I/O Linc query anomaly rather than a network issue. What is the I/O Linc firmware level? Although in the manual query trace the hops left count is moving around, 1 then 2 then 0 so there may be something interfering with the I/O Linc location.
belias Posted March 4, 2013 Posted March 4, 2013 LeeG, The IOLinc is a v.36. Another thing that's interesting: I have a few more v.36 IOLinc's around the house (for purposes other than garage doors) and they NEVER report a false status. Only difference is the garage door units are always in a "Sensor ON" state for the 3am query. I wonder if this has anything to do with it? - Brian
LeeG Posted March 4, 2013 Posted March 4, 2013 It would make a difference. If the 3AM message sequence is the same as the other user, a duplicate Relay Query status message looks like the Sensor Query response. Since the Relay is Off in a garage door situation, the Sensor is marked Off. If the Sensor was normally Off at 3AM the status would not be changed by the odd message sequence.
belias Posted March 9, 2013 Posted March 9, 2013 Just wanted to add... I created a script to notify me when the 3AM query results in an incorrect status. It looks like it happens about once every 2-3 days, and it's not specific to any one IOLinc (among the group of 3 on the garage doors). This morning I had a v.41 have the same issue as the other two v.36's. Any thoughts on how to resolve this? Thanks, - Brian
LeeG Posted March 9, 2013 Posted March 9, 2013 Need an Event Trace at LEVEL 3 to determine what is happening.
belias Posted March 9, 2013 Posted March 9, 2013 Good timing - I was just able to get this to occur while manually running the QueryAll program this morning with a Level 3 turned on. The IOLinc that incorrectly changed from Sensor ON to Sensor OFF is 12.FA.3C I have bolded these lines in the event trace below. I have also included the Level 3 Event Trace for when I right-click the device and perform a Query (and it return to the correct sensor status) at the bottom of the post. Thanks again for the help with this - much appreciated. ---QueryAll Level 3 Trace--- Sat 03/09/2013 10:49:38 AM : [ Time] 10:49:38 3(0) Sat 03/09/2013 10:49:38 AM : [iNST-TX-I1 ] 02 62 12 8F 6A 0F 19 00 Sat 03/09/2013 10:49:38 AM : [iNST-ACK ] 02 62 12.8F.6A 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:38 AM : [iNST-SRX ] 02 50 12.8F.6A 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:38 AM : [std-Direct Ack] 12.8F.6A-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:38 AM : [iNST-TX-I1 ] 02 62 0C 74 CC 0F 19 00 Sat 03/09/2013 10:49:38 AM : [iNST-ACK ] 02 62 0C.74.CC 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:39 AM : [iNST-SRX ] 02 50 0C.74.CC 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:39 AM : [std-Direct Ack] 0C.74.CC-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:39 AM : [iNST-TX-I1 ] 02 62 1B 8B FD 0F 19 00 Sat 03/09/2013 10:49:39 AM : [iNST-ACK ] 02 62 1B.8B.FD 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:39 AM : [iNST-SRX ] 02 50 1B.8B.FD 0F.9F.89 2B 00 FF (FF) Sat 03/09/2013 10:49:39 AM : [std-Direct Ack] 1B.8B.FD-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:39 AM : [iNST-TX-I1 ] 02 62 0C C5 DF 0F 19 00 Sat 03/09/2013 10:49:39 AM : [iNST-ACK ] 02 62 0C.C5.DF 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:40 AM : [iNST-SRX ] 02 50 0C.C5.DF 0F.9F.89 2B 00 FF (FF) Sat 03/09/2013 10:49:40 AM : [std-Direct Ack] 0C.C5.DF-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:40 AM : [iNST-TX-I1 ] 02 62 0C C3 F7 0F 19 00 Sat 03/09/2013 10:49:40 AM : [iNST-ACK ] 02 62 0C.C3.F7 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:40 AM : [iNST-SRX ] 02 50 0C.C3.F7 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:40 AM : [std-Direct Ack] 0C.C3.F7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:40 AM : [iNST-TX-I1 ] 02 62 12 90 E6 0F 19 00 Sat 03/09/2013 10:49:40 AM : [iNST-ACK ] 02 62 12.90.E6 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:41 AM : [iNST-SRX ] 02 50 12.90.E6 0F.9F.89 2B 00 FF (FF) Sat 03/09/2013 10:49:41 AM : [std-Direct Ack] 12.90.E6-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:41 AM : [iNST-TX-I1 ] 02 62 12 8A 02 0F 19 00 Sat 03/09/2013 10:49:41 AM : [iNST-ACK ] 02 62 12.8A.02 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:41 AM : [iNST-SRX ] 02 50 12.8A.02 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:41 AM : [std-Direct Ack] 12.8A.02-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:41 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 01 Sat 03/09/2013 10:49:41 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:42 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:42 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:42 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 00 Sat 03/09/2013 10:49:42 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:42 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:42 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:42 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 01 Sat 03/09/2013 10:49:42 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:42 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:49:42 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:42 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 00 Sat 03/09/2013 10:49:43 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:43 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:43 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:43 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:43 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:43 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 01 Sat 03/09/2013 10:49:43 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:49:43 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:43 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:43 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:49:43 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:44 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:44 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:44 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 00 Sat 03/09/2013 10:49:44 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:44 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:44 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:44 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 01 Sat 03/09/2013 10:49:44 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:45 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:45 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:45 AM : [iNST-TX-I1 ] 02 62 13 32 2D 0F 19 00 Sat 03/09/2013 10:49:45 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 23 00 01 (01) Sat 03/09/2013 10:49:45 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:45 AM : [iNST-ACK ] 02 62 13.32.2D 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:45 AM : [iNST-SRX ] 02 50 13.32.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:45 AM : [std-Direct Ack] 13.32.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:45 AM : [iNST-TX-I1 ] 02 62 12 E3 B0 0F 19 00 Sat 03/09/2013 10:49:45 AM : [iNST-ACK ] 02 62 12.E3.B0 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:46 AM : [iNST-SRX ] 02 50 12.E3.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:46 AM : [std-Direct Ack] 12.E3.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:46 AM : [iNST-TX-I1 ] 02 62 12 3C A5 0F 19 00 Sat 03/09/2013 10:49:46 AM : [iNST-ACK ] 02 62 12.3C.A5 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:46 AM : [iNST-SRX ] 02 50 12.3C.A5 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:46 AM : [std-Direct Ack] 12.3C.A5-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:46 AM : [iNST-TX-I1 ] 02 62 12 3C A5 0F 19 01 Sat 03/09/2013 10:49:46 AM : [iNST-ACK ] 02 62 12.3C.A5 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:47 AM : [iNST-SRX ] 02 50 12.3C.A5 0F.9F.89 2B 00 02 (02) Sat 03/09/2013 10:49:47 AM : [std-Direct Ack] 12.3C.A5-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:47 AM : [iNST-TX-I1 ] 02 62 13 45 7E 0F 19 00 Sat 03/09/2013 10:49:47 AM : [iNST-ACK ] 02 62 13.45.7E 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:47 AM : [iNST-SRX ] 02 50 13.45.7E 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:47 AM : [std-Direct Ack] 13.45.7E-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:47 AM : [iNST-TX-I1 ] 02 62 12 8B 19 0F 19 00 Sat 03/09/2013 10:49:47 AM : [iNST-ACK ] 02 62 12.8B.19 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:48 AM : [iNST-SRX ] 02 50 12.8B.19 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:48 AM : [std-Direct Ack] 12.8B.19-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:48 AM : [iNST-TX-I1 ] 02 62 14 73 02 0F 19 00 Sat 03/09/2013 10:49:48 AM : [iNST-ACK ] 02 62 14.73.02 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:48 AM : [iNST-SRX ] 02 50 14.73.02 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:48 AM : [std-Direct Ack] 14.73.02-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:48 AM : [iNST-TX-I1 ] 02 62 14 73 02 0F 19 01 Sat 03/09/2013 10:49:48 AM : [iNST-ACK ] 02 62 14.73.02 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:49 AM : [iNST-SRX ] 02 50 14.73.02 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:49 AM : [std-Direct Ack] 14.73.02-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:49 AM : [iNST-TX-I1 ] 02 62 12 8E B0 0F 19 00 Sat 03/09/2013 10:49:49 AM : [iNST-SRX ] 02 50 14.73.02 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:49:49 AM : [std-Direct Ack] 14.73.02-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:49 AM : [iNST-ACK ] 02 62 12.8E.B0 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:49 AM : [iNST-SRX ] 02 50 12.8E.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:49 AM : [std-Direct Ack] 12.8E.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:49 AM : [iNST-TX-I1 ] 02 62 12 F8 B0 0F 19 01 Sat 03/09/2013 10:49:49 AM : [iNST-ACK ] 02 62 12.F8.B0 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:50 AM : [iNST-SRX ] 02 50 12.F8.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:50 AM : [std-Direct Ack] 12.F8.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:50 AM : [iNST-TX-I1 ] 02 62 12 F8 B0 0F 19 00 Sat 03/09/2013 10:49:50 AM : [iNST-ACK ] 02 62 12.F8.B0 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:50 AM : [iNST-SRX ] 02 50 12.F8.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:50 AM : [std-Direct Ack] 12.F8.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:50 AM : [iNST-TX-I1 ] 02 62 12 F8 B0 0F 19 01 Sat 03/09/2013 10:49:50 AM : [iNST-ACK ] 02 62 12.F8.B0 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:51 AM : [iNST-SRX ] 02 50 12.F8.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:51 AM : [std-Direct Ack] 12.F8.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:51 AM : [iNST-TX-I1 ] 02 62 12 F8 B0 0F 19 00 Sat 03/09/2013 10:49:51 AM : [iNST-ACK ] 02 62 12.F8.B0 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:51 AM : [iNST-SRX ] 02 50 12.F8.B0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:51 AM : [std-Direct Ack] 12.F8.B0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:51 AM : [iNST-TX-I1 ] 02 62 14 36 A2 0F 19 00 Sat 03/09/2013 10:49:51 AM : [iNST-ACK ] 02 62 14.36.A2 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:52 AM : [iNST-SRX ] 02 50 14.36.A2 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:52 AM : [std-Direct Ack] 14.36.A2-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:52 AM : [iNST-TX-I1 ] 02 62 1D 64 79 0F 19 00 Sat 03/09/2013 10:49:52 AM : [iNST-ACK ] 02 62 1D.64.79 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:52 AM : [iNST-SRX ] 02 50 1D.64.79 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:52 AM : [std-Direct Ack] 1D.64.79-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:52 AM : [iNST-TX-I1 ] 02 62 20 96 E8 0F 19 00 Sat 03/09/2013 10:49:52 AM : [iNST-ACK ] 02 62 20.96.E8 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:53 AM : [iNST-SRX ] 02 50 20.96.E8 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:53 AM : [std-Direct Ack] 20.96.E8-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:53 AM : [iNST-TX-I1 ] 02 62 1D 67 77 0F 19 00 Sat 03/09/2013 10:49:53 AM : [iNST-ACK ] 02 62 1D.67.77 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:53 AM : [iNST-SRX ] 02 50 1D.67.77 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:53 AM : [std-Direct Ack] 1D.67.77-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:53 AM : [iNST-TX-I1 ] 02 62 14 31 54 0F 19 00 Sat 03/09/2013 10:49:53 AM : [iNST-ACK ] 02 62 14.31.54 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:54 AM : [iNST-SRX ] 02 50 14.31.54 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:54 AM : [std-Direct Ack] 14.31.54-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:54 AM : [iNST-TX-I1 ] 02 62 14 CB 9F 0F 19 00 Sat 03/09/2013 10:49:54 AM : [iNST-ACK ] 02 62 14.CB.9F 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:54 AM : [iNST-SRX ] 02 50 14.CB.9F 0F.9F.89 2B 00 FF (FF) Sat 03/09/2013 10:49:54 AM : [std-Direct Ack] 14.CB.9F-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:54 AM : [iNST-TX-I2 ] 02 62 14 CB 9F 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 Sat 03/09/2013 10:49:54 AM : [iNST-ACK ] 02 62 14.CB.9F 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 06 (00) Sat 03/09/2013 10:49:55 AM : [iNST-SRX ] 02 50 14.CB.9F 0F.9F.89 2B 2E 00 (00) Sat 03/09/2013 10:49:55 AM : [std-Direct Ack] 14.CB.9F-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:55 AM : [iNST-ERX ] 02 51 14 CB 9F 0F 9F 89 11 2E 00 01 01 00 00 20 20 1E FF 25 B0 01 00 71 00 Sat 03/09/2013 10:49:55 AM : [Ext-Direct ] 14.CB.9F-->ISY/PLM Group=0, Max Hops=1, Hops Left=0 Sat 03/09/2013 10:49:55 AM : [iNST-TX-I1 ] 02 62 1F C2 B7 0F 19 01 Sat 03/09/2013 10:49:55 AM : [iNST-ACK ] 02 62 1F.C2.B7 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:56 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:56 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:56 AM : [iNST-TX-I1 ] 02 62 1F C2 B7 0F 19 00 Sat 03/09/2013 10:49:56 AM : [iNST-ACK ] 02 62 1F.C2.B7 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:56 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 23 00 01 (01) Sat 03/09/2013 10:49:56 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:56 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 23 00 01 (01) Sat 03/09/2013 10:49:56 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:56 AM : [iNST-TX-I1 ] 02 62 1F C2 B7 0F 19 01 Sat 03/09/2013 10:49:56 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:56 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:56 AM : [iNST-ACK ] 02 62 1F.C2.B7 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:49:57 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 10:49:57 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:57 AM : [iNST-TX-I1 ] 02 62 1F C2 B7 0F 19 00 Sat 03/09/2013 10:49:57 AM : [iNST-ACK ] 02 62 1F.C2.B7 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:57 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 23 00 01 (01) Sat 03/09/2013 10:49:57 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:57 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 23 00 01 (01) Sat 03/09/2013 10:49:57 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:49:57 AM : [iNST-TX-I1 ] 02 62 14 A0 D7 0F 19 00 Sat 03/09/2013 10:49:57 AM : [iNST-SRX ] 02 50 1F.C2.B7 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:57 AM : [std-Direct Ack] 1F.C2.B7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:57 AM : [iNST-ACK ] 02 62 14.A0.D7 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:58 AM : [iNST-SRX ] 02 50 14.A0.D7 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:58 AM : [std-Direct Ack] 14.A0.D7-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:58 AM : [iNST-TX-I1 ] 02 62 14 7F C5 0F 19 00 Sat 03/09/2013 10:49:58 AM : [iNST-ACK ] 02 62 14.7F.C5 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:58 AM : [iNST-SRX ] 02 50 14.7F.C5 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:58 AM : [std-Direct Ack] 14.7F.C5-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:58 AM : [iNST-TX-I1 ] 02 62 14 52 8A 0F 19 00 Sat 03/09/2013 10:49:58 AM : [iNST-ACK ] 02 62 14.52.8A 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:58 AM : [iNST-SRX ] 02 50 14.52.8A 0F.9F.89 2B 00 FF (FF) Sat 03/09/2013 10:49:58 AM : [std-Direct Ack] 14.52.8A-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:58 AM : [iNST-TX-I1 ] 02 62 14 37 64 0F 19 00 Sat 03/09/2013 10:49:59 AM : [iNST-ACK ] 02 62 14.37.64 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:59 AM : [iNST-SRX ] 02 50 14.37.64 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:59 AM : [std-Direct Ack] 14.37.64-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:59 AM : [iNST-TX-I1 ] 02 62 16 A2 02 0F 19 00 Sat 03/09/2013 10:49:59 AM : [iNST-ACK ] 02 62 16.A2.02 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:49:59 AM : [iNST-SRX ] 02 50 16.A2.02 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:49:59 AM : [std-Direct Ack] 16.A2.02-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:49:59 AM : [iNST-TX-I1 ] 02 62 15 BA 2D 0F 19 01 Sat 03/09/2013 10:50:00 AM : [iNST-ACK ] 02 62 15.BA.2D 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:50:00 AM : [iNST-SRX ] 02 50 15.BA.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:00 AM : [std-Direct Ack] 15.BA.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:00 AM : [iNST-TX-I1 ] 02 62 15 BA 2D 0F 19 00 Sat 03/09/2013 10:50:00 AM : [iNST-ACK ] 02 62 15.BA.2D 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:00 AM : [iNST-SRX ] 02 50 15.BA.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:00 AM : [std-Direct Ack] 15.BA.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:00 AM : [iNST-TX-I1 ] 02 62 15 BA 2D 0F 19 01 Sat 03/09/2013 10:50:01 AM : [iNST-ACK ] 02 62 15.BA.2D 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:50:01 AM : [iNST-SRX ] 02 50 15.BA.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:01 AM : [std-Direct Ack] 15.BA.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:01 AM : [iNST-TX-I1 ] 02 62 15 BA 2D 0F 19 00 Sat 03/09/2013 10:50:01 AM : [iNST-ACK ] 02 62 15.BA.2D 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:01 AM : [iNST-SRX ] 02 50 15.BA.2D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:01 AM : [std-Direct Ack] 15.BA.2D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:01 AM : [iNST-TX-I1 ] 02 62 12 8A FB 0F 19 00 Sat 03/09/2013 10:50:02 AM : [iNST-ACK ] 02 62 12.8A.FB 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:02 AM : [iNST-SRX ] 02 50 12.8A.FB 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:02 AM : [std-Direct Ack] 12.8A.FB-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:02 AM : [iNST-TX-I1 ] 02 62 17 FB 45 0F 19 00 Sat 03/09/2013 10:50:02 AM : [iNST-ACK ] 02 62 17.FB.45 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:02 AM : [iNST-SRX ] 02 50 17.FB.45 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:02 AM : [std-Direct Ack] 17.FB.45-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:02 AM : [iNST-TX-I1 ] 02 62 17 FF C8 0F 19 00 Sat 03/09/2013 10:50:03 AM : [iNST-ACK ] 02 62 17.FF.C8 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:03 AM : [iNST-SRX ] 02 50 17.FF.C8 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:03 AM : [std-Direct Ack] 17.FF.C8-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:03 AM : [iNST-TX-I1 ] 02 62 16 9F CF 0F 19 00 Sat 03/09/2013 10:50:03 AM : [iNST-ACK ] 02 62 16.9F.CF 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:03 AM : [iNST-SRX ] 02 50 16.9F.CF 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:03 AM : [std-Direct Ack] 16.9F.CF-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:03 AM : [iNST-TX-I1 ] 02 62 16 9F C3 0F 19 00 Sat 03/09/2013 10:50:04 AM : [iNST-ACK ] 02 62 16.9F.C3 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:04 AM : [iNST-SRX ] 02 50 16.9F.C3 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:04 AM : [std-Direct Ack] 16.9F.C3-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:04 AM : [iNST-TX-I1 ] 02 62 17 A4 9D 0F 19 00 Sat 03/09/2013 10:50:04 AM : [iNST-ACK ] 02 62 17.A4.9D 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:04 AM : [iNST-SRX ] 02 50 17.A4.9D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:04 AM : [std-Direct Ack] 17.A4.9D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:04 AM : [iNST-TX-I1 ] 02 62 17 A4 9D 0F 19 01 Sat 03/09/2013 10:50:05 AM : [iNST-SRX ] 02 50 17.A4.9D 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:50:05 AM : [std-Direct Ack] 17.A4.9D-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:50:05 AM : [iNST-SRX ] 02 50 17.A4.9D 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:50:05 AM : [std-Direct Ack] 17.A4.9D-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:50:05 AM : [iNST-ACK ] 02 62 17.A4.9D 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:50:05 AM : [iNST-SRX ] 02 50 17.A4.9D 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:05 AM : [std-Direct Ack] 17.A4.9D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:05 AM : [iNST-TX-I1 ] 02 62 14 37 A0 0F 19 00 Sat 03/09/2013 10:50:05 AM : [iNST-ACK ] 02 62 14.37.A0 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:05 AM : [iNST-SRX ] 02 50 14.37.A0 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:05 AM : [std-Direct Ack] 14.37.A0-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:05 AM : [iNST-TX-I1 ] 02 62 16 DE DC 0F 19 00 Sat 03/09/2013 10:50:06 AM : [iNST-ACK ] 02 62 16.DE.DC 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:06 AM : [iNST-SRX ] 02 50 14.37.A0 0F.9F.89 23 00 00 (00) Sat 03/09/2013 10:50:06 AM : [std-Direct Ack] 14.37.A0-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Sat 03/09/2013 10:50:06 AM : [iNST-SRX ] 02 50 16.DE.DC 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:06 AM : [std-Direct Ack] 16.DE.DC-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:06 AM : [iNST-TX-I2 ] 02 62 16 DE DC 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 Sat 03/09/2013 10:50:06 AM : [iNST-ACK ] 02 62 16.DE.DC 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 06 (00) Sat 03/09/2013 10:50:07 AM : [iNST-SRX ] 02 50 16.DE.DC 0F.9F.89 2B 2E 00 (00) Sat 03/09/2013 10:50:07 AM : [std-Direct Ack] 16.DE.DC-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:07 AM : [iNST-ERX ] 02 51 16 DE DC 0F 9F 89 11 2E 00 01 01 00 00 20 20 1C 4C 3F 1D 00 00 00 00 Sat 03/09/2013 10:50:07 AM : [Ext-Direct ] 16.DE.DC-->ISY/PLM Group=0, Max Hops=1, Hops Left=0 Sat 03/09/2013 10:50:07 AM : [iNST-TX-I1 ] 02 62 15 BB 75 0F 19 01 Sat 03/09/2013 10:50:07 AM : [iNST-ACK ] 02 62 15.BB.75 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:50:07 AM : [iNST-SRX ] 02 50 15.BB.75 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:07 AM : [std-Direct Ack] 15.BB.75-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:07 AM : [iNST-TX-I1 ] 02 62 15 BB 75 0F 19 00 Sat 03/09/2013 10:50:07 AM : [iNST-ACK ] 02 62 15.BB.75 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:08 AM : [iNST-SRX ] 02 50 15.BB.75 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:08 AM : [std-Direct Ack] 15.BB.75-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:08 AM : [iNST-TX-I1 ] 02 62 15 BB 75 0F 19 01 Sat 03/09/2013 10:50:08 AM : [iNST-ACK ] 02 62 15.BB.75 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 10:50:08 AM : [iNST-SRX ] 02 50 15.BB.75 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:08 AM : [std-Direct Ack] 15.BB.75-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:08 AM : [iNST-TX-I1 ] 02 62 15 BB 75 0F 19 00 Sat 03/09/2013 10:50:08 AM : [iNST-ACK ] 02 62 15.BB.75 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 10:50:09 AM : [iNST-SRX ] 02 50 15.BB.75 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 10:50:09 AM : [std-Direct Ack] 15.BB.75-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:09 AM : [iNST-TX-I1 ] 02 62 19 BB 6D 0F 4F 02 Sat 03/09/2013 10:50:09 AM : [iNST-ACK ] 02 62 19.BB.6D 0F 4F 02 06 IOCTL (QUERY) Sat 03/09/2013 10:50:09 AM : [iNST-SRX ] 02 50 19.BB.6D 0F.9F.89 2B 4F 02 IOCTL (QUERY) Sat 03/09/2013 10:50:09 AM : [std-Direct Ack] 19.BB.6D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:09 AM : [iNST-TX-I1 ] 02 62 19 BB 6D 0F 49 00 Sat 03/09/2013 10:50:09 AM : [iNST-ACK ] 02 62 19.BB.6D 0F 49 00 06 RINPUT (00) Sat 03/09/2013 10:50:10 AM : [iNST-SRX ] 02 50 19.BB.6D 0F.9F.89 2B 49 40 RINPUT (40) Sat 03/09/2013 10:50:10 AM : [std-Direct Ack] 19.BB.6D-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:10 AM : [ 12 FA 3C 1] ST 0 Sat 03/09/2013 10:50:10 AM : [ 1F C2 B7 2] ST 255 Sat 03/09/2013 10:50:11 AM : [iNST-TX-I1 ] 02 62 00 00 17 CF 11 00 Sat 03/09/2013 10:50:11 AM : [iNST-ACK ] 02 62 00.00.17 CF 11 00 06 LTONRR (00) Sat 03/09/2013 10:50:11 AM : [ 12 3C A5 8] ST 255 Sat 03/09/2013 10:50:11 AM : [ 14 73 2 6] ST 255 Sat 03/09/2013 10:50:12 AM : [iNST-SRX ] 02 50 19.BB.6D 00.00.0B CB 11 00 LTONRR (00) Sat 03/09/2013 10:50:12 AM : [std-Group ] 19.BB.6D-->Group=11, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:12 AM : [ 19 BB 6D B] DON 0 Sat 03/09/2013 10:50:12 AM : [ 19 BB 6D B] ST 255 Sat 03/09/2013 10:50:12 AM : [iNST-SRX ] 02 50 19.BB.6D 0F.9F.89 41 11 0B LTONRR (0B) Sat 03/09/2013 10:50:12 AM : [std-Cleanup ] 19.BB.6D-->ISY/PLM Group=11, Max Hops=1, Hops Left=0 Sat 03/09/2013 10:50:12 AM : [iNST-DUP ] Previous message ignored. Sat 03/09/2013 10:50:13 AM : [iNST-SRX ] 02 50 19.BB.6D 00.00.0B CB 13 00 LTOFFRR(00) Sat 03/09/2013 10:50:13 AM : [std-Group ] 19.BB.6D-->Group=11, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:13 AM : [ 19 BB 6D B] DOF 0 Sat 03/09/2013 10:50:13 AM : [ 19 BB 6D B] ST 0 Sat 03/09/2013 10:50:13 AM : [iNST-SRX ] 02 50 19.BB.6D 00.00.0C CB 11 00 LTONRR (00) Sat 03/09/2013 10:50:13 AM : [std-Group ] 19.BB.6D-->Group=12, Max Hops=3, Hops Left=2 Sat 03/09/2013 10:50:13 AM : [ 19 BB 6D C] DON 0 Sat 03/09/2013 10:50:13 AM : [ 19 BB 6D C] ST 255 Sat 03/09/2013 10:50:13 AM : [VAR 2 18 ] 167 Sat 03/09/2013 10:50:13 AM : [iNST-SRX ] 02 50 19.BB.6D 0F.9F.89 41 11 0C LTONRR (0C) Sat 03/09/2013 10:50:13 AM : [std-Cleanup ] 19.BB.6D-->ISY/PLM Group=12, Max Hops=1, Hops Left=0 Sat 03/09/2013 10:50:13 AM : [iNST-DUP ] Previous message ignored. ---Manual single device query that returns IOLinc to correct status (Sensor ON)--- ** Note there was some other activity in the house around the same time that is also captured*** Sat 03/09/2013 11:00:30 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 01 Sat 03/09/2013 11:00:30 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 01 06 LTSREQ (01) Sat 03/09/2013 11:00:30 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 01 (01) Sat 03/09/2013 11:00:30 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 11:00:30 AM : [ 12 FA 3C 1] ST 255 Sat 03/09/2013 11:00:30 AM : [iNST-TX-I1 ] 02 62 12 FA 3C 0F 19 00 Sat 03/09/2013 11:00:30 AM : [iNST-ACK ] 02 62 12.FA.3C 0F 19 00 06 LTSREQ (LIGHT) Sat 03/09/2013 11:00:30 AM : [iNST-SRX ] 02 50 12.FA.3C 0F.9F.89 2B 00 00 (00) Sat 03/09/2013 11:00:30 AM : [std-Direct Ack] 12.FA.3C-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Sat 03/09/2013 11:00:31 AM : [iNST-TX-I1 ] 02 62 00 00 17 CF 13 00 Sat 03/09/2013 11:00:31 AM : [iNST-ACK ] 02 62 00.00.17 CF 13 00 06 LTOFFRR(00) Sat 03/09/2013 11:00:31 AM : [ 12 3C A5 8] ST 0 Sat 03/09/2013 11:00:31 AM : [ 14 73 2 6] ST 0 Sat 03/09/2013 11:00:34 AM : [iNST-SRX ] 02 50 14.2E.44 00.00.01 CB 11 01 LTONRR (01) Sat 03/09/2013 11:00:34 AM : [std-Group ] 14.2E.44-->Group=1, Max Hops=3, Hops Left=2 Sat 03/09/2013 11:00:34 AM : [ 14 2E 44 1] DON 1 Sat 03/09/2013 11:00:34 AM : [iNST-SRX ] 02 50 14.2E.44 00.00.01 CB 11 01 LTONRR (01) Sat 03/09/2013 11:00:34 AM : [std-Group ] 14.2E.44-->Group=1, Max Hops=3, Hops Left=2 Sat 03/09/2013 11:00:34 AM : [iNST-DUP ] Previous message ignored.
LeeG Posted March 9, 2013 Posted March 9, 2013 belias This is the same problem seen by another user. There are duplicate Relay ACKs being processed, the later one being received and processed after the ISY has moved on to the next Query Sensor. Since the duplicate Relay ACK looks like the Sensor response and the Relay Status is Off the Sensor is marked Off. This trace provided some additional information. Device 13.32.2D produced a duplicate Query Sensor response. The Sensor change for 12.FA.3C was the result of a duplicate Relay response as was the other user with the same symptom. This is the first Query Sensor response duplicate I have seen traced. The actual cause of these duplicate responses with Hops Left=0 (other responses are coming back with Hops Left=2) is not known. The working theory is the additional ACK is taking a different path back to the PLM, coming so late that the PLM does not realize it is a duplicate. Since this happened essentially on demand by running the QueryAll this morning, is it possible to move 12.FA.3C temporarily to the PLM plug point and run a few QueryAll requests to see if it is distance/location dependent or simply related to an I/O Linc. If this test can be done let me know and I will PM an email address of where to send the event trace files. Too much data to post all of that on the forum. The other user decided to suspend the 3AM Query. I have no solution at this point. What is the PLM firmware level? Tools | Diagnostics | PLM Info/Status
Recommended Posts