Is the Smart Virtual Thermostat killing my Vera?

AndersH · September 25, 2013, 7:36pm

I have two Smart Virtual Thermostats which have been working very good since May.
Now, I have done a few upgrades to my system: added a SRT321 thermostat and a Fibaro Wall plug, added a virtual switch and a countdown timer. I have several virtual switches and countdown timers already, so there are no new plugins.

I have however removed some unused plugins.

Now, my Vera does Luup restarts and reboots several times an hour. My LuaUPnP.log file grows at an alarming rate and is zipped and restarted within a matter of minutes.
The main content of the log file looks like the SmartVT1 is taking most of the focus / time /logging performance.
This is a random selection of lines from the log. To me, it looks like the SVT loops very fast, logging a lot of variable changes although there is no change in the variable’s values.

06 09/25/13 21:21:45.462 Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1800 #hooks: 0 upnp: 0 v:0xd16350/NONE duplicate:1 <0x2c5bf680>
06 09/25/13 21:21:45.464 Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 16 now: 16 #hooks: 0 upnp: 0 v:0xd25588/NONE duplicate:1 LEAK this:4096 start:11993088 to 0x1d29000 <0x2c5bf680>
06 09/25/13 21:21:45.465 Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,50,50,16,2.4,32.2 now: 1,50,50,16,2.4,32.2 #hooks: 0 upnp: 0 v:0xd162b0/NONE duplicate:1 <0x2c5bf680>
06 09/25/13 21:21:45.466 Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 6.6 now: 6.6 #hooks: 0 upnp: 0 v:0xd16330/NONE duplicate:1 <0x2c5bf680>

Any suggestion on what is going on, or how to proceed to get my Vera stable again? Restoring a backup seems like a good idea right now…

RichardTSchaefer · September 25, 2013, 8:21pm

If you do not already have a USB stick for storing the Log file … I would recommend that as well.
See Setup then Logs.

It looks like there are things the plugin developer can do as well to limit the log output (like not setting variables then they do not change!).

AndersH · September 26, 2013, 5:46am

[quote=“RichardTSchaefer, post:2, topic:177085”]If you do not already have a USB stick for storing the Log file … I would recommend that as well.
See Setup then Logs.[/quote]
I don’t have a USB stick for the log file, as I have a Vera Lite and it supports only one USB port. I use that one for my dataMine USB stick. I haven’t really studied the details, but my impression is that it’s difficult to combine dataMine and Vera logs on the same stick?

Is there anything I can do (configuration change) to limit the number of log entries per second?
As I mentioned, my log file grows to megabyte size within minutes. That surely can’t be the way it was intended?

olivas · September 26, 2013, 5:51am

What else do you have enabled/installed on your Vera? I noticed that the Energy plugin was killing my system until I removed it completely. Was pretty useless to me.

I was having the exact same symptoms until I removed it.

AndersH · September 26, 2013, 6:22am

[quote=“SOlivas, post:4, topic:177085”]What else do you have enabled/installed on your Vera? I noticed that the Energy plugin was killing my system until I removed it completely. Was pretty useless to me.

I was having the exact same symptoms until I removed it.[/quote]

As a matter of fact I removed the Ergy yesterday. I browsed thru the logs and saw a lot of Ergy related logging / error messgages. Since I do not really use Ergy, I removed it.
A couple of days ago, I removed the PL plugins and the Samsung TV plugin. I did not see any ill effects of these, but I didn’t need them either.
The system got somewhat more free memory, but didn’t get more stable.

I also observe that the free memory slowly is reduced in size. In the logs, there are some indications towards memory leakage. This could be a part of my explanation, but how do I (as an end user of the plugins) go about to figure out where the problem is?

Anyway, my system includes:
1 VeraLite
2x Fibaro 2x1.5kw micro switches
1 Fibaro micro dimmer
2x Fibaro door sensors with temp sensor attached
2 x Fibare Wall plug
1 Secure SRT321 thermostat (paired to one of the wall plugs)

Plugins:
2x Virtual Smart Thermostat (using the Fibaro door sensors for inhibit and temperature), controlling Fibaro Wall plug or micro switch.
several Virtual Switches
several Combination switches
2 countdown timers
dataMine
System monitor
Heliotrope
Weather plugin
4x Ping sensors

40 devices in total, including the virtual ones.

I plan to add two more Secure thermostats, a couple of wall plugs, a dimmer and a couple of 2x1.5 switches in the near future. I need to control more of my electric heating as the winter appoaches.
I would say this is still a small system. The vera Lite should surely be capable of this? I have read that the Lite should be used in systems up to 50 devices. Does that include virtual devices or z-wave devices only? If it includes virtual devices I’m getting close to that limit.

