[BUG] Hydrawise Binding - Scheduled runnable ended with an exception

stuetzomat · January 16, 2022, 3:51pm

Switch    AA_Watering_Ost_Run     "Watering Ost Run zones"    channel="hydrawise:controller:home:123456:zone1#run"}

digitaldan · January 24, 2022, 11:48pm

Can you run the binding in TRACE mode and post what the output is when you trying sending an ON command and then and OFF command ? (so we can see one that works and does not work)

You can turn logging on in the openHAB / Karaf CLI like:

log:set TRACE org.openhab.binding.hydrawise

digitaldan · January 24, 2022, 11:49pm

And by output , i mean post the relevant parts from your openHAB log, thanks.

stuetzomat · January 25, 2022, 10:59am

ok, thank you dan!

for “ON” in openHAB → item …:zone1#run (nothing happens)

2022-01-25 11:36:50.723 [DEBUG] [l.handler.HydrawiseControllerHandler] - handleCommand channel hydrawise:controller:home:661727:zone1#run Command ON
2022-01-25 11:36:50.725 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - Sending Mutation {"query":"mutation { startZone(zoneId: 4319930) { status } }"}
2022-01-25 11:36:50.726 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Sending Request: {"query":"mutation { startZone(zoneId: 4319930) { status } }"}
2022-01-25 11:36:51.244 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"startZone":{"status":"OK"}}}
2022-01-25 11:36:51.245 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - Mutation response {"data":{"startZone":{"status":"OK"}}}

