search

rgc99 / irrigation_unlimited

♒Irrigation controller for Home Assistant
MIT License
307 stars 45 forks source link

One zone/sequence stopped working and i cannot figure out w #168

Open Dinth opened 3 weeks ago

Dinth commented 3 weeks ago

Version of the custom_component

2024.5.0

Configuration


irrigation_unlimited:
  granularity: 10
  controllers:
    all_zones_config:
      show:
        timeline: true
    zones:
      - entity_id: "switch.backgarden_valve"
        name: "Backyard watering zone"
      - entity_id: "switch.greenhouse_valve"
        name: "Greenhouse watering zone"
      - entity_id: "switch.front_garden_valve"
        name: "Front garden watering zone"
      - entity_id: "switch.fox_sprinkler_valve"
        name: "Fox sprinkler"
      - entity_id: "switch.downstairs_watering"
        name: "Downstairs watering"
    sequences:
      - name: "Backgarden watering"
        duration: "00:05"
        delay: "00:02"
        repeat: 3
        zones:
          - zone_id: 1
        schedules:
          - name: "Before sunrise"
            time:
              sun: "sunrise"
              before: "01:00"
          - name: "After sunset"
            time:
              sun: "sunset"
              after: "00:30"
      - name: "Greenhouse watering"
        duration: "00:05"
        delay: "00:02"
        repeat: 3
        zones:
          - zone_id: 4
        schedules:
          - name: "Before sunrise"
            time:
              sun: "sunrise"
              before: "00:45"
          - name: "After sunset"
            time:
              sun: "sunset"
              after: "00:45"
      - name: "Front garden watering"
        duration: "00:07"
        delay: "00:02"
        repeat: 3
        zones:
          - zone_id: 3
        schedules:
          - name: "Before sunrise"
            time:
              sun: "sunrise"
              before: "02:00"
      - name: "Downstairs watering"
        duration: "00:01:00"
        zones:
          - zone_id: 5
        schedules:
          - name: "After sunrise"
            time:
              sun: "sunrise"
              after: "02:00"
            day: "odd"

Describe the bug

Everything worked last year, i have paused sequences for the winter and now after unpausing them, Backyard watering (one of the sequences) doesnt work. I cannot really figure it why. When i run it manually, it works fine, but automatic scheduling simply doesnt work.

image

Debug log

These messages are very important in helping to determine the problem. Go into Settings -> System -> Logs. In the search box put in irrigation_unlimited (Note the underscore between the two words) and press the LOAD FULL LOGS button. Copy and paste below.


2024-07-01 18:45:45.595 INFO (MainThread) [homeassistant.setup] Setting up irrigation_unlimited
2024-07-01 18:45:45.595 INFO (MainThread) [custom_components.irrigation_unlimited] 
-------------------------------------------------------------------
Irrigation Unlimited
Version: 2024.5.0
If you have any issues with this you need to open an issue here:
https://github.com/rgc99/irrigation_unlimited/issues
-------------------------------------------------------------------

2024-07-01 18:45:45.603 INFO (SyncWorker_7) [homeassistant.util.package] Attempting install of beautifulsoup4==4.12.3
2024-07-01 18:45:45.615 DEBUG (MainThread) [custom_components.irrigation_unlimited] LOAD
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:45:45] controller: 0, zone: 0, sequence: 0, entity: irrigation_unlimited.coordinator
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 1}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 1}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 1}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 1, "zones": [1]}
2024-07-01 18:45:45.616 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 1, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 1, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 2}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 2}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 2}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 2, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 2, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 2, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 3}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 3}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 3}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 3, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 3, "zones": [1]}
2024-07-01 18:45:45.617 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 3, "zones": [1]}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 4}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 4}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 4}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 0, sequence: 0, data: {"sequence_id": 4, "zones": [1]}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 4, "zones": [1]}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"reset": null, "sequence_id": 4, "zones": [1]}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 1, sequence: 0, data: {}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 1, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.618 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 1, sequence: 0, data: {"percentage": 0.0}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 2, sequence: 0, data: {}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 2, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 2, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.618 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 3, sequence: 0, data: {}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 3, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.619 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 3, sequence: 0, data: {"percentage": 50.0}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 4, sequence: 0, data: {}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 4, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 4, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: enable, controller: 1, zone: 5, sequence: 0, data: {}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: suspend, controller: 1, zone: 5, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.619 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 5, sequence: 0, data: {"reset": null}
2024-07-01 18:45:45.620 DEBUG (MainThread) [custom_components.irrigation_unlimited] START [2024-07-01 18:45:45]
2024-07-01 18:45:45.620 INFO (MainThread) [homeassistant.setup] Setup of domain irrigation_unlimited took 0.02 seconds

