New plug-in (re-written from scratch) for OpenSprinkler, supporting zones, programs and more!

therealdb · June 6, 2020, 5:56am

Thanks for trying and spotting the spelling error. Fixed now.

Yes, the same fear I have is that it’s truncating the response. I will probably soon change the code to use curl directly and see if that’s better. It seems that, from time to time, some thinks are missing from the response, both on Vera and Openluup. So it’s something related to the http library or OpenSprinkler API or both. I’m committed to fix it anyway. Thanks for helping, since I cannot repro this particular problem on my system.

TedM · June 6, 2020, 8:16am

Here’s the latest log from tonight about 10 minutes after I reloaded luup - this is just a section of it - couple interesting bits (I ran it through grep OpenSprinkler to eliminate the chaff)

After doing a luup.reload() I counted the plugin queried/refreshed from OSPi a total of 22 times each time getting the partial response and issuing the “got a nil response from API” before finally getting the full response and adding the device.

TedM · June 6, 2020, 8:39am

Moving the slider does turn on the sprinkler. Here is the log output for putting 10% (10 minutes) into one of the zones. And I confirmed this with the regular Opensprinkler webinterface that the sprinkler turned on. However, once the 10 minute time completes on OpenSprinkler and the water shuts off, and OpenSprinkler shows that the water is off, the slider on the device in Vera does not return to 0 it stays at 10%.

50 06/06/20 1:36:16.377 luup_log:51: VeraOpenSprinkler1.4.2: actionDimming: 52, 10, true <0x745e9520>
50 06/06/20 1:36:16.378 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“10”,52) old value “” <0x745e9520>
50 06/06/20 1:36:16.380 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelLast”,“10”,52) old value “” <0x745e9520>
50 06/06/20 1:36:16.392 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“10”,52) old value “” <0x745e9520>
50 06/06/20 1:36:16.393 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:SwitchPower1”,“Target”,“1”,52) old value “” <0x745e9520>
50 06/06/20 1:36:16.395 luup_log:51: VeraOpenSprinkler1.4.2: actionPower: 52 - 0 <0x745e9520>
50 06/06/20 1:36:16.396 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand(“cm”,{ 1=“en=1”, 2=“t=600”, 3=“sid=0”, 4=“pid=-1”, 5=“uwt=0” },51) <0x745e9520>
50 06/06/20 1:36:16.397 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand - url: “http://172.16.100.107/cm?en=1&t=600&sid=0&pid=-1&uwt=0&pw=a6d82bced638de3def1e9bbb4983225c” <0x745e9520>
50 06/06/20 1:36:16.401 luup_log:51: VeraOpenSprinkler1.4.2: HttpGet: “http://172.16.100.107/cm?en=1&t=600&sid=0&pid=-1&uwt=0&pw=a6d82bced638de3def1e9bbb4983225c” - “” - “closed” - “” <0x745e9520>
50 06/06/20 1:36:16.403 luup_log:51: VeraOpenSprinkler1.4.2: deviceMessage(52,“Unable to send command to controller”,true,15) <0x745e9520>
50 06/06/20 1:36:16.403 luup_log:51: VeraOpenSprinkler[1.4.2]: Switch power error: 52 - true - nil <0x745e9520>
50 06/06/20 1:36:16.404 luup_log:51: VeraOpenSprinkler1.4.2: deviceMessage(52,“Turning on”,false,15) <0x745e9520>
root@MiOS_50020223:/tmp/log/cmh#

I can move it to 0 and I get this in the log:

50 06/06/20 1:49:10.004 luup_log:51: VeraOpenSprinkler1.4.2: actionDimming: 52, 0, false <0x749e9520>
50 06/06/20 1:49:10.005 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,52) old value “10” <0x749e9520>
50 06/06/20 1:49:10.006 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelLast”,“0”,52) old value “10” <0x749e9520>
50 06/06/20 1:49:10.019 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,52) old value “10” <0x749e9520>
50 06/06/20 1:49:10.020 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:upnp-org:serviceId:SwitchPower1”,“Target”,“0”,52) old value “1” <0x749e9520>
root@MiOS_50020223:/tmp/log/cmh#

TedM · June 6, 2020, 9:01am

I did another luup.reload() This time the logs showed there was only 1 refresh on the json query before it got a good one. But there is still that second initVar error in the very last line.

Since it appears the json query only blows up during initial discovery maybe it’s not that critical if repeatedly sending the query will end up getting a response? Perhaps also the query should be sent at a lower rate like once every 30 seconds?