niharmehta · September 26, 2013, 6:40am

[quote=“AndersH, post:5, topic:177085”]Plugins:
2x Virtual Smart Thermostat (using the Fibaro door sensors for inhibit and temperature), controlling Fibaro Wall plug or micro switch.
several Virtual Switches
several Combination switches
2 countdown timers
dataMine
System monitor
Heliotrope
Weather plugin
4x Ping sensors[/quote]

Try disabling your Ping sensors. I had stability issues in the past related to ping sensors consuming resources and not letting go.

AndersH · September 26, 2013, 6:51am

Is there a way to disable without deleting them?

niharmehta · September 26, 2013, 2:47pm

I think you can disarm them. Give that a shot first.

AndersH · September 27, 2013, 7:04pm

[quote=“RichardTSchaefer, post:2, topic:177085”]If you do not already have a USB stick for storing the Log file … I would recommend that as well.
See Setup then Logs.[/quote]

Thanks for pointing this out. I have now started Vera logging together with the datamine (per dataMine instructions) on one common 16Gb USB Stick.
I did this only done half an hour ago, so time will tell if this is the mod that makes my Vera more stable.
The problem comes and goes, e.g. today it has run for 10 hours since last reboot.

niharmehta · September 27, 2013, 9:26pm

With ping sensor enabled or disabled ?

AndersH · September 28, 2013, 5:02pm

The ping sensors are still in use.

AndersH · September 29, 2013, 6:46pm

I think I now understand what was going on regarding the logs. I think the Smart Virtual Thermostat overloaded the system due to some unfortunate configuration settings. This SVT looped rapidly, filled up the log files. Vera then rebooted due to out of memory or a similar problem. This would happen several times an hour.

Thanks a lot for all suggestions.
I have now set SVT’s parameters ConstC, ConstT and AutoLearning back to default settings, effectively telling it to forget it’s learned constants and start over learning them again.
It remains to be seen if this actually cures it in the long run, but at least it looks good for now.

Edit 30.9: Several reboots during the night, I have too check the logs when I get home. Perhaps the rapid filling of the log file is solved, but the system is still pretty unstable.
Edit 6.10: The problem is not solved. It still happens from time to time.

AndersH · October 6, 2013, 6:10am

Here is a fragment from my log, showing a fraction of a second of the log. In total, I counted 302 lines of log for one second.

10/06/13 7:33:35.000	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.001	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037614 now: 1381037614 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.001	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037759 now: 1381037759 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.002	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.025	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.026	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 145 now: 144 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.027	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1799 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.029	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.030	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.030	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.033	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.055	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.056	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037614 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.057	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037759 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.057	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.060	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.060	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 144 now: 145 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.061	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1799 now: 1800 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:0 <0x2c09d680>
06	10/06/13 7:33:35.090	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.091	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.091	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.114	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.115	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.115	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037615 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.116	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037760 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.116	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.119	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.119	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 145 now: 145 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.120	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1800 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.122	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.123	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.144	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.146	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.148	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.148	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037615 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.148	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037760 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.149	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.151	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.152	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 145 now: 145 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.152	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1800 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.175	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.176	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.176	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.178	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.179	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.180	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037615 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.180	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037760 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.180	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.183	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.204	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 145 now: 145 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.205	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1800 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.207	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.208	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.208	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.211	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.212	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.212	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037615 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.212	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037760 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.213	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.235	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.236	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: heatTimeleft was: 145 now: 145 #hooks: 0 upnp: 0 v:0x896998/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.237	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: calTimeleft was: 1800 now: 1800 #hooks: 0 upnp: 0 v:0x8964c0/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.239	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.240	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.240	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: ConstT was: 7.9 now: 7.9 #hooks: 0 upnp: 0 v:0x8969b8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.243	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.243	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:HVAC_OperatingState1 variable: ModeState was: Heating now: Heating #hooks: 0 upnp: 0 v:0x8974c8/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.264	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: TimesStamp was: 1381037615 now: 1381037615 #hooks: 0 upnp: 0 v:0x863b90/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.265	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: EndHeatTimesStamp was: 1381037760 now: 1381037760 #hooks: 0 upnp: 0 v:0x87b398/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.265	Device_Variable::m_szValue_set device: 7 service: urn:micasaverde-com:serviceId:SmartVT1 variable: AutoLearning was: 1,5,50,13,2.3,8.1 now: 1,5,50,13,2.3,8.1 #hooks: 0 upnp: 0 v:0x896460/NONE duplicate:1 <0x2c09d680>
06	10/06/13 7:33:35.268	Device_Variable::m_szValue_set device: 7 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 13 now: 13 #hooks: 0 upnp: 0 v:0x8a5bd8/NONE duplicate:1 <0x2c09d680>

