Strange error message

[skozin]

Mark:

2 questions.

1.) My Smarthome Hub 2242 was not communicating well (Blue light on front would start blinking constantly and then be dark) so I talked to Smarthome and they suggested to do a factory reset on my router. That seemed to help, but when I restarted Homeseer 3 I get an error in the startup log. It will however connect to insteon.

What is this message? (see below)

2.) Also, when I took the hub to my office to see if I was receiving the same communication issue with the hub, The blue light stayed on constantly unless there was some insteon communication. It worked totally fine.

Any suggestions on how to keep the blue light from disappearing so often?

Here is the error message:

9/26/2015 8:11:26 AM ~!~Insteon~!~Error in GetMasterDeviceInformation: System.ArgumentOutOfRangeException: Index and length must refer to a location within the string.Parameter name: length at System.String.InternalSubStringWithChecks(Int32 startIndex, Int32 length, Boolean fAlwaysCopy) at Insteon.Support.Core.InsteonModule.GetMasterDeviceInformatio n(String DevType, String FirmwareVer, String SpecialValues)Startup Complete, 1 errors detected, check the log for more information.


Thanks,
Steve

Here is the log:

9/26/2015 8:11:13 AM ~!~Startup~!~Settings Loaded.
9/26/2015 8:11:13 AM ~!~Startup~!~
9/26/2015 8:11:13 AM ~!~Startup~!~*********************************************** *********************************
9/26/2015 8:11:13 AM ~!~Startup~!~ HomeSeer version 3.0.0.206 Edition: HS3 Standard Starting Now
9/26/2015 8:11:13 AM ~!~Startup~!~*********************************************** *********************************
9/26/2015 8:11:13 AM ~!~Startup~!~
9/26/2015 8:11:13 AM ~!~Startup~!~Creating Class Objects...
9/26/2015 8:11:13 AM ~!~Startup~!~COM port classes started.
9/26/2015 8:11:13 AM ~!~Startup~!~Application interface class started.
9/26/2015 8:11:14 AM ~!~Startup~!~Web server class started.
9/26/2015 8:11:14 AM ~!~Startup~!~E-Mail SEND class started.
9/26/2015 8:11:14 AM ~!~Startup~!~E-Mail RECEIVE class started.
9/26/2015 8:11:14 AM ~!~Startup~!~Plug-In Interface class started.
9/26/2015 8:11:14 AM ~!~Startup~!~HSPhone external interface class started.
9/26/2015 8:11:14 AM ~!~Startup~!~Local voice recognition class started.
9/26/2015 8:11:14 AM ~!~Startup~!~Database SAVE process started.
9/26/2015 8:11:14 AM ~!~Startup~!~Loading configuration file ...
9/26/2015 8:11:14 AM ~!~Database~!~Opening (Mode=Read Only) up HomeSeer database C:\Program Files (x86)\HomeSeer HS3\Data\HomeSeerData.hsd
9/26/2015 8:11:14 AM ~!~Database~!~Loading Devices...
9/26/2015 8:11:14 AM ~!~Load Config~!~118 total devices loaded.
9/26/2015 8:11:14 AM ~!~Load Config~!~Loading Event Groups...
9/26/2015 8:11:14 AM ~!~Load Config~!~Loading Events...
9/26/2015 8:11:14 AM ~!~Load Config~!~8 total events loaded.
9/26/2015 8:11:14 AM ~!~Load Config~!~Loading COUNTERS...
9/26/2015 8:11:14 AM ~!~Load Config~!~Loading TIMERS...
9/26/2015 8:11:14 AM ~!~Startup~!~HomeSeer version is: 3.0.0.206
9/26/2015 8:11:14 AM ~!~Startup~!~Creating Speaker Client Interface Object...
9/26/2015 8:11:14 AM ~!~Startup~!~This version of HomeSeer is registered as a HS3STANDARD version.
9/26/2015 8:11:14 AM ~!~Info~!~Remote plug-in API interface started on port 10400
9/26/2015 8:11:14 AM ~!~Startup~!~Checking for available plug-ins
9/26/2015 8:11:14 AM ~!~Plug-In~!~Found plug-in: EZFlora, version: 2.0.0.1
9/26/2015 8:11:14 AM ~!~Plug-In~!~Found plug-in: HSTouch Server, version: 3.0.0.68
9/26/2015 8:11:14 AM ~!~Plug-In~!~Found plug-in: Insteon, version: 3.0.5.20
9/26/2015 8:11:15 AM ~!~Plug-In~!~Found plug-in: Tasker Plugin, version: 3.0.2.9
9/26/2015 8:11:15 AM ~!~Startup~!~Initializing Plug-Ins
9/26/2015 8:11:15 AM ~!~Info~!~Plugin Insteon has connected. IP:127.0.0.1:64286
9/26/2015 8:11:15 AM ~!~Insteon~!~Host System: () 3.0.0.206
9/26/2015 8:11:15 AM ~!~Insteon~!~Plugin: HSPI_Insteon.DLL - v3.0.5.20
9/26/2015 8:11:15 AM ~!~Insteon~!~Insteon Support library (Insteon.dll) version: 1.0.5.20
9/26/2015 8:11:15 AM ~!~Insteon~!~RegisterCallback Completed
9/26/2015 8:11:15 AM ~!~Insteon~!~Enter InitIO
9/26/2015 8:11:15 AM ~!~Insteon~!~Enter InitPlugin
9/26/2015 8:11:15 AM ~!~Insteon~!~Global initialization of plug-in required, starting up.
9/26/2015 8:11:15 AM ~!~Insteon~!~Initializing Insteon hardware interface...
9/26/2015 8:11:15 AM ~!~Insteon~!~In ProcessRawReceiveThread
9/26/2015 8:11:15 AM ~!~Insteon~!~Initializing Interface...
9/26/2015 8:11:15 AM ~!~Insteon~!~In PLMBuffer_ProcessBufferThread
9/26/2015 8:11:15 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:15 AM ~!~Insteon~!~Sending command 02 60 to Interface, (Expecting 6 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
9/26/2015 8:11:16 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:16 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:16 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:16 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:16 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:16 AM ~!~Insteon~!~..Retry #1 required. Pausing 250ms for Interface to clear...
9/26/2015 8:11:16 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:17 AM ~!~Insteon~!~..My XML buffer begins with: 02602CE8CB03379C06
9/26/2015 8:11:17 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:17 AM ~!~Insteon~!~..Got Interface Response 02 60 2C E8 CB 03 37 9C 06
9/26/2015 8:11:17 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:17 AM ~!~Insteon~!~Sending command 02 60 to Interface, (Expecting 6 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
9/26/2015 8:11:18 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:18 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:18 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:18 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:18 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:18 AM ~!~Insteon~!~..Retry #1 required. Pausing 250ms for Interface to clear...
9/26/2015 8:11:19 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:19 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:19 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:19 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:19 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:19 AM ~!~Insteon~!~..Retry #2 required. Pausing 300ms for Interface to clear...
9/26/2015 8:11:20 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:20 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:20 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:20 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:20 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:20 AM ~!~Insteon~!~Sending command 02 60 to Interface, (Expecting 6 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
9/26/2015 8:11:21 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:21 AM ~!~Insteon~!~..My XML buffer begins with: 02602CE8CB03379C06
9/26/2015 8:11:21 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:21 AM ~!~Insteon~!~..Got Interface Response 02 60 2C E8 CB 03 37 9C 06
9/26/2015 8:11:21 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:21 AM ~!~Insteon~!~Sending command 02 60 to Interface, (Expecting 6 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
9/26/2015 8:11:22 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:22 AM ~!~Insteon~!~..My XML buffer begins with: 15
9/26/2015 8:11:22 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:22 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:22 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:22 AM ~!~Insteon~!~..Retry #1 required. Pausing 250ms for Interface to clear...
9/26/2015 8:11:23 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:23 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:23 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:23 AM ~!~Insteon~!~..Got NAK from the Interface for command
9/26/2015 8:11:23 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:23 AM ~!~Insteon~!~..Retry #2 required. Pausing 300ms for Interface to clear...
9/26/2015 8:11:24 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?0260=I=3
9/26/2015 8:11:24 AM ~!~Insteon~!~..My XML buffer begins with: 02602CE8CB03379C06
9/26/2015 8:11:24 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:24 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:24 AM ~!~Insteon~!~..Got Interface Response 02 60 2C E8 CB 03 37 9C 06
9/26/2015 8:11:24 AM ~!~Insteon~!~Hub contacted on 192.168.2.224:25105 with an Insteon address of 2C.E8.CB and firmware version 9C
9/26/2015 8:11:24 AM ~!~Insteon~!~Sending command 02 6B 48 to Hub, (Expecting 0 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
9/26/2015 8:11:24 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?026B48=I=3
9/26/2015 8:11:25 AM ~!~Insteon~!~..My XML buffer begins with: 1515
9/26/2015 8:11:25 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:25 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:25 AM ~!~Insteon~!~..Got NAK from the Hub for command
9/26/2015 8:11:25 AM ~!~Insteon~!~..Retry #1 required. Pausing 250ms for Hub to clear...
9/26/2015 8:11:26 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/3?026B48=I=3
9/26/2015 8:11:26 AM ~!~Insteon~!~..My XML buffer begins with: 026B4806
9/26/2015 8:11:26 AM ~!~Insteon~!~Sending http://192.168.2.224:25105/1?XB=M=1
9/26/2015 8:11:26 AM ~!~Insteon~!~..Got Hub Response 02 6B 48 06
9/26/2015 8:11:26 AM ~!~Insteon~!~PLMBuffer_Clean - Cleared 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 to locate next possible STX
9/26/2015 8:11:26 AM ~!~Insteon~!~Insteon hardware interface initialized.
9/26/2015 8:11:26 AM ~!~Insteon~!~I/O (Insteon Protocol) Interface Initializing...
9/26/2015 8:11:26 AM ~!~Insteon~!~In TransmitMessageThread
9/26/2015 8:11:26 AM ~!~Insteon~!~In CreateDevices
9/26/2015 8:11:26 AM ~!~Insteon~!~Reloading 61 Insteon device information tables...
9/26/2015 8:11:26 AM ~!~Insteon~!~Error in GetMasterDeviceInformation: System.ArgumentOutOfRangeException: Index and length must refer to a location within the string.Parameter name: length at System.String.InternalSubStringWithChecks(Int32 startIndex, Int32 length, Boolean fAlwaysCopy) at Insteon.Support.Core.InsteonModule.GetMasterDeviceInformatio n(String DevType, String FirmwareVer, String SpecialValues)
9/26/2015 8:11:30 AM ~!~Insteon~!~112 Matching HS device records were found and updated for the Insteon device database.
9/26/2015 8:11:30 AM ~!~Insteon~!~0 Matching HS device records were found and updated for the Insteon group database.
9/26/2015 8:11:30 AM ~!~Insteon~!~Exit CreateDevices
9/26/2015 8:11:30 AM ~!~Insteon~!~I/O(Insteon Protocol) Interface Initialized.
9/26/2015 8:11:30 AM ~!~Insteon~!~Waiting to initialize External Devices...
9/26/2015 8:11:30 AM ~!~Insteon~!~Exit InitPlugin
9/26/2015 8:11:30 AM ~!~Insteon~!~Enter InitX10
9/26/2015 8:11:30 AM ~!~Insteon~!~Enter InitPlugin
9/26/2015 8:11:30 AM ~!~Insteon~!~Global initialization already completed.
9/26/2015 8:11:30 AM ~!~Insteon~!~X10 Protocol Interface Initializing...
9/26/2015 8:11:30 AM ~!~Insteon~!~Exit InitPlugin
9/26/2015 8:11:30 AM ~!~Insteon~!~Exit InitX10
9/26/2015 8:11:30 AM ~!~Insteon~!~Exit InitIO
9/26/2015 8:11:30 AM ~!~Plug-In~!~Finished initializing plug-in Insteon
9/26/2015 8:11:31 AM ~!~Web Server~!~Local IP address (subnet) is: 192.168.2.50 (255.255.255.0)
9/26/2015 8:11:31 AM ~!~Web Server~!~UPNP Discovery started
9/26/2015 8:11:31 AM ~!~Web Server~!~Web Server started on port 8080
9/26/2015 8:11:31 AM ~!~Startup~!~Creating ASP.NET application host...
9/26/2015 8:11:31 AM ~!~Web Server~!~SSL Web Server started on port 443
9/26/2015 8:11:32 AM ~!~Startup~!~Start automation engine...
9/26/2015 8:11:32 AM ~!~Startup~!~Initializing Speaker Client Interface
9/26/2015 8:11:32 AM ~!~Speech~!~Listening for remote speaker connections on port 10401
9/26/2015 8:11:32 AM ~!~Database~!~Opening (Mode=Read/Write) up HomeSeer Energy database C:\Program Files (x86)\HomeSeer HS3\Data\Energy\Energy.hsd
9/26/2015 8:11:32 AM ~!~Startup~!~Updater services starting...
9/26/2015 8:11:32 AM ~!~Startup~!~Starting DDNS service...

[mnsandler]

not sure what that error is. the plugin is having trouble getting the device profile of one of your registered devices. Do they all look okay on the device mgmt page

the plugin polls the hub every sec for incoming msgs. this blue light might be responding to these requests. not sure

[skozin]

Looking at my Insteon.ini file, I noticed the Password as this:

HubPassword=HSENC2hRcDtsjO8yUF0Tz9iFnmJw==

And I know the password is not that.

Should I change that in the Insteon.ini?

FYI the Hub is working fine right now.

Thanks,
Steve

[mnsandler]

Looking at my Insteon.ini file, I noticed the Password as this:

HubPassword=HSENC2hRcDtsjO8yUF0Tz9iFnmJw==

And I know the password is not that.

Should I change that in the Insteon.ini?

FYI the Hub is working fine right now.

Thanks,
Steve

the password is encrypted before its stored in the ini file. what you see is the encrypted value. Do not change it. If its not working, you may update it via the plugin UI

[skozin]

Thanks, I will leave it alone.