Failed to Water - "Programs" shows it should have

First time I've experienced this but want to understand why just to be sure it's not a more severe issue. Saying this as I recently found that my unit was "offline" and had to force a reset to get it back online. I also saw last night that I had trouble connecting to the device, multiple tries before it finally connected.

I have my Mini-8 set to run program "Main" on Wednesday (firmware 4.0.975). Based on the "Programs" info it shows that it should have watered 85%. But the logs don't show that it watered and my driveway and sidewalk were not wet, so presume that the log is correct that it didn't water. The 7 day water history doesn't show and there is no current water restrictions. Looking at the Watering History it doesn't report any watering activity yesterday.

Here's the logs for the day (4/3). Guess it looks odd that it says it was "Program Simulation Log"

2019-04-03 03:53:55,914 - INFO  - rmThreadWatcher:407 - **  Automatic Update: Starting update check as interval to next watering program is bigger than 3600
2019-04-03 03:53:56,641 - INFO  - rmMainManager:442 - ** Automatic Update: No updates available
2019-04-03 05:31:58,669 - INFO  - noaa-parser:83 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
2019-04-03 05:32:05,210 - INFO  - noaa-parser:87 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
2019-04-03 05:32:10,236 - ERROR - fawn-parser:127 - *** Error retrieving daily data from FAWN
2019-04-03 05:32:10,246 - ERROR - fawn-parser:128 - could not convert string to float: 
Traceback (most recent call last):
  File "./RMParserFramework/parsers/fawn-parser.py", line 121, in perform
  File "./RMParserFramework/parsers/fawn-parser.py", line 133, in __toFloat
ValueError: could not convert string to float: 
2019-04-03 05:32:22,159 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2019-04-02 00:00:00
2019-04-03 05:32:22,383 - INFO  - rmMixer:124 - *** Received data: ('2019-04-02 00:00:00', temp=19.04/1, minTemp=None/0, maxTemp=None/0, rh=83.3/1, minRh=None/0, maxRh=None/0, wind=2.5699999999999997/1, solarRad=None/0, skyCover=None/0, rain=0.23/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=None/0, dewPoint=15.88/1, userData=None, minTemp=12.85/0, maxTemp=25.16/0, minRH=59/0, maxRH=98.2/0, et0cal=None, et0final=None)
2019-04-03 05:32:22,439 - INFO  - rmMixer:131 - *** Added Existing data: '2019-04-02 00:00:00', temp=19.61/1, minTemp=None/0, maxTemp=None/0, rh=None/0, minRh=None/0, maxRh=None/0, wind=1.1499999999999999/1, solarRad=None/0, skyCover=None/0, rain=None/0, et0=None/0, pop=1/1, qpf=1.27/1, condition=2/1, pressure=None/0, dewPoint=16.45/1, userData=None, minTemp=15.56/1, maxTemp=24.44/1, minRH=60/1, maxRH=None/0, et0cal=3.4584609721842766, et0final=3.46
2019-04-03 05:32:24,322 - INFO  - rmSimulator:571 - * Correction: Zone 1 et0Delta=0.14, qpfDelta=-2.05, rain=0.23, realPrecip=0.23, qpfUsedLimited=2.28
2019-04-03 05:32:24,353 - INFO  - rmSimulator:571 - * Correction: Zone 2 et0Delta=0.14, qpfDelta=-2.05, rain=0.23, realPrecip=0.23, qpfUsedLimited=2.28
2019-04-03 05:32:24,391 - INFO  - rmSimulator:571 - * Correction: Zone 4 et0Delta=0.14, qpfDelta=-2.05, rain=0.23, realPrecip=0.23, qpfUsedLimited=2.28
2019-04-03 05:32:24,420 - INFO  - rmSimulator:571 - * Correction: Zone 5 et0Delta=0.14, qpfDelta=-2.05, rain=0.23, realPrecip=0.23, qpfUsedLimited=2.28
2019-04-03 05:32:29,132 - INFO  - rmSimulator:571 - * Correction: Zone 3 et0Delta=-2.50, qpfDelta=0.23, rain=0.23, realPrecip=0.23, qpfUsedLimited=0.00
2019-04-03 09:34:39,847 - INFO  - rmMainManager:225 - Upgrade checked.
2019-04-03 09:36:32,588 - INFO  - rmMainManager:225 - Upgrade checked.
---------------------------- PROGRAM SIMULATION LOG -----------------------------
2019-04-03 05:32:23,410 - INFO  - rmSimulator:254 - Skipping old day 1554177600 < 1554264000 from simulation.
2019-04-03 05:32:23,417 - INFO  - rmSimulator:254 - Skipping old day 1554177600 < 1554264000 from simulation.
2019-04-03 05:32:23,497 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:24,446 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=False, ignoreInternetWeather=False, day=2019-04-03 00:00:00)
	- pastStart=2019-03-30 00:00:00, futureEnd=2019-04-06 00:00:00, pastMultiplier=4, futureMultiplier=3
	- qpfReal=2.03, qpfUsed=2.28, qpfEst=1.02, historyQpf=1.02, et0Real=16.27, et0Used=16.13, et0Est=13.77, historyEt0=13.77
	- et0Avg=6.01 detailsReal=[et0(m)=4.75, qpf(m)=0.00], [et0(m)=4.92, qpf(m)=0.00], [et0(m)=2.82, qpf(m)=0.76], [et0(m)=3.78, qpf(m)=1.27], detailsHistory=[et0(m)=4.61, qpf(i)=None], [et0(m)=4.76, qpf(i)=None], [et0(m)=4.40, qpf(m)=1.02]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    1    0.00   0.00  13.77     6.21     1.71   86.15      7.92       11.37      0.51      10       1326.00
	    2    0.00   0.00  13.77     7.44     1.72   84.80      9.17       11.37      0.60      10       1563.00
	    4    0.00   0.00  13.77     7.44     1.72   84.80      9.17       11.37      0.60      10       1563.00
	    5    0.00   0.00  13.77     6.21     1.71   86.15      7.92       11.37      0.51      10       1200.00