(...)

024-07-01 18:46:21.967 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up irrigation_unlimited.binary_sensor
2024-07-01 18:46:21.968 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 0, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_m
2024-07-01 18:46:21.969 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 1, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_z1
2024-07-01 18:46:21.969 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 2, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_z2
2024-07-01 18:46:21.970 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 3, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_z3
2024-07-01 18:46:21.970 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 4, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_z4
2024-07-01 18:46:21.970 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 5, sequence: 0, entity: binary_sensor.irrigation_unlimited_c1_z5
2024-07-01 18:46:21.970 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 0, sequence: 1, entity: binary_sensor.irrigation_unlimited_c1_s1
2024-07-01 18:46:21.971 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 0, sequence: 2, entity: binary_sensor.irrigation_unlimited_c1_s2
2024-07-01 18:46:21.971 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 0, sequence: 3, entity: binary_sensor.irrigation_unlimited_c1_s3
2024-07-01 18:46:21.971 DEBUG (MainThread) [custom_components.irrigation_unlimited] REGISTER [2024-07-01 18:46:21] controller: 1, zone: 0, sequence: 4, entity: binary_sensor.irrigation_unlimited_c1_s4

(...)

2024-07-01 18:48:42.854 DEBUG (MainThread) [custom_components.irrigation_unlimited] INITIALISED
2024-07-01 18:48:42.854 WARNING (MainThread) [custom_components.irrigation_unlimited] SYNCHRONISATION [2024-07-01 18:48:40] Switch does not match current state: expected: off, found: unavailable, switch: switch.greenhouse_valve

(...)

2024-07-01 21:51:04.325 DEBUG (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 21:51:04] service: adjust_time, controller: 1, zone: 0, sequence: 0, data: {"entity_id": ["binary_sensor.irrigation_unlimited_c1_m"], "sequence_id": 1, "reset": null}

(...)

2024-07-01 21:53:22.215 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 21:53:22] service: manual_run, controller: 1, zone: 1, sequence: 0, data: {"entity_id": ["binary_sensor.irrigation_unlimited_c1_z1"], "time": "0:20:00"}
2024-07-01 21:53:23.008 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:53:23] controller: 1, zone: 0, state: 1
2024-07-01 21:53:23.008 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:53:23] controller: 1, zone: 1, state: 1, data: 1.0.0.0.0

(...)

2024-07-01 21:54:36.587 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 21:54:36] service: cancel, controller: 1, zone: 1, sequence: 0, data: {"entity_id": ["binary_sensor.irrigation_unlimited_c1_z1"]}
2024-07-01 21:54:36.590 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:54:36] controller: 1, zone: 1, state: 0
2024-07-01 21:54:36.590 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:54:36] controller: 1, zone: 0, state: 0

(...)

2024-07-01 21:55:21.820 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 21:55:21] service: manual_run, controller: 1, zone: 0, sequence: 0, data: {"entity_id": ["binary_sensor.irrigation_unlimited_c1_m"], "sequence_id": 1, "time": "0:10:00"}
2024-07-01 21:55:22.007 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:55:22] controller: 1, zone: 0, state: 1
2024-07-01 21:55:22.008 INFO (MainThread) [custom_components.irrigation_unlimited] EVENT [2024-07-01 21:55:22] controller: 1, zone: 1, state: 1, data: 1.0.1.1.1
rgc99 commented 3 weeks ago

The adjustment for zone 1 is 0.0. If you take the debug off it is easier to see.

2024-07-01 18:45:45.618 INFO (MainThread) [custom_components.irrigation_unlimited] CALL [2024-07-01 18:45:45] service: adjust_time, controller: 1, zone: 1, sequence: 0, data: {"percentage": 0.0}