JBC Posted July 22, 2013 Posted July 22, 2013 I'm a new ISY 994i pro user here with problems getting up and running. FYI, I have tried OSX Snow Leopard, OSX Mountain Lion, and Windows 7. I also have performed factor resets on both the ISY and the PLM, but have got the same results. I can login to my ISY with no problems and the diagnostics indicate that the PLM is connected. When I choose the 'Start Linking' option, the PLM flashes and beeps and the 'Start Linking' dialog appears, but it immediately (approximately 0.1 seconds) disappears. I have checked security setting, but I can't find anything obvious: firewalls are disabled, and I have no antivirus running. Latest error log (OSX Mountain Lion) shows the following: - Time User Code Message Sun 2013/07/21 02:00:14 PM System -5 Start Sun 2013/07/21 02:00:15 PM System -110022 /CONF/INSTENG.OPT Sun 2013/07/21 02:00:15 PM System -110012 /CONF/INSTENG.OPT Sun 2013/07/21 02:00:16 PM System -170001 [Network] Established Sun 2013/07/21 02:13:15 PM System -5 Start Sun 2013/07/21 02:13:16 PM System -110022 /CONF/INSTENG.OPT Sun 2013/07/21 02:13:16 PM System -110012 /CONF/INSTENG.OPT Sun 2013/07/21 02:13:17 PM System -170001 [Network] Established Sun 2013/07/21 04:31:02 PM System -5 Start Sun 2013/07/21 04:31:03 PM System -110022 /CONF/INSTENG.OPT Sun 2013/07/21 04:31:03 PM System -110012 /CONF/INSTENG.OPT Any clues? What else should I try, or is this (as I suspect) just a faulty PLM? Thanks, James.
LeeG Posted July 23, 2013 Posted July 23, 2013 That generally means Start Linking was done before, the first Start Linking Popup did not display because of a firewall or AV problem so the Finish button cannot be clicked to terminate Start Linking. The PLM will timeout of linking mode but the ISY sits in Start Linking waiting for a device Set button press that will not be seen because the push of data from the ISY to the Admin Console is being blocked by the firewall or AV. Kaspersky, Avast and others by default will not allow the ISY to push data to the Admin Console. Reboot the ISY to clear the Start Linking. Set up the firewall/AV to accept the ISY IP address as a trusted address.
JBC Posted July 23, 2013 Author Posted July 23, 2013 Thanks for your response, but I am not running a firewall or an antivirus in my current configuration. I have even disabled my (Apple Time Capsule) router's NAT translation to make sure that its firewall isn't active. I have tried rebooting the ISY and the PLM, but it didn't help. What's next? Thanks, James.
LeeG Posted July 23, 2013 Posted July 23, 2013 The PLM can be tested by running Tools | Diagnostics | Event Viewer at LEVEL 3. Select Link Management | New INSTEON Device, enter the Insteon address of a device, Name of choice, Device Type at default Auto Discover. Click Ok. Post the event trace. If nothing appears in the event trace something is blocking the ISY pushing data to the Admin Console. If there is a PLM issue I would expect to see it in the event trace.
JBC Posted July 23, 2013 Author Posted July 23, 2013 Thanks again, Lee. I'm not at home at the moment, but I'll give this a shot when I return this evening. Is there anything that I have to do to activate Level 3 event tracing, or is that option directly available from the menu?
JBC Posted July 24, 2013 Author Posted July 24, 2013 Thanks. I set out to add a SwitchLinc and was surprised to see that it successfully linked, though the operation generated plenty of traffic: - Tue 07/23/2013 07:47:03 PM : [16 29 3B ] Added to list of devices to link to ISY Tue 07/23/2013 07:47:03 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 0D 00 Tue 07/23/2013 07:47:03 PM : [iNST-ACK ] 02 62 16.29.3B 0F 0D 00 06 (00) Tue 07/23/2013 07:47:04 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 0D 01 (01) Tue 07/23/2013 07:47:04 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:04 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 10 00 Tue 07/23/2013 07:47:04 PM : [iNST-ACK ] 02 62 16.29.3B 0F 10 00 06 ID-REQ (00) Tue 07/23/2013 07:47:04 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 10 00 ID-REQ (00) Tue 07/23/2013 07:47:04 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:04 PM : [iNST-SRX ] 02 50 16.29.3B 01.19.38 8B 01 00 (00) Tue 07/23/2013 07:47:04 PM : [std-Broadcast] 16.29.3B-->01.19.38, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:04 PM : [16 29 3B 0 ] Calibrating engine version Tue 07/23/2013 07:47:04 PM : [16 29 3B 0 ] May not fully support i2, reverting to i1 Tue 07/23/2013 07:47:04 PM : [16 29 3B 0 ] Using Database Link Table offset 0x0FF8 (reported 0x0000) Tue 07/23/2013 07:47:04 PM : [16 29 3B 1 ] Start : Adding device to ISY Tue 07/23/2013 07:47:04 PM : [16 29 3B 1 ] Finish : Adding device to ISY was Successful Tue 07/23/2013 07:47:04 PM : [16 29 3B 1 ] Link 0 : 0FF8 [A2001F221CFF1F01] Saving [A2001F221CFF1F01] Tue 07/23/2013 07:47:04 PM : [16 29 3B 1 ] Link 1 : 0FF0 [E2011F221CFF0001] Saving [E2011F221CFF0001] Tue 07/23/2013 07:47:04 PM : [iNST-TX-I2 ] 02 62 16 29 3B 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 Tue 07/23/2013 07:47:04 PM : [iNST-ACK ] 02 62 16.29.3B 1F 2E 00 01 00 00 00 00 00 00 00 00 00 00 00 00 D1 06 (00) Tue 07/23/2013 07:47:05 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2E 00 (00) Tue 07/23/2013 07:47:05 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:05 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2E 00 (00) Tue 07/23/2013 07:47:05 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:05 PM : [iNST-ERX ] 02 51 16 29 3B 1F 22 1C 11 2E 00 01 01 00 00 20 00 1C FE 00 00 00 2F 00 D1 Tue 07/23/2013 07:47:05 PM : [Ext-Direct ] 16.29.3B-->ISY/PLM Group=0, Max Hops=1, Hops Left=0 Tue 07/23/2013 07:47:05 PM : [ 16 29 3B 1] OL 255 Tue 07/23/2013 07:47:05 PM : [ 16 29 3B 1] RR 28 Tue 07/23/2013 07:47:05 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 19 00 Tue 07/23/2013 07:47:05 PM : [iNST-ACK ] 02 62 16.29.3B 0F 19 00 06 LTSREQ (LIGHT) Tue 07/23/2013 07:47:06 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 00 00 (00) Tue 07/23/2013 07:47:06 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:06 PM : [ 16 29 3B 1] ST 0 Tue 07/23/2013 07:47:06 PM : [ 16 29 3B 1] OL 255 Tue 07/23/2013 07:47:06 PM : [ 16 29 3B 1] RR 28 Tue 07/23/2013 07:47:06 PM : [16 29 3B 0 ] Successfully added device Tue 07/23/2013 07:47:06 PM : [All ] Writing 16 bytes to devices Tue 07/23/2013 07:47:06 PM : [16 29 3B 1 ] Link 0 : 0FF8 [A2001F221CFF1F01] Writing [A2001F221CFF1F01] Tue 07/23/2013 07:47:06 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:06 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:07 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:07 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:07 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F8 Tue 07/23/2013 07:47:07 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F8 06 PEEK (F8) Tue 07/23/2013 07:47:07 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:07 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:07 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 A2 Tue 07/23/2013 07:47:07 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 A2 06 POKE (A2) Tue 07/23/2013 07:47:08 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 A2 POKE (A2) Tue 07/23/2013 07:47:08 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:08 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F9 Tue 07/23/2013 07:47:08 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F9 06 PEEK (F9) Tue 07/23/2013 07:47:08 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:08 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:08 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:08 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:09 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:09 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:09 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FA Tue 07/23/2013 07:47:09 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FA 06 PEEK (FA) Tue 07/23/2013 07:47:09 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:09 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:09 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 1F Tue 07/23/2013 07:47:09 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:09 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:09 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 1F 06 POKE (1F) Tue 07/23/2013 07:47:09 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:09 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:10 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 1F POKE (1F) Tue 07/23/2013 07:47:10 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:10 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FB Tue 07/23/2013 07:47:10 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 1F POKE (1F) Tue 07/23/2013 07:47:10 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:10 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FB 06 PEEK (FB) Tue 07/23/2013 07:47:10 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:10 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:10 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 22 Tue 07/23/2013 07:47:10 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 22 06 POKE (22) Tue 07/23/2013 07:47:10 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:10 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:10 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:10 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:11 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 22 POKE (22) Tue 07/23/2013 07:47:11 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:11 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FC Tue 07/23/2013 07:47:11 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FC 06 PEEK (FC) Tue 07/23/2013 07:47:11 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:11 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:11 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 1C Tue 07/23/2013 07:47:11 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:11 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:11 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 1C 06 POKE (1C) Tue 07/23/2013 07:47:12 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 1C POKE (1C) Tue 07/23/2013 07:47:12 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:12 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FD Tue 07/23/2013 07:47:12 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FD 06 PEEK (FD) Tue 07/23/2013 07:47:12 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:12 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:12 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 FF Tue 07/23/2013 07:47:12 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 FF 06 POKE (FF) Tue 07/23/2013 07:47:12 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 FF POKE (FF) Tue 07/23/2013 07:47:12 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:12 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FE Tue 07/23/2013 07:47:13 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FE 06 PEEK (FE) Tue 07/23/2013 07:47:13 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:13 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:13 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 1F Tue 07/23/2013 07:47:13 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 1F 06 POKE (1F) Tue 07/23/2013 07:47:13 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 1F POKE (1F) Tue 07/23/2013 07:47:13 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:13 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B FF Tue 07/23/2013 07:47:14 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B FF 06 PEEK (FF) Tue 07/23/2013 07:47:14 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:14 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:14 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 01 Tue 07/23/2013 07:47:14 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 01 06 POKE (01) Tue 07/23/2013 07:47:14 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 01 POKE (01) Tue 07/23/2013 07:47:14 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:15 PM : [MNG-LNK-RSP ] 02 6F 40 E2 00 16 29 3B 01 19 38 06 Tue 07/23/2013 07:47:15 PM : [PLM ] Group 0 : Writing Controller Link matching [16 29 3B 1 ] Link 0 : 0FF8 [A2001F221CFF1F01] Tue 07/23/2013 07:47:15 PM : [16 29 3B 1 ] Link 1 : 0FF0 [E2011F221CFF0001] Writing [E2011F221CFF0001] Tue 07/23/2013 07:47:15 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:15 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:15 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:15 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:15 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F0 Tue 07/23/2013 07:47:16 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F0 06 PEEK (F0) Tue 07/23/2013 07:47:16 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:16 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:16 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 E2 Tue 07/23/2013 07:47:16 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:16 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:16 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 E2 06 POKE (E2) Tue 07/23/2013 07:47:17 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 E2 POKE (E2) Tue 07/23/2013 07:47:17 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:17 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F1 Tue 07/23/2013 07:47:17 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F1 06 PEEK (F1) Tue 07/23/2013 07:47:17 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 E2 POKE (E2) Tue 07/23/2013 07:47:17 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:17 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:17 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:17 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 01 Tue 07/23/2013 07:47:17 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 01 06 POKE (01) Tue 07/23/2013 07:47:17 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 01 POKE (01) Tue 07/23/2013 07:47:17 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:17 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F2 Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 01 POKE (01) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:18 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F2 06 PEEK (F2) Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 01 POKE (01) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:18 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 1F Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 2B 00 PEEK (00) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:18 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 1F 06 POKE (1F) Tue 07/23/2013 07:47:18 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 1F POKE (1F) Tue 07/23/2013 07:47:18 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:18 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F3 Tue 07/23/2013 07:47:19 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F3 06 PEEK (F3) Tue 07/23/2013 07:47:19 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:19 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:19 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 22 Tue 07/23/2013 07:47:19 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 22 06 POKE (22) Tue 07/23/2013 07:47:20 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 22 POKE (22) Tue 07/23/2013 07:47:20 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:20 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F4 Tue 07/23/2013 07:47:20 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 22 POKE (22) Tue 07/23/2013 07:47:20 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:20 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F4 06 PEEK (F4) Tue 07/23/2013 07:47:20 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:20 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:20 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 1C Tue 07/23/2013 07:47:20 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 1C 06 POKE (1C) Tue 07/23/2013 07:47:20 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 1C POKE (1C) Tue 07/23/2013 07:47:20 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:20 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F5 Tue 07/23/2013 07:47:21 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F5 06 PEEK (F5) Tue 07/23/2013 07:47:21 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:21 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:21 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 FF Tue 07/23/2013 07:47:21 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 FF 06 POKE (FF) Tue 07/23/2013 07:47:21 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 FF POKE (FF) Tue 07/23/2013 07:47:21 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:21 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F6 Tue 07/23/2013 07:47:22 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 29 FF POKE (FF) Tue 07/23/2013 07:47:22 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:22 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F6 06 PEEK (F6) Tue 07/23/2013 07:47:23 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:23 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:23 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:23 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:23 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:23 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:23 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B F7 Tue 07/23/2013 07:47:23 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B F7 06 PEEK (F7) Tue 07/23/2013 07:47:23 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:23 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:23 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 29 01 Tue 07/23/2013 07:47:23 PM : [iNST-ACK ] 02 62 16.29.3B 0F 29 01 06 POKE (01) Tue 07/23/2013 07:47:24 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 29 01 POKE (01) Tue 07/23/2013 07:47:24 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:24 PM : [MNG-LNK-RSP ] 02 6F 41 A2 01 16 29 3B 01 19 38 06 Tue 07/23/2013 07:47:24 PM : [PLM ] Group 1 : Writing Responder Link matching [16 29 3B 1 ] Link 1 : 0FF0 [E2011F221CFF0001] Tue 07/23/2013 07:47:24 PM : [16 29 3B 1 ] Link 2 : 0FE8 [0000000000000000] Writing [00..............] Tue 07/23/2013 07:47:24 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:24 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:24 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:24 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:24 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B E8 Tue 07/23/2013 07:47:25 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:25 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:25 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B E8 06 PEEK (E8) Tue 07/23/2013 07:47:25 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 23 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:25 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=0 Tue 07/23/2013 07:47:25 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:25 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:25 PM : [16 29 3B 1 ] Link 3 : 0FE0 : Writing High Water Byte Tue 07/23/2013 07:47:25 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 28 0F Tue 07/23/2013 07:47:25 PM : [iNST-ACK ] 02 62 16.29.3B 0F 28 0F 06 SET-MSB(0F) Tue 07/23/2013 07:47:25 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 28 0F SET-MSB(0F) Tue 07/23/2013 07:47:25 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Tue 07/23/2013 07:47:25 PM : [iNST-TX-I1 ] 02 62 16 29 3B 0F 2B E0 Tue 07/23/2013 07:47:26 PM : [iNST-ACK ] 02 62 16.29.3B 0F 2B E0 06 PEEK (E0) Tue 07/23/2013 07:47:27 PM : [iNST-SRX ] 02 50 16.29.3B 1F.22.1C 2B 2B 00 PEEK (00) Tue 07/23/2013 07:47:27 PM : [std-Direct Ack] 16.29.3B-->ISY/PLM Group=0, Max Hops=3, Hops Left=2 Adding another two SwitchLincs produced similar results. However, I got the following when I selected 'Start Linking': - Tue 07/23/2013 08:29:09 PM : Start Insteon Device Linking Mode Tue 07/23/2013 08:29:09 PM : [LNK-BGN ] 02 64 01 00 00 Tue 07/23/2013 08:29:09 PM : Stop Insteon Device Linking Mode, Final processing to follow Tue 07/23/2013 08:29:09 PM : [All ] Writing 0 bytes to devices Tue 07/23/2013 08:29:10 PM : [LNK-END ] 02 65 06 : : Unexpected, ignored (65) Tue 07/23/2013 08:29:10 PM : [All ] Writing 0 bytes to devices Any idea what is going on and why 'Start Linking' isn't working? I forgot to mention that I am running the 4.0.5 firmware. Thanks, James.
LeeG Posted July 24, 2013 Posted July 24, 2013 The SwitchLinc is an older device (not I2CS) which takes longer to add. The event trace does not show anything abnormal with the PLM when entering Start Linking. It looks like the Admin Console shutdown Start Linking for some reason. What is the level of Java? Check Help | About and verify both Firmware and UI lines indicate 4.0.5.
LeeG Posted July 24, 2013 Posted July 24, 2013 I traced a Start Linking on my system to double check my memory. It looks like your PLM did not respond to the Start Linking command as expected Tue 07/23/2013 10:50:19 PM : Start Insteon Device Linking Mode Tue 07/23/2013 10:50:19 PM : [LNK-BGN ] 02 64 01 00 06 Tue 07/23/2013 10:50:48 PM : Stop Insteon Device Linking Mode, Final processing to follow Tue 07/23/2013 10:50:48 PM : [LNK-END ] 02 65 06 : : Unexpected, ignored (65) Tue 07/23/2013 10:50:48 PM : [All ] Writing 0 bytes to devices Tue 07/23/2013 10:50:48 PM : [All ] Writing 0 bytes to devices Note the lack of the 06 on the end. The PLM should return an 06 or a 15. The 00 on the end of the message in your trace is not spec'ed. Tue 07/23/2013 08:29:09 PM : [LNK-BGN ] 02 64 01 00 00
JBC Posted July 24, 2013 Author Posted July 24, 2013 Thanks Lee, that's a good catch. I appreciate you help. In answer to your earlier questions, I'm running Java 7 update 25 on my Mac and both the UI and the firmware are 4.0.5. Worth opening a support ticket with UDI, do you think? James.
LeeG Posted July 24, 2013 Posted July 24, 2013 Cannot hurt. They can confirm that an invalid PLM response will result in the Start Linking popup being removed. Have you tried a factory reset of the PLM (not the ISY). If you do a factory reset of the PLM a File | Restore Modem (PLM) is required to rebuild the PLM link database.
JBC Posted July 24, 2013 Author Posted July 24, 2013 Yes, I have tried resetting the PLM (and repeated that procedure again just now) with no effect. I'll call it into UDI. Thanks again for all of your help, Lee. James.
JBC Posted July 26, 2013 Author Posted July 26, 2013 A final update to close the loop on this: - I contacted UDI Support and they very quickly responded to confirm that this was definitely a faulty PLM. I called my original supplier (Orchestrated Home) to process my warranty return and they mentioned that they had received a batch of faulty PLMs around the time I originally purchased my equipment (early December last year - I had aborted a couple of earlier attempts to set up my equipment after running out of time and I've been too busy since to commit the time to working through the problem.) This experience has been very frustrating at times, but I put that largely down to bad luck. I'm sure it would have been a lot easier - and more fun - had I received a functional unit on my first encounter with ISY. Now I'm looking forward to configuring my equipment. Most of all, I'm grateful for Lee's expertise and persistence in helping me trouble-shoot the cause of the problem. Thanks Lee! James.
LeeG Posted July 26, 2013 Posted July 26, 2013 Thanks James. Appreciate you taking the time to post back the resolution. There is another user who mentioned he was not able to get Start Linking to work. That was not the primary complaint but he may also have a bad PLM. Now the challenge is to find his original post. Good luck with the rest of your ISY activity. Hope you find it as good an experience as most of us have.
Recommended Posts