How can we help?

4.0.954 - Finish by sunrise - no watering

RickNY

August 02, 2018 10:43

On my HD12, tried the “Finish By” feature in 4.0.954 and set program to “Finish By 60 Minutes after sunrise”. Dashboard was and still is showing program supposed to run for 118% today, but sprinklers did not come on and watering history shows “Today No Watering Data”

log data is below:

2018-08-02 02:45:13,013 - INFO  - noaa-parser:83 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2018-08-02 02:45:16,474 - INFO  - noaa-parser:87 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2018-08-02 02:45:20,240 - INFO  - wunderground-parser:77 - WUnderground: station data retrieved for KNYFARMI10
2018-08-02 02:45:21,066 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2018-08-01 00:00:00
2018-08-02 02:45:21,081 - INFO  - rmMixer:124 - *** Received data: ('2018-08-01 00:00:00', temp=24.899999999999999/1, minTemp=None/0, maxTemp=None/0, rh=92/1, minRh=None/0, maxRh=None/0, wind=1.1100000000000001/1, solarRad=None/0, skyCover=None/0, rain=0.80000000000000004/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.8/1, dewPoint=23.5/1, userData=None, minTemp=21.600000000000001/0, maxTemp=28.100000000000001/0, minRH=82/0, maxRH=99/0, et0cal=None, et0final=None)
2018-08-02 02:45:21,096 - INFO  - rmMixer:131 - *** Added Existing data: '2018-08-01 00:00:00', temp=25.109999999999999/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=2.1499999999999999/1, solarRad=None/0, skyCover=None/0, rain=None/0, et0=None/0, pop=11/1, qpf=0/1, condition=0/1, pressure=None/0, dewPoint=23.329999999999998/1, userData=None, minTemp=21.670000000000002/1, maxTemp=28.329999999999998/1, minRH=79/1, maxRH=91/1, et0cal=3.8665495881621688, et0final=3.8700000000000001
2018-08-02 02:45:21,431 - INFO  - rmSimulator:569 - * Correction: Zone 4 et0Delta=1.37, qpfDelta=-6.81, rain=0.8, realPrecip=0.80, qpfUsedLimited=7.61
2018-08-02 02:45:21,438 - INFO  - rmSimulator:569 - * Correction: Zone 6 et0Delta=1.37, qpfDelta=-6.81, rain=0.8, realPrecip=0.80, qpfUsedLimited=7.61
2018-08-02 02:45:21,459 - INFO  - rmSimulator:569 - * Correction: Zone 8 et0Delta=1.37, qpfDelta=-6.81, rain=0.8, realPrecip=0.80, qpfUsedLimited=7.61
2018-08-02 02:45:21,653 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=42.0136560961 waterNeeded=2.66599055213 minRuntime=300
2018-08-02 02:45:21,915 - INFO  - rmSimulator:569 - * Correction: Zone 1 et0Delta=0.05, qpfDelta=-3.02, rain=0.8, realPrecip=0.80, qpfUsedLimited=3.82
2018-08-02 02:45:21,922 - INFO  - rmSimulator:569 - * Correction: Zone 2 et0Delta=0.05, qpfDelta=-3.02, rain=0.8, realPrecip=0.80, qpfUsedLimited=3.82
2018-08-02 02:45:21,929 - INFO  - rmSimulator:569 - * Correction: Zone 3 et0Delta=0.05, qpfDelta=-3.02, rain=0.8, realPrecip=0.80, qpfUsedLimited=3.82
2018-08-02 02:45:21,944 - INFO  - rmSimulator:569 - * Correction: Zone 7 et0Delta=0.05, qpfDelta=-3.02, rain=0.8, realPrecip=0.80, qpfUsedLimited=3.82
2018-08-02 02:45:22,150 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=54.9318464038 waterNeeded=3.48571862417 minRuntime=300
2018-08-02 02:45:22,623 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=25.2725051339 waterNeeded=1.60367523744 minRuntime=300
2018-08-02 02:45:23,013 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=75.6718934052 waterNeeded=4.80178521999 minRuntime=300
2018-08-02 02:45:23,433 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=7.6504607498 minRuntime=300
2018-08-02 02:45:23,890 - INFO  - rmSimulator:612 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=7.60694666377 minRuntime=300
2018-08-02 06:27:16,758 - INFO  - rmMainManager:216 - Upgrade checked.
2018-08-02 06:43:02,490 - INFO  - rmMainManager:216 - Upgrade checked.
2018-08-02 06:44:12,991 - INFO  - rmMainManager:216 - Upgrade checked.
---------------------------- PROGRAM SIMULATION LOG -----------------------------
2018-08-02 02:45:21,284 - INFO  - rmSimulator:254 - Skipping old day 1533096000 < 1533182400 from simulation.
2018-08-02 02:45:21,293 - INFO  - rmSimulator:254 - Skipping old day 1533096000 < 1533182400 from simulation.
2018-08-02 02:45:21,334 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 11 will try to use AW from main db first.
2018-08-02 02:45:21,466 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=11, fake=False, ignoreInternetWeather=False, day=2018-08-02 00:00:00)
	- pastStart=2018-07-31 00:00:00, futureEnd=2018-08-04 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=0.00, qpfUsed=7.61, qpfEst=2.27, historyQpf=2.27, et0Real=8.65, et0Used=7.28, et0Est=7.63, historyEt0=7.63
	- et0Avg=5.60 detailsReal=[et0(m)=4.90, qpf(i)=None], [et0(m)=3.75, qpf(m)=0.00], detailsHistory=[et0(m)=4.05, qpf(m)=0.25], [et0(m)=3.58, qpf(m)=2.02]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    4    0.00   0.00   7.63     5.82     6.82  112.86     12.64       49.77      1.00       7       3672.00
	    6    1.28   0.00   7.63     3.37     6.38  111.35      8.48        8.93      0.68       2       2030.00
	    8    0.43   0.00   7.63     4.29     6.55  116.19     10.41        8.93      0.80       2       7146.00

2018-08-02 02:45:21,563 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:21,656 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-02 00:00:00)
	- pastStart=2018-08-01 00:00:00, futureEnd=2018-08-03 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.25, historyQpf=0.25, et0Real=3.75, et0Used=None, et0Est=4.05, historyEt0=4.05
	- et0Avg=5.60 detailsReal=[et0(m)=3.75, qpf(m)=0.00], detailsHistory=[et0(m)=4.05, qpf(m)=0.25]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -0.11  -2.67   4.05     2.55     0.00    0.00      2.67        8.93      0.68       2         60.00

2018-08-02 02:45:21,819 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 10 will try to use AW from main db first.
2018-08-02 02:45:21,952 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=10, fake=False, ignoreInternetWeather=False, day=2018-08-03 00:00:00)
	- pastStart=2018-08-01 00:00:00, futureEnd=2018-08-05 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=0.25, qpfUsed=3.82, qpfEst=7.87, historyQpf=7.87, et0Real=7.80, et0Used=7.76, et0Est=7.70, historyEt0=7.70
	- et0Avg=5.60 detailsReal=[et0(m)=3.75, qpf(m)=0.00], [et0(m)=4.05, qpf(m)=0.25], detailsHistory=[et0(m)=3.58, qpf(m)=2.02], [et0(m)=4.11, qpf(m)=5.85]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   7.70    -1.06     2.45   18.20      1.39        8.93      0.68       2       2416.00
	    2    0.00   0.00   7.70    -1.06     2.45   18.20      1.39        8.93      0.68       2       2024.00
	    3    0.00   0.00   7.70    -1.06     2.45   18.20      1.39        8.93      0.68       2       2374.00
	    7    0.00   0.00   7.70     0.25     2.46   28.39      2.70       49.77      0.85       7       3306.00

2018-08-02 02:45:22,047 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:22,153 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-03 00:00:00)
	- pastStart=2018-08-02 00:00:00, futureEnd=2018-08-04 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.25, qpfUsed=None, qpfEst=2.02, historyQpf=2.02, et0Real=4.05, et0Used=None, et0Est=3.58, historyEt0=3.58
	- et0Avg=5.60 detailsReal=[et0(m)=4.05, qpf(m)=0.25], detailsHistory=[et0(m)=3.58, qpf(m)=2.02]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -2.67  -3.49   3.58     0.82     0.00    0.00      3.49        8.93      0.68       2         60.00

