more G-Labs products

Author Topic: Recurring errors (approx every 3 hours)  (Read 4154 times)

July 24, 2015, 09:29:40 PM
Read 4154 times

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
I have multiple door and window sensors, with programs written to send me a text message whenever they open or close.  Has been working fine for months.  For the past 3 days I will periodically (~ every three hours) get mass notifications of these programs running.  This has happened before when I lost power in my house and the raspberry Pi i'm running HG on has restarted.  Now, it is happening unrelated to a power outage.  Here's what I see in my log file.  Any help troubleshooting is appreciated.

Code: [Select]
2015-07-23T21:02:14.3212350+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 08 00 04 04 08 02 84 07 7E
2015-07-23T21:02:14.3379820+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T21:02:14.3643470+00:00 HomeAutomation.ZWave 8 ZWave Node ZWaveNode.WakeUpNotify 1
2015-07-23T22:45:05.7662820+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 FF 2D
2015-07-23T22:45:05.7780190+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:45:05.7841410+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 255.0
2015-07-23T22:45:05.7853290+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 1
2015-07-23T22:45:06.0312320+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Running
2015-07-23T22:45:07.7531640+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 00 D2
2015-07-23T22:45:07.7580570+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:45:07.7777730+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 0.0
2015-07-23T22:45:07.8499630+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 0
2015-07-23T22:45:07.8627550+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Running
2015-07-23T22:45:08.5606530+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 FF 2D
2015-07-23T22:45:08.5881390+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:45:08.6941900+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 255.0
2015-07-23T22:45:08.6953560+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 1
2015-07-23T22:45:16.5744930+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 00 D2
2015-07-23T22:45:16.5784370+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:45:16.5876650+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 0.0
2015-07-23T22:45:16.5888250+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 0
2015-07-23T22:45:20.4111630+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 04 08 03 30 03 FF 31
2015-07-23T22:45:20.4181290+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:45:20.4200190+00:00 HomeAutomation.ZWave 8 ZWave Node Sensor.Generic 255
2015-07-23T22:45:20.4557290+00:00 HomeAutomation.HomeGenie.Automation 1010 Automation Program Program.Status Running
2015-07-23T22:45:28.2537930+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Idle
2015-07-23T22:45:29.0024620+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Idle
2015-07-23T22:45:38.5281170+00:00 HomeAutomation.HomeGenie.Automation 1010 Automation Program Program.Status Idle
2015-07-23T22:46:41.6090970+00:00 HomeAutomation.HomeGenie Service.StatisticsLogger SQLite error
table ValuesHist has 6 columns but 7 values were supplied Exception.StackTrace   at System.Data.SQLite.SQLite3.Prepare (System.Data.SQLite.SQLiteConnection cnn, System.String strSql, System.Data.SQLite.SQLiteStatement previous, UInt32 timeoutMS, System.String& strRemain) [0x00000] in <filename unknown>:0
  at System.Data.SQLite.SQLiteCommand.BuildNextCommand () [0x00000] in <filename unknown>:0
2015-07-23T22:58:42.1652130+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 04 08 03 30 03 00 CE
2015-07-23T22:58:42.1795380+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T22:58:42.1822880+00:00 HomeAutomation.ZWave 8 ZWave Node Sensor.Generic 0
2015-07-23T22:58:42.2214850+00:00 HomeAutomation.HomeGenie.Automation 1011 Automation Program Program.Status Running
2015-07-23T22:59:06.4323430+00:00 HomeAutomation.HomeGenie.Automation 1011 Automation Program Program.Status Idle
2015-07-23T23:00:46.1828390+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 FF 2D
2015-07-23T23:00:46.1880340+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T23:00:46.1945910+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 255.0
2015-07-23T23:00:46.2826360+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 1
2015-07-23T23:00:46.3575670+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Running
2015-07-23T23:00:51.3982770+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 00 D2
2015-07-23T23:00:51.4081210+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T23:00:51.4375740+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 0.0
2015-07-23T23:00:51.5019580+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 0
2015-07-23T23:00:51.5360210+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Running
2015-07-23T23:01:14.1974730+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Idle
2015-07-23T23:01:16.9232280+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Idle
2015-07-23T23:01:41.5324870+00:00 HomeAutomation.HomeGenie Service.StatisticsLogger SQLite error
table ValuesHist has 6 columns but 7 values were supplied Exception.StackTrace   at System.Data.SQLite.SQLite3.Prepare (System.Data.SQLite.SQLiteConnection cnn, System.String strSql, System.Data.SQLite.SQLiteStatement previous, UInt32 timeoutMS, System.String& strRemain) [0x00000] in <filename unknown>:0
  at System.Data.SQLite.SQLiteCommand.BuildNextCommand () [0x00000] in <filename unknown>:0