09 06/06/20 1:55:15.386 JobHandler_LuaUPnP::Run device 51 ptr 0xeff2b8 OpenSprinkler Contro room 2 type urn:schemas-upnp-org:device:BinaryLight:1 cat 3:7 id parent 0/0xfd0d08 upnp: 0 plugin:9301 pnp:0 mac: ip:172.16.100.107 <0x77d77320>
50 06/06/20 1:55:32.641 luup_log:51: VeraOpenSprinkler Controller Plug-In starting… <0x772c4520>
50 06/06/20 1:55:32.656 luup_log:51: VeraOpenSprinkler[1.4.2]: Plugin starting: “VeraOpenSprinkler” - “1.4.2” <0x772c4520>
50 06/06/20 1:55:32.657 luup_log:51: VeraOpenSprinkler1.4.2: Running on OpenLuup: false <0x772c4520>
50 06/06/20 1:55:47.122 luup_log:51: VeraOpenSprinkler1.4.2: updateFromController started: true <0x75ac4520>
50 06/06/20 1:55:47.123 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand(“ja”,nil,51) <0x75ac4520>
50 06/06/20 1:55:47.125 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand - url: “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” <0x75ac4520>
50 06/06/20 1:55:47.174 luup_log:51: VeraOpenSprinkler1.4.2: HttpGet: “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” - 1 - “200” - "{"settings":{"devt":1591419347,"nbrd":1,"en":1,"sn1":0,"sn2":0,"rd":0,"rdst":0,"sunrise":309,"sunset":1219,"eip":788989956,"lwc":1591418777,"lswc":1591418777,"lupt":0,"lrbtc":5,"lrun":[0,99,600,1591418777],"loc":"42.36,-71.06","jsp":"https://ui.opensprinkler.com/js\“,\“wsp\”:\“weather.opensprinkler.com\”,\“wto\”:{\“key\”:\”\“},\“ifkey\”:\”\“,\“wtdata\”:{\“wp\”:\“Manual\”},\“wterr\”:0,\“sbits\”:[0,0],\“ps\”:[[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0]]},\“programs\”:{\“nprogs\”:0,\“nboards\”:1,\“mnp\”:40,\“mnst\”:4,\“pnsize\”:32,\“pd\”:[]},\“options\”:{\“fwv\”:219,\“tz\”:32,\“hp0\”:80,\“hp1\”:0,\“hwv\”:64,\“ext\”:0,\“sdt\”:0,\“mas\”:0,\“mton\”:0,\“mtof\”:0,\“wl\”:100,\“den\”:1,\“ipas\”:0,\“devid\”:0,\“uwt\”:0,\“ntp1\”:0,\“ntp2\”:0,\“ntp3\”:0,\“ntp4\”:0,\“lg\”:1,\“mas2\”:0,\“mton2\”:0,\“mtof2\”:0,\“fwm\”:3,\“fpr0\”:100,\“fpr1\”:0,\“re\”:0,\“sar\”:0,\“ife\”:0,\“sn1t\”:1,\“sn1o\”:0,\“sn1on\”:0,\“sn1of\”:0,\“wimod\”:169,\“reset\”:0,\“dexp\”:-1,\“mexp\”:24,\“hwt\”:255},\“status\”:{\“sn\”:[0,0,0,0,0,0,0,0],\“nstations\”:8}” <0x75ac4520>
50 06/06/20 1:55:47.183 luup_log:51: VeraOpenSprinkler1.4.2: Got a nil respose from API or error: “unterminated object at line 1, column 1”, true <0x75ac4520>
50 06/06/20 1:55:47.184 luup_log:51: VeraOpenSprinkler1.4.2: updateFromController completed <0x75ac4520>
50 06/06/20 1:55:47.185 luup_log:51: VeraOpenSprinkler1.4.2: Next refresh in 15 secs <0x75ac4520>
50 06/06/20 1:56:02.101 luup_log:51: VeraOpenSprinkler1.4.2: updateFromController started: true <0x75ac4520>
50 06/06/20 1:56:02.102 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand(“ja”,nil,51) <0x75ac4520>
50 06/06/20 1:56:02.103 luup_log:51: VeraOpenSprinkler1.4.2: sendDeviceCommand - url: “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” <0x75ac4520>
50 06/06/20 1:56:02.132 luup_log:51: VeraOpenSprinkler1.4.2: HttpGet: “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” - 1 - “200” - "{"settings":{"devt":1591419362,"nbrd":1,"en":1,"sn1":0,"sn2":0,"rd":0,"rdst":0,"sunrise":309,"sunset":1219,"eip":788989956,"lwc":1591418777,"lswc":1591418777,"lupt":0,"lrbtc":5,"lrun":[0,99,600,1591418777],"loc":"42.36,-71.06","jsp":"https://ui.opensprinkler.com/js\“,\“wsp\”:\“weather.opensprinkler.com\”,\“wto\”:{\“key\”:\”\“},\“ifkey\”:\”\“,\“wtdata\”:{\“wp\”:\“Manual\”},\“wterr\”:0,\“sbits\”:[0,0],\“ps\”:[[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0]]},\“programs\”:{\“nprogs\”:0,\“nboards\”:1,\“mnp\”:40,\“mnst\”:4,\“pnsize\”:32,\“pd\”:[]},\“options\”:{\“fwv\”:219,\“tz\”:32,\“hp0\”:80,\“hp1\”:0,\“hwv\”:64,\“ext\”:0,\“sdt\”:0,\“mas\”:0,\“mton\”:0,\“mtof\”:0,\“wl\”:100,\“den\”:1,\“ipas\”:0,\“devid\”:0,\“uwt\”:0,\“ntp1\”:0,\“ntp2\”:0,\“ntp3\”:0,\“ntp4\”:0,\“lg\”:1,\“mas2\”:0,\“mton2\”:0,\“mtof2\”:0,\“fwm\”:3,\“fpr0\”:100,\“fpr1\”:0,\“re\”:0,\“sar\”:0,\“ife\”:0,\“sn1t\”:1,\“sn1o\”:0,\“sn1on\”:0,\“sn1of\”:0,\“wimod\”:169,\“reset\”:0,\“dexp\”:-1,\“mexp\”:24,\“hwt\”:255},\“status\”:{\“sn\”:[0,0,0,0,0,0,0,0],\“nstations\”:8},\“stations\”:{\“masop\”:[255],\“masop2\”:[0],\“ignore_rain\”:[0],\“ignore_sn1\”:[0],\“ignore_sn2\”:[0],\“stn_dis\”:[128],\“stn_seq\”:[255],\“stn_spe\”:[0],\“snames\”:[\“NorthGardenSideLawn\”,\“NorthWestBackyard\”,\“SouthBackyard\”,\“SouthSideLawn\”,\“SouthGardenAndPorch\”,\“NorthEastBackyard\”,\“SouthBackyardHedge\”,\“S08\”],\“maxlen\”:32}}” <0x75ac4520>
50 06/06/20 1:56:02.142 luup_log:51: VeraOpenSprinkler1.4.2: [discovery] in progress… <0x75ac4520>
50 06/06/20 1:56:02.143 luup_log:51: VeraOpenSprinkler1.4.2: [discovery] valid jsonResponse: true <0x75ac4520>
50 06/06/20 1:56:02.144 luup_log:51: VeraOpenSprinkler1.4.2: ChildrenSameRoom: true, #2 <0x75ac4520>
50 06/06/20 1:56:02.145 luup_log:51: VeraOpenSprinkler1.4.2: [discovery] 1/3 in progress… <0x75ac4520>
50 06/06/20 1:56:02.145 luup_log:51: VeraOpenSprinkler1.4.2: Discovery: Zone 1 - Name: “NorthGardenSideLawn” <0x75ac4520>
50 06/06/20 1:56:02.146 luup_log:51: VeraOpenSprinkler1.4.2: Zone Device ready to be added: 1 <0x75ac4520>
50 06/06/20 1:56:02.147 luup_log:51: VeraOpenSprinkler1.4.2: Set Name for Device 52 - Zone #1: “NorthGardenSideLawn” <0x75ac4520>
50 06/06/20 1:56:02.148 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“0”,52) old value “0” <0x75ac4520>
50 06/06/20 1:56:02.159 luup_log:51: VeraOpenSprinkler1.4.2: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,52) old value “1” <0x75ac4520>
01 06/06/20 1:56:02.160 LuaInterface::CallFunction_Timer-5 function updateFromController failed [string “module(“L_VeraOpenSprinkler1”, package.seea…”]:308: attempt to call local ‘initVar’ (a string value)
root@MiOS_50020223:/tmp/log/cmh#

therealdb · June 6, 2020, 9:19am

it’s definitely getting truncated. I also found a small error (the last line) and got it fixed. V 1.4.3 is on GitHub, with CURL support. this should prevent the truncation.
Thanks for the help!

therealdb · June 6, 2020, 9:22am

imho this is related to some buffer or the way OS replies. 15 secs is the default, but you can tweak to whatever you want. it’s the Refresh variable in the master device.

OS should get MQTT support in the next version, so real time notifications are possible with a MQTT broker, but this is definitely out of scope for this plugin.

TedM · June 6, 2020, 5:20pm

Thanks. So I uploaded 143 and left the “Restart Luup after upload” and it appeared to work but then went into this sort of discovery loop where every 15 seconds it would discover then issue

06/06/20 9:59:59.236 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-0” <0x74e32520>
50 06/06/20 9:59:59.237 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-1” <0x74e32520>
50 06/06/20 9:59:59.238 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-2” <0x74e32520>
50 06/06/20 9:59:59.239 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-3” <0x74e32520>
50 06/06/20 9:59:59.240 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-4” <0x74e32520>
50 06/06/20 9:59:59.241 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-5” <0x74e32520>
50 06/06/20 9:59:59.242 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-6” <0x74e32520>

TedM · June 6, 2020, 5:20pm

So I then uninstalled the sprinkler plugin completely and reinstalled it then uploaded 143 and did a luup.reload() and this is what the log shows:

50 06/06/20 9:59:59.101 luup_log:51: VeraOpenSprinkler1.4.3: updateFromController started: true <0x74e32520>
50 06/06/20 9:59:59.103 luup_log:51: VeraOpenSprinkler1.4.3: sendDeviceCommand(“ja”,nil,51) <0x74e32520>
50 06/06/20 9:59:59.104 luup_log:51: VeraOpenSprinkler1.4.3: sendDeviceCommand - url: “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” <0x74e32520>
50 06/06/20 9:59:59.145 luup_log:51: VeraOpenSprinkler1.4.3: [HttpGet] “http://172.16.100.107/ja?&pw=a6d82bced638de3def1e9bbb4983225c” - "{"settings":{"devt":1591448399,"nbrd":1,"en":1,"sn1":1,"sn2":0,"rd":0,"rdst":0,"sunrise":309,"sunset":1219,"eip":788989956,"lwc":1591447609,"lswc":1591447609,"lupt":0,"lrbtc":5,"lrun":[0,99,600,1591418777],"loc":"42.36,-71.06","jsp":"https://ui.opensprinkler.com/js\“,\“wsp\”:\“weather.opensprinkler.com\”,\“wto\”:{\“key\”:\”\“},\“ifkey\”:\”\“,\“wtdata\”:{\“wp\”:\“Manual\”},\“wterr\”:0,\“sbits\”:[0,0],\“ps\”:[[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0],[0,0,0]]},\“programs\”:{\“nprogs\”:0,\“nboards\”:1,\“mnp\”:40,\“mnst\”:4,\“pnsize\”:32,\“pd\”:[]},\“options\”:{\“fwv\”:219,\“tz\”:32,\“hp0\”:80,\“hp1\”:0,\“hwv\”:64,\“ext\”:0,\“sdt\”:0,\“mas\”:0,\“mton\”:0,\“mtof\”:0,\“wl\”:100,\“den\”:1,\“ipas\”:0,\“devid\”:0,\“uwt\”:0,\“ntp1\”:0,\“ntp2\”:0,\“ntp3\”:0,\“ntp4\”:0,\“lg\”:1,\“mas2\”:0,\“mton2\”:0,\“mtof2\”:0,\“fwm\”:3,\“fpr0\”:100,\“fpr1\”:0,\“re\”:0,\“sar\”:0,\“ife\”:0,\“sn1t\”:1,\“sn1o\”:0,\“sn1on\”:0,\“sn1of\”:0,\“wimod\”:169,\“reset\”:0,\“dexp\”:-1,\“mexp\”:24,\“hwt\”:255},\“status\”:{\“sn\”:[0,0,0,0,0,0,0,0],\“nstations\”:8},\“stations\”:{\“masop\”:[255],\“masop2\”:[0],\“ignore_rain\”:[0],\“ignore_sn1\”:[0],\“ignore_sn2\”:[0],\“stn_dis\”:[128],\“stn_seq\”:[255],\“stn_spe\”:[0],\“snames\”:[\“NorthGardenSideLawn\”,\“NorthWestBackyard\”,\“SouthBackyard\”,\“SouthSideLawn\”,\“SouthGardenAndPorch\”,\“NorthEastBackyard\”,\“SouthBackyardHedge\”,\“S08\”],\“maxlen\”:32}}” <0x74e32520>
50 06/06/20 9:59:59.157 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] in progress… <0x74e32520>
50 06/06/20 9:59:59.158 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] valid jsonResponse: true <0x74e32520>
50 06/06/20 9:59:59.159 luup_log:51: VeraOpenSprinkler1.4.3: ChildrenSameRoom: true, #2 <0x74e32520>
50 06/06/20 9:59:59.160 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 1/3 in progress… <0x74e32520>
50 06/06/20 9:59:59.160 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 1 - Name: “NorthGardenSideLawn” <0x74e32520>
50 06/06/20 9:59:59.161 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 1 <0x74e32520>
50 06/06/20 9:59:59.162 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 52 - Zone #1: “NorthGardenSideLawn” <0x74e32520>
50 06/06/20 9:59:59.163 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“0”,52) old value “0” <0x74e32520>
50 06/06/20 9:59:59.165 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,52) old value “1” <0x74e32520>
50 06/06/20 9:59:59.180 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 2 - Name: “NorthWestBackyard” <0x74e32520>
50 06/06/20 9:59:59.181 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 2 <0x74e32520>
50 06/06/20 9:59:59.185 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 53 - Zone #2: “NorthWestBackyard” <0x74e32520>
50 06/06/20 9:59:59.186 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“1”,53) old value “1” <0x74e32520>
50 06/06/20 9:59:59.187 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,53) old value “1” <0x74e32520>
50 06/06/20 9:59:59.189 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 3 - Name: “SouthBackyard” <0x74e32520>
50 06/06/20 9:59:59.190 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 3 <0x74e32520>
50 06/06/20 9:59:59.190 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 54 - Zone #3: “SouthBackyard” <0x74e32520>
50 06/06/20 9:59:59.191 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“2”,54) old value “2” <0x74e32520>
50 06/06/20 9:59:59.192 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,54) old value “1” <0x74e32520>
50 06/06/20 9:59:59.194 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 4 - Name: “SouthSideLawn” <0x74e32520>
50 06/06/20 9:59:59.195 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 4 <0x74e32520>
50 06/06/20 9:59:59.196 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 55 - Zone #4: “SouthSideLawn” <0x74e32520>
50 06/06/20 9:59:59.197 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“3”,55) old value “3” <0x74e32520>
50 06/06/20 9:59:59.198 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,55) old value “1” <0x74e32520>
50 06/06/20 9:59:59.200 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 5 - Name: “SouthGardenAndPorch” <0x74e32520>
50 06/06/20 9:59:59.201 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 5 <0x74e32520>
50 06/06/20 9:59:59.201 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 56 - Zone #5: “SouthGardenAndPorch” <0x74e32520>
50 06/06/20 9:59:59.202 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“4”,56) old value “4” <0x74e32520>
50 06/06/20 9:59:59.203 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,56) old value “1” <0x74e32520>
50 06/06/20 9:59:59.205 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 6 - Name: “NorthEastBackyard” <0x74e32520>
50 06/06/20 9:59:59.206 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 6 <0x74e32520>
50 06/06/20 9:59:59.207 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 57 - Zone #6: “NorthEastBackyard” <0x74e32520>
50 06/06/20 9:59:59.208 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“5”,57) old value “5” <0x74e32520>
50 06/06/20 9:59:59.209 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,57) old value “1” <0x74e32520>
50 06/06/20 9:59:59.211 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 7 - Name: “SouthBackyardHedge” <0x74e32520>
50 06/06/20 9:59:59.212 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 7 <0x74e32520>
50 06/06/20 9:59:59.213 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 58 - Zone #7: “SouthBackyardHedge” <0x74e32520>
50 06/06/20 9:59:59.213 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“6”,58) old value “6” <0x74e32520>
50 06/06/20 9:59:59.214 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,58) old value “1” <0x74e32520>
50 06/06/20 9:59:59.216 luup_log:51: VeraOpenSprinkler1.4.3: Discovery: Zone 8 - Name: “S08” <0x74e32520>
50 06/06/20 9:59:59.217 luup_log:51: VeraOpenSprinkler1.4.3: Zone Device ready to be added: 8 <0x74e32520>
50 06/06/20 9:59:59.218 luup_log:51: VeraOpenSprinkler1.4.3: Set Name for Device 61 - Zone #8: “S08” <0x74e32520>
50 06/06/20 9:59:59.218 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“ZoneID”,“7”,61) old value “7” <0x74e32520>
50 06/06/20 9:59:59.220 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,61) old value “1” <0x74e32520>
50 06/06/20 9:59:59.221 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 1/3 completed… <0x74e32520>
50 06/06/20 9:59:59.222 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 2/3 in progress… <0x74e32520>
50 06/06/20 9:59:59.222 luup_log:51: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x74e32520>
50 06/06/20 9:59:59.223 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 2/3 completed… <0x74e32520>
50 06/06/20 9:59:59.224 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 3/3 in progress… <0x74e32520>
50 06/06/20 9:59:59.224 luup_log:51: VeraOpenSprinkler1.4.3: [discovery]Water Level Child Device ready to be added <0x74e32520>
50 06/06/20 9:59:59.226 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] 3/3 completed… <0x74e32520>
50 06/06/20 9:59:59.228 luup_log:51: VeraOpenSprinkler1.4.3: [discovery] completed… <0x74e32520>
50 06/06/20 9:59:59.229 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HaDevice1”,“Configured”,“1”,51) old value “1” <0x74e32520>
50 06/06/20 9:59:59.229 luup_log:51: VeraOpenSprinkler1.4.3: Update status in progress… <0x74e32520>
50 06/06/20 9:59:59.230 luup_log:51: VeraOpenSprinkler1.4.3: Controller status: 1, “1” <0x74e32520>
50 06/06/20 9:59:59.231 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:SwitchPower1”,“Status”,“1”,51) old value “1” <0x74e32520>
50 06/06/20 9:59:59.232 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“RainDelay”,“0”,51) old value “0” <0x74e32520>
50 06/06/20 9:59:59.233 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“DisplayLine1”,“Controller: ready”,51) old value “Controller: ready” <0x74e32520>
50 06/06/20 9:59:59.234 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“DisplayLine2”,“RainDelay: disabled”,51) old value “RainDelay: disabled” <0x74e32520>
50 06/06/20 9:59:59.235 luup_log:51: VeraOpenSprinkler1.4.3: Update status - Status: 1 - RainDelay: 0 - “16:00:00 (Wed 31 Dec 1969)” <0x74e32520>
50 06/06/20 9:59:59.236 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-0” <0x74e32520>
50 06/06/20 9:59:59.237 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-1” <0x74e32520>
50 06/06/20 9:59:59.238 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-2” <0x74e32520>
50 06/06/20 9:59:59.239 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-3” <0x74e32520>
50 06/06/20 9:59:59.240 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-4” <0x74e32520>
50 06/06/20 9:59:59.241 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-5” <0x74e32520>
50 06/06/20 9:59:59.242 luup_log:51: VeraOpenSprinkler1.4.3: Cannot find child: 51 - “OS-P-6” <0x74e32520>
50 06/06/20 9:59:59.243 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“MaxZones”,“8”,51) old value “8” <0x74e32520>
50 06/06/20 9:59:59.244 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #52: 1 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.245 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.246 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,52) old value “0” <0x74e32520>
50 06/06/20 9:59:59.247 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,52) old value “0” <0x74e32520>
50 06/06/20 9:59:59.248 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.249 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #53: 2 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.250 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.251 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,53) old value “0” <0x74e32520>
50 06/06/20 9:59:59.252 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,53) old value “0” <0x74e32520>
50 06/06/20 9:59:59.254 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.255 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #54: 3 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.256 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.257 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,54) old value “0” <0x74e32520>
50 06/06/20 9:59:59.258 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,54) old value “0” <0x74e32520>
50 06/06/20 9:59:59.259 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.260 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #55: 4 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.261 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.262 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,55) old value “0” <0x74e32520>
50 06/06/20 9:59:59.263 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,55) old value “0” <0x74e32520>
50 06/06/20 9:59:59.263 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.265 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #56: 5 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.266 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.266 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,56) old value “0” <0x74e32520>
50 06/06/20 9:59:59.268 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,56) old value “0” <0x74e32520>
50 06/06/20 9:59:59.269 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.270 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #57: 6 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.270 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.271 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,57) old value “0” <0x74e32520>
50 06/06/20 9:59:59.272 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,57) old value “0” <0x74e32520>
50 06/06/20 9:59:59.273 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.274 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #58: 7 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.275 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.276 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,58) old value “0” <0x74e32520>
50 06/06/20 9:59:59.277 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,58) old value “0” <0x74e32520>
50 06/06/20 9:59:59.278 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.279 luup_log:51: VeraOpenSprinkler1.4.3: Update Zone Skipped for #61: 8 - Status: 0 - 0 <0x74e32520>
50 06/06/20 9:59:59.280 luup_log:51: VeraOpenSprinkler1.4.3: Zone status: { 1=0, 2=0, 3=0 } <0x74e32520>
50 06/06/20 9:59:59.280 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelTarget”,“0”,61) old value “0” <0x74e32520>
50 06/06/20 9:59:59.281 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:upnp-org:serviceId:Dimming1”,“LoadLevelStatus”,“0”,61) old value “0” <0x74e32520>
50 06/06/20 9:59:59.282 luup_log:51: VeraOpenSprinkler1.4.3: Zone status level: 0 <0x74e32520>
50 06/06/20 9:59:59.283 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:bochicchio-com:serviceId:OpenSprinkler1”,“MasterStations”,“0,0”,51) old value “0,0” <0x74e32520>
50 06/06/20 9:59:59.284 luup_log:51: VeraOpenSprinkler1.4.3: Water level: 100 <0x74e32520>
50 06/06/20 9:59:59.285 luup_log:51: VeraOpenSprinkler1.4.3: setVar(“urn:micasaverde-com:serviceId:HumiditySensor1”,“CurrentLevel”,“100”,60) old value “100” <0x74e32520>
50 06/06/20 9:59:59.286 luup_log:51: VeraOpenSprinkler1.4.3: Setting Water level: 100 to dev#: 60 <0x74e32520>
50 06/06/20 9:59:59.286 luup_log:51: VeraOpenSprinkler1.4.3: updateFromController completed <0x74e32520>
50 06/06/20 9:59:59.287 luup_log:51: VeraOpenSprinkler1.4.3: Next refresh in 15 secs <0x74e32520>
02 06/06/20 10:00:54.523 JobHandler_LuaUPnP::FetchMissingFiles need to download D_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.523 JobHandler_LuaUPnP::m_sMissingFile_set D_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.524 JobHandler_LuaUPnP::FetchMissingFiles need to download I_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.524 JobHandler_LuaUPnP::m_sMissingFile_set I_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.524 JobHandler_LuaUPnP::FetchMissingFiles need to download L_VeraOpenSprinkler1.lua <0x775dd320>
02 06/06/20 10:00:54.525 JobHandler_LuaUPnP::m_sMissingFile_set L_VeraOpenSprinkler1.lua <0x775dd320>
02 06/06/20 10:00:54.525 JobHandler_LuaUPnP::FetchMissingFiles need to download S_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.525 JobHandler_LuaUPnP::m_sMissingFile_set S_VeraOpenSprinkler1.xml <0x775dd320>
01 06/06/20 10:00:54.552 Device_LuaUPnP::LoadDeviceDoc can’t load /etc/cmh-lu//D_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.552 JobHandler_LuaUPnP::m_sMissingFile_set /etc/cmh-lu//D_VeraOpenSprinkler1.xml <0x775dd320>
02 06/06/20 10:00:54.553 JobHandler_LuaUPnP::CreateDevice_LuaUPnP failed to load 62/D_VeraOpenSprinkler1.xml so device 62 is offline while waiting for download the file <0x775dd320>
01 06/06/20 10:00:58.118 JobHandler_LuaUPnP::CreateLuaInterfaces can’t load plugin /etc/cmh-lu//L_VeraOpenSprinkler1.lua <0x775dd320>
09 06/06/20 10:00:58.948 JobHandler_LuaUPnP::Run device 62 ptr 0xde4a60 room 0 type urn:bochicchio-com:device:OpenSprinkler1 cat 0:-1 id parent 0/0xea0770 upnp: 0 plugin:9301 pnp:0 mac: ip: <0x775dd320>
03 06/06/20 10:00:58.958 JobHandler_LuaUPnP::Run ready 20 devices oem:1 skin:1-mios installation 50020223 missing /etc/cmh-lu//D_VeraOpenSprinkler1.xml version 1.7.4970 (7.31) allow_evt: 1 allow_ud: 1 upnp_scan 0 netpnp: 1 reprovision: 0 secure: 0 upnp: 1 unsafelua: 1 tz:28800 tnow:1591462858 dst: 1 gmt:-25200 tz:PST / PDT fahr:1 changed: 0 pMem 0x157d000 start 14483456 diff: 8048640 <0x775dd320>
09 06/06/20 10:01:09.003 JobHandler_LuaUPnP::Run device 62 ptr 0xbd7a38 OpenSprinkler Controller room 0 type urn:schemas-upnp-org:device:BinaryLight:1 cat 3:7 id parent 0/0xc93510 upnp: 0 plugin:9301 pnp:0 mac: ip: <0x77061320>
50 06/06/20 10:01:26.545 luup_log:62: VeraOpenSprinkler Controller Plug-In starting… <0x765ae520>
50 06/06/20 10:01:26.566 luup_log:62: VeraOpenSprinkler[1.3.1]: Plugin starting: “VeraOpenSprinkler” - “1.3.1” <0x765ae520>
06 06/06/20 10:01:26.568 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: DebugMode was: EMPTY now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x765ae520>
06 06/06/20 10:01:26.569 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: Password was: EMPTY now: a6d82bced638de3def1e9bbb4983225c #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x765ae520>
06 06/06/20 10:01:26.569 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: Refresh was: EMPTY now: 15 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x765ae520>
06 06/06/20 10:01:26.570 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: MaxZones was: EMPTY now: 32 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x765ae520>
01 06/06/20 10:01:26.572 LuaInterface::CallFunction_Startup-2 device 62 function startupPlugin failed VeraOpenSprinkler with return false <0x765ae520>
01 06/06/20 10:01:26.573 LuImplementation::StartLua running startup code for 62 I_VeraOpenSprinkler1.xml failed <0x765ae520>
09 06/06/20 10:02:02.901 JobHandler_LuaUPnP::Run device 62 ptr 0xfb0cf8 OpenSprinkler Controller room 0 type urn:schemas-upnp-org:device:BinaryLight:1 cat 3:7 id parent 0/0x106f1a8 upnp: 0 plugin:9301 pnp:0 mac: ip: <0x77ddc320>
50 06/06/20 10:02:20.482 luup_log:62: VeraOpenSprinkler Controller Plug-In starting… <0x7732a520>
50 06/06/20 10:02:20.497 luup_log:62: VeraOpenSprinkler[1.4.3]: Plugin starting: “VeraOpenSprinkler” - “1.4.3” <0x7732a520>
01 06/06/20 10:02:20.498 LuaInterface::CallFunction_Startup-2 device 62 function startupPlugin failed VeraOpenSprinkler with return false <0x7732a520>
01 06/06/20 10:02:20.500 LuImplementation::StartLua running startup code for 62 I_VeraOpenSprinkler1.xml failed <0x7732a520>
09 06/06/20 10:04:06.764 JobHandler_LuaUPnP::Run device 62 ptr 0x10d3be8 OpenSprinkler Controller room 0 type urn:schemas-upnp-org:device:BinaryLight:1 cat 3:7 id parent 0/0x1191ff8 upnp: 0 plugin:9301 pnp:0 mac: ip:172.16.100.107 <0x77183320>
50 06/06/20 10:04:24.359 luup_log:62: VeraOpenSprinkler Controller Plug-In starting… <0x766d0520>
50 06/06/20 10:04:24.374 luup_log:62: VeraOpenSprinkler[1.4.3]: Plugin starting: “VeraOpenSprinkler” - “1.4.3” <0x766d0520>
06 06/06/20 10:04:24.375 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: CurrentVersion was: EMPTY now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x766d0520>
50 06/06/20 10:04:24.376 luup_log:62: VeraOpenSprinkler[1.4.3]: New version detected: reconfiguration in progress <0x766d0520>
06 06/06/20 10:04:24.376 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: CurrentVersion was: 0 now: 1.4.3 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x766d0520>
50 06/06/20 10:04:39.179 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x74ed0520>
06 06/06/20 10:04:39.181 Device_Variable::m_szValue_set device: 63 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.188 Device_Variable::m_szValue_set device: 64 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 1 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.194 Device_Variable::m_szValue_set device: 65 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 2 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.200 Device_Variable::m_szValue_set device: 66 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 3 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.206 Device_Variable::m_szValue_set device: 67 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 4 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.212 Device_Variable::m_szValue_set device: 68 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 5 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.218 Device_Variable::m_szValue_set device: 69 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 6 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:39.224 Device_Variable::m_szValue_set device: 70 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: ZoneID was: EMPTY now: 7 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:40.900 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: RainDelay was: EMPTY now: 0 #hooks: 0 upnp: 0 skip: 0 v:0x12c8730/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:40.901 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: DisplayLine1 was: EMPTY now: Controller: ready #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:40.902 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: DisplayLine2 was: EMPTY now: RainDelay: disabled #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:40.905 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: MaxZones was: 32 now: 8 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
06 06/06/20 10:04:41.049 Device_Variable::m_szValue_set device: 62 service: urn:bochicchio-com:serviceId:OpenSprinkler1 variable: MasterStations was: EMPTY now: 0,0 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x74ed0520>
09 06/06/20 10:04:48.696 JobHandler_LuaUPnP::Run device 62 ptr 0xa71eb0 OpenSprinkler Controller room 0 type urn:schemas-upnp-org:device:BinaryLight:1 cat 3:7 id parent 0/0xb3a9a0 upnp: 0 plugin:9301 pnp:0 mac: ip:172.16.100.107 <0x772e2320>
50 06/06/20 10:05:06.283 luup_log:62: VeraOpenSprinkler Controller Plug-In starting… <0x76830520>
50 06/06/20 10:05:06.298 luup_log:62: VeraOpenSprinkler[1.4.3]: Plugin starting: “VeraOpenSprinkler” - “1.4.3” <0x76830520>
50 06/06/20 10:05:21.218 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:05:36.166 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:05:51.179 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:06:06.165 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:06:21.171 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:06:36.151 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:06:51.161 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:07:06.171 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:07:21.167 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:07:36.152 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:07:51.172 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:08:06.175 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:08:21.153 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:08:36.151 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:08:51.172 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:09:06.166 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:09:21.171 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:09:36.151 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
50 06/06/20 10:09:51.198 luup_log:62: VeraOpenSprinkler[1.4.3]: [discovery] 2/3: no programs from controller <0x75030520>
root@MiOS_50020223:/tmp/log/cmh#

