How can we help?


No watering history for 2 days, no watering

Comments

14 comments

  • Avatar
    Rick Felter

    Once again, no watering this morning when the program said it was going to do 105% on the normal schedule.  Watering history is non-existent for today.  Does anyone from support even read or respond here?

    0
    Comment actions Permalink
  • Avatar
    RainMachine Nicholas

    Support and developers :).

    From the logs I see that you have a minimum runtime for zone 2 at 300 seconds and the duration was less so it was postponing but it should show up in watering history with Minimum Restriction in red.

    We would need more logs or details on how the programs/zones are setup. Support can request you an access so it can look over.

    0
    Comment actions Permalink
  • Avatar
    RainMachine Nicholas

    Could you check what's the START DATE of your program ?

    0
    Comment actions Permalink
  • Avatar
    Rick Felter

    Start date for this program is 2019-05-26.
    Also, yes minimum runtime is 300s. But the program was scheduled to run 105% based on the weather adaptive scheduling - we’ve had higher than normal temperatures and no rain.

    0
    Comment actions Permalink
  • Avatar
    Rick Felter (Edited )

    Also - I started having this exact same issue last summer when using the “Finish By x minutes before/after” scheduling option. My logs are showing the same thing as last time as well now that I turned debug logging on — that the program is scheduled to start at some negative amount of time . Same thing happened with this mornings scheduled run. Was supposed to finish by 20 mins after sunrise which would have it finish around 5:45 am. Graph indicated today was going to run 150% and normal program length is 2h:26m:36s

     

    0
    Comment actions Permalink
  • Avatar
    Rick Felter
    2019-07-01 05:53:35,468 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:53:51,511 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:54:07,554 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:54:09,216 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:54:09,326 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:54:23,596 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:54:32,510 - DEBUG - rmParserManager:506 - Program 12 will start in -12660 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11378 lastUpdate: 1561963462
    2019-07-01 05:54:32,512 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974872 lastUpdate: 1561963462
    2019-07-01 05:54:32,514 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974872 lastUpdate: 1561963462
    2019-07-01 05:54:32,516 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:54:32,518 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561974872 (2019-07-01 05:54:32)
    2019-07-01 05:54:39,639 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:54:55,682 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:55:09,255 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:55:09,364 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:55:11,724 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:55:27,766 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:55:32,524 - DEBUG - rmParserManager:506 - Program 12 will start in -12720 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11438 lastUpdate: 1561963462
    2019-07-01 05:55:32,526 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974932 lastUpdate: 1561963462
    2019-07-01 05:55:32,528 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974932 lastUpdate: 1561963462
    2019-07-01 05:55:32,530 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:55:32,532 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561974932 (2019-07-01 05:55:32)
    2019-07-01 05:55:43,809 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:55:59,853 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:56:09,216 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:56:09,323 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:56:15,896 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:56:25,278 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 05:56:31,275 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:56:32,538 - DEBUG - rmParserManager:506 - Program 12 will start in -12780 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11498 lastUpdate: 1561963462
    2019-07-01 05:56:32,540 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974992 lastUpdate: 1561963462
    2019-07-01 05:56:32,542 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561974992 lastUpdate: 1561963462
    2019-07-01 05:56:32,544 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:56:32,546 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561974992 (2019-07-01 05:56:32)
    2019-07-01 05:56:47,309 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:57:03,343 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:57:09,258 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:57:09,371 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:57:19,378 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:57:32,552 - DEBUG - rmParserManager:506 - Program 12 will start in -12840 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11558 lastUpdate: 1561963462
    2019-07-01 05:57:32,555 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975052 lastUpdate: 1561963462
    2019-07-01 05:57:32,557 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975052 lastUpdate: 1561963462
    2019-07-01 05:57:32,559 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:57:32,561 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975052 (2019-07-01 05:57:32)
    2019-07-01 05:57:35,411 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:57:51,445 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:58:07,479 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:58:09,216 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:58:09,324 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:58:23,513 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:58:32,566 - DEBUG - rmParserManager:506 - Program 12 will start in -12900 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11618 lastUpdate: 1561963462
    2019-07-01 05:58:32,569 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975112 lastUpdate: 1561963462
    2019-07-01 05:58:32,571 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975112 lastUpdate: 1561963462
    2019-07-01 05:58:32,573 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:58:32,575 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975112 (2019-07-01 05:58:32)
    2019-07-01 05:58:39,547 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:58:55,583 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:59:09,193 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 05:59:09,302 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 05:59:11,616 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:59:27,649 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:59:32,580 - DEBUG - rmParserManager:506 - Program 12 will start in -12960 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11678 lastUpdate: 1561963462
    2019-07-01 05:59:32,583 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975172 lastUpdate: 1561963462
    2019-07-01 05:59:32,585 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975172 lastUpdate: 1561963462
    2019-07-01 05:59:32,587 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 05:59:32,589 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975172 (2019-07-01 05:59:32)
    2019-07-01 05:59:43,683 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 05:59:59,716 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:00:09,233 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:00:09,391 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:00:15,749 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:00:31,782 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:00:32,594 - DEBUG - rmParserManager:506 - Program 12 will start in -13020 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11738 lastUpdate: 1561963462
    2019-07-01 06:00:32,597 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975232 lastUpdate: 1561963462
    2019-07-01 06:00:32,599 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975232 lastUpdate: 1561963462
    2019-07-01 06:00:32,601 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:00:32,603 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975232 (2019-07-01 06:00:32)
    2019-07-01 06:00:47,815 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:01:03,848 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:01:09,232 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:01:09,341 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:01:19,881 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:01:32,609 - DEBUG - rmParserManager:506 - Program 12 will start in -13080 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11798 lastUpdate: 1561963462
    2019-07-01 06:01:32,612 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975292 lastUpdate: 1561963462
    2019-07-01 06:01:32,614 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975292 lastUpdate: 1561963462
    2019-07-01 06:01:32,616 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:01:32,618 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975292 (2019-07-01 06:01:32)
    2019-07-01 06:01:35,914 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:01:51,947 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:02:07,980 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:02:09,194 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:02:09,303 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:02:24,013 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:02:32,624 - DEBUG - rmParserManager:506 - Program 12 will start in -13140 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11858 lastUpdate: 1561963462
    2019-07-01 06:02:32,627 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975352 lastUpdate: 1561963462
    2019-07-01 06:02:32,629 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975352 lastUpdate: 1561963462
    2019-07-01 06:02:32,631 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:02:32,633 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975352 (2019-07-01 06:02:32)
    2019-07-01 06:02:40,046 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:02:56,079 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:03:09,232 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:03:09,341 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:03:12,113 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:03:28,145 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:03:32,638 - DEBUG - rmParserManager:506 - Program 12 will start in -13200 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11918 lastUpdate: 1561963462
    2019-07-01 06:03:32,641 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975412 lastUpdate: 1561963462
    2019-07-01 06:03:32,643 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975412 lastUpdate: 1561963462
    2019-07-01 06:03:32,645 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:03:32,647 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975412 (2019-07-01 06:03:32)
    2019-07-01 06:03:44,178 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:04:00,212 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:04:09,232 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:04:09,339 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:04:16,247 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:04:32,282 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:04:32,653 - DEBUG - rmParserManager:506 - Program 12 will start in -13260 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 11978 lastUpdate: 1561963462
    2019-07-01 06:04:32,655 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975472 lastUpdate: 1561963462
    2019-07-01 06:04:32,657 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975472 lastUpdate: 1561963462
    2019-07-01 06:04:32,659 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:04:32,661 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975472 (2019-07-01 06:04:32)
    2019-07-01 06:04:48,315 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:05:04,349 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:05:09,194 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:05:09,311 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:05:20,383 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:05:32,667 - DEBUG - rmParserManager:506 - Program 12 will start in -13320 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12038 lastUpdate: 1561963462
    2019-07-01 06:05:32,669 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975532 lastUpdate: 1561963462
    2019-07-01 06:05:32,671 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975532 lastUpdate: 1561963462
    2019-07-01 06:05:32,673 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:05:32,675 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975532 (2019-07-01 06:05:32)
    2019-07-01 06:05:36,417 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:05:52,450 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:06:08,484 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:06:09,193 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:06:09,302 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:06:24,518 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:06:25,722 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:06:32,681 - DEBUG - rmParserManager:506 - Program 12 will start in -13380 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12098 lastUpdate: 1561963462
    2019-07-01 06:06:32,683 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975592 lastUpdate: 1561963462
    2019-07-01 06:06:32,685 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975592 lastUpdate: 1561963462
    2019-07-01 06:06:32,687 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:06:32,689 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975592 (2019-07-01 06:06:32)
    2019-07-01 06:06:41,736 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:06:57,770 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:07:09,193 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:07:09,304 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:07:13,804 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:07:29,838 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:07:32,695 - DEBUG - rmParserManager:506 - Program 12 will start in -13440 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12158 lastUpdate: 1561963462
    2019-07-01 06:07:32,697 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975652 lastUpdate: 1561963462
    2019-07-01 06:07:32,699 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975652 lastUpdate: 1561963462
    2019-07-01 06:07:32,701 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:07:32,703 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975652 (2019-07-01 06:07:32)
    2019-07-01 06:07:45,872 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:08:01,906 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:08:09,194 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:08:09,305 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:08:17,940 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:08:32,709 - DEBUG - rmParserManager:506 - Program 12 will start in -13500 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12218 lastUpdate: 1561963462
    2019-07-01 06:08:32,711 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975712 lastUpdate: 1561963462
    2019-07-01 06:08:32,713 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975712 lastUpdate: 1561963462
    2019-07-01 06:08:32,715 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:08:32,717 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975712 (2019-07-01 06:08:32)
    2019-07-01 06:08:33,973 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:08:50,007 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:09:06,041 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:09:09,169 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:09:09,277 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:09:22,074 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:09:32,723 - DEBUG - rmParserManager:506 - Program 12 will start in -13560 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12278 lastUpdate: 1561963462
    2019-07-01 06:09:32,725 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975772 lastUpdate: 1561963462
    2019-07-01 06:09:32,728 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975772 lastUpdate: 1561963462
    2019-07-01 06:09:32,729 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:09:32,731 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975772 (2019-07-01 06:09:32)
    2019-07-01 06:09:38,108 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:09:54,142 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:10:09,170 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:10:09,286 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:10:10,176 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:10:26,210 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:10:32,737 - DEBUG - rmParserManager:506 - Program 12 will start in -13620 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12338 lastUpdate: 1561963462
    2019-07-01 06:10:32,740 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975832 lastUpdate: 1561963462
    2019-07-01 06:10:32,742 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975832 lastUpdate: 1561963462
    2019-07-01 06:10:32,744 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:10:32,746 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975832 (2019-07-01 06:10:32)
    2019-07-01 06:10:42,245 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:10:58,279 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:11:09,170 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:11:09,281 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:11:14,312 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:11:30,347 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:11:32,751 - DEBUG - rmParserManager:506 - Program 12 will start in -13680 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12398 lastUpdate: 1561963462
    2019-07-01 06:11:32,754 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975892 lastUpdate: 1561963462
    2019-07-01 06:11:32,756 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975892 lastUpdate: 1561963462
    2019-07-01 06:11:32,758 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:11:32,760 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975892 (2019-07-01 06:11:32)
    2019-07-01 06:11:46,380 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:12:02,413 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:12:09,168 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:12:09,279 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:12:18,448 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:12:32,765 - DEBUG - rmParserManager:506 - Program 12 will start in -13740 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12458 lastUpdate: 1561963462
    2019-07-01 06:12:32,768 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975952 lastUpdate: 1561963462
    2019-07-01 06:12:32,770 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561975952 lastUpdate: 1561963462
    2019-07-01 06:12:32,772 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:12:32,774 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561975952 (2019-07-01 06:12:32)
    2019-07-01 06:12:34,481 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:12:50,515 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:13:06,548 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:13:09,207 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:13:09,314 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:13:22,582 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:13:32,779 - DEBUG - rmParserManager:506 - Program 12 will start in -13800 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12518 lastUpdate: 1561963462
    2019-07-01 06:13:32,781 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976012 lastUpdate: 1561963462
    2019-07-01 06:13:32,783 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976012 lastUpdate: 1561963462
    2019-07-01 06:13:32,785 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:13:32,787 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976012 (2019-07-01 06:13:32)
    2019-07-01 06:13:38,615 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:13:54,650 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:14:09,169 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:14:09,277 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:14:10,683 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:14:26,717 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:14:32,793 - DEBUG - rmParserManager:506 - Program 12 will start in -13860 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12578 lastUpdate: 1561963462
    2019-07-01 06:14:32,795 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976072 lastUpdate: 1561963462
    2019-07-01 06:14:32,798 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976072 lastUpdate: 1561963462
    2019-07-01 06:14:32,799 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:14:32,801 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976072 (2019-07-01 06:14:32)
    2019-07-01 06:14:42,750 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:14:58,784 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:15:09,169 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:15:09,278 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:15:14,818 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:15:30,851 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:15:32,807 - DEBUG - rmParserManager:506 - Program 12 will start in -13920 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12638 lastUpdate: 1561963462
    2019-07-01 06:15:32,809 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976132 lastUpdate: 1561963462
    2019-07-01 06:15:32,811 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976132 lastUpdate: 1561963462
    2019-07-01 06:15:32,813 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:15:32,815 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976132 (2019-07-01 06:15:32)
    2019-07-01 06:15:46,884 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:16:02,920 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:16:09,170 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:16:09,282 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:16:18,953 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:16:26,269 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:16:32,821 - DEBUG - rmParserManager:506 - Program 12 will start in -13980 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12698 lastUpdate: 1561963462
    2019-07-01 06:16:32,823 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976192 lastUpdate: 1561963462
    2019-07-01 06:16:32,825 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976192 lastUpdate: 1561963462
    2019-07-01 06:16:32,827 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:16:32,829 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976192 (2019-07-01 06:16:32)
    2019-07-01 06:16:34,268 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:16:50,301 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:17:06,335 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:17:09,169 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:17:09,278 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:17:22,369 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:17:32,835 - DEBUG - rmParserManager:506 - Program 12 will start in -14040 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12758 lastUpdate: 1561963462
    2019-07-01 06:17:32,838 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976252 lastUpdate: 1561963462
    2019-07-01 06:17:32,840 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976252 lastUpdate: 1561963462
    2019-07-01 06:17:32,842 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:17:32,845 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976252 (2019-07-01 06:17:32)
    2019-07-01 06:17:38,402 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:17:54,435 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:18:09,169 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:18:09,276 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:18:10,469 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:18:26,502 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:18:32,850 - DEBUG - rmParserManager:506 - Program 12 will start in -14100 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12818 lastUpdate: 1561963462
    2019-07-01 06:18:32,853 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976312 lastUpdate: 1561963462
    2019-07-01 06:18:32,855 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976312 lastUpdate: 1561963462
    2019-07-01 06:18:32,857 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:18:32,859 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976312 (2019-07-01 06:18:32)
    2019-07-01 06:18:42,538 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:18:58,572 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:19:09,138 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:19:09,247 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:19:14,605 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:19:30,640 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:19:32,864 - DEBUG - rmParserManager:506 - Program 12 will start in -14160 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12878 lastUpdate: 1561963462
    2019-07-01 06:19:32,867 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976372 lastUpdate: 1561963462
    2019-07-01 06:19:32,869 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976372 lastUpdate: 1561963462
    2019-07-01 06:19:32,871 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:19:32,873 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976372 (2019-07-01 06:19:32)
    2019-07-01 06:19:46,673 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:20:02,706 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:20:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:20:09,250 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:20:18,740 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:20:32,878 - DEBUG - rmParserManager:506 - Program 12 will start in -14220 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12938 lastUpdate: 1561963462
    2019-07-01 06:20:32,881 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976432 lastUpdate: 1561963462
    2019-07-01 06:20:32,883 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976432 lastUpdate: 1561963462
    2019-07-01 06:20:32,885 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:20:32,887 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976432 (2019-07-01 06:20:32)
    2019-07-01 06:20:34,773 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:20:50,806 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:21:06,845 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:21:09,177 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:21:09,287 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:21:22,879 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:21:32,893 - DEBUG - rmParserManager:506 - Program 12 will start in -14280 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 12998 lastUpdate: 1561963462
    2019-07-01 06:21:32,895 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976492 lastUpdate: 1561963462
    2019-07-01 06:21:32,897 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976492 lastUpdate: 1561963462
    2019-07-01 06:21:32,903 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:21:32,905 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976492 (2019-07-01 06:21:32)
    2019-07-01 06:21:38,915 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:21:54,949 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:22:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:22:09,248 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:22:10,982 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:22:27,016 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:22:32,913 - DEBUG - rmParserManager:506 - Program 12 will start in -14340 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13058 lastUpdate: 1561963462
    2019-07-01 06:22:32,916 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976552 lastUpdate: 1561963462
    2019-07-01 06:22:32,918 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976552 lastUpdate: 1561963462
    2019-07-01 06:22:32,920 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:22:32,922 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976552 (2019-07-01 06:22:32)
    2019-07-01 06:22:43,049 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:22:59,082 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:23:09,179 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:23:09,291 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:23:15,116 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:23:31,149 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:23:32,928 - DEBUG - rmParserManager:506 - Program 12 will start in -14400 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13118 lastUpdate: 1561963462
    2019-07-01 06:23:32,930 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976612 lastUpdate: 1561963462
    2019-07-01 06:23:32,932 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976612 lastUpdate: 1561963462
    2019-07-01 06:23:32,934 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:23:32,936 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976612 (2019-07-01 06:23:32)
    2019-07-01 06:23:47,183 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:24:03,218 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:24:09,179 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:24:09,290 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:24:19,252 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:24:32,942 - DEBUG - rmParserManager:506 - Program 12 will start in -14460 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13178 lastUpdate: 1561963462
    2019-07-01 06:24:32,944 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976672 lastUpdate: 1561963462
    2019-07-01 06:24:32,947 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976672 lastUpdate: 1561963462
    2019-07-01 06:24:32,948 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:24:32,951 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976672 (2019-07-01 06:24:32)
    2019-07-01 06:24:35,288 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:24:51,321 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:25:07,357 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:25:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:25:09,250 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:25:23,391 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:25:32,956 - DEBUG - rmParserManager:506 - Program 12 will start in -14520 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13238 lastUpdate: 1561963462
    2019-07-01 06:25:32,959 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976732 lastUpdate: 1561963462
    2019-07-01 06:25:32,961 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976732 lastUpdate: 1561963462
    2019-07-01 06:25:32,963 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:25:32,965 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976732 (2019-07-01 06:25:32)
    2019-07-01 06:25:39,425 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:25:55,458 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:26:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:26:09,250 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:26:11,492 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:26:26,726 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:26:28,715 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:26:32,970 - DEBUG - rmParserManager:506 - Program 12 will start in -14580 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13298 lastUpdate: 1561963462
    2019-07-01 06:26:32,973 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976792 lastUpdate: 1561963462
    2019-07-01 06:26:32,975 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976792 lastUpdate: 1561963462
    2019-07-01 06:26:32,977 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:26:32,979 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976792 (2019-07-01 06:26:32)
    2019-07-01 06:26:44,749 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:27:00,782 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:27:09,180 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:27:09,290 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:27:16,816 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:27:32,850 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:27:32,985 - DEBUG - rmParserManager:506 - Program 12 will start in -14640 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13358 lastUpdate: 1561963462
    2019-07-01 06:27:32,987 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976852 lastUpdate: 1561963462
    2019-07-01 06:27:32,989 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976852 lastUpdate: 1561963462
    2019-07-01 06:27:32,991 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:27:32,993 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976852 (2019-07-01 06:27:32)
    2019-07-01 06:27:48,883 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:28:04,920 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:28:09,138 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:28:09,251 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:28:20,954 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:28:32,998 - DEBUG - rmParserManager:506 - Program 12 will start in -14700 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13418 lastUpdate: 1561963462
    2019-07-01 06:28:33,001 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976912 lastUpdate: 1561963462
    2019-07-01 06:28:33,003 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976912 lastUpdate: 1561963462
    2019-07-01 06:28:33,005 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:28:33,007 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976912 (2019-07-01 06:28:32)
    2019-07-01 06:28:36,988 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:28:53,022 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:29:09,055 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:29:09,165 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:29:09,273 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:29:25,089 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:29:33,013 - DEBUG - rmParserManager:506 - Program 12 will start in -14760 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13478 lastUpdate: 1561963462
    2019-07-01 06:29:33,015 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976973 lastUpdate: 1561963462
    2019-07-01 06:29:33,017 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561976973 lastUpdate: 1561963462
    2019-07-01 06:29:33,019 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:29:33,021 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561976973 (2019-07-01 06:29:33)
    2019-07-01 06:29:41,123 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:29:57,157 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:30:09,124 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:30:09,240 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:30:13,191 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:30:29,225 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:30:33,027 - DEBUG - rmParserManager:506 - Program 12 will start in -14820 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13538 lastUpdate: 1561963462
    2019-07-01 06:30:33,029 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977033 lastUpdate: 1561963462
    2019-07-01 06:30:33,031 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977033 lastUpdate: 1561963462
    2019-07-01 06:30:33,033 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:30:33,035 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977033 (2019-07-01 06:30:33)
    2019-07-01 06:30:45,260 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:31:01,294 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:31:09,124 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:31:09,233 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:31:17,328 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:31:33,041 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:31:33,047 - DEBUG - rmParserManager:506 - Program 12 will start in -14880 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13598 lastUpdate: 1561963462
    2019-07-01 06:31:33,049 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977093 lastUpdate: 1561963462
    2019-07-01 06:31:33,053 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977093 lastUpdate: 1561963462
    2019-07-01 06:31:33,055 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:31:33,057 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977093 (2019-07-01 06:31:33)
    2019-07-01 06:31:49,391 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:32:05,425 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:32:09,125 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:32:09,232 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:32:21,459 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:32:33,063 - DEBUG - rmParserManager:506 - Program 12 will start in -14940 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13658 lastUpdate: 1561963462
    2019-07-01 06:32:33,065 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977153 lastUpdate: 1561963462
    2019-07-01 06:32:33,068 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977153 lastUpdate: 1561963462
    2019-07-01 06:32:33,070 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:32:33,071 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977153 (2019-07-01 06:32:33)
    2019-07-01 06:32:37,493 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:32:53,527 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:33:09,125 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:33:09,236 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:33:09,561 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:33:25,595 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:33:33,077 - DEBUG - rmParserManager:506 - Program 12 will start in -15000 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13718 lastUpdate: 1561963462
    2019-07-01 06:33:33,080 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977213 lastUpdate: 1561963462
    2019-07-01 06:33:33,082 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977213 lastUpdate: 1561963462
    2019-07-01 06:33:33,084 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:33:33,086 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977213 (2019-07-01 06:33:33)
    2019-07-01 06:33:41,628 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:33:57,662 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:34:09,162 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:34:09,274 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:34:13,695 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:34:29,729 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:34:33,091 - DEBUG - rmParserManager:506 - Program 12 will start in -15060 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13778 lastUpdate: 1561963462
    2019-07-01 06:34:33,094 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977273 lastUpdate: 1561963462
    2019-07-01 06:34:33,096 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977273 lastUpdate: 1561963462
    2019-07-01 06:34:33,098 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:34:33,100 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977273 (2019-07-01 06:34:33)
    2019-07-01 06:34:45,763 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:35:01,796 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:35:09,125 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:35:09,235 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:35:17,829 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:35:33,105 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:35:33,110 - DEBUG - rmParserManager:506 - Program 12 will start in -15120 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13838 lastUpdate: 1561963462
    2019-07-01 06:35:33,113 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977333 lastUpdate: 1561963462
    2019-07-01 06:35:33,115 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977333 lastUpdate: 1561963462
    2019-07-01 06:35:33,116 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:35:33,118 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977333 (2019-07-01 06:35:33)
    2019-07-01 06:35:39,723 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:39,726 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:40,955 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:40,958 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:42,561 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:35:42,663 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:35:42,670 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:35:42,678 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:35:42,685 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:35:42,691 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:35:42,697 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:35:42,702 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:35:44,026 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:44,029 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:47,096 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:47,099 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:49,862 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:49,865 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:50,832 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:35:52,933 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:52,936 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:56,005 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:56,008 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:35:56,881 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:35:57,100 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:35:58,235 - DEBUG - rmMainManager:161 - checkForUpgrade
    2019-07-01 06:35:58,818 - INFO - rmMainManager:239 - Upgrade checked.
    2019-07-01 06:35:58,826 - DEBUG - rmParserManager:506 - Program 12 will start in -15120 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13838 lastUpdate: 1561963462
    2019-07-01 06:35:58,828 - DEBUG - rmParserManager:109 - Parser None not run lastRunning timestamp 1561977333 current 1561977358
    2019-07-01 06:35:59,077 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:35:59,080 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:01,847 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:36:01,850 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:04,918 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:36:04,921 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:06,166 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:36:07,066 - DEBUG - rmMainManager:161 - uploadDiagnostics
    2019-07-01 06:36:07,987 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:36:07,990 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:08,587 - DEBUG - rmDiagUpload:90 - Connected to ftp.rainmachine.com
    2019-07-01 06:36:09,126 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:36:09,236 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:36:09,577 - DEBUG - rmDiagUpload:94 - Uploading file: log/rainmachine.log
    2019-07-01 06:36:11,059 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 55154)
    2019-07-01 06:36:11,062 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:11,621 - DEBUG - rmDiagUpload:94 - Uploading file: log/rainmachine.log.1.gz
    2019-07-01 06:36:13,322 - DEBUG - rmDiagUpload:94 - Uploading file: /tmp/simulator-volatile.log
    2019-07-01 06:36:14,956 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-doy.sqlite
    2019-07-01 06:36:15,822 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-main.sqlite
    2019-07-01 06:36:17,295 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-mixer.sqlite
    2019-07-01 06:36:18,335 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-parser.sqlite
    2019-07-01 06:36:19,517 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-settings.sqlite
    2019-07-01 06:36:20,579 - DEBUG - rmDiagUpload:94 - Uploading file: rainmachine-simulator.sqlite
    2019-07-01 06:36:21,544 - DEBUG - rmDiagUpload:94 - Uploading file: /rainmachine-cloud/cloud-client.log
    2019-07-01 06:36:22,201 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:36:23,607 - DEBUG - rmParserManager:506 - Program 12 will start in -15180 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13898 lastUpdate: 1561963462
    2019-07-01 06:36:23,609 - DEBUG - rmParserManager:109 - Parser None not run lastRunning timestamp 1561977333 current 1561977383
    2019-07-01 06:36:27,270 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:36:39,276 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:36:39,630 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:39,633 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:41,781 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:41,784 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:43,770 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:36:43,882 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:36:43,889 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:36:43,895 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:36:43,904 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:36:43,909 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:36:43,916 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:36:43,920 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:36:45,161 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:45,164 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:47,617 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:47,620 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:50,689 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:50,693 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:53,762 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:53,764 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:56,045 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:36:58,984 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:58,987 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:36:59,599 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:36:59,601 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:37:02,671 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:37:02,673 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:37:09,125 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:37:09,236 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:37:13,298 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:37:23,615 - DEBUG - rmParserManager:506 - Program 12 will start in -15240 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13958 lastUpdate: 1561963462
    2019-07-01 06:37:23,617 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977443 lastUpdate: 1561963462
    2019-07-01 06:37:23,619 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977443 lastUpdate: 1561963462
    2019-07-01 06:37:23,621 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:37:23,623 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977443 (2019-07-01 06:37:23)
    2019-07-01 06:37:29,332 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:37:34,347 - DEBUG - rmAuth:230 - Password: 848294c55b977583dedccdfd64e7d091512e342afea95c1712e947fa797a5d136ab91b39a23ce0c04a879d4053489178
    2019-07-01 06:37:34,349 - DEBUG - rmAuth:231 - Mangle: b4891784fa797a1dccdfdcc55b970b91b39f91512ed7583de2a23ce0e64e7d06c04a87384829455d136aa12e9478342afe79d40539a95c17
    2019-07-01 06:37:34,351 - DEBUG - rmHTTPResourceApi4:51 - 75a2e4d748c3c895872e46b66db17474ef304cdb65893dca37b3ac5a; Path=/api/4; Expires=Sat, 29 Jun 2024 10:37:34 GMT
    2019-07-01 06:37:34,547 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:37:34,656 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:37:35,732 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:37:36,477 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:37:36,483 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:37:36,489 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:37:36,496 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:37:36,501 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:37:36,507 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:37:36,512 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:37:45,527 - DEBUG - rmHTTPResourceApi4Watering:143 - watering/past Start 1559448000 End 1562040000
    2019-07-01 06:37:47,054 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:37:48,796 - DEBUG - rmMainManager:161 - checkForUpgrade
    2019-07-01 06:37:49,418 - INFO - rmMainManager:239 - Upgrade checked.
    2019-07-01 06:37:49,424 - DEBUG - rmParserManager:506 - Program 12 will start in -15240 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 13958 lastUpdate: 1561963462
    2019-07-01 06:37:49,426 - DEBUG - rmParserManager:109 - Parser None not run lastRunning timestamp 1561977443 current 1561977469
    2019-07-01 06:38:03,103 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:38:09,170 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:38:09,278 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:38:19,138 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:38:35,172 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:38:49,432 - DEBUG - rmParserManager:506 - Program 12 will start in -15300 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14018 lastUpdate: 1561963462
    2019-07-01 06:38:49,435 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977529 lastUpdate: 1561963462
    2019-07-01 06:38:49,437 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977529 lastUpdate: 1561963462
    2019-07-01 06:38:49,439 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:38:49,441 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977529 (2019-07-01 06:38:49)
    2019-07-01 06:38:51,207 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:39:07,241 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:39:09,137 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:39:09,244 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:39:23,275 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:39:39,309 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:39:49,447 - DEBUG - rmParserManager:506 - Program 12 will start in -15360 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14078 lastUpdate: 1561963462
    2019-07-01 06:39:49,449 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977589 lastUpdate: 1561963462
    2019-07-01 06:39:49,451 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977589 lastUpdate: 1561963462
    2019-07-01 06:39:49,453 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:39:49,455 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977589 (2019-07-01 06:39:49)
    2019-07-01 06:39:55,342 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:40:09,098 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:40:09,211 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:40:11,377 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:40:27,412 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:40:43,447 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:40:49,462 - DEBUG - rmParserManager:506 - Program 12 will start in -15420 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14138 lastUpdate: 1561963462
    2019-07-01 06:40:49,465 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977649 lastUpdate: 1561963462
    2019-07-01 06:40:49,471 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977649 lastUpdate: 1561963462
    2019-07-01 06:40:49,473 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:40:49,475 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977649 (2019-07-01 06:40:49)
    2019-07-01 06:40:59,487 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:41:09,100 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:41:09,208 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:41:15,522 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:41:31,556 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:41:47,591 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:41:49,481 - DEBUG - rmParserManager:506 - Program 12 will start in -15480 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14198 lastUpdate: 1561963462
    2019-07-01 06:41:49,484 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977709 lastUpdate: 1561963462
    2019-07-01 06:41:49,486 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977709 lastUpdate: 1561963462
    2019-07-01 06:41:49,488 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:41:49,490 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977709 (2019-07-01 06:41:49)
    2019-07-01 06:42:03,625 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:42:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:42:09,247 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:42:19,659 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:42:35,693 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:42:49,496 - DEBUG - rmParserManager:506 - Program 12 will start in -15540 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14258 lastUpdate: 1561963462
    2019-07-01 06:42:49,498 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977769 lastUpdate: 1561963462
    2019-07-01 06:42:49,501 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977769 lastUpdate: 1561963462
    2019-07-01 06:42:49,502 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:42:49,504 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977769 (2019-07-01 06:42:49)
    2019-07-01 06:42:51,727 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:43:06,081 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:06,084 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:06,342 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:43:06,458 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,465 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,471 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,478 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,483 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,492 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,497 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:43:06,778 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,784 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,790 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,804 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,810 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:43:06,817 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:43:06,821 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:43:08,382 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:43:08,538 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:08,541 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:09,137 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:43:09,247 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:43:11,610 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:11,613 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:14,682 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:14,685 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:17,754 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:17,757 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:20,520 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:20,523 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:23,592 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:23,595 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:24,422 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:43:26,663 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:26,666 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:29,735 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:29,738 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:32,504 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:32,507 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:35,573 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:35,576 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:38,644 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:38,647 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:38,954 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:43:40,942 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:43:41,718 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:41,721 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:44,687 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:44,690 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:47,554 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:47,557 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:49,510 - DEBUG - rmParserManager:506 - Program 12 will start in -15600 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14318 lastUpdate: 1561963462
    2019-07-01 06:43:49,513 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977829 lastUpdate: 1561963462
    2019-07-01 06:43:49,515 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977829 lastUpdate: 1561963462
    2019-07-01 06:43:49,517 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:43:49,519 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977829 (2019-07-01 06:43:49)
    2019-07-01 06:43:50,626 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:50,629 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:53,698 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:53,701 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:56,770 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:56,773 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:43:57,242 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:43:59,539 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:43:59,542 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:03,846 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:03,849 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:05,679 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:05,682 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:08,752 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:08,754 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:09,138 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:44:09,247 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:44:10,423 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:44:11,518 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:11,521 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:14,414 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:44:14,592 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:14,595 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:17,662 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:17,665 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:20,736 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:20,738 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:23,805 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:23,808 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:26,570 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:26,573 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:29,512 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:29,515 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:31,719 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:44:32,714 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:32,717 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:35,787 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:35,789 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:38,551 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:38,554 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:40,242 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:44:41,625 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:41,627 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:44,696 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:44,699 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:47,768 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:47,771 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:48,241 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:44:49,525 - DEBUG - rmParserManager:506 - Program 12 will start in -15660 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14378 lastUpdate: 1561963462
    2019-07-01 06:44:49,527 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977889 lastUpdate: 1561963462
    2019-07-01 06:44:49,529 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977889 lastUpdate: 1561963462
    2019-07-01 06:44:49,531 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:44:49,533 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977889 (2019-07-01 06:44:49)
    2019-07-01 06:44:50,535 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:50,538 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:53,605 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:53,608 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:56,677 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:56,680 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:44:59,751 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:44:59,754 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:02,514 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:02,517 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:04,792 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:45:05,586 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:05,589 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:08,658 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:08,661 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:09,139 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:45:09,244 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:45:10,462 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:45:11,731 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:11,734 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:14,501 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:14,504 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:17,569 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:17,572 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:20,635 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:45:20,676 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:45:20,679 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:45:27,120 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:45:36,018 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:45:48,084 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:45:49,539 - DEBUG - rmParserManager:506 - Program 12 will start in -15720 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14438 lastUpdate: 1561963462
    2019-07-01 06:45:49,541 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977949 lastUpdate: 1561963462
    2019-07-01 06:45:49,543 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561977949 lastUpdate: 1561963462
    2019-07-01 06:45:49,545 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:45:49,547 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561977949 (2019-07-01 06:45:49)
    2019-07-01 06:45:53,511 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:46:06,399 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, uid:12, end_date:-1, start_time:-5040, updated:-1, active:True, sd_m:5, durationRefresh:15, type:1, sd_d:26, freq_modified:0, lastDuration:13194, simulationExpired:False, sd_y:2019, lastDurationTimestamp:1561977953, st_m:23, st_h:2, name:F Lawn & Rear R Shrubs, zoneLines:{1: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:1, duration:0, order:1, 2: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:2, duration:0, order:2, 3: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:3, duration:0, order:3, 4: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:5, duration:0, order:5, 6: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:6, duration:0, order:6, 7: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:7, duration:0, order:7, 8: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:8, duration:0, order:8, 9: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, futureField1:0, delay_on:False, useWaterSense:0, param:2, delay:0, cs_min:0, recurring:True, start_date:1558843200, cs_on:False
    2019-07-01 06:46:07,254 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:46:09,096 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:46:09,414 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:46:09,519 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:46:10,980 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:10,987 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:11,002 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:11,009 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:11,018 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:11,025 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:11,030 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:46:25,541 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:46:26,693 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:26,696 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:26,699 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:26,717 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:26,886 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:46:27,024 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:27,030 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:27,036 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:27,043 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:27,049 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:46:27,055 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:46:27,059 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:46:29,776 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:29,786 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:32,537 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:32,540 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:35,600 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:35,603 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:38,672 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:38,676 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:41,666 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:46:41,745 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:41,747 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:49,425 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:49,428 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:49,553 - DEBUG - rmParserManager:506 - Program 12 will start in -15780 seconds(2019-07-01 02:23:00) deltaLastParsersRun: 14498 lastUpdate: 1561963462
    2019-07-01 06:46:49,555 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978009 lastUpdate: 1561963462
    2019-07-01 06:46:49,557 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978009 lastUpdate: 1561963462
    2019-07-01 06:46:49,559 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:46:49,561 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561978009 (2019-07-01 06:46:49)
    2019-07-01 06:46:50,654 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:50,657 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:53,726 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:53,729 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:56,798 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:56,801 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:46:57,866 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:46:58,420 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:46:58,541 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, lastDurationTimestamp:1561978017, end_date:-1, start_time:648, updated:-1, active:True, sd_m:5, durationRefresh:15, sd_d:26, freq_modified:0, lastDuration:13194, simulationExpired:False, sd_y:2019, uid:12, st_m:48, st_h:6, name:F Lawn & Rear R Shrubs, zoneLines:{1: userPercentage:1.0, coef:1.5, flag:0, calc_wd:0, active:True, zid:1, duration:0, order:1, 2: userPercentage:1.0, coef:1.5, flag:0, calc_wd:0, active:True, zid:2, duration:0, order:2, 3: userPercentage:1.0, coef:1.5, flag:0, calc_wd:0, active:True, zid:3, duration:0, order:3, 4: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:5, duration:0, order:5, 6: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:6, duration:0, order:6, 7: userPercentage:1.0, coef:1.5, flag:0, calc_wd:0, active:True, zid:7, duration:0, order:7, 8: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:8, duration:0, order:8, 9: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, futureField1:0, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, type:1, start_date:1558843200, cs_on:False
    2019-07-01 06:46:59,563 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:46:59,566 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:00,909 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, lastDurationTimestamp:1561978017, end_date:-1, start_time:648, updated:-1, active:True, sd_m:5, durationRefresh:15, type:1, sd_d:26, freq_modified:0, futureField1:0, simulationExpired:False, sd_y:2019, uid:12, st_m:48, st_h:6, name:F Lawn & Rear R Shrubs, zoneLines:{1: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:1, duration:0, order:1, 2: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:2, duration:0, order:2, 3: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:3, duration:0, order:3, 4: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:5, duration:0, order:5, 6: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:6, duration:0, order:6, 7: coef:1.5, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:7, duration:0, order:7, 8: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:8, duration:0, order:8, 9: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, lastDuration:13194, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, start_date:1558843200, cs_on:False
    2019-07-01 06:47:02,636 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:02,639 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:05,707 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:05,710 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:08,779 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:08,782 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:09,137 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:47:09,245 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:47:11,544 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:11,547 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:14,618 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:14,620 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:17,689 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:17,692 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:20,761 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:20,764 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:23,527 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:23,530 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:26,600 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:26,603 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:28,365 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:47:30,593 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:30,596 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:32,746 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:32,749 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:35,814 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:35,817 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:38,581 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:38,583 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:41,058 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:41,061 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:43,918 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:43,925 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:43,931 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:43,940 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:43,946 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:43,952 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:43,957 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:47:44,622 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 59164)
    2019-07-01 06:47:44,625 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:49,566 - DEBUG - rmParserManager:506 - Program 12 will start in 60 seconds(2019-07-01 06:48:00) deltaLastParsersRun: 14558 lastUpdate: 1561963462
    2019-07-01 06:47:49,568 - INFO - rmParserManager:509 - ** Forcing parsers run because program 12 will start in 60 seconds
    2019-07-01 06:47:49,570 - DEBUG - rmParserManager:158 - * Running parser NOAA Parser with interval 21600
    2019-07-01 06:47:49,657 - INFO - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
    2019-07-01 06:47:49,661 - DEBUG - rmParser:118 - Parser 'NOAA Parser': downloading from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php?lat=40.8436211&lon=-73.0533421&product=time-series&begin=2019-07-01&Unit=e&maxt=maxt&mint=mint&temp=temp&qpf=qpf&dew=dew&pop12=pop12&wspd=wspd&rh=rh&maxrh=maxrh&minrh=minrh
    2019-07-01 06:47:50,256 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:47:50,258 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:50,394 - DEBUG - noaa-parser:381 - precipitation liquid: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,396 - DEBUG - noaa-parser:400 - precipitationliquid Added interval head 2019-07-01 02:00:00 cache with value: 0.00
    2019-07-01 06:47:50,399 - DEBUG - noaa-parser:400 - precipitationliquid Added interval head 2019-07-01 08:00:00 cache with value: 0.00
    2019-07-01 06:47:50,401 - DEBUG - noaa-parser:400 - precipitationliquid Added interval head 2019-07-01 14:00:00 cache with value: 0.00
    2019-07-01 06:47:50,403 - DEBUG - noaa-parser:400 - precipitationliquid Added interval head 2019-07-01 20:00:00 cache with value: 0.00
    2019-07-01 06:47:50,406 - DEBUG - noaa-parser:381 - precipitation liquid: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,409 - DEBUG - noaa-parser:381 - precipitation liquid: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,426 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,429 - DEBUG - noaa-parser:400 - temperaturemaximum Added interval head 2019-07-01 08:00:00 cache with value: 83
    2019-07-01 06:47:50,431 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,433 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,436 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,438 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,441 - DEBUG - noaa-parser:381 - temperature maximum: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,443 - DEBUG - noaa-parser:374 - temperature maximum: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,461 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,463 - DEBUG - noaa-parser:400 - temperatureminimum Added interval head 2019-07-01 09:00:00 cache with value: 63
    2019-07-01 06:47:50,466 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,468 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,470 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,473 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,475 - DEBUG - noaa-parser:381 - temperature minimum: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,477 - DEBUG - noaa-parser:374 - temperature minimum: reject date 2019-07-07 09:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,509 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,511 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 08:00:00 cache with value: 69
    2019-07-01 06:47:50,514 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 11:00:00 cache with value: 76
    2019-07-01 06:47:50,516 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 14:00:00 cache with value: 80
    2019-07-01 06:47:50,518 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 17:00:00 cache with value: 81
    2019-07-01 06:47:50,520 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 20:00:00 cache with value: 76
    2019-07-01 06:47:50,522 - DEBUG - noaa-parser:400 - temperaturehourly Added interval head 2019-07-01 23:00:00 cache with value: 72
    2019-07-01 06:47:50,524 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,528 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,532 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,534 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,537 - DEBUG - noaa-parser:381 - temperature hourly: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,540 - DEBUG - noaa-parser:374 - temperature hourly: reject date 2019-07-07 02:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,542 - DEBUG - noaa-parser:374 - temperature hourly: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,545 - DEBUG - noaa-parser:374 - temperature hourly: reject date 2019-07-07 14:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,547 - DEBUG - noaa-parser:374 - temperature hourly: reject date 2019-07-07 20:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,549 - INFO - noaa-parser:418 - Adding from Cache: temperaturehourly: 62 for 2019-07-01 05:00:00
    2019-07-01 06:47:50,585 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,588 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 08:00:00 cache with value: 59
    2019-07-01 06:47:50,590 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 11:00:00 cache with value: 57
    2019-07-01 06:47:50,592 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 14:00:00 cache with value: 56
    2019-07-01 06:47:50,595 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 17:00:00 cache with value: 58
    2019-07-01 06:47:50,597 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 20:00:00 cache with value: 59
    2019-07-01 06:47:50,599 - DEBUG - noaa-parser:400 - temperaturedew point Added interval head 2019-07-01 23:00:00 cache with value: 64
    2019-07-01 06:47:50,601 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,605 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,608 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,611 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,614 - DEBUG - noaa-parser:381 - temperature dew point: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,617 - DEBUG - noaa-parser:374 - temperature dew point: reject date 2019-07-07 02:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,619 - DEBUG - noaa-parser:374 - temperature dew point: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,622 - DEBUG - noaa-parser:374 - temperature dew point: reject date 2019-07-07 14:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,624 - DEBUG - noaa-parser:374 - temperature dew point: reject date 2019-07-07 20:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,626 - INFO - noaa-parser:418 - Adding from Cache: temperaturedew point: 58 for 2019-07-01 05:00:00
    2019-07-01 06:47:50,658 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,660 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 08:00:00 cache with value: 8
    2019-07-01 06:47:50,663 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 11:00:00 cache with value: 7
    2019-07-01 06:47:50,665 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 14:00:00 cache with value: 6
    2019-07-01 06:47:50,668 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 17:00:00 cache with value: 7
    2019-07-01 06:47:50,670 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 20:00:00 cache with value: 8
    2019-07-01 06:47:50,672 - DEBUG - noaa-parser:400 - wind-speedsustained Added interval head 2019-07-01 23:00:00 cache with value: 7
    2019-07-01 06:47:50,674 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,678 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,681 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,684 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,687 - DEBUG - noaa-parser:381 - wind-speed sustained: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,690 - DEBUG - noaa-parser:374 - wind-speed sustained: reject date 2019-07-07 02:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,692 - DEBUG - noaa-parser:374 - wind-speed sustained: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,694 - DEBUG - noaa-parser:374 - wind-speed sustained: reject date 2019-07-07 14:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,697 - DEBUG - noaa-parser:374 - wind-speed sustained: reject date 2019-07-07 20:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,699 - INFO - noaa-parser:418 - Adding from Cache: wind-speedsustained: 8 for 2019-07-01 05:00:00
    2019-07-01 06:47:50,720 - INFO - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2019-06-30 20:00:00 as it's in the past
    2019-07-01 06:47:50,722 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,724 - DEBUG - noaa-parser:400 - probability-of-precipitation12 hour Added interval head 2019-07-01 08:00:00 cache with value: 10
    2019-07-01 06:47:50,727 - DEBUG - noaa-parser:400 - probability-of-precipitation12 hour Added interval head 2019-07-01 20:00:00 cache with value: 18
    2019-07-01 06:47:50,729 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,732 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,734 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,736 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,739 - DEBUG - noaa-parser:381 - probability-of-precipitation 12 hour: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,741 - DEBUG - noaa-parser:374 - probability-of-precipitation 12 hour: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,775 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,777 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 08:00:00 cache with value: 70
    2019-07-01 06:47:50,780 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 11:00:00 cache with value: 52
    2019-07-01 06:47:50,782 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 14:00:00 cache with value: 44
    2019-07-01 06:47:50,784 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 17:00:00 cache with value: 45
    2019-07-01 06:47:50,787 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 20:00:00 cache with value: 55
    2019-07-01 06:47:50,789 - DEBUG - noaa-parser:400 - humidityrelative Added interval head 2019-07-01 23:00:00 cache with value: 76
    2019-07-01 06:47:50,791 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,794 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,798 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,801 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,804 - DEBUG - noaa-parser:381 - humidity relative: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,807 - DEBUG - noaa-parser:374 - humidity relative: reject date 2019-07-07 02:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,809 - DEBUG - noaa-parser:374 - humidity relative: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,811 - DEBUG - noaa-parser:374 - humidity relative: reject date 2019-07-07 14:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,813 - DEBUG - noaa-parser:374 - humidity relative: reject date 2019-07-07 20:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,815 - INFO - noaa-parser:418 - Adding from Cache: humidityrelative: 86 for 2019-07-01 05:00:00
    2019-07-01 06:47:50,834 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,836 - DEBUG - noaa-parser:400 - humidityminimum relative Added interval head 2019-07-01 20:00:00 cache with value: 42
    2019-07-01 06:47:50,839 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,841 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,843 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,846 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,848 - DEBUG - noaa-parser:381 - humidity minimum relative: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,850 - DEBUG - noaa-parser:374 - humidity minimum relative: reject date 2019-07-07 20:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,871 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:50,873 - DEBUG - noaa-parser:400 - humiditymaximum relative Added interval head 2019-07-01 08:00:00 cache with value: 86
    2019-07-01 06:47:50,876 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:50,878 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:50,880 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:50,883 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:50,885 - DEBUG - noaa-parser:381 - humidity maximum relative: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:50,887 - DEBUG - noaa-parser:374 - humidity maximum relative: reject date 2019-07-07 08:00:00 as it's over the max parser day: 2019-07-07 00:00:00
    2019-07-01 06:47:50,907 - INFO - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php
    2019-07-01 06:47:50,910 - DEBUG - rmParser:118 - Parser 'NOAA Parser': downloading from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php?lat=40.8436211&lon=-73.0533421&startDate=2019-07-01&format=24+hourly&numDays=6&Unit=e
    2019-07-01 06:47:51,225 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-01 00:00:00 - 2019-07-01 00:00:00
    2019-07-01 06:47:51,227 - DEBUG - noaa-parser:400 - conditions-iconforecast-NWS Added interval head 2019-07-01 06:00:00 cache with value: http://www.nws.noaa.gov/weather/images/fcicons/few.jpg
    2019-07-01 06:47:51,230 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-02 00:00:00 - 2019-07-02 00:00:00
    2019-07-01 06:47:51,232 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-03 00:00:00 - 2019-07-03 00:00:00
    2019-07-01 06:47:51,235 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-04 00:00:00 - 2019-07-04 00:00:00
    2019-07-01 06:47:51,237 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-05 00:00:00 - 2019-07-05 00:00:00
    2019-07-01 06:47:51,239 - DEBUG - noaa-parser:381 - conditions-icon forecast-NWS: found new day: 2019-07-06 00:00:00 - 2019-07-06 00:00:00
    2019-07-01 06:47:51,483 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:47:51,485 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:51,905 - DEBUG - rmParserManager:158 - * Running parser WUnderground Parser with interval 21600
    2019-07-01 06:47:51,990 - DEBUG - rmParser:118 - Parser 'WUnderground Parser': downloading from https://api.weather.com/v3/location/near?geocode=40.8436211,-73.0533421&product=pws&format=json&apiKey=cd2dfac7d9e14f69adfac7d9e19f69ca
    2019-07-01 06:47:52,530 - DEBUG - rmParser:118 - Parser 'WUnderground Parser': downloading from https://api.weather.com/v3/location/near?geocode=40.8436211,-73.0533421&product=airport&format=json&apiKey=cd2dfac7d9e14f69adfac7d9e19f69ca
    2019-07-01 06:47:52,810 - DEBUG - rmParser:118 - Parser 'WUnderground Parser': downloading from https://api.weather.com/v3/wx/forecast/daily/5day?geocode=40.8436211,-73.0533421&language=en-US&units=m&format=json&apiKey=cd2dfac7d9e14f69adfac7d9e19f69ca
    2019-07-01 06:47:53,102 - DEBUG - rmParser:118 - Parser 'WUnderground Parser': downloading from https://api.weather.com/v2/pws/dailysummary/7day?stationId=KNYFARMI10&format=json&units=m&apiKey=cd2dfac7d9e14f69adfac7d9e19f69ca
    2019-07-01 06:47:53,401 - INFO - wunderground-parser:87 - WUnderground: station data retrieved for KNYFARMI10
    2019-07-01 06:47:53,403 - INFO - wunderground-parser:95 - WUnderground: forecast data retrieved.
    2019-07-01 06:47:53,492 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:47:53,708 - DEBUG - rmMixer:53 - * RUN MIXER (forced = False)
    2019-07-01 06:47:53,771 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:53,781 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:53,793 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:53,804 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:53,819 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:47:53,832 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:47:53,843 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:47:54,002 - INFO - rmMixer:123 - *** DATA IN THE PAST: 2019-06-30 00:00:00
    2019-07-01 06:47:54,005 - INFO - rmMixer:124 - *** Received data: ('2019-06-30 00:00:00', temp=23/1, minTemp=None/0, maxTemp=None/0, rh=74/1, minRh=None/0, maxRh=None/0, wind=1.3899999999999999/1, solarRad=None/0, skyCover=None/0, rain=9.1400000000000006/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.81/1, dewPoint=18/1, userData=None, minTemp=16/0, maxTemp=28/0, minRH=51/0, maxRH=96/0, et0cal=None, et0final=None)
    2019-07-01 06:47:54,017 - INFO - rmMixer:131 - *** Added Existing data: '2019-06-30 00:00:00', temp=23.329999999999998/1, minTemp=None/0, maxTemp=None/0, rh=69/1, minRh=None/0, maxRh=None/0, wind=1.03/1, solarRad=None/0, skyCover=None/0, rain=9.1400000000000006/1, et0=None/0, pop=26/1, qpf=0.45000000000000001/1, condition=12/1, pressure=100.81/1, dewPoint=17.02/1, userData=None, minTemp=17.23/1, maxTemp=28.170000000000002/1, minRH=48/1, maxRH=88/1, et0cal=5.4449451809109366, et0final=5.4400000000000004
    2019-07-01 06:47:54,020 - INFO - rmMixer:132 - *** All available Data: [('2019-06-30 00:00:00', temp=23/1, minTemp=None/0, maxTemp=None/0, rh=74/1, minRh=None/0, maxRh=None/0, wind=1.3899999999999999/1, solarRad=None/0, skyCover=None/0, rain=9.1400000000000006/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=100.81/1, dewPoint=18/1, userData=None, minTemp=16/0, maxTemp=28/0, minRH=51/0, maxRH=96/0, et0cal=None, et0final=None), ('2019-06-30 00:00:00', temp=23.329999999999998/1, minTemp=None/0, maxTemp=None/0, rh=69/1, minRh=None/0, maxRh=None/0, wind=1.03/1, solarRad=None/0, skyCover=None/0, rain=9.1400000000000006/1, et0=None/0, pop=26/1, qpf=0.45000000000000001/1, condition=12/1, pressure=100.81/1, dewPoint=17.02/1, userData=None, minTemp=17.23/1, maxTemp=28.170000000000002/1, minRH=48/1, maxRH=88/1, et0cal=5.4449451809109366, et0final=5.4400000000000004)]
    2019-07-01 06:47:54,026 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-01 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 1, fTMinC: 18.34, fTMaxC: 28.67, fU2z: 3.75, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 47.0, fRHMax: 79.0, fPressure: None, fKrs: 0.19, fTDewpointC: 14.84]
    2019-07-01 06:47:54,028 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-01 00:00:00: et0calc=5.5603388869231862
    2019-07-01 06:47:54,031 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-01 00:00:00, et0Calc=5.5603388869231862, et0Final=5.5599999999999996, qpf=0.0
    2019-07-01 06:47:54,034 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-02 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 2, fTMinC: 20.78, fTMaxC: 30.22, fU2z: 3.56, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 52.0, fRHMax: 81.0, fPressure: None, fKrs: 0.19, fTDewpointC: 17.99]
    2019-07-01 06:47:54,037 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-02 00:00:00: et0calc=5.5345070901814175
    2019-07-01 06:47:54,039 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-02 00:00:00, et0Calc=5.5345070901814175, et0Final=5.5300000000000002, qpf=0.88
    2019-07-01 06:47:54,043 - DEBUG - rmMixer:217 - Computing ET0 for 2019-06-30 00:00:00 with params:
    * input=[day: 2019, month: 6, day: 30, fTMinC: 16.62, fTMaxC: 28.09, fU2z: 1.21, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 49.0, fRHMax: 92.0, fPressure: 100.81, fKrs: 0.19, fTDewpointC: 17.51]
    2019-07-01 06:47:54,046 - DEBUG - rmMixer:244 - ET0 was computed for 2019-06-30 00:00:00: et0calc=5.4308774926013683
    2019-07-01 06:47:54,048 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-06-30 00:00:00, et0Calc=5.4308774926013683, et0Final=5.4299999999999997, qpf=0.45000000000000001
    2019-07-01 06:47:54,052 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-03 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 3, fTMinC: 21.06, fTMaxC: 29.72, fU2z: 2.23, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 57.0, fRHMax: 87.0, fPressure: None, fKrs: 0.19, fTDewpointC: 19.13]
    2019-07-01 06:47:54,054 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-03 00:00:00: et0calc=5.1677114464260105
    2019-07-01 06:47:54,056 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-03 00:00:00, et0Calc=5.1677114464260105, et0Final=5.1699999999999999, qpf=0.0
    2019-07-01 06:47:54,060 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-06 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 6, fTMinC: 22.11, fTMaxC: 29.22, fU2z: 3.46, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 71.0, fRHMax: 93.0, fPressure: None, fKrs: 0.19, fTDewpointC: 22.22]
    2019-07-01 06:47:54,062 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-06 00:00:00: et0calc=4.4482660325327412
    2019-07-01 06:47:54,064 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-06 00:00:00, et0Calc=4.4482660325327412, et0Final=4.4500000000000002, qpf=4.9400000000000004
    2019-07-01 06:47:54,068 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-05 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 5, fTMinC: 21.56, fTMaxC: 28.45, fU2z: 4.13, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 73.0, fRHMax: 95.0, fPressure: None, fKrs: 0.19, fTDewpointC: 21.95]
    2019-07-01 06:47:54,070 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-05 00:00:00: et0calc=4.2529699732994786
    2019-07-01 06:47:54,073 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-05 00:00:00, et0Calc=4.2529699732994786, et0Final=4.25, qpf=0.90000000000000002
    2019-07-01 06:47:54,076 - DEBUG - rmMixer:217 - Computing ET0 for 2019-07-04 00:00:00 with params:
    * input=[day: 2019, month: 7, day: 4, fTMinC: 21.06, fTMaxC: 29.22, fU2z: 3.06, fU2m: None, fLat: 40.8436211, fElevation: 54.1912727356, fRs: None, fEa: None, fRHMin: 63.0, fRHMax: 90.0, fPressure: None, fKrs: 0.19, fTDewpointC: 20.56]
    2019-07-01 06:47:54,079 - DEBUG - rmMixer:244 - ET0 was computed for 2019-07-04 00:00:00: et0calc=4.8525568531586156
    2019-07-01 06:47:54,081 - DEBUG - rmMixer:257 - *** Mixer result: day=2019-07-04 00:00:00, et0Calc=4.8525568531586156, et0Final=4.8499999999999996, qpf=0.0
    2019-07-01 06:47:54,232 - DEBUG - rmParserManager:223 - *** END Running parsers: 4266, 1561978069 (2019-07-01 06:47:49)
    2019-07-01 06:47:54,236 - DEBUG - rmRestrictions:363 - Setting qpf 0.45, min temp 16.62 for day: 1561867200(2019-06-30 00:00:00)
    2019-07-01 06:47:54,239 - DEBUG - rmRestrictions:363 - Setting qpf 0.0, min temp 18.34 for day: 1561953600(2019-07-01 00:00:00)
    2019-07-01 06:47:54,242 - DEBUG - rmRestrictions:363 - Setting qpf 0.88, min temp 20.78 for day: 1562040000(2019-07-02 00:00:00)
    2019-07-01 06:47:54,244 - DEBUG - rmRestrictions:363 - Setting qpf 0.0, min temp 21.06 for day: 1562126400(2019-07-03 00:00:00)
    2019-07-01 06:47:54,248 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:47:54,257 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:47:54,261 - DEBUG - rmRestrictions:363 - Setting qpf 0.0, min temp 21.06 for day: 1562212800(2019-07-04 00:00:00)
    2019-07-01 06:47:54,264 - DEBUG - rmRestrictions:363 - Setting qpf 0.9, min temp 21.56 for day: 1562299200(2019-07-05 00:00:00)
    2019-07-01 06:47:54,271 - DEBUG - rmRestrictions:363 - Setting qpf 4.94, min temp 22.11 for day: 1562385600(2019-07-06 00:00:00)
    2019-07-01 06:47:54,274 - DEBUG - rmSimulator:204 - *** BEGIN Running simulator for forecast: 42661561978069 / 2019-07-01 06:47:49
    2019-07-01 06:47:54,288 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1561867200 / 2019-06-30 00:00:00
    2019-07-01 06:47:54,304 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1561867200 / 2019-06-30 00:00:00
    2019-07-01 06:47:54,306 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1561953600 / 2019-07-01 00:00:00
    2019-07-01 06:47:54,443 - INFO - rmSimulator:605 - * Correction: Zone 1 et0Delta=0.89, qpfDelta=1.52, rain=9.14, realPrecip=9.14, qpfUsedLimited=7.62
    2019-07-01 06:47:54,452 - INFO - rmSimulator:605 - * Correction: Zone 2 et0Delta=0.89, qpfDelta=1.52, rain=9.14, realPrecip=9.14, qpfUsedLimited=7.62
    2019-07-01 06:47:54,465 - INFO - rmSimulator:605 - * Correction: Zone 3 et0Delta=0.89, qpfDelta=1.52, rain=9.14, realPrecip=9.14, qpfUsedLimited=7.62
    2019-07-01 06:47:54,483 - INFO - rmSimulator:605 - * Correction: Zone 7 et0Delta=0.89, qpfDelta=1.52, rain=9.14, realPrecip=9.14, qpfUsedLimited=7.62
    2019-07-01 06:47:54,676 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=6.5759500359 minRuntime=300
    2019-07-01 06:47:54,783 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1561953600 / 2019-07-01 00:00:00
    2019-07-01 06:47:54,785 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1562040000 / 2019-07-02 00:00:00
    2019-07-01 06:47:55,066 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=7.52546482132 minRuntime=300
    2019-07-01 06:47:55,194 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1562040000 / 2019-07-02 00:00:00
    2019-07-01 06:47:55,196 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1562126400 / 2019-07-03 00:00:00
    2019-07-01 06:47:55,486 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=7.98004378357 minRuntime=300
    2019-07-01 06:47:55,604 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1562126400 / 2019-07-03 00:00:00
    2019-07-01 06:47:55,606 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1562212800 / 2019-07-04 00:00:00
    2019-07-01 06:47:55,867 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=7.76573866015 minRuntime=300
    2019-07-01 06:47:56,003 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1562212800 / 2019-07-04 00:00:00
    2019-07-01 06:47:56,005 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1562299200 / 2019-07-05 00:00:00
    2019-07-01 06:47:56,291 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=90.0 waterNeeded=6.63801958184 minRuntime=300
    2019-07-01 06:47:56,410 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1562299200 / 2019-07-05 00:00:00
    2019-07-01 06:47:56,412 - DEBUG - rmSimulator:213 - * BEGIN Running simulator for day: 1562385600 / 2019-07-06 00:00:00
    2019-07-01 06:47:56,684 - INFO - rmSimulator:648 - minRuntime Restriction pid=1, zid=2: AW to next day tmachine=55.7686913964 waterNeeded=3.53882090212 minRuntime=300
    2019-07-01 06:47:56,802 - DEBUG - rmSimulator:230 - * END Running simulator for day: 1562385600 / 2019-07-06 00:00:00
    2019-07-01 06:47:56,882 - DEBUG - rmSimulator:249 - *** END Running simulator for forecast: 42661561978069 / 2019-07-01 06:47:49
    2019-07-01 06:47:57,319 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:47:57,322 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:48:00,270 - DEBUG - rmPrograms:807 - Autocycles disabled for program 12
    2019-07-01 06:48:00,276 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:48:00,280 - DEBUG - rmProgramScheduler:162 - *** Program 12 scheduled at 2019-07-01 06:48:00 ; simulator data = [
    * programInfo = (db_rowid=1141, programID=12, et0Est=11.09, qpfEst=0.88)
    * zoneData = (db_rowid=3642, zid=1, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2416.00, computedWateringTime=2273.00, condition=0),
    * zoneData = (db_rowid=3643, zid=2, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2024.00, computedWateringTime=1904.00, condition=0),
    * zoneData = (db_rowid=3644, zid=3, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2374.00, computedWateringTime=2233.00, condition=0),
    * zoneData = (db_rowid=3645, zid=7, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.59, aw=0.00, wp=58.97, userWateringTime=1982.00, computedWateringTime=1169.00, condition=0)
    ]
    2019-07-01 06:48:00,283 - DEBUG - rmProgramScheduler:190 - Zone 1 is SIMULATED (db_rowid=3642, zid=1, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2416.00, computedWateringTime=2273.00, condition=0)
    2019-07-01 06:48:00,286 - DEBUG - rmProgramScheduler:190 - Zone 2 is SIMULATED (db_rowid=3643, zid=2, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2024.00, computedWateringTime=1904.00, condition=0)
    2019-07-01 06:48:00,289 - DEBUG - rmProgramScheduler:190 - Zone 3 is SIMULATED (db_rowid=3644, zid=3, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.94, aw=0.00, wp=94.06, userWateringTime=2374.00, computedWateringTime=2233.00, condition=0)
    2019-07-01 06:48:00,292 - DEBUG - rmProgramScheduler:190 - Zone 7 is SIMULATED (db_rowid=3645, zid=7, day=1561953600/2019-07-01 00:00:00, forecastID=4266, forecastTime=None/None, coef=0.59, aw=0.00, wp=58.97, userWateringTime=1982.00, computedWateringTime=1169.00, condition=0)
    2019-07-01 06:48:00,336 - DEBUG - rmMainDataRecords:643 - Notified cloud client about change
    2019-07-01 06:48:00,339 - INFO - rmProgramScheduler:262 - Program 12 added to watering queue and/or watering history
    2019-07-01 06:48:00,392 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:48:00,394 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:48:00,774 - INFO - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=12, zid=1, ust='2019-07-01 06:48:00', rst='2019-07-01 06:48:00', ud=2416, md=2273, rd=0, aw=0, flag=0, manual=False, running=True, flowClicksDelta=0)
    2019-07-01 06:48:00,777 - DEBUG - rmMainDataRecords:643 - Notified cloud client about change
    2019-07-01 06:48:00,855 - DEBUG - rmProgramSchedulerCore:177 - Updating valve status to 000000000001 ...
    2019-07-01 06:48:09,138 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:48:09,244 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:48:27,466 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:48:48,624 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:48:48,627 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:48:49,013 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:48:49,156 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:48:49,162 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:48:49,169 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:48:49,176 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:48:49,181 - DEBUG - rmRestrictions:318 - Checking program F Lawn & Rear R Shrubs QPF 0
    2019-07-01 06:48:49,185 - DEBUG - rmRestrictions:244 - Zone Water surplus AW (0.234370) > 0
    2019-07-01 06:48:49,190 - DEBUG - rmRestrictions:318 - Checking program R Lawn & Gazebo Shrubs QPF 0
    2019-07-01 06:48:49,194 - DEBUG - rmRestrictions:303 - No minimum temperature for 1562472000 found, won't restrict.
    2019-07-01 06:48:51,366 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:48:51,369 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:48:54,438 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:48:54,441 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:48:56,887 - DEBUG - rmParserManager:506 - Program 12 will start in 0 seconds(2019-07-01 06:48:00) deltaLastParsersRun: 14618 lastUpdate: 1561963462
    2019-07-01 06:48:56,890 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978136 lastUpdate: 1561978069
    2019-07-01 06:48:56,893 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978136 lastUpdate: 1561978069
    2019-07-01 06:48:56,894 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:48:56,896 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561978136 (2019-07-01 06:48:56)
    2019-07-01 06:48:57,200 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:48:57,202 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:00,271 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:00,275 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:03,343 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:03,345 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:06,415 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:06,418 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:09,110 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:49:09,218 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:49:09,487 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:09,490 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:12,252 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:12,256 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:15,330 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:15,333 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:18,396 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:18,399 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:21,469 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:21,472 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:21,629 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:49:24,234 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:24,237 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:27,306 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:27,309 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:30,378 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:30,381 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:33,450 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:33,453 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:36,216 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:36,219 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:39,287 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:39,290 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:42,359 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:42,362 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:45,432 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:45,435 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:48,197 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:48,200 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:51,268 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:51,271 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:51,617 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:49:54,340 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:54,343 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:49:56,901 - DEBUG - rmParserManager:506 - Program 12 will start in -60 seconds(2019-07-01 06:48:00) deltaLastParsersRun: 71 lastUpdate: 1561978069
    2019-07-01 06:49:56,904 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978196 lastUpdate: 1561978069
    2019-07-01 06:49:56,906 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978196 lastUpdate: 1561978069
    2019-07-01 06:49:56,908 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:49:56,910 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561978196 (2019-07-01 06:49:56)
    2019-07-01 06:49:57,413 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:49:57,415 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:00,178 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:00,181 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:03,250 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:03,252 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:06,323 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:06,326 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:09,115 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:50:09,225 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:50:09,402 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:09,405 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:12,161 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:12,164 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:15,234 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:15,237 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:18,303 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:18,306 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:21,377 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:21,379 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:21,636 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:50:22,245 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, uid:13, end_date:-1, start_time:-5040, updated:-1, active:True, sd_m:6, durationRefresh:15, type:1, sd_d:26, freq_modified:0, lastDuration:7728, simulationExpired:False, sd_y:2019, lastDurationTimestamp:1561978221, st_m:54, st_h:3, name:R Lawn & Gazebo Shrubs, zoneLines:{1: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:1, duration:0, order:1, 2: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:2, duration:0, order:2, 3: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:3, duration:0, order:3, 4: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:5, duration:900, order:5, 6: coef:1.04294231725, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:6, duration:0, order:6, 7: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:7, duration:0, order:7, 8: coef:1.04688209641, userPercentage:0.75, flag:0, calc_wd:0, active:True, zid:8, duration:0, order:8, 9: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, futureField1:0, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, start_date:1561521600, cs_on:False
    2019-07-01 06:50:24,448 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:24,450 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:27,215 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:27,218 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:30,287 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:30,290 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:33,971 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:33,974 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:36,430 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:36,433 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:39,194 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:39,197 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:39,312 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, lastDurationTimestamp:1561978221, end_date:-1, start_time:-5038, updated:-1, active:True, sd_m:6, durationRefresh:15, sd_d:26, freq_modified:0, lastDuration:7728, simulationExpired:False, sd_y:2019, uid:13, st_m:54, st_h:3, name:R Lawn & Gazebo Shrubs, zoneLines:{1: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:1, duration:0, order:1, 2: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:2, duration:0, order:2, 3: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:3, duration:0, order:3, 4: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:5, duration:900, order:5, 6: userPercentage:1.0, coef:1.04294231725, flag:0, calc_wd:0, active:True, zid:6, duration:0, order:6, 7: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:7, duration:0, order:7, 8: userPercentage:0.75, coef:1.04688209641, flag:0, calc_wd:0, active:True, zid:8, duration:0, order:8, 9: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, futureField1:0, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, type:1, start_date:1561521600, cs_on:False
    2019-07-01 06:50:41,475 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, lastDurationTimestamp:1561978221, end_date:-1, start_time:-5038, updated:-1, active:True, sd_m:6, durationRefresh:15, type:1, sd_d:26, freq_modified:0, futureField1:0, simulationExpired:False, sd_y:2019, uid:13, st_m:54, st_h:3, name:R Lawn & Gazebo Shrubs, zoneLines:{1: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:1, duration:0, order:1, 2: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:2, duration:0, order:2, 3: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:3, duration:0, order:3, 4: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:5, duration:900, order:5, 6: coef:1.04294231725, userPercentage:1.0, flag:0, calc_wd:0, active:True, zid:6, duration:0, order:6, 7: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:7, duration:0, order:7, 8: coef:1.04688209641, userPercentage:0.75, flag:0, calc_wd:0, active:True, zid:8, duration:0, order:8, 9: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: coef:None, userPercentage:1.0, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, lastDuration:7728, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, start_date:1561521600, cs_on:False
    2019-07-01 06:50:42,266 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:42,269 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:44,011 - DEBUG - rmHTTPResourceApi4Program:340 - Program skeleton updated: cs_cycles:0, ignoreInternetWeather:False, lastDurationTimestamp:1561978221, end_date:-1, start_time:-5038, updated:-1, active:True, sd_m:6, durationRefresh:15, sd_d:26, freq_modified:0, futureField1:0, simulationExpired:False, sd_y:2019, uid:13, st_m:54, st_h:3, name:R Lawn & Gazebo Shrubs, zoneLines:{1: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:1, duration:0, order:1, 2: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:2, duration:0, order:2, 3: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:3, duration:0, order:3, 4: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:4, duration:0, order:4, 5: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:5, duration:900, order:5, 6: userPercentage:1.0, coef:1.04294231725, flag:0, calc_wd:0, active:True, zid:6, duration:0, order:6, 7: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:7, duration:0, order:7, 8: userPercentage:0.75, coef:1.04688209641, flag:0, calc_wd:0, active:True, zid:8, duration:0, order:8, 9: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:9, duration:0, order:9, 10: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:10, duration:0, order:10, 11: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:11, duration:0, order:11, 12: userPercentage:1.0, coef:None, flag:0, calc_wd:None, active:False, zid:12, duration:0, order:12}, program_coef:0.0, lastDuration:7728, delay_on:False, useWaterSense:False, param:2, delay:0, cs_min:0, recurring:True, type:1, start_date:1561521600, cs_on:False
    2019-07-01 06:50:45,338 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:45,341 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:48,410 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:48,413 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:51,482 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:51,485 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:51,863 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:50:54,247 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:54,250 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:50:56,915 - DEBUG - rmParserManager:506 - Program 12 will start in -120 seconds(2019-07-01 06:48:00) deltaLastParsersRun: 131 lastUpdate: 1561978069
    2019-07-01 06:50:56,918 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978256 lastUpdate: 1561978069
    2019-07-01 06:50:56,920 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978256 lastUpdate: 1561978069
    2019-07-01 06:50:56,922 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:50:56,924 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561978256 (2019-07-01 06:50:56)
    2019-07-01 06:50:57,321 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:50:57,323 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:00,392 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:00,395 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:03,464 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:03,467 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:06,229 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:06,232 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:09,110 - DEBUG - rmMachineDiag:148 - Network gateway (10.68.1.1) up on interface wlan0
    2019-07-01 06:51:09,220 - DEBUG - rmMachineDiag:164 - Wireless interface wlan0: up
    2019-07-01 06:51:09,301 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:09,304 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:12,373 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:12,376 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:15,447 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:15,450 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:18,210 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:18,213 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:21,282 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:21,286 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:22,078 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:51:24,354 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:24,357 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:27,427 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:27,429 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:30,192 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:30,194 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:33,264 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:33,267 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:36,336 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:36,338 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:39,408 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:39,411 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:42,173 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:42,176 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:45,246 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:45,250 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:48,318 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:48,321 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:51,389 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:51,392 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:52,029 - DEBUG - rmPrograms:807 - Autocycles disabled for program 13
    2019-07-01 06:51:54,461 - DEBUG - rmLocalNetworkAdvertise:126 - Got data: hello from addr ('10.68.1.6', 50828)
    2019-07-01 06:51:54,464 - DEBUG - rmLocalNetworkAdvertise:86 - Sent data: SPRINKLER||18:d6:c7:11:5e:01||12 Duke St||https://10.68.1.65:8080/||1
    2019-07-01 06:51:56,929 - DEBUG - rmParserManager:506 - Program 12 will start in -180 seconds(2019-07-01 06:48:00) deltaLastParsersRun: 191 lastUpdate: 1561978069
    2019-07-01 06:51:56,931 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978316 lastUpdate: 1561978069
    2019-07-01 06:51:56,933 - DEBUG - rmParserManager:155 - * Ignored because interval 21600 not expired for timestamp 1561978316 lastUpdate: 1561978069
    2019-07-01 06:51:56,935 - DEBUG - rmParserManager:221 - * No new value available from parsers
    2019-07-01 06:51:56,937 - DEBUG - rmParserManager:223 - *** END Running parsers: None, 1561978316 (2019-07-01 06:51:56)
    0
    Comment actions Permalink
  • Avatar
    RainMachine Nicholas

    It's possible to be related to Finish by option. Although we fixed the issue in a previous update by not changing program start time 12h before starting it might still hit a corner case (maybe in combination with WUnderground).

    The negatives seconds from program start time will always appear it's not an indication of this possible bug.

    I would be interested in running some simulations on your data, could you upload diagnostics from Settings > About > Upload Diagnostics ?

     

     

    0
    Comment actions Permalink
  • Avatar
    Rick Felter

    I was able to send data just a bit ago. I also did open a support case, and Brandon got some info from me. Yesterday, I modified the start time to just be a static 4:00 AM for both programs I run. This time of year I have to make sure the lawn gets watered or it will go south quick. The program did run as scheduled this morning. Also, as mentioned in the ticket, this happened on 6/21, 6/26, 6/27, 6/28, 6/30, and 7/1 where the Scheduled program did not run where it was forecast to do so (in excess of min watering time), but did not run and also just left no entry at all in the watering history as to why it didn’t run - the days are just not shown at all, and the app will say “No watering data” for those days. I mentioned in the ticket that this began on 6/21 - the first day of summer, and also when each morning’s sunrise starts getting earlier each day - not sure if that has any connection. One other thing that happened on 6/21 was that a family member accidentally deleted one of the two programs on their phone, and I had to restore from the backup on 6/22 - is it possible the restore fouled something up?

    0
    Comment actions Permalink
  • Avatar
    RainMachine Nicholas

    Yes it might be related but it should behave like that anyway. Going to run your configuration in our simulator for a few days to check it out.

    0
    Comment actions Permalink
  • Avatar
    Rick Felter

    Just remember that when I sent the diagnostics, the schedule had been changed to start at 4:00 AM on each program, and the problems were occurring when each were set to “Finish By 20 Mins After Sunrise”

    0
    Comment actions Permalink
  • Avatar
    Rick Felter

    Also - since changing the start time from the “finish by” to a static 4:00 am, the programs have been running every day and reporting results in the “Watering History”

    0
    Comment actions Permalink
  • Avatar
    Doug Curtis (Edited )

    I am also experiencing this issue with the "finish by". I have had an existing program (1) setup on my Rainmachine Pro16 since May with "Finish 30 Minutes After Sunrise". Everything ran as expected. Recently I upgraded to the 4.0.1131 firmware (from the previous current) and the program continued to operate correctly.  I recently decided to change "Finish 30" to "Finish 45". Made the change via the iOS App (version 4.6.103 (1) and the interface (app and webpage) will show you the next scheduled start day and adaptive watering amount, but it won't run.

    The log below is from this morning. you can see that it was set to run @ 04:20. The parsers ran correctly and then nothing after this in the logs. The web interface shows the next run to be 04:10 on Sunday, July 14th.

    2019-07-13 04:11:38,363 - INFO  - rmParserManager:509 - ** Forcing parsers run because program 1 will start in 540 seconds
    2019-07-13 04:11:38,732 - INFO  - noaa-parser:115 - Fetching Hourly data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdXMLclient.php
    2019-07-13 04:11:40,199 - INFO  - noaa-parser:370 - probability-of-precipitation 12 hour: reject date 2019-07-12 20:00:00 as it's in the past
    2019-07-13 04:11:40,464 - INFO  - noaa-parser:119 - Fetching Daily data from https://noaa.rainmachine.com/xml/sample_products/browser_interface/ndfdBrowserClientByDay.php 
    2019-07-13 04:11:44,967 - INFO  - wunderground-parser:87 - WUnderground: station data retrieved for KMICALED30
    2019-07-13 04:11:44,992 - INFO  - wunderground-parser:95 - WUnderground: forecast data retrieved.
    2019-07-13 04:11:47,997 - INFO  - rmMixer:123 - *** DATA IN THE PAST: 2019-07-12 00:00:00
    2019-07-13 04:11:48,006 - INFO  - rmMixer:124 - *** Received data: ('2019-07-12 00:00:00', temp=19/1, minTemp=None/0, maxTemp=None/0, rh=77/1, minRh=None/0, maxRh=None/0, wind=0/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.47/1, dewPoint=15/1, userData=None, minTemp=11/0, maxTemp=31/0, minRH=41/0, maxRH=99/0, et0cal=None, et0final=None)
    2019-07-13 04:11:48,026 - INFO  - rmMixer:131 - *** Added Existing data: '2019-07-12 00:00:00', temp=19.510000000000002/1, minTemp=None/0, maxTemp=None/0, rh=68/1, minRh=None/0, maxRh=None/0, wind=0.77200000000000002/1, solarRad=None/0, skyCover=None/0, rain=None/0, et0=None/0, pop=7/1, qpf=0/1, condition=2/1, pressure=None/0, dewPoint=12.92/1, userData=None, minTemp=15.390000000000001/1, maxTemp=27.219999999999999/1, minRH=44/1, maxRH=84/1, et0cal=5.1183273799218725, et0final=5.1200000000000001
    2019-07-13 04:11:48,038 - INFO  - rmMixer:132 - *** All available Data: [('2019-07-12 00:00:00', temp=19/1, minTemp=None/0, maxTemp=None/0, rh=77/1, minRh=None/0, maxRh=None/0, wind=0/1, solarRad=None/0, skyCover=None/0, rain=0/1, et0=None/0, pop=None/0, qpf=None/0, condition=None/0, pressure=101.47/1, dewPoint=15/1, userData=None, minTemp=11/0, maxTemp=31/0, minRH=41/0, maxRH=99/0, et0cal=None, et0final=None), ('2019-07-12 00:00:00', temp=19.510000000000002/1, minTemp=None/0, maxTemp=None/0, rh=68/1, minRh=None/0, maxRh=None/0, wind=0.77200000000000002/1, solarRad=None/0, skyCover=None/0, rain=None/0, et0=None/0, pop=7/1, qpf=0/1, condition=2/1, pressure=None/0, dewPoint=12.92/1, userData=None, minTemp=15.390000000000001/1, maxTemp=27.219999999999999/1, minRH=44/1, maxRH=84/1, et0cal=5.1183273799218725, et0final=5.1200000000000001)]
    2019-07-13 04:11:54,049 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=11: AW to next day tmachine=271.472611355 waterNeeded=1.87924200485
    2019-07-13 04:11:56,959 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=2: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:56,979 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=3: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,014 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=4: AW to next day tmachine=388.454933688 waterNeeded=1.46295652161
    2019-07-13 04:11:57,190 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=5: AW to next day tmachine=388.454933688 waterNeeded=1.46295652161
    2019-07-13 04:11:57,209 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=6: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,453 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=7: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,472 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=8: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,492 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=9: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,512 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=10: AW to next day tmachine=287.744395324 waterNeeded=1.46295652161
    2019-07-13 04:11:57,551 - INFO  - rmSimulator:642 -      Frequency MODULATION pid=1, zid=13: AW to next day tmachine=282.895310569 waterNeeded=1.95689130421

    This is the next log entry when I manually started the program:

    2019-07-13 04:30:00,631 - INFO  - rmMainManager:239 - Upgrade checked.
    2019-07-13 04:31:30,631 - INFO  - rmProgramScheduler:193 - Zone 2 is not simulated won't carry available water
    2019-07-13 04:31:30,640 - INFO  - rmProgramScheduler:193 - Zone 3 is not simulated won't carry available water
    2019-07-13 04:31:30,648 - INFO  - rmProgramScheduler:193 - Zone 4 is not simulated won't carry available water
    2019-07-13 04:31:30,657 - INFO  - rmProgramScheduler:193 - Zone 5 is not simulated won't carry available water
    2019-07-13 04:31:30,666 - INFO  - rmProgramScheduler:193 - Zone 6 is not simulated won't carry available water
    2019-07-13 04:31:30,674 - INFO  - rmProgramScheduler:193 - Zone 7 is not simulated won't carry available water
    2019-07-13 04:31:30,683 - INFO  - rmProgramScheduler:193 - Zone 8 is not simulated won't carry available water
    2019-07-13 04:31:30,691 - INFO  - rmProgramScheduler:193 - Zone 9 is not simulated won't carry available water
    2019-07-13 04:31:30,699 - INFO  - rmProgramScheduler:193 - Zone 10 is not simulated won't carry available water
    2019-07-13 04:31:30,708 - INFO  - rmProgramScheduler:193 - Zone 11 is not simulated won't carry available water
    2019-07-13 04:31:30,716 - INFO  - rmProgramScheduler:193 - Zone 12 is not simulated won't carry available water
    2019-07-13 04:31:30,725 - INFO  - rmProgramScheduler:193 - Zone 13 is not simulated won't carry available water
    2019-07-13 04:31:31,593 - INFO  - rmProgramSchedulerWatering:250 - Watering - starting zone: (uid=None, pid=1, zid=2, ust='2019-07-13 04:31:00', rst='2019-07-13 04:31:31', ud=881, md=881, rd=0, aw=0, flag=0, manual=True, running=True, flowClicksDelta=0)

    The simulation is showing that watering needs to occur, it just doesn't start the program:

    ---------------------------- PROGRAM SIMULATION LOG -----------------------------
    2019-07-13 04:11:48,914 - INFO  - rmSimulator:254 - Skipping old day 1562904000 < 1562990400 from simulation.
    2019-07-13 04:11:48,919 - INFO  - rmSimulator:254 - Skipping old day 1562904000 < 1562990400 from simulation.
    2019-07-13 04:11:49,683 - INFO  - rmSimulator:386 - Correction for the past enabled. Program 1 will try to use AW from main db first.
    2019-07-13 04:11:50,518 - INFO  - rmSimulator:536 - 
    * Program Coefficients (pid=1, fake=False, ignoreInternetWeather=False, day=2019-07-14 00:00:00)
    	- pastStart=2019-07-13 00:00:00, futureEnd=2019-07-15 00:00:00, pastMultiplier=1, futureMultiplier=1
    	- qpfReal=0.00, qpfUsed=None, qpfEst=0.20, historyQpf=0.20, et0Real=5.31, et0Used=None, et0Est=5.64, historyEt0=5.64
    	- et0Avg=5.60 detailsReal=[et0(m)=5.31, qpf(m)=0.00], detailsHistory=[et0(m)=5.64, qpf(m)=0.20]
    	 zone  lastAW  newAW  chEt0  waterFF  corrPast  water%  waterNeeded  fielCap  cropCoef  zoneType  userDuration
    	    2    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2        881.00
    	    3    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2        881.00
    	    4    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2       1189.35
    	    5    0.00   0.00   5.64     4.35     0.00   97.12      4.35        5.68      0.80       2       1189.35
    	    6    0.00   0.00   5.64     4.35     0.00   97.12      4.35        5.68      0.80       2        881.00
    	    7    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2        881.00
    	    8    0.00   0.00   5.64     4.35     0.00   97.12      4.35        5.68      0.80       2        881.00
    	    9    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2        881.00
    	   10    0.00   0.00   5.64     4.35     0.00   97.12      4.35        8.93      0.80       2        881.00
    	   11    0.00   0.00   5.64     3.67     0.00   96.49      3.67        8.93      0.68       2        550.00
    	   12    0.00   0.00   5.64     5.48     0.00   97.84      5.48       26.34      1.00       4        809.00
    	   13    0.00   0.00   5.64     4.63     0.00   97.33      4.63       26.34      0.85       4        688.00

    I've also uploaded my log files. On the 12th (yesterday) after the program didn't run, I created a new program (2) that mirrored the first, set the start time (04:45, no "Finish After") and the program processed and ran correctly.

    Another item I noted, but haven't been able to fully diagnose, when I changed from 30 to 45 minutes on the app, it was showing "after sunrise". I reopened the program to check the settings and it was then "after sunset".  I changed it a couple times and the app finally showed "after sunrise". Logging in to the web interface and the program showed "after sunset". I was able to change it via the webpage and it continued to stay.  As I stated, I haven't had time to do in depth testing on this, but there appears to be some sort of issue/conflict going on when setting it.

    Additional question, when I manually kicked off the program, it runs for the full amount. Is that a) expected function and b) if so, how do you get it to use the "adaptive" rates?

    0
    Comment actions Permalink
  • Avatar
    Rick Felter

    Just to chime in — I also experienced my issue after I had changed the “Finish By” time. And when I had gone back in the app on iOS, I also ran into that same issue with it showing After Sunset instead of After Sunrise.

    I wouldn’t have to keep changing the Finish By offset if they added in the ability to Finish By a specific time, but that’s a topic that’s been discussed elsewhere.

    1
    Comment actions Permalink
  • Avatar
    RainMachine Nicholas

    We are investigating but it's difficult to replicate.

    About manual start of a program, it's designed to run 100% of the time set. There is no option no to manual run a program with the computed duration unless you set the start time to current time.

    0
    Comment actions Permalink

Please sign in to leave a comment.