2015-07-23T23:54:14.5326670+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 04 03 20 01 FF 2B
2015-07-23T23:54:14.5480070+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T23:54:14.5501070+00:00 HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Basic 255.0
2015-07-23T23:54:14.6274980+00:00 HomeAutomation.ZWave 4 ZWave Node Status.Level 1
2015-07-23T23:54:14.7277650+00:00 HomeAutomation.HomeGenie.Automation 1004 Automation Program Program.Status Running
2015-07-23T23:54:28.1974570+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 04 03 20 01 00 D4
2015-07-23T23:54:28.2080950+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06
2015-07-23T23:5#Version: 1.0

July 24, 2015, 10:29:35 PM
Reply #1

dani

  • *****
  • Information
  • Global Moderator
  • Posts: 535
I think it's related to an error of the structure of database statistics file. Look at that post !
http://www.homegenie.it/forum/index.php?topic=976.msg5968#msg5968

Cheers
Dani

July 24, 2015, 10:59:12 PM
Reply #2

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Thanks dani, I've replaced my database statistics file with the one you linked to in the other post.  Will keep an eye on things over the next day or so.

July 24, 2015, 11:01:19 PM
Reply #3

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
As an aside, is there a recommended database size to specify for homegenie?

July 26, 2015, 12:48:56 PM
Reply #4

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Unfortunately around 5AM today it happened again.  Here is the log file.  The second item in the log file is "#Start-Date".  Does this mean that HG had restarted itself?

Code: [Select]
#Software: HomeGenie 1.0.0.0
#Start-Date: 2015-07-26T09:33:23.9028570+00:00
"#Fields: datetime source-domain source-id description property value"

"2015-07-26T09:33:26.9287990+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output MIG ERROR: could not load interface 'EmbeddedSystems.RaspiGPIO': Argument cannot be null."
Parameter name: type
"2015-07-26T09:33:27.0346330+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output MIG ERROR: could not load interface 'EmbeddedSystems.Weeco4mGPIO': Argument cannot be null."
Parameter name: type
#Version: 1.0
#Software: HomeGenie 1.0.0.0
#Start-Date: 2015-07-26T09:33:27.7234590+00:00
"#Fields: datetime source-domain source-id description property value"