2022-01-25 11:37:06.246 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Sending Request: {"query":"{\n  me {\n    email\n    lastContact\n      controllers {\n      id\n      name\n        status {\n        summary\n        online\n        lastContact {\n          timestamp\n        }\n      }    \n      location {\n        coordinates {\n          latitude\n          longitude\n        }\n        forecast(days: 3) {\n          time\n          updateTime\n          conditions\n          averageWindSpeed {\n            value\n            unit\n          }\n          highTemperature {\n            value\n            unit\n          }\n          lowTemperature {\n            value\n            unit\n          }\n          probabilityOfPrecipitation\n          precipitation {\n            value\n            unit\n          }\n          averageHumidity\n        }\n      }\n      zones {\n        id\n        name\n        status {\n          suspendedUntil {\n            timestamp\n          }     \n        }\n        icon {\n          id\n          fileName\n          customImage {\n            id\n            url\n          }\n        }\n        number {\n          value\n          label\n        }\n        scheduledRuns {\n          summary\n          currentRun{\n             id\n            startTime {\n              timestamp\n            }\n            endTime {\n              timestamp\n            }\n            duration\n            status {\n              value\n              label\n            }\n          }\n          nextRun {\n            id\n            startTime {\n              timestamp\n            }\n            endTime {\n              timestamp\n            }\n            duration\n          }\n        }\n      }\n      sensors {\n        id\n        name\n        input {\n          number\n          label\n        }\n        status {\n          active\n          waterFlow {\n            value\n            unit\n          }\n        }\n        model {\n          modeType\n          active\n          offLevel\n          offTimer\n          delay\n        }\n      }\n    }\n  }\n}"}
2022-01-25 11:37:06.578 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"me":{"email":"myemail@fake.at","lastContact":"Sat, 15 Jan 22 12:49:08 +0000","controllers":[{"id":661727,"name":"Home","status":{"summary":"Alles in Ordnung!","online":true,"lastContact":{"timestamp":1643107011}},"location":{"coordinates":{"latitude":home,"longitude":home},"forecast":[{"time":"Tue, 25 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Sonnig","averageWindSpeed":{"value":7,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-4,"unit":"\u00b0C"},"probabilityOfPrecipitation":14.44,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":73},{"time":"Wed, 26 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Sch\u00f6n\/meist sonnig","averageWindSpeed":{"value":9,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-5,"unit":"\u00b0C"},"probabilityOfPrecipitation":9.75,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":67},{"time":"Thu, 27 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Gr\u00f6\u00dftenteils bew\u00f6lkt","averageWindSpeed":{"value":7,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-5,"unit":"\u00b0C"},"probabilityOfPrecipitation":27.04,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":61}]},"zones":[{"id":4319930,"name":"Ost","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":1,"label":"Zone 1"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320523,"name":"Sued","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":2,"label":"Zone 2"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320525,"name":"Hang","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":3,"label":"Zone 3"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320526,"name":"West","status":{"suspendedUntil":null},"icon":{"id":1,"fileName":"leaf.png","customImage":null},"number":{"value":4,"label":"Zone 4"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320527,"name":"Hochbeet","status":{"suspendedUntil":null},"icon":{"id":9,"fileName":"tree.png","customImage":null},"number":{"value":5,"label":"Zone 5"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}}],"sensors":[]}]}}}
2022-01-25 11:37:06.587 [TRACE] [l.handler.HydrawiseControllerHandler] - onData my controller id 661727
2022-01-25 11:37:06.588 [TRACE] [l.handler.HydrawiseControllerHandler] - Updating Controller 661727 sensors [] forecast [org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@18c638c, org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@eaa6d8, org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@13be2de] 
2022-01-25 11:37:06.589 [DEBUG] [l.handler.HydrawiseControllerHandler] - updateState updating hydrawise:controller:home:661727:system#lastcontact 2022-01-25T10:36:51.000+0000
2022-01-25 11:37:06.590 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast1 temperaturehigh °C 6
2022-01-25 11:37:06.591 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast1 temperaturelow °C -4
2022-01-25 11:37:06.593 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast2 temperaturehigh °C 6
2022-01-25 11:37:06.594 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast2 temperaturelow °C -5
2022-01-25 11:37:06.596 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast3 temperaturehigh °C 6
2022-01-25 11:37:06.597 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast3 temperaturelow °C -5
2022-01-25 11:37:06.598 [TRACE] [l.handler.HydrawiseControllerHandler] - Updateing Zone zone1 Ost

for “OFF” in openHAB after I started it in the controller itself… (it stops immediatly)

2022-01-25 11:44:04.967 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AA_Watering_Ost_Summary' changed from Keine Ausführung geplant to Ost wird zum nächsten Mal am Dienstag, 25. Januar 2022, 11:44 (Österreich Zeit) für 5 Minuten ausgeführt. Manueller Beregnungszyklus
2022-01-25 11:44:04.972 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AA_Watering_Ost_Nextruntime' changed from UNDEF to 2022-01-25T10:44:05.000+0000
==> /var/log/openhab/openhab.log <==
2022-01-25 11:44:29.285 [DEBUG] [l.handler.HydrawiseControllerHandler] - handleCommand channel hydrawise:controller:home:661727:zone1#run Command OFF
2022-01-25 11:44:29.286 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - Sending Mutation {"query":"mutation { stopZone(zoneId: 4319930) { status } }"}
2022-01-25 11:44:29.287 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Sending Request: {"query":"mutation { stopZone(zoneId: 4319930) { status } }"}
2022-01-25 11:44:29.847 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"stopZone":{"status":"OK"}}}
2022-01-25 11:44:29.848 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - Mutation response {"data":{"stopZone":{"status":"OK"}}}

2022-01-25 11:44:44.850 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Sending Request: {"query":"{\n  me {\n    email\n    lastContact\n      controllers {\n      id\n      name\n        status {\n        summary\n        online\n        lastContact {\n          timestamp\n        }\n      }    \n      location {\n        coordinates {\n          latitude\n          longitude\n        }\n        forecast(days: 3) {\n          time\n          updateTime\n          conditions\n          averageWindSpeed {\n            value\n            unit\n          }\n          highTemperature {\n            value\n            unit\n          }\n          lowTemperature {\n            value\n            unit\n          }\n          probabilityOfPrecipitation\n          precipitation {\n            value\n            unit\n          }\n          averageHumidity\n        }\n      }\n      zones {\n        id\n        name\n        status {\n          suspendedUntil {\n            timestamp\n          }     \n        }\n        icon {\n          id\n          fileName\n          customImage {\n            id\n            url\n          }\n        }\n        number {\n          value\n          label\n        }\n        scheduledRuns {\n          summary\n          currentRun{\n             id\n            startTime {\n              timestamp\n            }\n            endTime {\n              timestamp\n            }\n            duration\n            status {\n              value\n              label\n            }\n          }\n          nextRun {\n            id\n            startTime {\n              timestamp\n            }\n            endTime {\n              timestamp\n            }\n            duration\n          }\n        }\n      }\n      sensors {\n        id\n        name\n        input {\n          number\n          label\n        }\n        status {\n          active\n          waterFlow {\n            value\n            unit\n          }\n        }\n        model {\n          modeType\n          active\n          offLevel\n          offTimer\n          delay\n        }\n      }\n    }\n  }\n}"}
2022-01-25 11:44:45.185 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"me":{"email":"myemail@fake.at","lastContact":"Sat, 15 Jan 22 12:49:08 +0000","controllers":[{"id":661727,"name":"Home","status":{"summary":"Alles in Ordnung!","online":true,"lastContact":{"timestamp":1643107471}},"location":{"coordinates":{"latitude":home,"longitude":home},"forecast":[{"time":"Tue, 25 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Sonnig","averageWindSpeed":{"value":7,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-4,"unit":"\u00b0C"},"probabilityOfPrecipitation":14.44,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":73},{"time":"Wed, 26 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Sch\u00f6n\/meist sonnig","averageWindSpeed":{"value":9,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-5,"unit":"\u00b0C"},"probabilityOfPrecipitation":9.75,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":67},{"time":"Thu, 27 Jan 22 00:00:00 +0100","updateTime":"Mon, 24 Jan 22 22:14:22 +0100","conditions":"Gr\u00f6\u00dftenteils bew\u00f6lkt","averageWindSpeed":{"value":7,"unit":"km\/h"},"highTemperature":{"value":6,"unit":"\u00b0C"},"lowTemperature":{"value":-5,"unit":"\u00b0C"},"probabilityOfPrecipitation":27.04,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":61}]},"zones":[{"id":4319930,"name":"Ost","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":1,"label":"Zone 1"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320523,"name":"Sued","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":2,"label":"Zone 2"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320525,"name":"Hang","status":{"suspendedUntil":null},"icon":{"id":10,"fileName":"grass.png","customImage":null},"number":{"value":3,"label":"Zone 3"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320526,"name":"West","status":{"suspendedUntil":null},"icon":{"id":1,"fileName":"leaf.png","customImage":null},"number":{"value":4,"label":"Zone 4"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}},{"id":4320527,"name":"Hochbeet","status":{"suspendedUntil":null},"icon":{"id":9,"fileName":"tree.png","customImage":null},"number":{"value":5,"label":"Zone 5"},"scheduledRuns":{"summary":"Keine Ausf\u00fchrung geplant","currentRun":null,"nextRun":null}}],"sensors":[]}]}}}
2022-01-25 11:44:45.206 [TRACE] [l.handler.HydrawiseControllerHandler] - onData my controller id 661727
2022-01-25 11:44:45.208 [TRACE] [l.handler.HydrawiseControllerHandler] - Updating Controller 661727 sensors [] forecast [org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@11b43ef, org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@cefead, org.openhab.binding.hydrawise.internal.api.graphql.dto.Forecast@1dd36d0] 
2022-01-25 11:44:45.210 [DEBUG] [l.handler.HydrawiseControllerHandler] - updateState updating hydrawise:controller:home:661727:system#lastcontact 2022-01-25T10:44:31.000+0000
2022-01-25 11:44:45.212 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast1 temperaturehigh °C 6
2022-01-25 11:44:45.215 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast1 temperaturelow °C -4
2022-01-25 11:44:45.218 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast2 temperaturehigh °C 6
2022-01-25 11:44:45.220 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast2 temperaturelow °C -5
2022-01-25 11:44:45.224 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast3 temperaturehigh °C 6
2022-01-25 11:44:45.227 [DEBUG] [l.handler.HydrawiseControllerHandler] - TEMP forecast3 temperaturelow °C -5
2022-01-25 11:44:45.234 [TRACE] [l.handler.HydrawiseControllerHandler] - Updateing Zone zone1 Ost 
2022-01-25 11:44:45.236 [DEBUG] [l.handler.HydrawiseControllerHandler] - updateState updating hydrawise:controller:home:661727:zone1#summary Keine Ausführung geplant
2022-01-25 11:44:45.238 [DEBUG] [l.handler.HydrawiseControllerHandler] - updateState updating hydrawise:controller:home:661727:zone1#duration UNDEF
2022-01-25 11:44:45.240 [DEBUG] [l.handler.HydrawiseControllerHandler] - updateState updating hydrawise:controller:home:661727:zone1#nextruntime UNDEF

==> /var/log/openhab/events.log <==
2022-01-25 11:44:45.248 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AA_Watering_Ost_Summary' changed from Ost wird zum nächsten Mal am Dienstag, 25. Januar 2022, 11:44 (Österreich Zeit) für 5 Minuten ausgeführt. Manueller Beregnungszyklus to Keine Ausführung geplant
2022-01-25 11:44:45.250 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'AA_Watering_Ost_Nextruntime' changed from 2022-01-25T10:44:05.000+0000 to UNDEF

But I have noticed something else…

When I start the irrigation on the controller, I also have to enter the duration.
If I then stop it via openhab, it stops immediately. If I then start it again via openhab, it takes a little while and the sprinkling also starts.
In my opinion, this must somehow have something to do with the countdown.
I have also tried everything in this regard (command for …zone1#runcustom), but here I get the error…

2022-01-25 11:35:54.046 [WARN ] [l.handler.HydrawiseControllerHandler] - Invalid command type for run custom org.openhab.core.library.types.DecimalType```

digitaldan · January 27, 2022, 4:35am

Hmm, that does seems different. The logs show the service receives the on command and returns back with an "status":"OK" , so it’s sending the command at least, but obviously does not actually do anything. Does this zone have a watering length setup in the Hydrawise app ?

In any case, if you look in the docs there is a runcustom channel that takes a Time QuantityType as its command, so if you send it 10m or 120s (basically a string) it will run the zone for that amount of time, i’m guessing you need to use that instead.

stuetzomat · January 27, 2022, 7:54am

hello dan,

thanks for your help. that was the trick.

if i just use the “runcustom” it works fine and immediately.

the following inputs/formats work for “runcustom”.
“5 min”
“120 s”

thanks a lot for your help!

April_Wexler · June 27, 2022, 12:43pm

Hi @digitaldan,
since some time I see in the logs an minor warning. The major functions (e.g. Zone1 on/off) is working perfect - but there seems to be an issue with the scheduled actions configured via Hydrawise App. Do you have an idea what this is? (running OH3.3)

2022-06-27 14:27:26.689 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hydrawise:account:ff257852' changed from INITIALIZING to ONLINE
2022-06-27 14:27:26.731 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hydrawise:controller:ff257852:312233' changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2022-06-27 14:27:26.764 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'HydrawiseControllerHunterX2_ForecastTime' changed from NULL to 2022-06-27T00:00:00.000+0200
2022-06-27 14:27:26.773 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'hydrawise:controller:ff257852:312233' changed from INITIALIZING to ONLINE
2022-06-27 14:27:26.805 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.util.ConcurrentModificationException: null
	at java.util.ArrayList.forEach(ArrayList.java:1543) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:200) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:183) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) ~[?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
	at java.lang.Thread.run(Thread.java:829) [?:?]

digitaldan · July 1, 2022, 8:52pm

Yeah, that should not happen! Do you have more than one Hydrawise controller listed in your Hydrawise cloud account?

April_Wexler · July 2, 2022, 10:54am

thanks for your answer. I double-checked this: no, there is just one controller for my HunterX2.

digitaldan · July 6, 2022, 11:23pm

Ok thanks. FYI i have not forgot about this, planning on looking again over the weekend.

digitaldan · July 17, 2022, 5:08pm

@April_Wexler can you run the binding in TRACE logging for and then send me a private message with the results? so in the CLI console you would do a

log:set TRACE org.openhab.binding.hydrawise

and to reset it when you are done

log:set DEFAULT org.openhab.binding.hydrawise

Thanks

digitaldan · July 18, 2022, 8:35pm

Thanks for the log. I have an updated version of the binding you can download here. To install you can either uninstall the current binding then drop this one in your addons folder, or use the CLI to update the currently running binding like bundle:update org.openhab.binding.hydrawise file:///some/dir/org.openhab.binding.hydrawise-3.4.0-SNAPSHOT.jar

Let me know how that goes!

grutmic · July 23, 2022, 4:18pm

I run in a problem with lastContact, i am not able to do the correct definiton in my items file, could someone send me your definiton for openHAB 3.3 Version?

i tried these ways:

channel="hydrawise:controller:000be4ab:76566:system#lastcontact"
channel="hydrawise:controller:000be4ab:76566:system#lastContact"
channel="hydrawise:controller:000be4ab:76566:system#lastcontacttime"

Is this correct?

This works perfect for the status summary:

channel="hydrawise:controller:000be4ab:76566:system#summary"

So what could the problem be? If i look into the Trace the data will come in …

I tried Dan’s Version 3.4 jar file, but the same problem.

grutmic · July 23, 2022, 5:28pm

I found one error at the end of the trace:

2022-07-23 19:26:34.885 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.util.ConcurrentModificationException: null
        at java.util.ArrayList.forEach(ArrayList.java:1543) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:200) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:183) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) ~[?:?]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) ~[?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) ~[?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
        at java.lang.Thread.run(Thread.java:829) [?:?]

April_Wexler · November 28, 2022, 2:22pm

Hi @digitaldan,
same problem for me.
The binding which comes with OH3.3:

I tried the binding org.openhab.binding.hydrawise-3.4.0-SNAPSHOT.jar, same error:

I was able to remove the controller, scan again - controller was found but again “error handler”.

Currently it is not “sprinkler” time - but spring/sommer will come soon again

best, Kai

digitaldan · November 28, 2022, 6:33pm

Hi, can you report what version of the binding openHAB thinks is loaded? So from the OH console/cli issue the command:

bundle:list -s |grep org.openhab.binding.hydrawise

The result would look something like:

271 │ Active │ 80 │ 3.4.0.202211090343 │ org.openhab.binding.hydrawise

April_Wexler · November 29, 2022, 7:54am

bundle:list -s |grep org.openhab.binding.hydrawise
291 │ Active │  80 │ 3.4.0.202207181728     │ org.openhab.binding.hydrawise

digitaldan · November 29, 2022, 4:37pm

Thanks, i think there were additional changes made after i posted that jar, can you try using one from the 3.4 nightly build? Those can always be found at:

https://ci.openhab.org/job/openHAB-Addons/lastSuccessfulBuild/artifact/bundles/org.openhab.binding.hydrawise/target/

April_Wexler · November 30, 2022, 8:01am

I downloaded the new version, unfortunately no change:

digitaldan · December 2, 2022, 9:35pm

Ok, there is definitely quite a few places i assume data should be there that is throwing this error , and probably in other places to if data is missing. I can fix those in the binding, but in the mean time, can you enable TRACE debugging on the binding and PM me a response message from the Hydrawise API with your zones? It will be a giant blob of JSON data, so hard to miss. Want to make sure there is not a larger API issue.