2019-04-03 05:32:25,221 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:25,659 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-03 00:00:00)
	- pastStart=2019-04-02 00:00:00, futureEnd=2019-04-04 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=1.27, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=3.78, et0Used=None, et0Est=4.61, historyEt0=4.61
	- et0Avg=6.01 detailsReal=[et0(m)=3.78, qpf(m)=1.27], detailsHistory=[et0(m)=4.61, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.61     2.77     0.00   76.74      2.77       11.37      0.60      10         60.00

2019-04-03 05:32:26,971 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:27,678 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-04 00:00:00)
	- pastStart=2019-04-03 00:00:00, futureEnd=2019-04-05 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.61, et0Used=None, et0Est=4.76, historyEt0=4.76
	- et0Avg=6.01 detailsReal=[et0(m)=4.61, qpf(m)=0.00], detailsHistory=[et0(m)=4.76, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.76     2.86     0.00   79.29      2.86       11.37      0.60      10         60.00

2019-04-03 05:32:28,659 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 13 will try to use AW from main db first.
2019-04-03 05:32:29,159 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=13, fake=False, ignoreInternetWeather=False, day=2019-04-05 00:00:00)
	- pastStart=2019-04-02 00:00:00, futureEnd=2019-04-09 00:00:00, pastMultiplier=3, futureMultiplier=4
	- qpfReal=1.27, qpfUsed=0.00, qpfEst=1.02, historyQpf=1.02, et0Real=13.15, et0Used=15.65, et0Est=18.54, historyEt0=18.54
	- et0Avg=6.01 detailsReal=[et0(m)=3.78, qpf(m)=1.27], [et0(m)=4.61, qpf(m)=0.00], [et0(m)=4.76, qpf(m)=0.00], detailsHistory=[et0(m)=4.40, qpf(m)=1.02], [et0(m)=4.88, qpf(i)=None], [et0(m)=4.70, qpf(i)=None], [et0(m)=4.56, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    3    0.00   0.00  18.54    10.31    -1.69   59.81      8.62       49.77      0.60       7       2552.00

2019-04-03 05:32:29,856 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:30,275 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-05 00:00:00)
	- pastStart=2019-04-04 00:00:00, futureEnd=2019-04-06 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=1.02, historyQpf=1.02, et0Real=4.76, et0Used=None, et0Est=4.40, historyEt0=4.40
	- et0Avg=6.01 detailsReal=[et0(m)=4.76, qpf(m)=0.00], detailsHistory=[et0(m)=4.40, qpf(m)=1.02]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.40     1.82     0.00   50.57      1.82       11.37      0.60      10         60.00