"2015-07-26T09:33:27.6073390+00:00 HomeAutomation.HomeGenie 0 HomeGenie service ready SystemInfo.HttpPort 8084"
"2015-07-26T09:33:27.7601730+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output MIG ERROR: could not load interface 'EmbeddedSystems.RaspiGPIO': Argument cannot be null."
Parameter name: type
"2015-07-26T09:33:27.7611140+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output MIG ERROR: could not load interface 'EmbeddedSystems.Weeco4mGPIO': Argument cannot be null."
Parameter name: type
"2015-07-26T09:33:35.2340380+00:00 HomeAutomation.HomeGenie.Automation 6 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.2496310+00:00 HomeAutomation.HomeGenie.Automation 7 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.2598810+00:00 HomeAutomation.HomeGenie.Automation 16 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.2649540+00:00 HomeAutomation.HomeGenie.Automation 37 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.2700670+00:00 HomeAutomation.HomeGenie.Automation 39 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.2941970+00:00 HomeAutomation.HomeGenie.Automation 72 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3003350+00:00 HomeAutomation.HomeGenie.Automation 73 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3066560+00:00 HomeAutomation.HomeGenie.Automation 74 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3121690+00:00 HomeAutomation.HomeGenie.Automation 75 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3177680+00:00 HomeAutomation.HomeGenie.Automation 84 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3236560+00:00 HomeAutomation.HomeGenie.Automation 90 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3287340+00:00 HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3477540+00:00 HomeAutomation.HomeGenie.Automation 142 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3529720+00:00 HomeAutomation.HomeGenie.Automation 143 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3581780+00:00 HomeAutomation.HomeGenie.Automation 144 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3632820+00:00 HomeAutomation.HomeGenie.Automation 154 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3688170+00:00 HomeAutomation.HomeGenie.Automation 180 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3739790+00:00 HomeAutomation.HomeGenie.Automation 200 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3794920+00:00 HomeAutomation.HomeGenie.Automation 81 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3846810+00:00 HomeAutomation.HomeGenie.Automation 93 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.3896730+00:00 HomeAutomation.HomeGenie.Automation 76 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.4046580+00:00 HomeAutomation.HomeGenie.Automation 100 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.8219080+00:00 HomeAutomation.HomeGenie.Automation 8 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.8365710+00:00 HomeAutomation.HomeGenie.Automation 62 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.8417610+00:00 HomeAutomation.HomeGenie.Automation 133 Automation Program Program.Status Idle"
"2015-07-26T09:33:35.8574800+00:00 HomeAutomation.HomeGenie.Automation 28 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0220380+00:00 HomeAutomation.HomeGenie.Automation 36 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0296130+00:00 HomeAutomation.HomeGenie.Automation 1000 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0362100+00:00 HomeAutomation.HomeGenie.Automation 1001 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0433860+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0502250+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0575680+00:00 HomeAutomation.HomeGenie.Automation 1004 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0638210+00:00 HomeAutomation.HomeGenie.Automation 1005 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0713830+00:00 HomeAutomation.HomeGenie.Automation 1006 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0776530+00:00 HomeAutomation.HomeGenie.Automation 1007 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0838450+00:00 HomeAutomation.HomeGenie.Automation 1008 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0903550+00:00 HomeAutomation.HomeGenie.Automation 1009 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.0965530+00:00 HomeAutomation.HomeGenie.Automation 1010 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1033670+00:00 HomeAutomation.HomeGenie.Automation 1011 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1098010+00:00 HomeAutomation.HomeGenie.Automation 34 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1151530+00:00 HomeAutomation.HomeGenie.Automation 35 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1201710+00:00 HomeAutomation.HomeGenie.Automation 70 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1250360+00:00 HomeAutomation.HomeGenie.Automation 82 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1299540+00:00 HomeAutomation.HomeGenie.Automation 88 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1350440+00:00 HomeAutomation.HomeGenie.Automation 112 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1405590+00:00 HomeAutomation.HomeGenie.Automation 145 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1529020+00:00 HomeAutomation.HomeGenie.Automation 77 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1595950+00:00 HomeAutomation.HomeGenie.Automation 38 Automation Program Program.Status Idle"
"2015-07-26T09:33:36.1832670+00:00 HomeAutomation.HomeGenie.Automation 150 Automation Program Program.Status Idle"
"2015-07-26T09:33:38.1060050+00:00 HomeAutomation.HomeGenie.Automation 1012 Automation Program Program.Status Idle"
"2015-07-26T09:33:38.1114070+00:00 HomeAutomation.HomeGenie.Automation 26 Automation Program Program.Status Idle"
"2015-07-26T09:33:49.9295700+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 03 00 02 FE"
"2015-07-26T09:33:43.7694700+00:00 HomeGenie.UpdateChecker 0 HomeGenie Update Checker InstallProgress.Update STARTED"
"2015-07-26T09:33:44.1493880+00:00 HomeAutomation.HomeGenie.Automation 90 Automation Program HomeGenie.SecurityArmed 0"
"2015-07-26T09:33:49.9565130+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06"
"2015-07-26T09:33:49.9732180+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.0094030+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 25 01 02 05 00 1D FF 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 03 01 3C"
"2015-07-26T09:33:50.0420350+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.1011140+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 1"
"2015-07-26T09:33:50.1020250+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 2"
"2015-07-26T09:33:50.1028290+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 3"
"2015-07-26T09:33:50.1036690+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 4"
"2015-07-26T09:33:50.1043870+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 5"
"2015-07-26T09:33:50.1052270+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 6"
"2015-07-26T09:33:50.1060610+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 7"
"2015-07-26T09:33:50.1067400+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 8"
"2015-07-26T09:33:50.1213750+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 01 BB"
"2015-07-26T09:33:50.1641410+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 92 16 00 02 02 01 33"
"2015-07-26T09:33:50.1664760+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.1706010+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.2230830+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 02 B8"
"2015-07-26T09:33:50.2667410+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.2688860+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.2697360+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.3241190+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 03 B9"
"2015-07-26T09:33:50.3691950+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.3713220+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.3722750+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.4250780+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 04 BE"
"2015-07-26T09:33:50.4716700+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.4738020+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.4793010+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.5480850+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 05 BF"
"2015-07-26T09:33:50.5965900+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.5987310+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.5996740+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.6490480+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 06 BC"
"2015-07-26T09:33:50.6990780+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.7010830+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.7019850+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.7501500+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 07 BD"
"2015-07-26T09:33:50.8015350+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.8035090+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.8044920+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.8511050+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 01 04 00 41 08 B2"
"2015-07-26T09:33:50.9038340+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 06 01 09 01 41 53 9C 00 04 20 01 5C"
"2015-07-26T09:33:50.9184980+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.9200620+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T09:33:50.9528370+00:00 HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Discovery Complete"
"2015-07-26T09:33:39.7425230+00:00 HomeAutomation.HomeGenie.Automation 84 Automation Program Program.Status Setup"
"2015-07-26T09:33:40.9182510+00:00 HomeAutomation.HomeGenie.Automation 84 Automation Program Program"

