OpenSprinkler Plugin Timer Keeps Resetting
-
Hello @therealdb with your OpenSprinkler plugin occasionally when I set a zone for an amount of time lets say 10 minutes, something happens and the counter for 10 minutes counts down 5 seconds to 9:55 then it resets back to 10 minutes. This continues to repeat so the zone will stay on for an infinite amount of time. In my experience this usually happens once the plugin has been running for about a day (ie no recent Luup reloads). I grabbed some logs but it doesn't seem to show anything weird happening. This issue does not happen when I set a zone timer through the OpenSprinkler app. Oddly enough when I stop the zone through the OpenSprinkler app when the timer keeps resetting, the same zone gets queued right away. It seems that the plugin is sending the start zone command every 5 ish seconds.
50 06/07/21 19:19:00.469 luup_log:1304: VeraOpenSprinkler[1.50](setVar@135): setVar("urn:upnp-org:serviceId:Dimming1","LoadLevelTarget","9",1310) old value "11" <0x6b8fe520> 06 06/07/21 19:19:00.469 Device_Variable::m_szValue_set device: 1310 service: urn:upnp-org:serviceId:Dimming1 variable: LoadLevelTarget was: 11 now: 9 #hooks: 0 upnp: 0 skip: 0 v:0x16fabd8/NONE duplicate:0 <0x6b8fe520> 50 06/07/21 19:19:00.480 luup_log:1304: VeraOpenSprinkler[1.50](setVar@135): setVar("urn:upnp-org:serviceId:Dimming1","LoadLevelLast","9",1310) old value "11" <0x6b8fe520> 06 06/07/21 19:19:00.481 Device_Variable::m_szValue_set device: 1310 service: urn:upnp-org:serviceId:Dimming1 variable: LoadLevelLast was: 11 now: 9 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x6b8fe520> 50 06/07/21 19:19:00.482 luup_log:1304: VeraOpenSprinkler[1.50](setVar@135): setVar("urn:upnp-org:serviceId:Dimming1","LoadLevelStatus","9",1310) old value "11" <0x6b8fe520> 06 06/07/21 19:19:00.482 Device_Variable::m_szValue_set device: 1310 service: urn:upnp-org:serviceId:Dimming1 variable: LoadLevelStatus was: 11 now: 9 #hooks: 0 upnp: 0 skip: 0 v:0x16fac40/NONE duplicate:0 <0x6b8fe520> 50 06/07/21 19:19:00.494 luup_log:1304: VeraOpenSprinkler[1.50](setVar@135): setVar("urn:upnp-org:serviceId:SwitchPower1","Target","1",1310) old value "1" <0x6b8fe520> 50 06/07/21 19:19:00.495 luup_log:1304: VeraOpenSprinkler[1.50](actionPowerInternal@785): [actionPower] #1310 - 5 <0x6b8fe520> 50 06/07/21 19:19:00.495 luup_log:1304: VeraOpenSprinkler[1.50](sendDeviceCommand@254): sendDeviceCommand("cm",{ 1="en=1", 2="t=540", 3="sid=5", 4="pid=-1", 5="uwt=0" },1304) <0x6b8fe520> 50 06/07/21 19:19:00.496 luup_log:1304: VeraOpenSprinkler[1.50](sendDeviceCommand@276): sendDeviceCommand - url: "http://192.168.8.225:80/cm?en=1&t=540&sid=5&pid=-1&uwt=0&pw=a6d82bced638de3def1e9bbb4983225c" <0x6b8fe520> 50 06/07/21 19:19:00.556 luup_log:1304: VeraOpenSprinkler[1.50](nil@214): [HttpGet] "http://192.168.8.225:80/cm?en=1&t=540&sid=5&pid=-1&uwt=0&pw=a6d82bced638de3def1e9bbb4983225c" - "{\"result\":1}" <0x6b8fe520> 50 06/07/21 19:19:00.557 luup_log:1304: VeraOpenSprinkler[1.50](setVar@135): setVar("urn:upnp-org:serviceId:SwitchPower1","Status","1",1310) old value "1" <0x6b8fe520> 50 06/07/21 19:19:00.557 luup_log:1304: VeraOpenSprinkler[1.50](deviceMessage@244): deviceMessage(1310,"Turning on for 540 seconds",false,15) <0x6b8fe520>```
-
@pabla what firmware are you running? I’ve never tested with 7.32 and maybe I need to further debug it. I’m sorry for the problem you’ll seeing.
@therealdb running the latest 7.32 beta. I’ve had this problem I think since the beginning of this plug-in, reported it once but at the time it happened too randomly. It’s still hard to figure out when it happens exactly
-
Hi @therealdb have you gotten a chance to see if you can reproduce the same issue I am having?
-
Hi @therealdb have you gotten a chance to see if you can reproduce the same issue I am having?
-
Hi @therealdb have you gotten a chance to see if you can reproduce the same issue I am having?
@pabla I can't repro at the moment, but I'll leave my test Vera plugged in for a day or two and try again.
I've tracked the room part, and it should be fixed by setting "Configured" variable on the master to 0 and wait for reload. I've also found that programs are re-created in this condition, so I'll investigate this part.
-
@pabla I can't repro at the moment, but I'll leave my test Vera plugged in for a day or two and try again.
I've tracked the room part, and it should be fixed by setting "Configured" variable on the master to 0 and wait for reload. I've also found that programs are re-created in this condition, so I'll investigate this part.
@therealdb Hm that's odd you're not able to reproduce this, I can continually reproduce it now. What model OpenSprinkler are you running?
-
@therealdb Hm that's odd you're not able to reproduce this, I can continually reproduce it now. What model OpenSprinkler are you running?
@pabla latest one, I’m not sure about the exact model. Please send me some variables (dms are ok) and more logs. It should be something related to your setup and I could replicate it with the exact code. Also, are you running it on the latest 7.32 beta build?
-
@pabla latest one, I’m not sure about the exact model. Please send me some variables (dms are ok) and more logs. It should be something related to your setup and I could replicate it with the exact code. Also, are you running it on the latest 7.32 beta build?
@therealdb Here are the variables, I will probably send you the logs via Github or something since I can't attach a file in PM on the forums. Yes I am on the latest 7.32 build.
-
I have also been seeing this in the logs when the Opensprinkler controller isnt responding to any commands from the Vera
50 06/21/21 8:45:48.103 luup_log:1304: VeraOpenSprinkler[1.50]: [updateFromController] error: false - nil - nil <0x72581520>
-
Okay so aside from the counter resetting issue, I have also noticed that the first zone that I start it will default to a 1 minute run no matter what I do. Then once that 1 min run is complete the zone is queued to complete the remaining amount of the run. Ie if I set the zone to 25 mins, it will first run for 1 minute then queue to run 24 mins a bit later. After that the all the zones are unresponsive. This is all happening on the plug-in side, the OpenSprinkler app works just fine even when all this weird stuff is happening. I can definitely pull some logs later today, hoping a reload doesn’t happen in between since that is what usually temporarily fixes the issue.
-
Attached some logs on a new issue on Github, the logs show two zones with the counter resetting after 5 seconds. One small thing I have noticed in the OpenSprinkler app is when the counter set at XX:59 seconds it doesn't reset but when it set at XX:00 it resets
-
@pabla sorry for the long wait, I've been very busy at work lately. Anyway, I've just released hotfix 2, with an attempt to fix it. The problem maybe related to the fact that I was resetting the load level to 0 when less than 1 minute was left, and this may cause something strange with the device on the Vera. It seems to work OK on my test Vera Edge, but it's not easy to replicate this bug, so please try this new version and let me know. only the lua file was changed.