Mysterious Shutdown

[gjtherk@comcast.net]

For the 3rd time in 2 weeks, my HomeSeer shutdown for no apparent reason. Noticed events were not running and found the system not running.

PC was running fine and this is a dedicated machine.

No error log in the config folder.

Log offers no clues.

2/18/2012 8:36:08 PM ~!~Device Control~!~Device: 1st Flr Family Room Valance Lights (\44) OFF
2/18/2012 8:36:09 PM ~!~Device Control~!~Device: 1st Flr Kitchen Soffit Lighting (\64) OFF
2/18/2012 8:36:11 PM ~!~Device Control~!~Device: 1st Flr Kitchen Undercabinet Lights (\65) OFF
2/18/2012 8:36:12 PM ~!~Device Control~!~Device: Basement Bath, Bsmt Sauna Lights ON (\91) OFF
2/18/2012 8:36:26 PM ~!~Device Control~!~Device: Basement Bath, Bsmt Ceiling Lights (]3) OFF
2/18/2012 8:36:43 PM ~!~Device Control~!~Device: 1st Flr Hallway, 1st Floor Ceiling Lights Primary (\62) OFF
2/18/2012 8:36:50 PM ~!~Device Control~!~Device: 1st Flr Dinette Dinette - Ceiling ON load (\66) OFF
2/18/2012 8:36:52 PM ~!~Device Control~!~Device: Exterior Exterior Receptacle, Main Deck (_36) OFF
2/18/2012 8:36:53 PM ~!~Device Control~!~Device: Exterior Exterior Receptacle, Mstr Deck (_37) OFF
2/18/2012 8:36:59 PM ~!~Device Control~!~Device: 1st Flr Family Room Fireplace Spot (\16) OFF
2/18/2012 8:42:57 PM ~!~Startup~!~
2/18/2012 8:42:57 PM ~!~Startup~!~*********************************************** *********************************
2/18/2012 8:42:57 PM ~!~Startup~!~ HomeSeer version 2.5.0.49 Starting Now (Standard Build)
2/18/2012 8:42:57 PM ~!~Startup~!~*********************************************** *********************************
2/18/2012 8:42:57 PM ~!~Startup~!~
2/18/2012 8:42:57 PM ~!~Startup~!~Using system's regional settings
2/18/2012 8:42:57 PM ~!~System~!~Ending plug-in process hs_compatibility
2/18/2012 8:42:57 PM ~!~Startup~!~Creating Class Objects...
2/18/2012 8:42:57 PM ~!~Startup~!~COM port classes started.
2/18/2012 8:42:57 PM ~!~Startup~!~Application interface class started.
2/18/2012 8:42:57 PM ~!~Startup~!~Web server class started.
2/18/2012 8:42:57 PM ~!~Startup~!~E-Mail SEND class started.
2/18/2012 8:42:57 PM ~!~Startup~!~E-Mail RECEIVE class started.
2/18/2012 8:42:57 PM ~!~Startup~!~Z-Wave class started.
2/18/2012 8:42:57 PM ~!~Startup~!~Plug-In Interface class started.
2/18/2012 8:42:57 PM ~!~Startup~!~Speech recognition class started.
2/18/2012 8:42:57 PM ~!~Startup~!~HSPhone dialer class started.
2/18/2012 8:42:57 PM ~!~Startup~!~Loading Settings
2/18/2012 8:42:57 PM ~!~Warning~!~HomeSeer was not shut down successfully the previous time it was started.
2/18/2012 8:42:57 PM ~!~Database~!~HomeSeer is performing database maintenance on homeseer 091810.mdb
2/18/2012 8:42:57 PM ~!~Database~!~Database maintenance Completed Successfully.
2/18/2012 8:42:58 PM ~!~Startup~!~Settings Loaded.
2/18/2012 8:42:58 PM ~!~Startup~!~Current sunrise/sunset values calculated.
2/18/2012 8:42:58 PM ~!~Startup~!~HSPhone external interface class started.
2/18/2012 8:42:58 PM ~!~Startup~!~Local voice recognition class started.
2/18/2012 8:42:58 PM ~!~Startup~!~Database SAVE process started.
2/18/2012 8:42:58 PM ~!~Startup~!~Loading configuration file ...

[joegr]

Left click on "My Computer" and then select manage.
Click on event viewer and then look at application and system.
You may find something in those logs to give you some clue about it. Look for entries at or before the time in question.