July 26, 2015, 07:36:03 PM
Reply #5

Gene

  • *****
  • Information
  • Administrator
  • Posts: 1472
  • Tangible is the future!
    • Yet Another Programmer
these errors

Code: [Select]
MIG ERROR: could not load interface 'EmbeddedSystems.RaspiGPIO'
MIG ERROR: could not load interface 'EmbeddedSystems.Weeco4mGPIO'

are due to the presence of an old configuration file.
You can open the systemconfig.xml file with a text editor and remove those two interfaces from the MIG section.

Code: [Select]
#Start-Date: 2015-07-26T09:33:23.9028570+00:00

this line in the log is only put once when HG is started.
If you think that this was unexpected, you can look at previous lines in the log file and see what was logged before the restart.

g.


July 26, 2015, 10:37:02 PM
Reply #6

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Thanks Gene.  I've removed those two interfaces from systemconfig.xml.  Will continue to monitor. 

The only thing I see prior to the restart are a lot of "StdErr redirectConsole.OutputSPI and OutputSPO messages:

Code: [Select]
"2015-07-26T02:13:30.2951950+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Running"
"2015-07-26T02:13:35.6193690+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 00 D2"
"2015-07-26T02:13:35.6216800+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T02:13:35.6237260+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 0.0"
"2015-07-26T02:13:35.6785680+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 0"
"2015-07-26T02:13:35.7863690+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Running"
"2015-07-26T02:13:37.7099420+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Idle"
"2015-07-26T02:13:41.5309250+00:00 HomeAutomation.HomeGenie.Automation 1003 Automation Program Program.Status Idle"
"2015-07-26T02:14:27.9339370+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 FF 2D"
"2015-07-26T02:14:27.9363320+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"
"2015-07-26T02:14:27.9383200+00:00 HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 255.0"
"2015-07-26T02:14:28.0007520+00:00 HomeAutomation.ZWave 2 ZWave Node Status.Level 1"
"2015-07-26T02:14:28.0643890+00:00 HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Running"
"2015-07-26T02:14:33.4195470+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPI > 01 09 00 04 00 02 03 20 01 00 D2"
"2015-07-26T02:14:33.4218960+00:00 HomeGenie.System Console StdOut/StdErr redirect Console.Output SPO < 06"

July 27, 2015, 08:49:45 AM
Reply #7

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Issue persists on my machine. One thing I've noticed is that mono appears to have abnormally high cpu usage on my raspberry pi. It averages between 95 and 98 percent, with load numbers averages above 2. Is this normal for an install of mono that is supporting homegenie alone?

July 27, 2015, 06:22:08 PM
Reply #8

dani

  • *****
  • Information
  • Global Moderator
  • Posts: 535
Does you try to change SD Card. Or try to reformat it nd make new install ?

July 27, 2015, 06:41:15 PM
Reply #9

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Hi dani,

I haven't yet, I have been avoiding doing that as I didn't want to re-setup everything.

If I backup my HG configuration and start from scratch, does importing the config backup restore all z-wave devices that were paired to the z-wave control stick, or does that have to be re-done manually?

July 27, 2015, 11:41:23 PM
Reply #10

dani

  • *****
  • Information
  • Global Moderator
  • Posts: 535
When I use HG backup, my restauration works always. I dont lose any node and groups and progs

July 28, 2015, 03:25:22 AM
Reply #11

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Thanks dani, i did a clean install and my mono CPU usage is back to normal levels.

July 31, 2015, 09:23:52 PM
Reply #12

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Unfortunately my issue still persists.  Went to a raspberry pi 2, installed latest version of mono, and I periodically get resets due to high CPU loads.  See screenshot below:

http://i.imgur.com/ZpjDdqR.png

Any thoughts as to what I can do to troubleshoot?  I have no "complex" programs added, only those to send email notifications of door openings and closings, as well as 3 webcams.

August 01, 2015, 02:16:52 PM
Reply #13

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
strace of one of the high CPU utilization threads:

Code: [Select]
--- SIGPWR (Power failure) @ 0 (0) ---
rt_sigprocmask(SIG_BLOCK, [XCPU], NULL, 8) = 0
futex(0x22ab38, FUTEX_WAKE_PRIVATE, 1)  = 1
rt_sigsuspend(~[XCPU RTMIN RT_1])       = ? ERESTARTNOHAND (To be restarted)
--- SIGXCPU (CPU time limit exceeded) @ 0 (0) ---
rt_sigreturn(0x1b7f110)                 = -1 EINTR (Interrupted system call)
rt_sigprocmask(SIG_UNBLOCK, [XCPU], NULL, 8) = 0
futex(0x22ab38, FUTEX_WAKE_PRIVATE, 1)  = 1
rt_sigreturn(0x76cef54c)                = 1
--- SIGPWR (Power failure) @ 0 (0) ---
rt_sigprocmask(SIG_BLOCK, [XCPU], NULL, 8) = 0
rt_sigsuspend(~[XCPU RTMIN RT_1])       = ? ERESTARTNOHAND (To be restarted)
--- SIGXCPU (CPU time limit exceeded) @ 0 (0) ---
rt_sigreturn(0x1b7f110)                 = -1 EINTR (Interrupted system call)
rt_sigprocmask(SIG_UNBLOCK, [XCPU], NULL, 8) = 0
rt_sigreturn(0x76cef54c)                = 1991784768
--- SIGPWR (Power failure) @ 0 (0) ---
rt_sigprocmask(SIG_BLOCK, [XCPU], NULL, 8) = 0
futex(0x22ab38, FUTEX_WAKE_PRIVATE, 1)  = 1
rt_sigsuspend(~[XCPU RTMIN RT_1])       = ? ERESTARTNOHAND (To be restarted)
--- SIGXCPU (CPU time limit exceeded) @ 0 (0) ---
rt_sigreturn(0x1b7f110)                 = -1 EINTR (Interrupted system call)
rt_sigprocmask(SIG_UNBLOCK, [XCPU], NULL, 8) = 0
rt_sigreturn(0x76cef54c)                = 1992999216
--- SIGPWR (Power failure) @ 0 (0) ---
rt_sigprocmask(SIG_BLOCK, [XCPU], NULL, 8) = 0
futex(0x22ab38, FUTEX_WAKE_PRIVATE, 1)  = 1
rt_sigsuspend(~[XCPU RTMIN RT_1])       = ? ERESTARTNOHAND (To be restarted)
--- SIGXCPU (CPU time limit exceeded) @ 0 (0) ---
rt_sigreturn(0x1b7f110)                 = -1 EINTR (Interrupted system call)
rt_sigprocmask(SIG_UNBLOCK, [XCPU], NULL, 8) = 0
rt_sigreturn(0x76cef54c)                = 1992999216

August 04, 2015, 05:08:56 PM
Reply #14

MJCfromCT

  • *
  • Information
  • Newbie
  • Posts: 22
Several days later and still experiencing this issue.  Watching "top" I notice that CPU usage is not instantly changing from "no load" to 100% utilization, but it is a steady increase from 0-100% utilization.

Any thoughts as to how to troubleshoot this?  The only programs I'm running are the following:

Code: [Select]
// send email
Net.SendMessage("[email protected]",   // recipients
                "HomeGenie - Back Door Closed",                          // subject
                "Back Door Closed");        // message text

With the following trigger code:

Code: [Select]
// trigger code
return Modules.InGroup("Main Level")
             .WithName("Back Door").Get() // get reference to the selected module
             .Parameter("Status.Level").DecimalValue == 0;

The trigger logic is set to trigger when condition evaluation switches to "true"

Now, I have a similar program for door/window openings, just that i change the Status.Level.DecimalValue in the trigger code to "==1".  I have 5 door/window sensors, so I have 10 of these programs (there is probably a way to combine all 10 into one program, but I am not C# savvy enough to figure that out!).  Is the fact that I have 10 of these small programs running causing the high CPU load?