I guess that something in the devices created by one of the prior versions was causing the plugin to be unhappy so uninstalling and reinstalling recreated the device tree.

Is Discovery supposed to issue that 2/3 message like that? The log shows a discovery complete then that?

Also, zone S08 is disabled in the OpenSprinkler interface yet the plugin sees it and creates a device for it vera interface.

If you put this version 143 in the vera store it would be good since I could just uninstall/reinstall the app to clear out all of the device configs for testing changes in later versions.

TedM · June 6, 2020, 5:26pm

also, there is a rain guage attached to the OSPi and the plug is not creating a device for it. It is one of those ones that has the fiber washers that expand and admittedly it’s a P.O.S. because it only detects rain if there’s a deluge and then it takes several hours for the washers to expand to the point that they trigger the guage.

therealdb · June 6, 2020, 9:46pm

Yep, it’s the http call. Thanks for reporting. Tomorrow I will inspect the logs and try to further fix it.
Unfortunately disabled stations are not offered via api, but I could further investigate to find a solution.
Sensors are not supported, but I will capture your reply and try to offer it as well. Thanks for the help!

therealdb · June 21, 2020, 9:28am

Version 1.4.5 released on GitHub. If it’s stable enough, I’ll post to Mios Store as well.

What’s new in this version:

support for disabled stations: if a station is disabled in OS, this will be deleted from your Vera/openluup controller. If you change a station and mark as visible, a new discovery is needed (set Configured to 0 and do a luup reload, new action coming soon to discover with no reloads).
support for sensors (rain delay, sensor 1 and 2) as freeze sensor. Every sensor type is supported, but right now all are normally closed and offered as freeze sensor. I’ll add more support in the future (IE: name detected from the real type of sensor), as well as support for normaly open/closed type. I’m just trying to find the right balance, but since this version adds more stability, I wanted to release it ASAP.
a lot of stability work/cleanup (especially related to Water Level, sensors, programs)

