Watering on a restricted day
This morning I got a notification on my phone that watering was starting. I have authorized days as Wednesday or Saturday... today is Tuesday. How is it possible the machine violated the restriction I have in my program?
-
Yes, and when i opened the app on my phone it was counting down from 24 hours... no idea what that was. When I stopped it, i could find no trace of it running or water history showing any watering today. it's really weird. I'm glad I caught it before it ran for more than 20 minutes or so.
-
Yes, I'm sure it was running the actual sprinklers. I looked at the log data and I believe it's because it was running with inaccurate time for quite a while. Log snippet below, but leading up to this it had TONS of errors about parsing data from NOAA and WUnderground. Then all of a sudden it appears it figured out how to reset its time properly. I don't think it's working properly yet though. The about screen shows 100% CPU. I haven't logged into it on SSH yet to figure out what's consuming it.
2018-08-15 06:33:05,003 - INFO - noaa-parser:87 - Fetching Daily data from https://graphical.weather.gov/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 2018-08-15 06:37:45,001 - ERROR - rmParser:133 - *** Error in parser 'NOAA Parser' while downloading data from https://graphical.weather.gov/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php?lat=33.9334244&lon=-84.3143956&startDate=2018-08-15&format=24+hourly&numDays=6&Unit=e, error: 2018-08-15 06:42:25,001 - ERROR - rmParser:133 - *** Error in parser 'WUnderground Parser' while downloading data from http://api.wunderground.com/api/15a58660a67a5603/geolookup/conditions/forecast10day/yesterday/q/pws:KGADUNWO10.json, error: 2018-08-15 06:42:25,003 - ERROR - rmParserManager:167 - * Cannot execute parser WUnderground Parser 2018-08-15 06:42:25,005 - ERROR - rmParserManager:168 - 'NoneType' object has no attribute 'read' Traceback (most recent call last): File "./RMParserFramework/rmParserManager.py", line 164, in run File "./RMParserFramework/rmParser.py", line 47, in timedPerform File "/rainmachine-app/RMParserFramework/parsers/wunderground-parser.py", line 77, in perform jsonContent = d.read() AttributeError: 'NoneType' object has no attribute 'read' 2018-08-15 06:43:25,045 - INFO - noaa-parser:83 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php 2018-08-15 06:48:05,001 - ERROR - rmParser:133 - *** Error in parser 'NOAA Parser' while downloading data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php?lat=33.9334244&lon=-84.3143956&startDate=2018-08-15&format=24+hourly&numDays=6&Unit=e, error: 2018-08-15 06:48:05,003 - INFO - noaa-parser:87 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 2018-08-15 06:52:45,001 - ERROR - rmParser:133 - *** Error in parser 'NOAA Parser' while downloading data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php?lat=33.9334244&lon=-84.3143956&startDate=2018-08-15&format=24+hourly&numDays=6&Unit=e, error: 2018-08-15 06:52:45,003 - INFO - noaa-parser:83 - Fetching Hourly data from https://forecast.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php 2018-08-15 06:53:25,045 - ERROR - rmParser:27 - *** Timeout occurred while running a parser: Timer expired 2018-08-15 06:53:25,048 - ERROR - rmParser:133 - *** Error in parser 'NOAA Parser' while downloading data from https://forecast.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php?lat=33.9334244&lon=-84.3143956&startDate=2018-08-15&format=24+hourly&numDays=6&Unit=e&token=px808345forc, error: Timer expired 2018-08-15 06:53:25,049 - INFO - noaa-parser:87 - Fetching Daily data from https://forecast.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 2018-08-15 06:57:01,394 - WARNING - rmSimulator:319 - Data computed without forecast info. Nothing will be saved into simulator.allData table. 2018-08-15 06:57:01,407 - INFO - rmPrograms:743 - Automatic Cycles [{'zoneLinesData': [userPercentage:1, coef:1.0, flag:0, calc_wd:0, active:True, zid:1, duration:100, order:1, userPercentage:1, coef:1.0, flag:0, calc_wd:0, active:True, zid:2, duration:100, order:2, userPercentage:1, coef:1.0, flag:0, calc_wd:0, active:True, zid:3, duration:100, order:3, userPercentage:1, coef:1.0, flag:0, calc_wd:0, active:True, zid:4, duration:100, order:4], 'cycle': 0}] 2018-08-15 06:57:01,412 - INFO - rmProgramScheduler:184 - Zone 1 is not simulated won't carry available water 2018-08-15 06:57:01,414 - INFO - rmProgramScheduler:184 - Zone 2 is not simulated won't carry available water 2018-08-15 06:57:01,416 - INFO - rmProgramScheduler:184 - Zone 3 is not simulated won't carry available water 2018-08-15 06:57:01,418 - INFO - rmProgramScheduler:184 - Zone 4 is not simulated won't carry available water 2018-08-15 06:57:01,421 - INFO - rmProgramScheduler:242 - Program 1 added to watering queue and/or watering history 2018-08-15 06:57:01,425 - INFO - rmProgramSchedulerWatering:232 - Watering - starting zone: (uid=None, pid=1, zid=1, ust='2018-08-15 06:57:00', rst='2018-08-15 06:57:01', ud=100, md=100, rd=0, aw=0, flag=0, manual=False, running=True) 2018-08-14 06:56:58,481 - INFO - noaa-parser:83 - Fetching Hourly data from https://graphical.weather.gov/xml/sample_products/browser_interface/ndfdXMLclient.php 2018-08-14 06:57:09,873 - INFO - rmMixer:123 - *** DATA IN THE PAST: 2018-08-13 00:00:00 2018-08-14 06:57:09,884 - INFO - rmMixer:124 - *** Received data: ('2018-08-13 00:00:00', temp=28.600000000000001/1, minTemp=None/0, maxTemp=None/0, rh=71/1, minRh=None/0, maxRh=None/0, wind=0.17000000000000001/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=97.569999999999993/1, dewPoint=18.600000000000001/1, userData=None, minTemp=19.199999999999999/0, maxTemp=37.899999999999999/0, minRH=24/0, maxRH=71/0, et0cal=None, et0final=None) 2018-08-14 07:19:20,928 - INFO - rmProgramSchedulerWatering:272 - UpdateZone available water: 0 2018-08-14 07:19:20,972 - INFO - rmProgramSchedulerWatering:140 - Watering - zone finished: (uid=None, pid=1, zid=1, ust='2018-08-15 06:57:00', rst='2018-08-15 06:57:01', ud=100, md=100, rd=-85061, aw=0, flag=1, manual=False, running=False) 2018-08-14 07:41:32,107 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 08:16:36,550 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 08:17:25,453 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 08:19:09,860 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 09:43:01,685 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 09:52:55,884 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 09:53:14,049 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 09:54:04,174 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-14 09:54:24,467 - INFO - rmMainManager:216 - Upgrade checked. 2018-08-15 10:34:53,140 - INFO - rmMainManager:216 - Upgrade checked. ---------------------------- PROGRAM SIMULATION LOG ----------------------------- 2018-08-14 06:57:10,168 - INFO - rmSimulator:254 - Skipping old day 1534132800 < 1534305600 from simulation. 2018-08-14 06:57:10,173 - INFO - rmSimulator:254 - Skipping old day 1534132800 < 1534305600 from simulation. 2018-08-14 06:57:10,176 - INFO - rmSimulator:254 - Skipping old day 1534219200 < 1534305600 from simulation. 2018-08-14 06:57:10,187 - INFO - rmSimulator:254 - Skipping old day 1534219200 < 1534305600 from simulation. 2018-08-14 06:57:10,216 - INFO - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first. 2018-08-14 06:57:10,338 - INFO - rmSimulator:904 - Timestamp 1533960000 ForecastDay 1534305600 2018-08-14 06:57:10,357 - INFO - rmSimulator:907 - getting available water for program 1 from main db with value None 2018-08-14 06:57:10,359 - INFO - rmSimulator:908 - > __getAvailableWater from wd_log: None 2018-08-14 06:57:10,363 - INFO - rmSimulator:912 - getting available water for program 1 from simulator db with value None 2018-08-14 06:57:10,367 - INFO - rmSimulator:916 - pid=1, zone 1: no AW record, defaulting to 0.0 for day 2018-08-11 00:00:00 2018-08-14 06:57:10,375 - INFO - rmSimulator:904 - Timestamp 1533960000 ForecastDay 1534305600 2018-08-14 06:57:10,385 - INFO - rmSimulator:907 - getting available water for program 1 from main db with value None 2018-08-14 06:57:10,394 - INFO - rmSimulator:908 - > __getAvailableWater from wd_log: None 2018-08-14 06:57:10,400 - INFO - rmSimulator:912 - getting available water for program 1 from simulator db with value None 2018-08-14 06:57:10,402 - INFO - rmSimulator:916 - pid=1, zone 2: no AW record, defaulting to 0.0 for day 2018-08-11 00:00:00 2018-08-14 06:57:10,405 - INFO - rmSimulator:904 - Timestamp 1533960000 ForecastDay 1534305600 2018-08-14 06:57:10,436 - INFO - rmSimulator:907 - getting available water for program 1 from main db with value None 2018-08-14 06:57:10,438 - INFO - rmSimulator:908 - > __getAvailableWater from wd_log: None 2018-08-14 06:57:10,441 - INFO - rmSimulator:912 - getting available water for program 1 from simulator db with value None 2018-08-14 06:57:10,446 - INFO - rmSimulator:916 - pid=1, zone 3: no AW record, defaulting to 0.0 for day 2018-08-11 00:00:00 2018-08-14 06:57:10,453 - INFO - rmSimulator:904 - Timestamp 1533960000 ForecastDay 1534305600 2018-08-14 06:57:10,462 - INFO - rmSimulator:907 - getting available water for program 1 from main db with value None 2018-08-14 06:57:10,464 - INFO - rmSimulator:908 - > __getAvailableWater from wd_log: None 2018-08-14 06:57:10,481 - INFO - rmSimulator:912 - getting available water for program 1 from simulator db with value None 2018-08-14 06:57:10,483 - INFO - rmSimulator:916 - pid=1, zone 4: no AW record, defaulting to 0.0 for day 2018-08-11 00:00:00 2018-08-14 06:57:10,485 - INFO - rmSimulator:500 - * Program Coefficients (pid=1, fake=False, ignoreInternetWeather=0, day=2018-08-15 00:00:00) - pastStart=2018-08-11 00:00:00, futureEnd=2018-08-18 00:00:00, pastMultiplier=4, futureMultiplier=3 - qpfReal=0.00, qpfUsed=None, qpfEst=6.53, historyQpf=6.53, et0Real=23.85, et0Used=None, et0Est=14.54, historyEt0=14.54 - et0Avg=5.61 detailsReal=[et0(a)=5.61, qpf(i)=None], [et0(a)=5.61, qpf(i)=None], [et0(m)=7.19, qpf(i)=None], [et0(m)=5.44, qpf(m)=0.00], detailsHistory=[et0(m)=5.33, qpf(i)=None], [et0(m)=4.84, qpf(m)=1.53], [et0(m)=4.37, qpf(m)=5.00] zone lastAW newAW chEt0 waterFF corrPast water% waterNeeded fielCap cropCoef zoneType userDuration 1 0.00 0.00 14.54 5.10 0.00 37.91 5.10 21.92 0.80 2 300.00 2 0.00 0.00 14.54 5.10 0.00 37.91 5.10 21.92 0.80 2 300.00 3 0.00 0.00 14.54 5.10 0.00 37.91 5.10 21.92 0.80 2 300.00 4 0.00 0.00 14.54 8.01 0.00 47.61 8.01 95.98 1.00 7 300.00 2018-08-14 06:57:10,582 - INFO - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db
Please sign in to leave a comment.
Comments
5 comments