eventwatcher isn't reporting on anything....

lionelandjen · July 10, 2015, 5:03pm

hi,

so I have a setup at home which works perfectly.

I have a setup at a separate location,which has 2 devices: the exact same thermostat than at home and an IR repeater.

I’ve setup eventwatcher using an external config file using the same values as home, except different device ID for the thermostat:

cat /www/EWextras.conf 3.urn:upnp-org:serviceId:TemperatureSensor1.CurrentTemperature 3.urn:upnp-org:serviceId:TemperatureSetpoint1_Heat.CurrentSetpoint 3.urn:upnp-org:serviceId:HVAC_UserOperatingMode1.ModeStatus 3.urn:micasaverde-com:serviceId:HVAC_OperatingState1.ModeState

to check that I have the right device ID and config, I pulled the logs for this device:

grep 'device: 3' /tmp/log/cmh/LuaUPnP.log | awk '{print $8}' | sort -u urn:micasaverde-com:serviceId:HaDevice1 urn:micasaverde-com:serviceId:ZWaveDevice1 urn:upnp-org:serviceId:HVAC_FanOperatingMode1 urn:upnp-org:serviceId:TemperatureSensor1 urn:upnp-org:serviceId:TemperatureSetpoint1

and, to be sure it’s actually recording the temperature, I pulled them out of the logs:

grep 'device: 3' /tmp/log/cmh/LuaUPnP.log | grep CurrentTemperature 06 07/10/15 11:32:06.849 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 78 now: 78 #hooks: 1 upnp: 0 skip: 0 v:0xba5810/NONE duplicate:1 <0x776a6520> 06 07/10/15 11:35:02.799 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 78 now: 78 #hooks: 1 upnp: 0 skip: 0 v:0x954820/NONE duplicate:1 <0x77840520> 06 07/10/15 11:46:30.039 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 78 now: 79 #hooks: 1 upnp: 0 skip: 0 v:0xd6c7c8/NONE duplicate:0 <0x76ffa520> 06 07/10/15 12:00:26.740 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 1 upnp: 0 skip: 0 v:0x79fac0/NONE duplicate:1 <0x76f42520> 06 07/10/15 12:12:08.056 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 2 upnp: 0 skip: 0 v:0x773ac0/NONE duplicate:1 <0x77544520> 06 07/10/15 12:14:49.266 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 2 upnp: 0 skip: 0 v:0x773ac0/NONE duplicate:1 <0x77544520> 06 07/10/15 12:43:57.841 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 2 upnp: 0 skip: 0 v:0x773ac0/NONE duplicate:1 <0x77544520> 06 07/10/15 12:47:37.851 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 2 upnp: 0 skip: 0 v:0xac17f8/NONE duplicate:1 <0x777c0520> 06 07/10/15 12:50:24.301 Device_Variable::m_szValue_set device: 3 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 79 now: 79 #hooks: 2 upnp: 0 skip: 0 v:0xac17f8/NONE duplicate:1 <0x777c0520>

and yet ip:3480/data_request?id=lr_EventWatcher&plot=3&variable=CurrentTemperature returns nothing.

and when trying to get the logs out, all Isee is the RESTART :

Date/Time Class Device No. Device Name Variable Value Argument

1 2015-07-10, 12:47:31.184 E 0 Vera RESTART

and here’s the list of watched variables:

Device No. Device Name Service Variable Room 3 Thermostat TemperatureSensor1 CurrentTemperature /www/EWextras.conf 3 Thermostat UserOperatingMode1 ModeStatus /www/EWextras.conf 3 Thermostat Heat CurrentSetpoint /www/EWextras.conf 3 Thermostat OperatingState1 ModeState /www/EWextras.conf

even weirder, CPU reporting works just fine.

so,clearly,I’m doing something wrong, but what?

akbooer · July 10, 2015, 5:14pm

Can you confirm the version of EventWatcher on both of your systems?

My first guess would be the the second installation is the App Store install which may not have the ‘Extras’ capability.

lionelandjen · July 10, 2015, 9:00pm

2015.01.30 @akbooer

lionelandjen · July 10, 2015, 9:13pm

2014.12.07 @akbooer at home where it works.

akbooer · July 10, 2015, 9:42pm

Does it report on things NOT in the Extras file?

lionelandjen · July 11, 2015, 3:44am

That’s the thing… There isn’t much to report on… All I have is the thermostat and an ir repeater. All I have in the logs is the restart mention showing up.

akbooer · July 11, 2015, 6:53am

Nothing inVera’s log at startup to indicate an EW failure?

I’m wondering why you’re using thevExtras files, since these are standard device variables? Have you tried just using the standard categories?

Bit of a puzzler, this one!

akbooer · July 12, 2015, 7:44am

I’m wondering if this is due to some unexpected control characters in the Extras file. Did you, perhaps, use a different editor which puts in cr/lf? I’ve not yet checked the code which should strip these things out, but I’ve come across instances like this before.

What system(s) are you running?

lionelandjen · July 15, 2015, 6:21pm

I used vi to edit the extras file, so that wasn’t the issue

but I figured out the issue…

poll interval is set to 10800, so it didn’t poll frequently enough. additionally the thermostat I use requires an extrasconfig file for some reason (you helped me set it up in the first place) but I forgot that it also creates additional “ghost” devices. I was using the wrong device ID…

I just needed to be a lot more patient. oddly, though, hitting the “poll Now” button doesn’t seem to do anything?

akbooer · July 15, 2015, 6:46pm

Ah yes. If the value doesn’t update, then nothing gets logged!

Glad you have it sorted… I was grasping at straws.