Announcement

**mnsandler** · July 15, 2012, 08:43 PM

I don't think this error caused the shutdown as I've seen this with other users. Please update to the latest beta which traps and reports this error. Its only occurs during startup when the plugin receives a message before the plugin is complete initialized.

the entries in your log above indicates hs crashed; ie was not shutdown gracefulle.

How about in the hs debug logs? any thing interesting there around the time of the last shutdown

**gjtherk@comcast.net** · July 19, 2012, 09:10 AM

Another gracefull, unexplained HS shutdown. No error log

7/18/2012 5:50:00 PM ~!~Device Control~!~Device: 1st Flr Hallway, 1st Floor Ceiling Lights Primary (\62) OFF
7/18/2012 5:50:00 PM ~!~Device Control~!~Device: 1st Flr Hallway, 1st Floor Ceiling Lights Secondary (_6) OFF
7/18/2012 5:50:00 PM ~!~Event~!~Event Trigger "2nd Floor Stairs OFF Day no motion"
7/18/2012 5:50:00 PM ~!~Device Control~!~Device: 2nd Flr Stairs Toe Lights Upper (\31) OFF
7/18/2012 5:52:00 PM ~!~Insteon~!~Received _98 (Foyer Dusk/Dawn Sensor) Go OFF
7/18/2012 5:52:13 PM ~!~Shutdown~!~Application shutdown at 7/18/2012 5:52:13 PM
7/18/2012 5:52:13 PM ~!~Shutdown~!~Running shutdown script ...
7/18/2012 5:52:13 PM ~!~Shutdown~!~Shutdown Script Completed
7/18/2012 5:52:13 PM ~!~Shutdown~!~Removing Plug-in Links...
7/18/2012 5:52:13 PM ~!~Shutdown~!~Removing all Callbacks...
7/18/2012 5:52:13 PM ~!~Shutdown~!~Shutting down plug-ins ...
7/18/2012 5:52:13 PM ~!~Shutdown~!~Shutting down X10 plug-in.
7/18/2012 5:52:13 PM ~!~Shutdown~!~Shutting down all I-O/Generic plug-ins.
7/18/2012 5:52:13 PM ~!~Shutdown~!~Shutting down plug-in: Insteon
7/18/2012 5:52:22 PM ~!~Shutdown~!~Finished shutting down all I-O/Generic plug-ins.
7/18/2012 5:52:22 PM ~!~Shutdown~!~Shutting down database...
7/18/2012 5:52:23 PM ~!~Database~!~Processed: 100 devices...
7/18/2012 5:52:23 PM ~!~Database~!~Processed: 200 devices...
7/18/2012 5:52:23 PM ~!~Database~!~Finished: 258 devices, applying device changes to database...
7/18/2012 5:52:23 PM ~!~Database~!~Processed: 100 events...
7/18/2012 5:52:24 PM ~!~Database~!~Processed: 200 events...
7/18/2012 5:52:24 PM ~!~Database~!~Finished: 289 events, applying changes to the database...
7/18/2012 5:52:25 PM ~!~Database~!~Finished applying device changes to the database.
7/18/2012 5:52:25 PM ~!~Database~!~Updating Events
7/18/2012 5:52:26 PM ~!~Database~!~Updating Event Actions
7/18/2012 5:52:26 PM ~!~Database~!~Updating Event Conditions
7/18/2012 5:52:27 PM ~!~Database~!~Finished applying event changes to the database.
7/18/2012 5:52:27 PM ~!~Database~!~Finished applying TVTimer changes to the database.
7/18/2012 5:52:27 PM ~!~Shutdown~!~Saving device status ...
7/18/2012 5:52:27 PM ~!~Shutdown~!~Shutting down web server ...
7/18/2012 5:52:32 PM ~!~Web Server~!~Web Server stopped
7/18/2012 5:52:32 PM ~!~Shutdown~!~Shutting down email ...
7/18/2012 5:52:32 PM ~!~Shutdown~!~Shutting down threads ...
7/18/2012 5:52:33 PM ~!~Shutdown~!~-------------------------------------------------------------------
7/18/2012 5:52:33 PM ~!~Shutdown~!~ HomeSeer Successfully Shut Down Now
7/18/2012 5:52:33 PM ~!~Shutdown~!~-------------------------------------------------------------------
7/19/2012 12:20:42 AM ~!~Startup~!~
7/19/2012 12:20:42 AM ~!~Startup~!~*********************************************** *********************************
7/19/2012 12:20:42 AM ~!~Startup~!~ HomeSeer version 2.5.0.49 Starting Now (Standard Build)
7/19/2012 12:20:42 AM ~!~Startup~!~*********************************************** *********************************

**mnsandler** · July 19, 2012, 09:16 AM

are you manally logging the date and time when this occurs? maybe there is a pattern

also, anything in the detailed hs debug logs? check the folders

**gjtherk@comcast.net** · July 19, 2012, 10:28 AM

I was keeping a spreadsheet on these things, but stopped a while back.

What do I look for in the HS degug logs? (Not an I-T guy)

**mnsandler** · July 19, 2012, 10:32 AM

there should be a Debug Logs folder in your homeseer folder

just check each log file around the time of the shutdown and see if there is anything interesting. I'm not sure what to look for exactly

**mnsandler** · July 25, 2012, 09:06 PM

Anything interesting in the hs debug logs?

**gjtherk@comcast.net** · August 6, 2012, 12:28 PM

Still shutting down