Not tested on Vera, since I mainly use openluup these days, but this should work and it’s only using standard device type, so 100% supported on mobile apps as well.

As with every release, the plugin will do a new discovery and should detected these sensors automatically. Any feedback is appreciated, since I have no sensors attached and this was developed with the API doc only. Thanks!

tbully · July 4, 2020, 3:18pm

Hi everyone! Need a little advice. I’m still running this plugin successfully. However, I’ve been going back and forth with support due to constant reloads and reboots. One thing they mentioned was plugins trying to check for updates and then reloading. They wanted me to uninstall the OS plugin but I’d rather not.

I decided to disable my plugins to not auto-update to see if it’d help. However, I don’t see the OS plugin in my “apps”. Maybe I installed it manually?

Is there a way to “convert” to the MIOS store version without losing all my integrations to Reactor, etc? Or do I have to start from scratch again?

Thanks.

therealdb · July 4, 2020, 4:49pm

Update to the latest version on GitHub, reboots should be over.

I will release the current version soon, no need to really get it from the store, it will always be behind. All that said, latest version is pretty stable and I don’t plan to update anytime soon, since it’s basically feature complete and everything from OS is offered via virtual devices, even sensors.

tbully · July 5, 2020, 3:04am

Thanks! I updated today. I’m still getting reloads/reboots. I’m sure it’s being caused by something else.