2018-08-02 02:45:22,315 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 11 will try to use AW from main db first.
2018-08-02 02:45:22,442 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=11, fake=False, ignoreInternetWeather=False, day=2018-08-04 00:00:00)
	- pastStart=2018-08-02 00:00:00, futureEnd=2018-08-06 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=2.27, qpfUsed=None, qpfEst=5.85, historyQpf=5.85, et0Real=7.63, et0Used=None, et0Est=8.82, historyEt0=8.82
	- et0Avg=5.60 detailsReal=[et0(m)=4.05, qpf(m)=0.25], [et0(m)=3.58, qpf(m)=2.02], detailsHistory=[et0(m)=4.11, qpf(m)=5.85], [et0(m)=4.70, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    4    0.00   0.00   8.82     4.14     0.00   36.95      4.14       49.77      1.00       7       3672.00
	    6    0.00   0.00   8.82     1.32     0.00   17.28      1.32        8.93      0.68       2       2030.00
	    8    0.00   0.00   8.82     2.37     0.00   26.50      2.37        8.93      0.80       2       7146.00

2018-08-02 02:45:22,524 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:22,626 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-04 00:00:00)
	- pastStart=2018-08-03 00:00:00, futureEnd=2018-08-05 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=2.02, qpfUsed=None, qpfEst=5.85, historyQpf=5.85, et0Real=3.58, et0Used=None, et0Est=4.11, historyEt0=4.11
	- et0Avg=5.60 detailsReal=[et0(m)=3.58, qpf(m)=2.02], detailsHistory=[et0(m)=4.11, qpf(m)=5.85]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -3.49  -1.60   4.11    -1.88     0.00    0.00      1.60        8.93      0.68       2         60.00