2019-04-03 05:32:31,248 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:32,173 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=False, ignoreInternetWeather=False, day=2019-04-06 00:00:00)
	- pastStart=2019-04-03 00:00:00, futureEnd=2019-04-10 00:00:00, pastMultiplier=3, futureMultiplier=4
	- qpfReal=1.02, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=13.77, et0Used=None, et0Est=14.14, historyEt0=14.14
	- et0Avg=6.01 detailsReal=[et0(m)=4.61, qpf(m)=0.00], [et0(m)=4.76, qpf(m)=0.00], [et0(m)=4.40, qpf(m)=1.02], detailsHistory=[et0(m)=4.88, qpf(i)=None], [et0(m)=4.70, qpf(i)=None], [et0(m)=4.56, 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  14.14     7.21     0.00   58.86      7.21       11.37      0.51      10       1326.00
	    2    0.00   0.00  14.14     8.48     0.00   58.86      8.48       11.37      0.60      10       1563.00
	    4    0.00   0.00  14.14     8.48     0.00   58.86      8.48       11.37      0.60      10       1563.00
	    5    0.00   0.00  14.14     7.21     0.00   58.86      7.21       11.37      0.51      10       1200.00

2019-04-03 05:32:32,591 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:33,244 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-06 00:00:00)
	- pastStart=2019-04-05 00:00:00, futureEnd=2019-04-07 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=1.02, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.40, et0Used=None, et0Est=4.88, historyEt0=4.88
	- et0Avg=6.01 detailsReal=[et0(m)=4.40, qpf(m)=1.02], detailsHistory=[et0(m)=4.88, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.88     2.93     0.00   81.27      2.93       11.37      0.60      10         60.00

2019-04-03 05:32:34,460 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:34,910 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-07 00:00:00)
	- pastStart=2019-04-06 00:00:00, futureEnd=2019-04-08 00:00:00, pastMultiplier=1, futureMultiplier=1
	- qpfReal=0.00, qpfUsed=None, qpfEst=0.00, historyQpf=0.00, et0Real=4.88, et0Used=None, et0Est=4.70, historyEt0=4.70
	- et0Avg=6.01 detailsReal=[et0(m)=4.88, qpf(i)=None], detailsHistory=[et0(m)=4.70, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.70     2.82     0.00   78.31      2.82       11.37      0.60      10         60.00

2019-04-03 05:32:36,053 - INFO  - rmSimulator:354 - Correction for the past enabled. Program 1 will try to use AW from main db first.
2019-04-03 05:32:36,582 - INFO  - rmSimulator:502 - 
* Program Coefficients (pid=1, fake=True, ignoreInternetWeather=0, day=2019-04-08 00:00:00)
	- pastStart=2019-04-07 00:00:00, futureEnd=2019-04-09 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.56, historyEt0=4.56
	- et0Avg=6.01 detailsReal=[et0(m)=4.70, qpf(i)=None], detailsHistory=[et0(m)=4.56, qpf(i)=None]
	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
	    2    0.00   0.00   4.56     2.73     0.00   75.85      2.73       11.37      0.60      10         60.00

RainMachine Nicholas April 04, 2019 05:16

Is your program being set to Finish before Sunrise option ? There is currently a bug affecting these kind of programs. Also the firmware should be 4.0.975.

If it's not a program with Finish before Sunrise the only thing that I can think of is that your unit going offline ? Power fail or maybe transformer fail ? Maybe you can keep an eye over the device uptime to check if it reboots/powers off. There is a offline notification but it waits for device around 6h before sending an alert.

Bob Neal April 04, 2019 14:27

Thanks @Nicholas - my error on typing in the firmware, it is 4.0.975. No idea if it was offline. If it was, it was offline on it's own as my wifi itself didn't go offline (my Ring Alarm system immediately notifies me if it loses wifi and reports that it then is running on cellular). The logs don't show anything as being offline. I do have it set to finish before sunrise, but it's been set like that for quite some time and never had the problem. My dripline program ran the night before and it is set to finish before sunrise as well. What I did fine interesting is this entry in my log, which based on time stamp would have probably been the time when my irrigation system would have started to run.

---------------------------- PROGRAM SIMULATION LOG -----------------------------
2019-04-03 05:32:23,410 - INFO  - rmSimulator:254 - Skipping old day 1554177600 < 1554264000 from simulation.
2019-04-03 05:32:23,417 - INFO  - rmSimulator:254 - Skipping old day 1554177600 < 1554264000 from simulation.

So not sure why it shows as "simulation", it def wasn't me doing that as I was still asleep during that time and hours after that.  Perhaps that's due to the "bug".

I guess I'll keep on eye on things going forward.

How can we help?

Failed to Water - "Programs" shows it should have

Comments

Didn't find what you were looking for?