I’d rather be more analytical about these reboots/reloads instead of throwing stuff at the problem randomly. (But that’s what support should be doing! Problem is this has been going on for weeks now! @Sorin)

therealdb · July 5, 2020, 7:21am

Maybe look at logs and enable verbose logs for the plugin. Just set debug variable to 1 to enable verbose logging.

Sorin · July 6, 2020, 11:36am

Hi there, I gave an heads up to the CC team to try and prio this for a solution, if possible.

tbully · July 6, 2020, 1:38pm

Thanks @sorin. I’m fine pulling plugins to try to fix the issue. I’m just not in to indiscriminately doing so thereby creating a logic/configuration mess in its wake. Hard or strong evidence pointing to an individual plugin/issue would be best.

The reboots/reloads did pick up after updating to this plugin but I also did a Firmware update around the same time…soooooo…

ui677fik · July 26, 2020, 12:17am

I believe I enabled logging by toggling the ‘DebugMode’ variable from 0 to 1 in ‘Advanced’ → ‘Variables’.

Where can I find these logs to read them or how can I access them?

therealdb · July 26, 2020, 6:24am

Correct. Logs are standard vera logs http://wiki.micasaverde.com/index.php/Logs

Mai_Pensato · February 14, 2021, 7:26pm

@therealdb I used my Opensprinkler for several years with the old plugin. I decided today to upgrade to your new version of this plugin.
Situation:

Vera Plus with fw 1.7.2935
Open Sprinkler FW 2.1.5 (1) Hardware 2.3-AC

I first uninstalled the old plugin. This went well.
Then I installed your plugin version 1.4.5 from the Vera appstore.
I got the message to fill in the ip-adress so I did this and also put in the password (using the MD5 conversion) in the Variables section.
Now I see the main controller device as a light bulb turned ON without the message. But I don’t get any of the 8 zones I have…
I read through this thread and the read.me on github.
Solution would be to put Configure on 0 but I cannot find where to do this ?
Can you point me in the right direction ?