[gjtherk@comcast.net]

Mystery

Thank you. Found some interesting error messages having to do with a printer on the network, but I'm not sure why. Will continue to investigate.

[jon00]

You may also have a handle leak. Take a look at this thread and check your handle count over time.

[gjtherk@comcast.net]

Thanks Jon: I am following up with your information and suggestions. Appreciate your advice and assistance.

[gjtherk@comcast.net]

Happened again, but this time an error log

Unfortuntately, the upload of the zip file fails preventing me from attaching it. Is there a trick to that?

I've tried to just paste it in to this field twice but it didn't post.

[pbibm]

How big is the file... If you scroll through the response you get it might say something like "attachments larger than 1mb" or something like that.

If that's the case, you might have to break it up into several that are smaller. Its a pain, I know

[mnsandler]

Unfortuntately, the upload of the zip file fails preventing me from attaching it. Is there a trick to that?

I've tried to just paste it in to this field twice but it didn't post.

can you copy & paste the error and a few log entries before and after the error

[gjtherk@comcast.net]

Error log Is this enough? It is huge

3/20/2012 10:55:04 PM - Timed poll for Insteon device ID 05.9C.41 Unit code 45, Last contact 3/20/2012 10:45:01 PM. Polling interval of 10 min. reached, polling...
3/20/2012 10:55:04 PM - Requesting Insteon status (Polling) for device 1st Flr Laundry Ceiling (mstr) (05.9C.41)
3/20/2012 10:55:04 PM - Attempting to update the status for 1st Flr Laundry Ceiling (mstr) (05.9C.41)
3/20/2012 10:55:04 PM - ResourceManager::RequestExclusive for Resource 1
3/20/2012 10:55:04 PM - ResourceManager::RequestExclusive acquired for Resource 1
3/20/2012 10:55:04 PM - Sending command 02 62 05 9C 41 0A 19 00 to RF PLM, (Expecting 0 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
3/20/2012 10:55:04 PM - ..Got RF PLM Response 02 62 05 9C 41 0A 19 00 06
3/20/2012 10:55:04 PM - ResourceManager::ReleaseExclusive for Resource 1
3/20/2012 10:55:04 PM - Received STX 0x50 (Raw Insteon Rec):01 05 9C 41 18 D4 48 22 18 00 - Event: New msg for RF PLM, Src[05.9C.41] Dst[18.D4.48] {Ack/Dir} Cmd1: Stop Ramp Cmd2: 00
3/20/2012 10:55:04 PM - Adding 01 05 9C 41 18 D4 48 22 18 00 to gReceivedInsteonMsgs Queue
3/20/2012 10:55:04 PM - CheckRawMessageMatch: 01 05 9C 41 18 D4 48 22 18 00 against key 0* 05 9C 41 18 D4 48 2* ** **
3/20/2012 10:55:04 PM - Wait: Found a matching Insteon raw message: 01 05 9C 41 18 D4 48 22 18 00
3/20/2012 10:55:04 PM - Received message from 1st Flr Laundry Ceiling (mstr) (05.9C.41) taking hops: 2
3/20/2012 10:55:04 PM - Received a Direct ACK from 05.9C.41
3/20/2012 10:55:04 PM - [CheckDeltas] Database recently heard delta value for device 05.9C.41 is current.
3/20/2012 10:55:04 PM - Database delta value of 24 for device 05.9C.41 is current. No changes since last database update.
3/20/2012 10:55:04 PM - StandardDeviceClass ProcessStatus called with Level:0
3/20/2012 10:55:04 PM - Setting status and value for device \45. Old status:3 Old Value:0, New Status:3 New Value:0
3/20/2012 10:55:07 PM - ***:Error in PagePut: Input string was not in a correct format. -- Details System.FormatException: Input string was not in a correct format.
at System.Number.StringToNumber(String str, NumberStyles options, NumberBuffer& number, NumberFormatInfo info, Boolean parseDecimal)
at System.Number.ParseInt32(String s, NumberStyles style, NumberFormatInfo info)
at WebInterface.PagePut(String& data)
3/20/2012 10:55:14 PM - Edit Insteon Device #73 Addr: 0D.44.C9, Name:Table Lamp, Location:Bdrm, Guest
3/20/2012 10:55:19 PM - Timed poll for Insteon device ID 05.9A.AD Unit code 46, Last contact 3/20/2012 10:45:03 PM. Polling interval of 10 min. reached, polling...
3/20/2012 10:55:19 PM - Requesting Insteon status (Polling) for device 1st Flr Laundry Ceiling (05.9A.AD)
3/20/2012 10:55:19 PM - Attempting to update the status for 1st Flr Laundry Ceiling (05.9A.AD)
3/20/2012 10:55:19 PM - ResourceManager::RequestExclusive for Resource 1
3/20/2012 10:55:19 PM - ResourceManager::RequestExclusive acquired for Resource 1
3/20/2012 10:55:19 PM - Sending command 02 62 05 9A AD 0A 19 00 to RF PLM, (Expecting 0 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
3/20/2012 10:55:19 PM - ..Got RF PLM Response 02 62 05 9A AD 0A 19 00 06
3/20/2012 10:55:19 PM - ResourceManager::ReleaseExclusive for Resource 1
3/20/2012 10:55:19 PM - Received STX 0x50 (Raw Insteon Rec):01 05 9A AD 18 D4 48 22 20 00 - Event: New msg for RF PLM, Src[05.9A.AD] Dst[18.D4.48] {Ack/Dir} Cmd1: Set Op Flags Cmd2: 00
3/20/2012 10:55:19 PM - Adding 01 05 9A AD 18 D4 48 22 20 00 to gReceivedInsteonMsgs Queue
3/20/2012 10:55:19 PM - CheckRawMessageMatch: 01 05 9A AD 18 D4 48 22 20 00 against key 0* 05 9A AD 18 D4 48 2* ** **
3/20/2012 10:55:19 PM - Wait: Found a matching Insteon raw message: 01 05 9A AD 18 D4 48 22 20 00
3/20/2012 10:55:19 PM - [CheckDeltas] Database recently heard delta value for device 05.9A.AD is current.
3/20/2012 10:55:19 PM - Database delta value of 32 for device 05.9A.AD is current. No changes since last database update.
3/20/2012 10:55:19 PM - StandardDeviceClass ProcessStatus called with Level:0
3/20/2012 10:55:19 PM - Setting status and value for device \46. Old status:3 Old Value:0, New Status:3 New Value:0
3/20/2012 10:55:19 PM - Received message from 1st Flr Laundry Ceiling (05.9A.AD) taking hops: 2
3/20/2012 10:55:19 PM - Received a Direct ACK from 05.9A.AD
3/20/2012 10:55:19 PM - Timed poll for Insteon device ID 1A.60.65 Unit code 10, Last contact 3/20/2012 10:48:49 PM. Polling interval of 6 min. reached, polling...
3/20/2012 10:55:19 PM - Requesting Insteon status (Polling) for device 2nd Flr Bdrm, Mstr Vanity (1A.60.65)
3/20/2012 10:55:19 PM - Attempting to update the status for 2nd Flr Bdrm, Mstr Vanity (1A.60.65)
3/20/2012 10:55:19 PM - ResourceManager::RequestExclusive for Resource 1
3/20/2012 10:55:19 PM - ResourceManager::RequestExclusive acquired for Resource 1
3/20/2012 10:55:19 PM - Sending command 02 62 1A 60 65 0A 19 00 to RF PLM, (Expecting 0 bytes extra back) Pre-Payload ACK:False, NAK is ok:False
3/20/2012 10:55:20 PM - ..Got RF PLM Response 02 62 1A 60 65 0A 19 00 06
3/20/2012 10:55:20 PM - ResourceManager::ReleaseExclusive for Resource 1
3/20/2012 10:55:20 PM - Received STX 0x50 (Raw Insteon Rec):01 1A 60 65 18 D4 48 22 03 00 - Event: New msg for RF PLM, Src[1A.60.65] Dst[18.D4.48] {Ack/Dir} Cmd1: Product Data Rqst Cmd2: 00
3/20/2012 10:55:20 PM - Adding 01 1A 60 65 18 D4 48 22 03 00 to gReceivedInsteonMsgs Queue
3/20/2012 10:55:20 PM - CheckRawMessageMatch: 01 1A 60 65 18 D4 48 22 03 00 against key 0* 1A 60 65 18 D4 48 2* ** **
3/20/2012 10:55:20 PM - Wait: Found a matching Insteon raw message: 01 1A 60 65 18 D4 48 22 03 00
3/20/2012 10:55:20 PM - Received message from 2nd Flr Bdrm, Mstr Vanity (1A.60.65) taking hops: 2
3/20/2012 10:55:20 PM - Received a Direct ACK from 1A.60.65
3/20/2012 10:55:20 PM - [CheckDeltas] Database recently heard delta value for device 1A.60.65 is current.
3/20/2012 10:55:20 PM - Database delta value of 3 for device 1A.60.65 is current. No changes since last database update.
3/20/2012 10:55:20 PM - StandardDeviceClass ProcessStatus called with Level:0
3/20/2012 10:55:20 PM - Setting status and value for device ^10. Old status:3 Old Value:0, New Status:3 New Value:0
3/20/2012 10:55:28 PM - ***:Error in PagePut: Input string was not in a correct format. -- Details System.FormatException: Input string was not in a correct format.
at System.Number.StringToNumber(String str, NumberStyles options, NumberBuffer& number, NumberFormatInfo info, Boolean parseDecimal)
at System.Number.ParseInt32(String s, NumberStyles style, NumberFormatInfo info)
at WebInterface.PagePut(String& data)
--------*End Pre-Error Log*--------------
--------*Start C:\Program Files\HomeSeer HS2\Config\Insteon.ini*--------------
[Config]
GroupBaseCode=[
NumDevices=128
DeviceBaseCode=\
DetailedLog=1
MaxHops=2
NumRetries=3
WaitTimeout=3
NumGroups=7
LastInterfaceAddress=18.D4.48
DeviceLocationFilterIndex=5
DoCleanups=1
GroupLocationFilterIndex=0
UseLocation2=1
DisablePolling=0
NumInterfaceLinks=441
SortDeviceLinks=1
InterfaceCapability=5
[Device 1]
DeviceID=07.42.96
DeviceName=Center Island Chandelier
DeviceLocation=Kitchen
PollingInterval=15
DeviceType=0101
FirmwareVer=27
DatabaseDelta=41
EEValues=DefaultRampRate=26,ResumeDim=136,DefaultOnLevel=254
LastContact=3/20/2012 10:55:00 PM
NumLinks=5
RecentDatabaseDelta=41
LinksDownloaded=1
NoChannelSync=0
Protocol=1
TotalInteractions=3006
FailedInteractions=152
SyncLinkedDevices=0
Link 1=E2 01 18 D4 48 FF 1F 01 1
Link 2=A2 FE 18 D4 48 FF 1F 01 2
Link 3=E2 01 08 4D 25 FF 1A 06 3
Link 4=A2 06 08 4D 25 FF 1A 01 4
Link 5=A2 02 18 D4 48 FF 18 01 5
HopData=2973,265,2071,637
[Device 2]
DeviceID=00.4D.3C
DeviceName=Table Lamp
DeviceLocation=Office

[mnsandler]

Can you tell what plugin page you were submitting and post the [device] section if you were editing a device

[gjtherk@comcast.net]

Plug In

I wasn't doing anything when the error occurred.

I was 400 miles away sleeping in a motel with no Internet if you can believe that. And I can't really access on my BlackBerry storm.

[mnsandler]

did the plugin stop working because of this?

the error you posted from 3/20 was generated from submitting one of the webpages within the plugin.

Can you verify that all the PollingInterval keys in your insteon.ini have an integer value with them

[gjtherk@comcast.net]

Polling

I have no idea why it stopped working. All I know is that it does from time to time. Normally there hasn't been an error message.

Most devices have the number a number between 5 and 10 in the polling box but several are set for zero since they are off line - used for Christmas lights etc.

[mnsandler]

I guess what I'm after is when did the plugin stop working? was it right after the error you

do you see any msgs like the following in the rest of the log after the error:

Received STX 0x50 (means the plugin recvd an inbound msg)
..Got RF PLM Response 02 62 1A 60 65 0A 19 00 06 (means the plm sent an outbound msg)

[gjtherk@comcast.net]

Correction

I was travelling when this all took place. In looking at the logs, the system shutdown on it's own Monday afternoon around 4:30 PM.

Now that I look at the calendar, this error message was generated the following day.

May not be connected. The mystery shutdowns have been leaving no error messages so I was happy to see one, but didn't realize the date difference until you probed further.

My apologies.