I started documenting the shutdowns on July 26th. This is a pretty small sample and time frame, but it appears that it happens about every three days and the last event prior to shutdown is always a wireless motion detector/photo cell action. The time between the last event and shut down is 4 minutes in 3 cases and 2 minutes in 1 case.

<TABLE style="WIDTH: 569pt; BORDER-COLLAPSE: collapse" border=0 cellSpacing=0 cellPadding=0 width=758><COLGROUP><COL style="WIDTH: 48pt" width=64><COL style="WIDTH: 48pt" width=64><COL style="WIDTH: 53pt; mso-width-source: userset; mso-width-alt: 2560" width=70><COL style="WIDTH: 420pt; mso-width-source: userset; mso-width-alt: 20480" width=560><TBODY><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; WIDTH: 96pt; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0; mso-ignore: colspan" class=xl67 height=20 width=128 colSpan=2 align=center>Shutdown</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; WIDTH: 53pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl72 width=70>Script</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; WIDTH: 420pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl72 width=560>Last message prior to Shutdown</TD></TR><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl70 height=20>Date</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl71>Time</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl69></TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl69></TD></TR><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl65 height=20>07/26/12</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl66>21:14:21</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">Shutdown</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">7/26/2012 9:12:48 PM ~!~Insteon~!~Received \7 (Foyer Motion Detector) Go OFF</TD></TR><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl65 height=20>07/29/12</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl66>23:32:05</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">Shutdown</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">7/29/2012 11:27:04 PM ~!~Insteon~!~Motion Sensor 14.2B.BA settings read successfully.</TD></TR><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl65 height=20>08/03/12</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl66>8:43:57</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">Shutdown</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">8/3/2012 8:39:34 AM ~!~Insteon~!~Received _80 (Laundry Dusk/Dawn Sensor) Go ON</TD></TR><TR style="HEIGHT: 15pt" height=20><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; HEIGHT: 15pt; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl65 height=20>08/06/12</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0" class=xl66>10:28:03</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">Shutdown</TD><TD style="BORDER-BOTTOM: #f0f0f0; BORDER-LEFT: #f0f0f0; BACKGROUND-COLOR: transparent; BORDER-TOP: #f0f0f0; BORDER-RIGHT: #f0f0f0">8/6/2012 10:24:56 AM ~!~Insteon~!~Motion Sensor 14.69.48 settings read successfully.</TD></TR></TBODY></TABLE>

**mnsandler** · August 6, 2012, 01:18 PM

are the hs debug logs still active? anything interesting in them areound the same time and dates?

**gjtherk@comcast.net** · August 6, 2012, 09:34 PM

Debug

I thought it was running, but apparently not since July 6th. Tried to start using this command in the immediate script: &hs.debugmode=255
but I don't think it is working.

**gjtherk@comcast.net** · August 6, 2012, 09:35 PM

"syntax error" in regular log

**mnsandler** · August 7, 2012, 02:24 PM

i just tried the cmd and it worked for me and i got the following results in the log. read bottom up.

8/7/2012 3:23:13 PM System Current debug events set:
8/7/2012 3:23:12 PM System Control Panel Immediate Script: &hs.debugmode=0
8/7/2012 3:22:56 PM System Current debug events set: Scripts Events Conditions Status_Change Procedure_Trace Genuine_Channels Z-Wave Plug-In_Calls
8/7/2012 3:22:56 PM System Control Panel Immediate Script: &hs.debugmode=255

**gjtherk@comcast.net** · August 7, 2012, 03:02 PM

I wonder if a re-boot would be useful?

<TABLE border=0 cellSpacing=2 cellPadding=0 width="100%"><TBODY><TR><TD class=LOGDateTime0 noWrap align=left>8/7/2012 3:00:44 PM </TD><TD class=LOGType0 colSpan=3 align=left>Error </TD><TD class=LOGEntry0 colSpan=8 align=left>Running script, init error: Syntax error</TD></TR><TR><TD class=LOGDateTime1 noWrap align=left>8/7/2012 3:00:44 PM </TD><TD class=LOGType1 colSpan=3 align=left>System </TD><TD class=LOGEntry1 colSpan=8 align=left>Control Panel Immediate Script: &hs.debugmode=255&hs.debugmode=255</TD></TR></TBODY></TABLE>

**gjtherk@comcast.net** · August 7, 2012, 03:04 PM

Never mind

I see the problem. There was a duplicate script lurking below the dialogue box. Deleted that and it appears to be running now.

<TABLE border=0 cellSpacing=2 cellPadding=0 width="100%"><TBODY><TR><TD class=LOGDateTime0 noWrap align=left>8/7/2012 3:02:57 PM </TD><TD class=LOGType0 colSpan=3 align=left>System </TD><TD class=LOGEntry0 colSpan=8 align=left>Current debug events set: Scripts Events Conditions Status_Change Procedure_Trace Genuine_Channels Z-Wave Plug-In_Calls </TD></TR><TR><TD class=LOGDateTime1 noWrap align=left>8/7/2012 3:02:56 PM </TD><TD class=LOGType1 colSpan=3 align=left>System </TD><TD class=LOGEntry1 colSpan=8 align=left>Control Panel Immediate Script: &hs.debugmode=255</TD></TR></TBODY></TABLE>

**mnsandler** · August 7, 2012, 04:34 PM

ok, so maybe in a few days we'll have more info

btw, what is the "Read settings after On cmds" values you have set for the two MS listed in your log above?

**gjtherk@comcast.net** · August 7, 2012, 04:53 PM

Set to 5

Announcement

Mysterious Shutdown

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment

Comment