When the log is filled at this rate, the Vera CPU is quite loaded, and a luup restart or Vera reboot will be the result (in a few minutes).

RichardTSchaefer · October 6, 2013, 7:50am

Well that doesn’t look good … looks like it is in a constant loop.

AndersH · October 6, 2013, 5:27pm

I agree. The big question is then why?
A lot of users run this plugin with success, as I have done myself since I set up my VeraLite in May 2013. Also, I run two instances, of which only one seems to have this problem (although intermittently). However, it must be said that the other one has hardly been used to control the heat, as it is in my workshop, and I do not heat it during summer. The Smart Virtual Thermostat has been running, but only in “eco” mode, and with the room temperature above the thermostat’s setpoint.

The instance giving me trouble has on the other hand been controlling the heat in my chicken shed since May. I use the window sensor as inhibit. The window is open during the day (hence the heat is off) and closed during the night. During the night, the thermostat controls the heat and does it well. But from time to time this infinite loop behaviour takes over the complete Vera and runs it in the ditch.
I think this might be the same problem as discussed in
[url=http://forum.micasaverde.com/index.php/topic,9425.90.html]http://forum.micasaverde.com/index.php/topic,9425.90.html[/url]

AndersH · October 21, 2013, 6:25am

I am afraid the short answer to the question asked in the topic is “yes”.
I have tried quite a few different things over the past weeks, but the change that got my Vera back to its normal, stable state was removing the Smart Virtual Termostat instance that caused the trouble. I have now replaced it by a combination switch and a couple of scenes that will turn on the heat if the window is closed, and the inside temp is below 12 degrees.

However, my other Smart Virtual Thermostat is left untouched, and seems to work without any problems (so far). Perhaps there was some odd settings in the first one, or perhaps having two instances is the real problem? I’m sorry, I cannot say.

I have run it like this for approx one week now, and I have not seen any Vera reboots in that period. Also, the only luup engine restarts I see are the automatic ones in the middle of the night.

clarry · January 10, 2014, 8:56pm

I have the same problem described here. I’m using Vera Lite with 3 Everspring wall switches and one Fibaro wall plug. For temperature sensors I use 3 in 1 EZMotion. I’ve installed 3 SVT devices and as result I started having vera restarting random number of times per hour. As you can imagine at moments access to Vera was close to impossible.

The reason to chose using SVT was of course to save some money on additional devices and it’s convenient that it appears in the Vera mobile app as normal thermostat, well not completely as it’s showing only part of the buttons visible in UI5.

Apart from entering the IDs for temp sensors and heaters I’ve changed no other configuration or whatsoever in this plugin.

So far no remedy was found. As result I kind of gave up and went into direction of using scenes to control my heaters. Really disappointing experience for the past 2 months:(, having in mind that I bought Vera to have better control on the heating.

So if anyone can give hint how to fix this problem I’d be really grateful.

houk · January 12, 2014, 7:50pm

I have 2 virtual thermostat working since 1,5 year without problem. But both have diffrent temp sensors and heaters. Only one sensor is written to both. Outside temp.

My version of plugin is 2.5. Maybe this has minning.

Im not using inhibit Sensors. This set is empty

clarry · January 13, 2014, 9:07pm

Are your SVTs switched on the whole time? I mean do you switch them off from time to time? Mine are always on, either on Eco or Comfort, sometimes on Heat.
For me when I install one device the first several days are ok. Then step by step I start getting these restarts and frequency increases. If I use three SVTs then time before all these restarts starts is shorter. It looks like really that somewhee logs start pile up or some kind of memory leak is there.

My version of the SVT is the latest on the AppStore i.e. 2.5.

Debugging Vera logs is not really and easy job. One need really to know the internals. Even in UI5 the alerts are not really self explanable for instance what the heck should mean "?heck logs XXX’? The guys should really work more on the supportability aspect of the product

clarry · January 17, 2014, 9:00am

I have to report that after the last post I tracked how much time it takes to reach this restart situation with a single SVT device. About 4 days.

I’ve removed the device, re-added it and configured the inside, outside temp. sensors and added the heater. It needed about 4 days to reach situation where frequent restarts again appeared.

Does anyone has an idea what might be different from other users reporting that they have no problems with SVT? Z-Wave communication, vera config, etc?