2018-08-02 02:45:22,733 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 10 will try to use AW from main db first.
2018-08-02 02:45:22,845 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=10, fake=False, ignoreInternetWeather=False, day=2018-08-05 00:00:00)
	- pastStart=2018-08-03 00:00:00, futureEnd=2018-08-07 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=7.87, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=7.70, et0Used=None, et0Est=9.39, historyEt0=9.39
	- et0Avg=5.60 detailsReal=[et0(m)=3.58, qpf(m)=2.02], [et0(m)=4.11, qpf(m)=5.85], detailsHistory=[et0(m)=4.70, qpf(i)=None], [et0(m)=4.68, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   9.39     6.38     0.00   83.82      6.38        8.93      0.68       2       2416.00
	    2    0.00   0.00   9.39     6.38     0.00   83.82      6.38        8.93      0.68       2       2024.00
	    3    0.00   0.00   9.39     6.38     0.00   83.82      6.38        8.93      0.68       2       2374.00
	    7    0.00   0.00   9.39     7.98     0.00   83.82      7.98       49.77      0.85       7       3306.00

2018-08-02 02:45:22,913 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:23,016 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-05 00:00:00)
	- pastStart=2018-08-04 00:00:00, futureEnd=2018-08-06 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=5.85, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.11, et0Used=None, et0Est=4.70, historyEt0=4.70
	- et0Avg=5.60 detailsReal=[et0(m)=4.11, qpf(m)=5.85], detailsHistory=[et0(m)=4.70, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -1.60  -4.80   4.70     3.20     0.00    0.00      4.80        8.93      0.68       2         60.00

2018-08-02 02:45:23,146 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 11 will try to use AW from main db first.
2018-08-02 02:45:23,252 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=11, fake=False, ignoreInternetWeather=False, day=2018-08-06 00:00:00)
	- pastStart=2018-08-04 00:00:00, futureEnd=2018-08-08 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=5.85, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=8.82, et0Used=None, et0Est=9.30, historyEt0=9.30
	- et0Avg=5.60 detailsReal=[et0(m)=4.11, qpf(m)=5.85], [et0(m)=4.70, qpf(i)=None], detailsHistory=[et0(m)=4.68, qpf(i)=None], [et0(m)=4.62, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    4    0.00   0.00   9.30     9.30     0.00   83.07      9.30       49.77      1.00       7       3672.00
	    6    0.00   0.00   9.30     6.33     0.00   83.07      6.33        8.93      0.68       2       2030.00
	    8    0.00   0.00   9.30     7.44     0.00   83.07      7.44        8.93      0.80       2       7146.00

2018-08-02 02:45:23,325 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:23,436 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-06 00:00:00)
	- pastStart=2018-08-05 00:00:00, futureEnd=2018-08-07 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.70, et0Used=None, et0Est=4.68, historyEt0=4.68
	- et0Avg=5.60 detailsReal=[et0(m)=4.70, qpf(i)=None], detailsHistory=[et0(m)=4.68, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -4.47  -7.65   4.68     3.18     0.00    0.00      7.65        8.93      0.68       2         60.00

2018-08-02 02:45:23,584 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 10 will try to use AW from main db first.
2018-08-02 02:45:23,718 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=10, fake=False, ignoreInternetWeather=False, day=2018-08-07 00:00:00)
	- pastStart=2018-08-05 00:00:00, futureEnd=2018-08-09 00:00:00, pastMultiplier=2, futureMultiplier=2
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=9.39, et0Used=None, et0Est=4.62, historyEt0=4.62
	- et0Avg=5.60 detailsReal=[et0(m)=4.70, qpf(i)=None], [et0(m)=4.68, qpf(i)=None], detailsHistory=[et0(m)=4.62, qpf(i)=None], [et0(i)=None, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   4.62     3.14     0.00   41.25      3.14        8.93      0.68       2       2416.00
	    2    0.00   0.00   4.62     3.14     0.00   41.25      3.14        8.93      0.68       2       2024.00
	    3    0.00   0.00   4.62     3.14     0.00   41.25      3.14        8.93      0.68       2       2374.00
	    7    0.00   0.00   4.62     3.93     0.00   41.25      3.93       49.77      0.85       7       3306.00

2018-08-02 02:45:23,801 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2018-08-02 02:45:23,893 - INFO  - rmSimulator:500 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2018-08-07 00:00:00)
	- pastStart=2018-08-06 00:00:00, futureEnd=2018-08-08 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.68, et0Used=None, et0Est=4.62, historyEt0=4.62
	- et0Avg=5.60 detailsReal=[et0(m)=4.68, qpf(i)=None], detailsHistory=[et0(m)=4.62, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -4.47  -7.61   4.62     3.14     0.00    0.00      7.61        8.93      0.68       2         60.00

Comments

23 comments

RickNY August 02, 2018 10:57

Also looked at the console itself. The schedule for that program says start time is “60 mins after sunset”, which isn’t right and shows a next watering date of tomorrow. Going back to old schedule for now. We have a heat wave right now, can’t skip watering.

0

Comment actions Permalink
RainMachine Nicholas August 02, 2018 17:06

Maybe the hour that was reschedule already passed ? Console means directly on LCD of the unit ?

0

Comment actions Permalink
RickNY August 03, 2018 10:35

I’ll have to wait a couple of days until the next event. Had rain last night and now a surplus for the next day or two. Yes, console meaning the LCD. Also notice the scheduling on the LCD doesn’t have the finish by option. It’s just the web interface.

0

Comment actions Permalink
RickNY August 07, 2018 10:17

This simply isn’t working on my device. It is calculating the start time correctly when viewing the web UI of the programs screen — but the sprinklers do not come on at that time. Two days now for both of my zones, I’ve had to go in and edit the start time to a fixed time, and then the sprinklers run as expected. I.e program start today was supposed to be 5:04 AM in order to finish by 15 mins after sunrise. As of 5:45, sprinklers did not come on. Changed to 5:56 AM fixed start time, and program runs. I opened up a ticket for this on the website and sent diagnostics as well as attached debug log.

0

Comment actions Permalink
RickNY August 23, 2018 10:58

This was related to the iOS app not having support for the new finish by setting. New beta now has it, and program ran properly this morning. Thanks.

0

Comment actions Permalink
RickNY August 28, 2018 10:21

Update... After several days of watering without issue at the scheduled time, today nothing ran. The program shows it was supposed to run 72% of its scheduled time, and it didn’t run. Additionally, the watering history for today simply says “No watering data”.

0

Comment actions Permalink
RickNY August 28, 2018 16:31
Also notice now in the log, the system is reporting that the program is going to start in a negative number of seconds:
```
2018-08-28 12:26:00,278 - DEBUG - rmParserManager:506 - Program 11 will start in -31020 seconds(2018-08-28 03:49:00) deltaLastParsersRun: 8295 lastUpdate: 1535465265
```
It should have ran at 3:49 AM this morning.. How do I resolve this?
0

Comment actions Permalink
Lorie August 30, 2018 16:26 (Edited August 30, 2018 16:41)

Hi Rick,
I have looked over the your logs and yes, most probably it is a bug, but it should not be related to or influencing your "not watering" problem. Its effect should only be that the simulator will run for a program that actually it's not scheduled for that day.
Also, as far as I can see, you are/were experiencing some rain in those days and I think that watering needs were satisfied.
Also, I suspect that the programs are running ok now. Let me know if this is not the case.

Thanks,

Theodore

0

Comment actions Permalink
RickNY August 30, 2018 17:45 (Edited August 30, 2018 17:50)

As far as the rain goes -- no, we have not had any rain since 8/22. And no, the programs are only running OK now because after 2 days in a row of the programs failing to run at all, I changed the schedule back to "Start 120 minutes before sunrise". When the feature (Finish by...) was first introduced in the beta, it didn't work at all.. But then the iOS app was updated to support that option as well, and I changed the schedule to "Finish by 10 minutes after sunrise". And that was working for both of my programs for 7-10 days or so -- and then it just stopped working with no error messages at all, the watering history displaying "No watering data" on the day it was supposed to run.. After the day would end, the watering history would just have nothing at all for that day -- usually it would at least show the day, show the originally scheduled run time for each zone -- and if it didnt water, it would show 0:00 for each zone along with either "Water surplus" if there was a surplus, or "Rain detected by sensor" if the rain sensor was triggered. I've attached a full screenshot of my watering history.. You can see that on Tue 8/28, there is simply nothing there. Wed 8/29, you can see I just ended up doing manual watering because it skipped that one as well. You can see on days like 8/27, there were zones that didnt water due to a surplus. And days like 8/22 where there was rain detected.

Oh and one more thing -- on those watering history pages -- where it says on days like "Thu Aug 30 2018 -- 0.12 inch Rain since last run" -- not even sure where it gets that from -- because there has not been any recorded since 8/22.. I know this for a fact because my weather source is my own Weather Underground station -- and there has been no rain at all since 8/22.

0

Comment actions Permalink
RainMachine Nicholas September 11, 2018 06:59

If your Weather Underground has API key setup it will also get forecast not only your own observed data. Why do you think "skipping" is bad ? Depending on zones settings and evapotranspiration zones might be skipped and that's how it should work. Do you actually see vegetation wilting ?

0

Comment actions Permalink
RickNY September 11, 2018 09:15

Skipping is fine if there is a surplus. THAT. is what it’s supposed to do. What it is not supposed to do is when the simulation runs and the logs show that the zones have water needed values, and it is supposed to run, and it just doesn’t. And yes, we develop brown spots on the lawn when we have continuous days of 90 deg+ weather and sun with no rain.

Why can’t you just acknowledge it isn’t working properly instead of arguing with me? When it skips a day, the watering history states why (Surplus, restriction, etc) — in these cases the program prepares to run - and then doesn’t. And the watering history shows nothing at all.. And the logs indicate that the program is supposed to run in some negative value of minutes after it was supposed to run.

0

Comment actions Permalink
RainMachine Nicholas September 12, 2018 05:06

Hi Rick,

I'm not arguing for the sake of arguing, I'm trying to find out if your problem steams from the Finish before ... option or something else. We can't replicate the issue with Finish before... not starting watering. I believe that it might be something related to Available Water management and weather data received from WUnderground. RainMachine reports (on the graphs) what data it got from WUnderground.

What I think it happens is:

1. RainMachine gets RAIN and QPF report from WUnderground which is not quite correct with your observations

2. This RAIN/QPF is saved as Available Water and programs are getting skipped.

3. The program not showing on watering log for an entire day could this be because when you changed from Finish by to Start before the next run was reset to the current day and since program was every 2 days it didn't water next day ?

To solve 1. I would add another weather service like NOAA or Forecast.io. For 2. maybe reduce the Field Capacity from each zone setting to have less available water.

P.S. Program showing with negative start offset in logs is not a bug, it's correct it means that program had been scheduled to start in the past from the current time.That doesn't mean that program really started watering, just that it supposed to start but it might be skipped for various reasons (adaptive frequency, restrictions etc).

0

Comment actions Permalink
RickNY September 12, 2018 09:57

I am using two sources — NOAA and WU. I am using WU without an API key just for my station data.

Here’s the thing - I switched it back to “Start 2 hours before sunrise”, and the schedule works fine. I don’t want to start messing with field capacity and other variables because the zones and programs work perfectly when using the other scheduling options. Now that we are in September, I can try using the “Finish by” option again since there will be less concern of high water need.

0

Comment actions Permalink
Edward Ng March 03, 2019 03:21

Hi Everyone, I appear to have the same problem as Rick.

I am using firmware 4.0.975, one weather service, willyweather, program set to end 10 mins before sunrise, and I noticed my mini 8 skipped this morning's watering outright - using adaptive frequency skip, despite us going through a serious heatwave.

I will be trying again with an edited program that is set to start 3 hours before sunrise instead.

0

Comment actions Permalink
RainMachine Nicholas March 04, 2019 07:26

I would check the available water for zones in the programs. To really check if your programs start with your sunrise/sunset schedule create a new program with a zone and disable/uncheck Weather Adaptive.

0

Comment actions Permalink
RainMachine Nicholas March 14, 2019 10:47 (Edited March 14, 2019 10:48)

We believe that we found the culprit and we will issue a fix. What happens is that a weather refresh might increase the last computed running time of a program putting its start time in the past.

Example: Program has to start at 4AM so it can finish at 6AM. Now it's 3AM and we get new weather which increases the program running time by 2h. That means now

the program has to start at 2AM which already passed, so it won't start.

This happens, especially on programs with long running times. The obvious fix would be to let the program run even if it overshoots the sunrise limit or carry over the difference after sunrise to next run (but last option might not be a healthy one).

1

Comment actions Permalink
Edward Ng March 15, 2019 02:42

Sounds great! Is it also possible to introduce logging that reports when the calculated start time is refreshed, so that we know why the device choose to behave that way. Agreed with the proposed solution to let the program overshoot sunrise if needed .

0

Comment actions Permalink
Jeffry Battles March 27, 2019 20:02

I started a new thread to report this problem. I should have done a little more searching! I am having this exact issue ,Finish 60 Minutes Before Sunrise, and the program was flat out skipped with no reported reason for skipping i.e. Water Surplus. When will the fix be released? I will be changing the start time to a fixed start time until the patch is released.

0

Comment actions Permalink
RainMachine Nicholas March 28, 2019 08:50

The new release is being tested internally at this moment and will go to beta process most likely next week. After that depending on beta feedback the stable should be released next month.

0

Comment actions Permalink
Srizzuto91 August 18, 2019 05:27

August 17, 2019: using the parameter "End nnn before Sunrise" still reported as a known problem.

0

Comment actions Permalink

mike August 12, 2021 15:22

Sorry to revive an old thread here, but I'm having the exact same issue with my 16 zone RainMachine (4.0.1135). My program was scheduled to "Finish 0 minutes before sunrise". For most of the summer this has been working correctly, but as soon as a heat wave hit (and just 0.18" of rain in the last week), the program doesn't run at all, and I get "No watering data" listed in Watering History for the last two days. I have reviewed all of the above, and this is definitely not an issue of water surplus, nor soil type settings in my zones. I have multiple weather sources as well, including my own WU station. In fact, I purchased the weather station specifically because this problem has been recurring for the last several years (and I've tried changing soil types and field capacity to no avail). Always the same result – it's not a matter of "0 minutes watered" for each zone, or shorter run times than I prefer, but **NO WATERING DATA**, and no run time at all. That has to be a bug, and my gardens and lawn aren't getting watered (and they're suffering).

I just switched the schedule to start at 3:30AM. Frankly, I don't care when the program completes as long as it runs. I'll post the log below (Aug 9 and 10, normal watering activity, aug 11 and 12, failure condition). I also submitted diagnostics (Aug 12, 11:00AM EDT) in case that's helpful.

2021-08-09 23:10:20,557 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-09 23:10:21,196 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-10 03:53:24,400 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-10 03:53:24,415 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-10 03:53:25,648 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-10 03:53:27,001 - INFO  - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2021-08-09 20:00:00 as it's in the past
2021-08-10 03:53:27,211 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-10 03:53:29,906 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-09 00:00:00
2021-08-10 03:53:29,909 - INFO  - rmMixer:124 - *** Received data: ('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-10 03:53:29,934 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-09 00:00:00', temp=22.140000000000001/1, minTemp=None/0, maxTemp=None/0, rh=88/1, minRh=None/0, maxRh=None/0, wind=2.5/1, solarRad=None/0, skyCover=None/0, rain=3.6099999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=None/0, dewPoint=20/1, userData=None, minTemp=20/1, maxTemp=23.890000000000001/1, minRH=79/1, maxRH=98/1, et0cal=2.719721689918615, et0final=2.7200000000000002
2021-08-10 03:53:29,937 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-09 00:00:00', temp=22.140000000000001/1, minTemp=None/0, maxTemp=None/0, rh=88/1, minRh=None/0, maxRh=None/0, wind=2.5/1, solarRad=None/0, skyCover=None/0, rain=3.6099999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=None/0, dewPoint=20/1, userData=None, minTemp=20/1, maxTemp=23.890000000000001/1, minRH=79/1, maxRH=98/1, et0cal=2.719721689918615, et0final=2.7200000000000002)]
2021-08-10 03:53:30,365 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=10.9518771807 minRuntime=120
2021-08-10 03:53:31,423 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=10.9758378378 minRuntime=120
2021-08-10 03:53:31,998 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.2902058567 minRuntime=120
2021-08-10 03:53:32,542 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.3928484847 minRuntime=120
2021-08-10 03:53:33,119 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.7714497521 minRuntime=120
2021-08-10 03:53:33,668 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6579535548 minRuntime=120
2021-08-10 03:54:33,963 - INFO  - rmParserManager:509 - ** Forcing parsers run because program 6 will start in 60 seconds
2021-08-10 03:54:35,425 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-10 03:54:35,427 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-10 03:54:36,668 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-10 03:54:37,840 - INFO  - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2021-08-09 20:00:00 as it's in the past
2021-08-10 03:54:38,067 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-10 03:55:01,838 - INFO  - rmProgramScheduler:262 - Program 6 added to watering queue and/or watering history
2021-08-10 03:55:02,465 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=1, ust='2021-08-10 00:00:00', rst='2021-08-10 03:55:02', ud=450, md=249, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 03:59:11,100 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 03:59:11,142 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=1, ust='2021-08-10 00:00:00', rst='2021-08-10 03:55:02', ud=450, md=249, rd=249, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 03:59:11,146 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=2, ust='2021-08-10 00:00:00', rst='2021-08-10 03:59:11', ud=300, md=171, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:02:02,516 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:02:02,564 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=2, ust='2021-08-10 00:00:00', rst='2021-08-10 03:59:11', ud=300, md=171, rd=171, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:02:02,569 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=3, ust='2021-08-10 00:00:00', rst='2021-08-10 04:02:02', ud=150, md=86, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:03:28,816 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:03:28,870 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=3, ust='2021-08-10 00:00:00', rst='2021-08-10 04:02:02', ud=150, md=86, rd=86, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:03:28,874 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=4, ust='2021-08-10 00:00:00', rst='2021-08-10 04:03:28', ud=300, md=162, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:06:10,215 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:06:10,263 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=4, ust='2021-08-10 00:00:00', rst='2021-08-10 04:03:28', ud=300, md=162, rd=162, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:06:10,267 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=5, ust='2021-08-10 00:00:00', rst='2021-08-10 04:06:10', ud=360, md=200, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:09:30,702 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:09:30,780 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=5, ust='2021-08-10 00:00:00', rst='2021-08-10 04:06:10', ud=360, md=200, rd=200, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:09:30,785 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=6, ust='2021-08-10 00:00:00', rst='2021-08-10 04:09:30', ud=1350, md=748, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:21:58,037 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:21:58,079 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=6, ust='2021-08-10 00:00:00', rst='2021-08-10 04:09:30', ud=1350, md=748, rd=748, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:21:58,083 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=7, ust='2021-08-10 00:00:00', rst='2021-08-10 04:21:58', ud=300, md=158, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:24:36,422 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:24:36,468 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=7, ust='2021-08-10 00:00:00', rst='2021-08-10 04:21:58', ud=300, md=158, rd=158, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:24:36,473 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=8, ust='2021-08-10 00:00:00', rst='2021-08-10 04:24:36', ud=600, md=333, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:30:10,005 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:30:10,045 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=8, ust='2021-08-10 00:00:00', rst='2021-08-10 04:24:36', ud=600, md=333, rd=333, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:30:10,049 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=9, ust='2021-08-10 00:00:00', rst='2021-08-10 04:30:10', ud=360, md=200, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:33:30,453 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:33:30,530 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=9, ust='2021-08-10 00:00:00', rst='2021-08-10 04:30:10', ud=360, md=200, rd=200, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:33:30,535 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=10, ust='2021-08-10 00:00:00', rst='2021-08-10 04:33:30', ud=300, md=158, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:36:08,900 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:36:08,940 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=10, ust='2021-08-10 00:00:00', rst='2021-08-10 04:33:30', ud=300, md=158, rd=158, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:36:08,945 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=11, ust='2021-08-10 00:00:00', rst='2021-08-10 04:36:08', ud=150, md=14, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:36:22,114 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:36:22,157 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=11, ust='2021-08-10 00:00:00', rst='2021-08-10 04:36:08', ud=150, md=14, rd=14, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:36:22,161 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=12, ust='2021-08-10 00:00:00', rst='2021-08-10 04:36:22', ud=450, md=249, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:40:31,675 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:40:31,717 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=12, ust='2021-08-10 00:00:00', rst='2021-08-10 04:36:22', ud=450, md=249, rd=249, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:40:31,721 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=13, ust='2021-08-10 00:00:00', rst='2021-08-10 04:40:31', ud=660, md=366, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:46:37,356 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:46:37,404 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=13, ust='2021-08-10 00:00:00', rst='2021-08-10 04:40:31', ud=660, md=366, rd=366, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:46:37,408 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=14, ust='2021-08-10 00:00:00', rst='2021-08-10 04:46:37', ud=300, md=166, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:49:23,822 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:49:23,866 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=14, ust='2021-08-10 00:00:00', rst='2021-08-10 04:46:37', ud=300, md=166, rd=166, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:49:23,870 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=1, ust='2021-08-10 00:00:00', rst='2021-08-10 04:49:23', ud=450, md=249, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:53:32,354 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:53:32,396 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=1, ust='2021-08-10 00:00:00', rst='2021-08-10 04:49:23', ud=450, md=249, rd=249, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:53:32,400 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=2, ust='2021-08-10 00:00:00', rst='2021-08-10 04:53:32', ud=300, md=171, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:56:23,754 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:56:23,797 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=2, ust='2021-08-10 00:00:00', rst='2021-08-10 04:53:32', ud=300, md=171, rd=171, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:56:23,802 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=3, ust='2021-08-10 00:00:00', rst='2021-08-10 04:56:23', ud=150, md=86, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 04:57:49,090 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 04:57:49,140 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=3, ust='2021-08-10 00:00:00', rst='2021-08-10 04:56:23', ud=150, md=86, rd=86, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 04:57:49,145 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=4, ust='2021-08-10 00:00:00', rst='2021-08-10 04:57:49', ud=300, md=162, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:00:31,494 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:00:31,552 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=4, ust='2021-08-10 00:00:00', rst='2021-08-10 04:57:49', ud=300, md=162, rd=162, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:00:31,556 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=5, ust='2021-08-10 00:00:00', rst='2021-08-10 05:00:31', ud=360, md=200, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:03:51,978 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:03:52,058 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=5, ust='2021-08-10 00:00:00', rst='2021-08-10 05:00:31', ud=360, md=200, rd=200, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:03:52,063 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=6, ust='2021-08-10 00:00:00', rst='2021-08-10 05:03:52', ud=1350, md=748, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:16:20,246 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:16:20,290 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=6, ust='2021-08-10 00:00:00', rst='2021-08-10 05:03:52', ud=1350, md=748, rd=748, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:16:20,294 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=7, ust='2021-08-10 00:00:00', rst='2021-08-10 05:16:20', ud=300, md=158, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:18:58,649 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:18:58,713 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=7, ust='2021-08-10 00:00:00', rst='2021-08-10 05:16:20', ud=300, md=158, rd=158, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:18:58,718 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=8, ust='2021-08-10 00:00:00', rst='2021-08-10 05:18:58', ud=600, md=333, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:24:31,293 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:24:31,344 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=8, ust='2021-08-10 00:00:00', rst='2021-08-10 05:18:58', ud=600, md=333, rd=333, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:24:31,349 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=9, ust='2021-08-10 00:00:00', rst='2021-08-10 05:24:31', ud=360, md=200, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:27:51,738 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:27:51,793 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=9, ust='2021-08-10 00:00:00', rst='2021-08-10 05:24:31', ud=360, md=200, rd=200, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:27:51,798 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=10, ust='2021-08-10 00:00:00', rst='2021-08-10 05:27:51', ud=300, md=158, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:30:29,165 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:30:29,222 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=10, ust='2021-08-10 00:00:00', rst='2021-08-10 05:27:51', ud=300, md=158, rd=158, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:30:29,226 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=11, ust='2021-08-10 00:00:00', rst='2021-08-10 05:30:29', ud=150, md=14, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:30:43,376 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:30:43,439 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=11, ust='2021-08-10 00:00:00', rst='2021-08-10 05:30:29', ud=150, md=14, rd=14, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:30:43,443 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=12, ust='2021-08-10 00:00:00', rst='2021-08-10 05:30:43', ud=450, md=249, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:34:52,981 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:34:53,024 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=12, ust='2021-08-10 00:00:00', rst='2021-08-10 05:30:43', ud=450, md=249, rd=249, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:34:53,029 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=13, ust='2021-08-10 00:00:00', rst='2021-08-10 05:34:53', ud=660, md=366, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:40:59,652 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:40:59,706 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=13, ust='2021-08-10 00:00:00', rst='2021-08-10 05:34:53', ud=660, md=366, rd=366, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:40:59,710 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=6, zid=14, ust='2021-08-10 00:00:00', rst='2021-08-10 05:40:59', ud=300, md=166, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
2021-08-10 05:43:45,054 - INFO  - rmProgramSchedulerWatering:295 - UpdateZone available water: 0
2021-08-10 05:43:45,096 - INFO  - rmProgramSchedulerWatering:150 - Watering - zone finished: (uid=None, pid=6, zid=14, ust='2021-08-10 00:00:00', rst='2021-08-10 05:40:59', ud=300, md=166, rd=166, aw=0, flag=0, manual=False, running=False, flowClicksDelta=0)
2021-08-10 05:43:45,126 - INFO  - rmProgramSchedulerWatering:160 - Watering - program finished: programId=6 flag=0
2021-08-10 09:54:43,543 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-10 09:54:43,546 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-10 09:54:44,183 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-10 09:54:45,062 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-10 09:54:46,334 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-09 00:00:00
2021-08-10 09:54:46,337 - INFO  - rmMixer:124 - *** Received data: ('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-10 09:54:46,348 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-09 00:00:00', temp=22.07/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=1.53/1, solarRad=None/0, skyCover=None/0, rain=3.7599999999999998/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=25.449999999999999/1, minRH=74/1, maxRH=98/1, et0cal=3.4090276513716233, et0final=3.4100000000000001
2021-08-10 09:54:46,352 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-09 00:00:00', temp=22.07/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=1.53/1, solarRad=None/0, skyCover=None/0, rain=3.7599999999999998/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=25.449999999999999/1, minRH=74/1, maxRH=98/1, et0cal=3.4090276513716233, et0final=3.4100000000000001)]
2021-08-10 09:54:46,687 - INFO  - rmSimulator:614 - * Correction: Zone 1 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,693 - INFO  - rmSimulator:614 - * Correction: Zone 2 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,713 - INFO  - rmSimulator:614 - * Correction: Zone 3 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,732 - INFO  - rmSimulator:614 - * Correction: Zone 4 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,746 - INFO  - rmSimulator:614 - * Correction: Zone 5 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,755 - INFO  - rmSimulator:614 - * Correction: Zone 6 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,765 - INFO  - rmSimulator:614 - * Correction: Zone 7 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,774 - INFO  - rmSimulator:614 - * Correction: Zone 8 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,783 - INFO  - rmSimulator:614 - * Correction: Zone 9 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,793 - INFO  - rmSimulator:614 - * Correction: Zone 10 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,802 - INFO  - rmSimulator:614 - * Correction: Zone 11 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,811 - INFO  - rmSimulator:614 - * Correction: Zone 12 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,821 - INFO  - rmSimulator:614 - * Correction: Zone 13 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,830 - INFO  - rmSimulator:614 - * Correction: Zone 14 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 09:54:46,973 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=10.8480422973 minRuntime=120
2021-08-10 09:54:47,382 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9666673711 minRuntime=120
2021-08-10 09:54:47,798 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.1742927866 minRuntime=120
2021-08-10 09:54:48,182 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.4545687147 minRuntime=120
2021-08-10 09:54:48,598 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.3265019852 minRuntime=120
2021-08-10 10:10:19,449 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-10 10:10:20,024 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-10 15:55:22,979 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-10 15:55:22,981 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-10 15:55:23,635 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-10 15:55:29,849 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-10 15:55:31,306 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-09 00:00:00
2021-08-10 15:55:31,309 - INFO  - rmMixer:124 - *** Received data: ('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-10 15:55:31,321 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-09 00:00:00', temp=22.039999999999999/1, minTemp=None/0, maxTemp=None/0, rh=90/1, minRh=None/0, maxRh=None/0, wind=1.05/1, solarRad=None/0, skyCover=None/0, rain=3.8399999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=26.23/1, minRH=72/1, maxRH=98/1, et0cal=3.6432868880846043, et0final=3.6400000000000001
2021-08-10 15:55:31,325 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-09 00:00:00', temp=22.039999999999999/1, minTemp=None/0, maxTemp=None/0, rh=90/1, minRh=None/0, maxRh=None/0, wind=1.05/1, solarRad=None/0, skyCover=None/0, rain=3.8399999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=26.23/1, minRH=72/1, maxRH=98/1, et0cal=3.6432868880846043, et0final=3.6400000000000001)]
2021-08-10 15:55:31,651 - INFO  - rmSimulator:614 - * Correction: Zone 1 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,657 - INFO  - rmSimulator:614 - * Correction: Zone 2 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,669 - INFO  - rmSimulator:614 - * Correction: Zone 3 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,678 - INFO  - rmSimulator:614 - * Correction: Zone 4 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,688 - INFO  - rmSimulator:614 - * Correction: Zone 5 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,697 - INFO  - rmSimulator:614 - * Correction: Zone 6 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,706 - INFO  - rmSimulator:614 - * Correction: Zone 7 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,716 - INFO  - rmSimulator:614 - * Correction: Zone 8 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,726 - INFO  - rmSimulator:614 - * Correction: Zone 9 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,735 - INFO  - rmSimulator:614 - * Correction: Zone 10 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,745 - INFO  - rmSimulator:614 - * Correction: Zone 11 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,754 - INFO  - rmSimulator:614 - * Correction: Zone 12 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,764 - INFO  - rmSimulator:614 - * Correction: Zone 13 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,773 - INFO  - rmSimulator:614 - * Correction: Zone 14 et0Delta=0.06, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 15:55:31,940 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.5021491039 minRuntime=120
2021-08-10 15:55:32,323 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.0158474629 minRuntime=120
2021-08-10 15:55:32,731 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.0497022893 minRuntime=120
2021-08-10 15:55:33,136 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=10.3760435985 minRuntime=120
2021-08-10 15:55:33,563 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.5259293195 minRuntime=120
2021-08-10 21:10:17,542 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-10 21:10:18,090 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-10 21:56:19,482 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-10 21:56:19,484 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-10 21:56:20,175 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-10 21:56:21,390 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-10 21:56:22,881 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-09 00:00:00
2021-08-10 21:56:22,884 - INFO  - rmMixer:124 - *** Received data: ('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-10 21:56:22,896 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-09 00:00:00', temp=22.02/1, minTemp=None/0, maxTemp=None/0, rh=90/1, minRh=None/0, maxRh=None/0, wind=0.81000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.8799999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=26.620000000000001/1, minRH=71/1, maxRH=98/1, et0cal=3.7598512808900941, et0final=3.7599999999999998
2021-08-10 21:56:22,899 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-09 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=91/1, minRh=None/0, maxRh=None/0, wind=0.56000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.9100000000000001/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/0, maxTemp=27/0, minRH=70/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-09 00:00:00', temp=22.02/1, minTemp=None/0, maxTemp=None/0, rh=90/1, minRh=None/0, maxRh=None/0, wind=0.81000000000000005/1, solarRad=None/0, skyCover=None/0, rain=3.8799999999999999/1, et0=None/0, pop=81/1, qpf=1.02/1, condition=15/1, pressure=101.38/1, dewPoint=20/1, userData=None, minTemp=19/1, maxTemp=26.620000000000001/1, minRH=71/1, maxRH=98/1, et0cal=3.7598512808900941, et0final=3.7599999999999998)]
2021-08-10 21:56:23,223 - INFO  - rmSimulator:614 - * Correction: Zone 1 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,230 - INFO  - rmSimulator:614 - * Correction: Zone 2 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,237 - INFO  - rmSimulator:614 - * Correction: Zone 3 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,247 - INFO  - rmSimulator:614 - * Correction: Zone 4 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,256 - INFO  - rmSimulator:614 - * Correction: Zone 5 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,266 - INFO  - rmSimulator:614 - * Correction: Zone 6 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,275 - INFO  - rmSimulator:614 - * Correction: Zone 7 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,285 - INFO  - rmSimulator:614 - * Correction: Zone 8 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,294 - INFO  - rmSimulator:614 - * Correction: Zone 9 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,303 - INFO  - rmSimulator:614 - * Correction: Zone 10 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,312 - INFO  - rmSimulator:614 - * Correction: Zone 11 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,322 - INFO  - rmSimulator:614 - * Correction: Zone 12 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,331 - INFO  - rmSimulator:614 - * Correction: Zone 13 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,340 - INFO  - rmSimulator:614 - * Correction: Zone 14 et0Delta=0.02, qpfDelta=0.71, rain=0.71, realPrecip=0.71, qpfUsedLimited=0.00
2021-08-10 21:56:23,480 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6877966496 minRuntime=120
2021-08-10 21:56:23,888 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.3969009797 minRuntime=120
2021-08-10 21:56:24,281 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.631609611 minRuntime=120
2021-08-10 21:56:24,682 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.1822881613 minRuntime=120
2021-08-10 21:56:25,109 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6325370591 minRuntime=120
2021-08-11 03:47:26,775 - INFO  - rmParserManager:509 - ** Forcing parsers run because program 6 will start in 540 seconds
2021-08-11 03:47:27,850 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-11 03:47:27,852 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-11 03:47:28,495 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-11 03:47:29,631 - INFO  - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2021-08-10 20:00:00 as it's in the past
2021-08-11 03:47:29,734 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-11 03:47:31,275 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-10 00:00:00
2021-08-11 03:47:31,278 - INFO  - rmMixer:124 - *** Received data: ('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-11 03:47:31,290 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-10 00:00:00', temp=23.41/1, minTemp=None/0, maxTemp=None/0, rh=83/1, minRh=None/0, maxRh=None/0, wind=2.0600000000000001/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=None/0, dewPoint=20.32/1, userData=None, minTemp=20/1, maxTemp=25.559999999999999/1, minRH=74/1, maxRH=98/1, et0cal=3.2624492790560042, et0final=3.2599999999999998
2021-08-11 03:47:31,293 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-10 00:00:00', temp=23.41/1, minTemp=None/0, maxTemp=None/0, rh=83/1, minRh=None/0, maxRh=None/0, wind=2.0600000000000001/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=None/0, dewPoint=20.32/1, userData=None, minTemp=20/1, maxTemp=25.559999999999999/1, minRH=74/1, maxRH=98/1, et0cal=3.2624492790560042, et0final=3.2599999999999998)]
2021-08-11 03:47:31,599 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.4937311039 minRuntime=120
2021-08-11 03:47:32,298 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.2868246645 minRuntime=120
2021-08-11 03:47:32,704 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.3996289345 minRuntime=120
2021-08-11 03:47:33,088 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.0790681714 minRuntime=120
2021-08-11 03:47:33,494 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6032701557 minRuntime=120
2021-08-11 03:47:33,906 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9492165066 minRuntime=120
2021-08-11 08:10:15,405 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-11 08:10:15,998 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-11 09:48:17,884 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-11 09:48:17,887 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-11 09:48:18,538 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-11 09:48:19,879 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-11 09:48:21,580 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-10 00:00:00
2021-08-11 09:48:21,583 - INFO  - rmMixer:124 - *** Received data: ('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-11 09:48:21,594 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-10 00:00:00', temp=22.710000000000001/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=1.8700000000000001/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.66/1, userData=None, minTemp=20/1, maxTemp=24.780000000000001/1, minRH=78/1, maxRH=98/1, et0cal=2.9732088260900427, et0final=2.9700000000000002
2021-08-11 09:48:21,598 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-10 00:00:00', temp=22.710000000000001/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=1.8700000000000001/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.66/1, userData=None, minTemp=20/1, maxTemp=24.780000000000001/1, minRH=78/1, maxRH=98/1, et0cal=2.9732088260900427, et0final=2.9700000000000002)]
2021-08-11 09:48:22,413 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.1230262898 minRuntime=120
2021-08-11 09:48:22,798 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.2634405142 minRuntime=120
2021-08-11 09:48:23,228 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.0351942352 minRuntime=120
2021-08-11 09:48:23,623 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6377539912 minRuntime=120
2021-08-11 09:48:24,049 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8155264017 minRuntime=120
2021-08-11 15:48:27,573 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-11 15:48:27,578 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-11 15:48:28,244 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-11 15:48:29,203 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-11 15:48:31,529 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-10 00:00:00
2021-08-11 15:48:31,532 - INFO  - rmMixer:124 - *** Received data: ('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-11 15:48:31,544 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-10 00:00:00', temp=22.359999999999999/1, minTemp=None/0, maxTemp=None/0, rh=92/1, minRh=None/0, maxRh=None/0, wind=1.77/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.829999999999998/1, userData=None, minTemp=20/1, maxTemp=24.390000000000001/1, minRH=80/1, maxRH=98/1, et0cal=2.8264169902898009, et0final=2.8300000000000001
2021-08-11 15:48:31,548 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-10 00:00:00', temp=22.359999999999999/1, minTemp=None/0, maxTemp=None/0, rh=92/1, minRh=None/0, maxRh=None/0, wind=1.77/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.829999999999998/1, userData=None, minTemp=20/1, maxTemp=24.390000000000001/1, minRH=80/1, maxRH=98/1, et0cal=2.8264169902898009, et0final=2.8300000000000001)]
2021-08-11 15:48:32,377 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.3920374358 minRuntime=120
2021-08-11 15:48:32,759 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.6287177365 minRuntime=120
2021-08-11 15:48:33,138 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.3070182157 minRuntime=120
2021-08-11 15:48:33,550 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.6420914953 minRuntime=120
2021-08-11 15:48:33,955 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8007855204 minRuntime=120
2021-08-11 19:10:13,528 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-11 19:10:14,085 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-11 21:49:15,856 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-11 21:49:15,858 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-11 21:49:16,529 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-11 21:49:17,479 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-11 21:49:18,829 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-10 00:00:00
2021-08-11 21:49:18,832 - INFO  - rmMixer:124 - *** Received data: ('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None)
2021-08-11 21:49:18,844 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-10 00:00:00', temp=22.18/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=1.72/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.920000000000002/1, userData=None, minTemp=20/1, maxTemp=24.199999999999999/1, minRH=81/1, maxRH=98/1, et0cal=2.7537049902016655, et0final=2.75
2021-08-11 21:49:18,848 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-10 00:00:00', temp=22/1, minTemp=None/0, maxTemp=None/0, rh=95/1, minRh=None/0, maxRh=None/0, wind=1.6699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.29000000000001/1, dewPoint=21/1, userData=None, minTemp=20/0, maxTemp=24/0, minRH=83/0, maxRH=99/0, et0cal=None, et0final=None), ('2021-08-10 00:00:00', temp=22.18/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=1.72/1, solarRad=None/0, skyCover=None/0, rain=0.70999999999999996/1, et0=None/0, pop=21/1, qpf=0.89000000000000001/1, condition=15/1, pressure=101.29000000000001/1, dewPoint=20.920000000000002/1, userData=None, minTemp=20/1, maxTemp=24.199999999999999/1, minRH=81/1, maxRH=98/1, et0cal=2.7537049902016655, et0final=2.75)]
2021-08-11 21:49:19,658 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8317233569 minRuntime=120
2021-08-11 21:49:20,035 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8542942142 minRuntime=120
2021-08-11 21:49:20,440 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.0668792408 minRuntime=120
2021-08-11 21:49:20,839 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9147020858 minRuntime=120
2021-08-11 21:49:21,255 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.938374455 minRuntime=120
2021-08-12 03:20:22,851 - INFO  - rmParserManager:509 - ** Forcing parsers run because program 6 will start in 540 seconds
2021-08-12 03:20:24,037 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-12 03:20:24,039 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-12 03:20:24,700 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-12 03:20:25,524 - INFO  - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2021-08-11 20:00:00 as it's in the past
2021-08-12 03:20:25,629 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-12 03:20:26,968 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-11 00:00:00
2021-08-12 03:20:26,971 - INFO  - rmMixer:124 - *** Received data: ('2021-08-11 00:00:00', temp=25/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=3.3300000000000001/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.95/1, dewPoint=24/1, userData=None, minTemp=23/0, maxTemp=27/0, minRH=85/0, maxRH=98/0, et0cal=None, et0final=None)
2021-08-12 03:20:26,983 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-11 00:00:00', temp=24.84/1, minTemp=None/0, maxTemp=None/0, rh=85/1, minRh=None/0, maxRh=None/0, wind=5/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=13/1, qpf=0/1, condition=5/1, pressure=None/0, dewPoint=22.059999999999999/1, userData=None, minTemp=21.84/1, maxTemp=28.329999999999998/1, minRH=74/1, maxRH=96/1, et0cal=3.8405112479537098, et0final=3.8399999999999999
2021-08-12 03:20:26,986 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-11 00:00:00', temp=25/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=3.3300000000000001/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.95/1, dewPoint=24/1, userData=None, minTemp=23/0, maxTemp=27/0, minRH=85/0, maxRH=98/0, et0cal=None, et0final=None), ('2021-08-11 00:00:00', temp=24.84/1, minTemp=None/0, maxTemp=None/0, rh=85/1, minRh=None/0, maxRh=None/0, wind=5/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=13/1, qpf=0/1, condition=5/1, pressure=None/0, dewPoint=22.059999999999999/1, userData=None, minTemp=21.84/1, maxTemp=28.329999999999998/1, minRH=74/1, maxRH=96/1, et0cal=3.8405112479537098, et0final=3.8399999999999999)]
2021-08-12 03:20:27,265 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.7894344119 minRuntime=120
2021-08-12 03:20:27,954 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.7132076472 minRuntime=120
2021-08-12 03:20:28,343 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9709151279 minRuntime=120
2021-08-12 03:20:28,753 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8863723555 minRuntime=120
2021-08-12 03:20:29,142 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.92460886 minRuntime=120
2021-08-12 03:20:29,555 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9502305715 minRuntime=120
2021-08-12 06:10:12,131 - INFO  - rmThreadWatcher:438 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2021-08-12 06:10:12,707 - INFO  - rmMainManager:513 - ** Automatic Update: No updates available
2021-08-12 09:21:14,836 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for [redacted]
2021-08-12 09:21:14,838 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
2021-08-12 09:21:15,470 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2021-08-12 09:21:16,314 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2021-08-12 09:21:17,616 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2021-08-11 00:00:00
2021-08-12 09:21:17,619 - INFO  - rmMixer:124 - *** Received data: ('2021-08-11 00:00:00', temp=25/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=3.3300000000000001/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.95/1, dewPoint=24/1, userData=None, minTemp=23/0, maxTemp=27/0, minRH=85/0, maxRH=98/0, et0cal=None, et0final=None)
2021-08-12 09:21:17,630 - INFO  - rmMixer:131 - *** Added Existing data: '2021-08-11 00:00:00', temp=24.920000000000002/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=4.1699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=13/1, qpf=0/1, condition=5/1, pressure=100.95/1, dewPoint=23.030000000000001/1, userData=None, minTemp=22.420000000000002/1, maxTemp=27.670000000000002/1, minRH=79/1, maxRH=97/1, et0cal=3.3350359124479132, et0final=3.3399999999999999
2021-08-12 09:21:17,634 - INFO  - rmMixer:132 - *** All available Data: [('2021-08-11 00:00:00', temp=25/1, minTemp=None/0, maxTemp=None/0, rh=93/1, minRh=None/0, maxRh=None/0, wind=3.3300000000000001/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.95/1, dewPoint=24/1, userData=None, minTemp=23/0, maxTemp=27/0, minRH=85/0, maxRH=98/0, et0cal=None, et0final=None), ('2021-08-11 00:00:00', temp=24.920000000000002/1, minTemp=None/0, maxTemp=None/0, rh=89/1, minRh=None/0, maxRh=None/0, wind=4.1699999999999999/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=13/1, qpf=0/1, condition=5/1, pressure=100.95/1, dewPoint=23.030000000000001/1, userData=None, minTemp=22.420000000000002/1, maxTemp=27.670000000000002/1, minRH=79/1, maxRH=97/1, et0cal=3.3350359124479132, et0final=3.3399999999999999)]
2021-08-12 09:21:18,451 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.9111650596 minRuntime=120
2021-08-12 09:21:18,862 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=12.2081003291 minRuntime=120
2021-08-12 09:21:19,265 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.7661935063 minRuntime=120
2021-08-12 09:21:19,673 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8016143219 minRuntime=120
2021-08-12 09:21:20,073 - INFO  - rmSimulator:657 -      minRuntime Restriction pid=1, zid=2: AW to next day tmachine=120 waterNeeded=11.8886347353 minRuntime=120
2021-08-12 10:42:06,798 - INFO  - rmMainManager:239 - Upgrade checked.
2021-08-12 10:49:41,299 - INFO  - rmMainManager:239 - Upgrade checked.
2021-08-12 10:50:26,461 - INFO  - rmMainManager:239 - Upgrade checked.
2021-08-12 11:00:21,365 - INFO  - rmMainManager:239 - Upgrade checked.
---------------------------- PROGRAM SIMULATION LOG -----------------------------
2021-08-12 09:21:17,752 - INFO  - rmSimulator:254 - Skipping old day 1628654400 < 1628740800 from simulation.
2021-08-12 09:21:17,755 - INFO  - rmSimulator:254 - Skipping old day 1628654400 < 1628740800 from simulation.
2021-08-12 09:21:17,923 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 6 will try to use AW from main db first.
2021-08-12 09:21:18,309 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=6, fake=False, ignoreInternetWeather=0, day=2021-08-13 00:00:00)
	- pastStart=2021-08-12 00:00:00, futureEnd=2021-08-14 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.09, et0Used=None, et0Est=3.90, historyEt0=3.90
	- et0Avg=5.00 detailsReal=[et0(m)=4.09, qpf(m)=0.00], detailsHistory=[et0(m)=3.90, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   3.90     3.12     0.00   77.92      3.12        6.25      0.80       2        900.00
	    2    0.00   0.00   3.90     3.90     0.00   77.92      3.90       16.03      1.00       4        600.00
	    3    0.00   0.00   3.90     3.90     0.00   77.92      3.90       16.03      1.00       4        300.00
	    4    0.00   0.00   3.90     2.65     0.00   77.92      2.65        7.96      0.68       2        600.00
	    5    0.00   0.00   3.90     3.12     0.00   77.92      3.12       11.37      0.80       2        720.00
	    6    0.00   0.00   3.90     3.12     0.00   77.92      3.12        4.47      0.80       2       2700.00
	    7    0.00   0.00   3.90     2.34     0.00   77.92      2.34       49.77      0.60       7        600.00
	    8    0.00   0.00   3.90     3.12     0.00   77.92      3.12        4.47      0.80       2       1200.00
	    9    0.00   0.00   3.90     3.12     0.00   77.92      3.12        7.15      0.80       2        720.00
	   10    0.00   0.00   3.90     2.34     0.00   77.92      2.34       27.37      0.60       7        600.00
	   11    0.00   0.00   3.90     2.65     0.00   77.92      2.65       11.37      0.68       2        300.00
	   12    0.00   0.00   3.90     3.12     0.00   77.92      3.12        4.47      0.80       2        900.00
	   13    0.00   0.00   3.90     3.12     0.00   77.92      3.12       11.37      0.80       2       1320.00
	   14    0.00   0.00   3.90     3.12     0.00   77.92      3.12        7.15      0.80       2        600.00

2021-08-12 09:21:18,381 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2021-08-12 09:21:18,454 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2021-08-13 00:00:00)
	- pastStart=2021-08-12 00:00:00, futureEnd=2021-08-14 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.09, et0Used=None, et0Est=3.90, historyEt0=3.90
	- et0Avg=5.00 detailsReal=[et0(m)=4.09, qpf(m)=0.00], detailsHistory=[et0(m)=3.90, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -8.02 -11.91   3.90     3.90     0.00    0.00     11.91       16.03      1.00       4         60.00

2021-08-12 09:21:18,583 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 6 will try to use AW from main db first.
2021-08-12 09:21:18,708 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=6, fake=False, ignoreInternetWeather=0, day=2021-08-14 00:00:00)
	- pastStart=2021-08-13 00:00:00, futureEnd=2021-08-15 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.75, historyQpf=0.75, et0Real=3.90, et0Used=None, et0Est=4.42, historyEt0=4.42
	- et0Avg=5.00 detailsReal=[et0(m)=3.90, qpf(m)=0.00], detailsHistory=[et0(m)=4.42, qpf(m)=0.75]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   4.42     3.31     0.00   82.74      3.31        6.25      0.80       2        900.00
	    2    0.00   0.00   4.42     4.19     0.00   83.86      4.19       16.03      1.00       4        600.00
	    3    0.00   0.00   4.42     4.19     0.00   83.86      4.19       16.03      1.00       4        300.00
	    4    0.00   0.00   4.42     2.78     0.00   81.74      2.78        7.96      0.68       2        600.00
	    5    0.00   0.00   4.42     3.31     0.00   82.74      3.31       11.37      0.80       2        720.00
	    6    0.00   0.00   4.42     3.31     0.00   82.74      3.31        4.47      0.80       2       2700.00
	    7    0.00   0.00   4.42     2.43     0.00   80.86      2.43       49.77      0.60       7        600.00
	    8    0.00   0.00   4.42     3.31     0.00   82.74      3.31        4.47      0.80       2       1200.00
	    9    0.00   0.00   4.42     3.31     0.00   82.74      3.31        7.15      0.80       2        720.00
	   10    0.00   0.00   4.42     2.43     0.00   80.86      2.43       27.37      0.60       7        600.00
	   11    0.00   0.00   4.42     2.78     0.00   81.74      2.78       11.37      0.68       2        300.00
	   12    0.00   0.00   4.42     3.31     0.00   82.74      3.31        4.47      0.80       2        900.00
	   13    0.00   0.00   4.42     3.31     0.00   82.74      3.31       11.37      0.80       2       1320.00
	   14    0.00   0.00   4.42     3.31     0.00   82.74      3.31        7.15      0.80       2        600.00

2021-08-12 09:21:18,799 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2021-08-12 09:21:18,866 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2021-08-14 00:00:00)
	- pastStart=2021-08-13 00:00:00, futureEnd=2021-08-15 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.75, historyQpf=0.75, et0Real=3.90, et0Used=None, et0Est=4.42, historyEt0=4.42
	- et0Avg=5.00 detailsReal=[et0(m)=3.90, qpf(m)=0.00], detailsHistory=[et0(m)=4.42, qpf(m)=0.75]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -8.02 -12.21   4.42     4.19     0.00    0.00     12.21       16.03      1.00       4         60.00

2021-08-12 09:21:18,986 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 6 will try to use AW from main db first.
2021-08-12 09:21:19,110 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=6, fake=False, ignoreInternetWeather=0, day=2021-08-15 00:00:00)
	- pastStart=2021-08-14 00:00:00, futureEnd=2021-08-16 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.75, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.42, et0Used=None, et0Est=3.75, historyEt0=3.75
	- et0Avg=5.00 detailsReal=[et0(m)=4.42, qpf(m)=0.75], detailsHistory=[et0(m)=3.75, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   3.75     3.00     0.00   75.02      3.00        6.25      0.80       2        900.00
	    2    0.00   0.00   3.75     3.75     0.00   75.02      3.75       16.03      1.00       4        600.00
	    3    0.00   0.00   3.75     3.75     0.00   75.02      3.75       16.03      1.00       4        300.00
	    4    0.00   0.00   3.75     2.55     0.00   75.02      2.55        7.96      0.68       2        600.00
	    5    0.00   0.00   3.75     3.00     0.00   75.02      3.00       11.37      0.80       2        720.00
	    6    0.00   0.00   3.75     3.00     0.00   75.02      3.00        4.47      0.80       2       2700.00
	    7    0.00   0.00   3.75     2.25     0.00   75.02      2.25       49.77      0.60       7        600.00
	    8    0.00   0.00   3.75     3.00     0.00   75.02      3.00        4.47      0.80       2       1200.00
	    9    0.00   0.00   3.75     3.00     0.00   75.02      3.00        7.15      0.80       2        720.00
	   10    0.00   0.00   3.75     2.25     0.00   75.02      2.25       27.37      0.60       7        600.00
	   11    0.00   0.00   3.75     2.55     0.00   75.02      2.55       11.37      0.68       2        300.00
	   12    0.00   0.00   3.75     3.00     0.00   75.02      3.00        4.47      0.80       2        900.00
	   13    0.00   0.00   3.75     3.00     0.00   75.02      3.00       11.37      0.80       2       1320.00
	   14    0.00   0.00   3.75     3.00     0.00   75.02      3.00        7.15      0.80       2        600.00

2021-08-12 09:21:19,183 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2021-08-12 09:21:19,268 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2021-08-15 00:00:00)
	- pastStart=2021-08-14 00:00:00, futureEnd=2021-08-16 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.75, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.42, et0Used=None, et0Est=3.75, historyEt0=3.75
	- et0Avg=5.00 detailsReal=[et0(m)=4.42, qpf(m)=0.75], detailsHistory=[et0(m)=3.75, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -8.02 -11.77   3.75     3.75     0.00    0.00     11.77       16.03      1.00       4         60.00

2021-08-12 09:21:19,398 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 6 will try to use AW from main db first.
2021-08-12 09:21:19,534 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=6, fake=False, ignoreInternetWeather=0, day=2021-08-16 00:00:00)
	- pastStart=2021-08-15 00:00:00, futureEnd=2021-08-17 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=3.75, et0Used=None, et0Est=3.79, historyEt0=3.79
	- et0Avg=5.00 detailsReal=[et0(m)=3.75, qpf(m)=0.00], detailsHistory=[et0(m)=3.79, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   3.79     3.03     0.00   75.73      3.03        6.25      0.80       2        900.00
	    2    0.00   0.00   3.79     3.79     0.00   75.73      3.79       16.03      1.00       4        600.00
	    3    0.00   0.00   3.79     3.79     0.00   75.73      3.79       16.03      1.00       4        300.00
	    4    0.00   0.00   3.79     2.57     0.00   75.73      2.57        7.96      0.68       2        600.00
	    5    0.00   0.00   3.79     3.03     0.00   75.73      3.03       11.37      0.80       2        720.00
	    6    0.00   0.00   3.79     3.03     0.00   75.73      3.03        4.47      0.80       2       2700.00
	    7    0.00   0.00   3.79     2.27     0.00   75.73      2.27       49.77      0.60       7        600.00
	    8    0.00   0.00   3.79     3.03     0.00   75.73      3.03        4.47      0.80       2       1200.00
	    9    0.00   0.00   3.79     3.03     0.00   75.73      3.03        7.15      0.80       2        720.00
	   10    0.00   0.00   3.79     2.27     0.00   75.73      2.27       27.37      0.60       7        600.00
	   11    0.00   0.00   3.79     2.57     0.00   75.73      2.57       11.37      0.68       2        300.00
	   12    0.00   0.00   3.79     3.03     0.00   75.73      3.03        4.47      0.80       2        900.00
	   13    0.00   0.00   3.79     3.03     0.00   75.73      3.03       11.37      0.80       2       1320.00
	   14    0.00   0.00   3.79     3.03     0.00   75.73      3.03        7.15      0.80       2        600.00

2021-08-12 09:21:19,610 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2021-08-12 09:21:19,676 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2021-08-16 00:00:00)
	- pastStart=2021-08-15 00:00:00, futureEnd=2021-08-17 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=3.75, et0Used=None, et0Est=3.79, historyEt0=3.79
	- et0Avg=5.00 detailsReal=[et0(m)=3.75, qpf(m)=0.00], detailsHistory=[et0(m)=3.79, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -8.02 -11.80   3.79     3.79     0.00    0.00     11.80       16.03      1.00       4         60.00

2021-08-12 09:21:19,805 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 6 will try to use AW from main db first.
2021-08-12 09:21:19,936 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=6, fake=False, ignoreInternetWeather=0, day=2021-08-17 00:00:00)
	- pastStart=2021-08-16 00:00:00, futureEnd=2021-08-18 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=3.79, et0Used=None, et0Est=3.87, historyEt0=3.87
	- et0Avg=5.00 detailsReal=[et0(m)=3.79, qpf(m)=0.00], detailsHistory=[et0(m)=3.87, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00   3.87     3.10     0.00   77.47      3.10        6.25      0.80       2        900.00
	    2    0.00   0.00   3.87     3.87     0.00   77.47      3.87       16.03      1.00       4        600.00
	    3    0.00   0.00   3.87     3.87     0.00   77.47      3.87       16.03      1.00       4        300.00
	    4    0.00   0.00   3.87     2.63     0.00   77.47      2.63        7.96      0.68       2        600.00
	    5    0.00   0.00   3.87     3.10     0.00   77.47      3.10       11.37      0.80       2        720.00
	    6    0.00   0.00   3.87     3.10     0.00   77.47      3.10        4.47      0.80       2       2700.00
	    7    0.00   0.00   3.87     2.32     0.00   77.47      2.32       49.77      0.60       7        600.00
	    8    0.00   0.00   3.87     3.10     0.00   77.47      3.10        4.47      0.80       2       1200.00
	    9    0.00   0.00   3.87     3.10     0.00   77.47      3.10        7.15      0.80       2        720.00
	   10    0.00   0.00   3.87     2.32     0.00   77.47      2.32       27.37      0.60       7        600.00
	   11    0.00   0.00   3.87     2.63     0.00   77.47      2.63       11.37      0.68       2        300.00
	   12    0.00   0.00   3.87     3.10     0.00   77.47      3.10        4.47      0.80       2        900.00
	   13    0.00   0.00   3.87     3.10     0.00   77.47      3.10       11.37      0.80       2       1320.00
	   14    0.00   0.00   3.87     3.10     0.00   77.47      3.10        7.15      0.80       2        600.00

2021-08-12 09:21:20,010 - INFO  - rmSimulator:395 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2021-08-12 09:21:20,076 - INFO  - rmSimulator:545 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2021-08-17 00:00:00)
	- pastStart=2021-08-16 00:00:00, futureEnd=2021-08-18 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=3.79, et0Used=None, et0Est=3.87, historyEt0=3.87
	- et0Avg=5.00 detailsReal=[et0(m)=3.79, qpf(m)=0.00], detailsHistory=[et0(m)=3.87, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2   -8.02 -11.89   3.87     3.87     0.00    0.00     11.89       16.03      1.00       4         60.00

RickNY August 12, 2021 21:43

Wish I could give good news -- I was never able to get the "Finish By" feature to work reliably. Like you, things would seem to go OK until suddenly nothing would get watered at all (and USUALLY it would act up in the middle of a heat wave with no rainfall)

I gave up on using the feature 2 years ago and now strictly use "Start x minutes before sunrise"

0

Comment actions Permalink
Atkinsongf August 14, 2021 03:35

This thread describes my issue that I started another thread on last month. The issue is still there and for some reason, they cannot seem to duplicate it. It has occurred twice for me this summer so far, the latest was on 8/11.

I still think it's an issue where the rainmachine does the final calculation of the needed start time, but finds that time is in the past for whatever reason. It seems to occur when the next watering cycle is a higher percentage than the previous one, so would need to start earlier.

0

Comment actions Permalink

Please sign in to leave a comment.

How can we help?

Comments

Didn't find what you were looking for?