Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[hydrawise] Communication error #16570

Open
clown-m opened this issue Mar 24, 2024 · 49 comments
Open

[hydrawise] Communication error #16570

clown-m opened this issue Mar 24, 2024 · 49 comments
Assignees
Labels
bug An unexpected problem or unintended behavior of an add-on

Comments

@clown-m
Copy link

clown-m commented Mar 24, 2024

I upgraded to 4.1.2 today while i saw that there is an authorization bugfix in the hydrawise binding done, but still i got this message after the upgrade in the BASIC UI Thing setup:

COMMUNICATION_ERROR
Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}_

I am running the Hydrawise Binding on openHAB 4.1.2 stable version on a Debian Buster Machine.

This failure comes up since some days, all other APP's are running as normal.

Any idea?

@clown-m clown-m added the bug An unexpected problem or unintended behavior of an add-on label Mar 24, 2024
@digitaldan
Copy link
Contributor

{"errors":{"message":"Could not resolve promise"}}_

Hmm, that's an error coming from the Hydrawise servers, seems like they have a bug, although i'm not sure why its working for me and others and not your account. You want want to try deleting the "account" and "controller" thing and add them back new.

@digitaldan digitaldan self-assigned this Mar 24, 2024
@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

I removed already the Binding and the account thing's. No success, still did not work

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

I started the DEBUG mode, here is the log entry:

org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:315) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:261) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-03-24 17:23:01.729 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:315) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:261) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]

@digitaldan
Copy link
Contributor

Can you turn on TRACE logging and see what we are sending? I wonder if its choking on a GraphQL variable that maybe is not supported in your region.

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Ok, here is the TRACE 👍

2024-03-24 17:34:02.782 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Handler initialized.
2024-03-24 17:34:04.087 [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}"}
2024-03-24 17:34:04.988 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-03-24 17:34:04.989 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Retrying failed poll
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:315) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:261) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-03-24 17:34:04.994 [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}"}
2024-03-24 17:34:05.272 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-03-24 17:34:05.273 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:315) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:261) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]

@digitaldan
Copy link
Contributor

Can you try something for me?

  1. Login to the web hydrawise app at https://app.hydrawise.com/
  2. After you successfully log in, go to https://app.hydrawise.com/api/v2/graph/explore
  3. Enter the following in the pane on the left, then click the Play button at the top, and report back the result in the right pane. I have a screenshot below of what it looks like for me.
{
  me {
    email
    lastContact
      controllers {
      id
      name
        status {
        summary
        online
        lastContact {
          timestamp
        }
      }    
      location {
        coordinates {
          latitude
          longitude
        }
        forecast(days: 3) {
          time
          updateTime
          conditions
          averageWindSpeed {
            value
            unit
          }
          highTemperature {
            value
            unit
          }
          lowTemperature {
            value
            unit
          }
          probabilityOfPrecipitation
          precipitation {
            value
            unit
          }
          averageHumidity
        }
      }
      zones {
        id
        name
        status {
          suspendedUntil {
            timestamp
          }     
        }
        icon {
          id
          fileName
          customImage {
            id
            url
          }
        }
        number {
          value
          label
        }
        scheduledRuns {
          summary
          currentRun{
             id
            startTime {
              timestamp
            }
            endTime {
              timestamp
            }
            duration
            status {
              value
              label
            }
          }
          nextRun {
            id
            startTime {
              timestamp
            }
            endTime {
              timestamp
            }
            duration
          }
        }
      }
      sensors {
        id
        name
        input {
          number
          label
        }
        status {
          active
          waterFlow {
            value
            unit
          }
        }
        model {
          modeType
          active
          offLevel
          offTimer
          delay
        }
      }
    }
  }
}
image

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Ok Dan, here is the result of the query:

{ "errors": [ { "message": "Schema does not define the required query root type.", "extensions": { "category": "graphql" }, "locations": [ { "line": 1, "column": 1 } ] } ] }

@digitaldan
Copy link
Contributor

can you post a screen shot ? Does the left panel show any red, like its malformed ?

@digitaldan
Copy link
Contributor

also make sure these are selected

image

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Ok, here is what you want, no red on the left side

image

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

image

@digitaldan
Copy link
Contributor

hmm, so odd, does your system show online when you login to the webportal?

image

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Yes both Hydrawise Controller are online and attached to the WLAN

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

image

@digitaldan
Copy link
Contributor

can you try removing sections in the GraphQL query and run again? I would start by removing forecast first

forecast(days: 3) {
...
}

then if that still breaks, remove zones, then sensors? I'm trying to isolate this to a specific part of the query thats breaking it for your account, I'm not sure what they don't like in it.

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

I reduced the forecast up to this

forecast(days: 3) { time }

Still errors, only if i take it completly out, then it works

@digitaldan
Copy link
Contributor

Ahhh, ok , thanks for doing that. i figured the broken promise on their end was trying to hit some 3rd party service. Let me think how i can fix this and still keep weather for the users where it works.

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Does the forecast works on your implementation? Where could i find the docu, on the hunter site is only an email address to ask questions?

@digitaldan
Copy link
Contributor

It does, and it works for others, but the API is not supported by them for 3rd party use, so i would not go asking questions and bringing attention to our binding

@digitaldan
Copy link
Contributor

out of curiosity, if you go back to the original query and replace the forecast(days: 3) with forecast(days: 0) , and leave the rest of the body intact, does it work ?

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

Dan, it give an error but the rest of the information seems to be ok.

image

@digitaldan
Copy link
Contributor

Gotcha, thanks. If i have to i might make the weather part an option of the controller thing, so it can be disabled, although i think its still odd the query causes their backend to generate an error like that. If this was a public API i would open a bug ticket up, but since its not, will just have to work around it.

@clown-m
Copy link
Author

clown-m commented Mar 24, 2024

May be they do not offer the forecast feature for germany/europe?
In your old version the forecast did not have values and i did not set them up, but it does not give me an error (just for your info).
I do not really need the informations, i have a private weather station and send these data to weather underground and wu send the data to Hydrawise, so my data will end in my controller at the end.

@digitaldan
Copy link
Contributor

May be they do not offer the forecast feature for germany/europe?

Thats my suspicion too, but a GraphQL service should be returning an empty result when no results are available, not an internal error (looks like nodejs maybe?) which is definitely a bug on their end . I'm going to probably send a test GraphQL request to test if weather is supported and drop it if its not. Hopefully its not too much work.

@clown-m
Copy link
Author

clown-m commented Mar 25, 2024

Thanks for your support i really appreciate your work.

@clown-m
Copy link
Author

clown-m commented Mar 25, 2024

If you need someone fpr testing, don't hesitate to ask, i am interested in your solution and using the software.

@DenDeXTeR51
Copy link

Same problem on Account thing - Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}

@clown-m
Copy link
Author

clown-m commented Mar 26, 2024

Where do you live? We think that Hydrawise did not offer the "forecast" in all countries, the problem seems to be, that Hydrawise answer this by an error message.

@DenDeXTeR51
Copy link

DenDeXTeR51 commented Mar 26, 2024

Ukraine, forecast always work for me, so I dont know why its broken now... At HydraWise.com web-ui I see forecast and everything seems ok

@DenDeXTeR51
Copy link

@digitaldan Can you add to Binding configuration option "Do not load forecasts" if this solves issue? At least we will have working zone triggers....

@digitaldan
Copy link
Contributor

Can you add to Binding configuration option

As i mentioned above, I'm going to detect if weather is supported in the binding, there will not be a need for manual configuration.

Ukraine, forecast always work for me,

My guess is they use multiple weather providers depending on the region, and the code that does this is broken in the graphql endpoint for parts of Europe. It's likely they will fix this at some point, but until then we will just work around it.

@clown-m
Copy link
Author

clown-m commented Mar 26, 2024

Dan, i have seen in the answer message that the forecast ist category "business", so may be this is only available for the commercial version?

image

@digitaldan
Copy link
Contributor

Good find, but i'm not on a commercial system, so i don't think thats it. Also i think this must have broken recently due to a change on their side, so i think this is just a bug we have to work around.

@DenDeXTeR51
Copy link

I send message to HydraWise API Support (hunterindustries.com) with link to our github discussion, hope they can help...

@digitaldan
Copy link
Contributor

Hey @DenDeXTeR51 let me handle this, maybe read the rest of the thread for context.

@DenDeXTeR51
Copy link

@digitaldan Ok, Summer Season soon will start so hope new version of binding will be soon... Thanks!

@DenDeXTeR51
Copy link

@digitaldan Any news?

@digitaldan
Copy link
Contributor

I have an updated version you can try, you can quickly update the binding from the OH CLI like this:

bundle:update org.openhab.binding.hydrawise https://github.com/digitaldan/openhab-addons/releases/download/hydrawise-weather-fix/org.openhab.binding.hydrawise-4.2.0-SNAPSHOT.jar

Or you can uninstall the old binding and drop the following in your addons folder:

https://github.com/digitaldan/openhab-addons/releases/download/hydrawise-weather-fix/org.openhab.binding.hydrawise-4.2.0-SNAPSHOT.jar

I recommend using the first approach.

Make sure to have DEBUG or TRACE logging enabled in case this does not work.

@clown-m
Copy link
Author

clown-m commented Apr 8, 2024

Hi Dan, thanks for the new version, is this compatible to version 4.1.2, i am still on the stable version?

@DenDeXTeR51
Copy link

DenDeXTeR51 commented Apr 8, 2024

Hi @digitaldan .

Strange results... I update via karaf and get:

2024-04-08 11:16:18.837 [WARN ] [org.eclipse.jetty.server.HttpChannel] - /rest/things/hydrawise:account:8cc104a03a/enable
org.apache.cxf.interceptor.Fault: org/openhab/core/config/discovery/AbstractThingHandlerDiscoveryService
	at org.apache.cxf.service.invoker.AbstractInvoker.createFault([AbstractInvoker.java:162](http://abstractinvoker.java:162/)) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke([AbstractInvoker.java:128](http://abstractinvoker.java:128/)) ~[?:?]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke([JAXRSInvoker.java:201](http://jaxrsinvoker.java:201/)) ~[?:?]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke([JAXRSInvoker.java:104](http://jaxrsinvoker.java:104/)) ~[?:?]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor$[1.run(ServiceInvokerInterceptor.java:59)](http://1.run(ServiceInvokerInterceptor.java:59)) ~[?:?]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage([ServiceInvokerInterceptor.java:96](http://serviceinvokerinterceptor.java:96/)) ~[?:?]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept([PhaseInterceptorChain.java:307](http://phaseinterceptorchain.java:307/)) ~[?:?]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage([ChainInitiationObserver.java:121](http://chaininitiationobserver.java:121/)) ~[?:?]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke([AbstractHTTPDestination.java:265](http://abstracthttpdestination.java:265/)) ~[?:?]
	at org.apache.cxf.transport.servlet.ServletController.invokeDestination([ServletController.java:234](http://servletcontroller.java:234/)) ~[?:?]
	at org.apache.cxf.transport.servlet.ServletController.invoke([ServletController.java:208](http://servletcontroller.java:208/)) ~[?:?]
	at org.apache.cxf.transport.servlet.ServletController.invoke([ServletController.java:160](http://servletcontroller.java:160/)) ~[?:?]
	at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke([CXFNonSpringServlet.java:225](http://cxfnonspringservlet.java:225/)) ~[?:?]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest([AbstractHTTPServlet.java:304](http://abstracthttpservlet.java:304/)) ~[?:?]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doPut([AbstractHTTPServlet.java:234](http://abstracthttpservlet.java:234/)) ~[?:?]
	at javax.servlet.http.HttpServlet.service([HttpServlet.java:520](http://httpservlet.java:520/)) ~[bundleFile:4.0.4]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service([AbstractHTTPServlet.java:279](http://abstracthttpservlet.java:279/)) ~[?:?]
	at org.ops4j.pax.web.service.spi.servlet.OsgiInitializedServlet.service([OsgiInitializedServlet.java:102](http://osgiinitializedservlet.java:102/)) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHolder.handle([ServletHolder.java:799](http://servletholder.java:799/)) ~[?:?]
	at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter([ServletHandler.java:1656](http://servlethandler.java:1656/)) ~[?:?]
	at org.ops4j.pax.web.service.spi.servlet.OsgiFilterChain.doFilter([OsgiFilterChain.java:100](http://osgifilterchain.java:100/)) ~[?:?]
	at org.ops4j.pax.web.service.jetty.internal.PaxWebServletHandler.doHandle([PaxWebServletHandler.java:320](http://paxwebservlethandler.java:320/)) ~[?:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle([ScopedHandler.java:143](http://scopedhandler.java:143/)) ~[bundleFile:9.4.52.v20230823]
	at [org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)](http://org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)) ~[?:?]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle([HandlerWrapper.java:127](http://handlerwrapper.java:127/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle([ScopedHandler.java:235](http://scopedhandler.java:235/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle([SessionHandler.java:1624](http://sessionhandler.java:1624/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle([ScopedHandler.java:233](http://scopedhandler.java:233/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle([ContextHandler.java:1440](http://contexthandler.java:1440/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope([ScopedHandler.java:188](http://scopedhandler.java:188/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.servlet.ServletHandler.doScope([ServletHandler.java:505](http://servlethandler.java:505/)) ~[?:?]
	at org.eclipse.jetty.server.session.SessionHandler.doScope([SessionHandler.java:1594](http://sessionhandler.java:1594/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope([ScopedHandler.java:186](http://scopedhandler.java:186/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope([ContextHandler.java:1355](http://contexthandler.java:1355/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle([ScopedHandler.java:141](http://scopedhandler.java:141/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle([ContextHandlerCollection.java:234](http://contexthandlercollection.java:234/)) ~[bundleFile:9.4.52.v20230823]
	at org.ops4j.pax.web.service.jetty.internal.PrioritizedHandlerCollection.handle([PrioritizedHandlerCollection.java:96](http://prioritizedhandlercollection.java:96/)) ~[?:?]
	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle([GzipHandler.java:722](http://gziphandler.java:722/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle([HandlerWrapper.java:127](http://handlerwrapper.java:127/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.Server.handle([Server.java:516](http://server.java:516/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1([HttpChannel.java:487](http://httpchannel.java:487/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.dispatch([HttpChannel.java:732](http://httpchannel.java:732/)) ~[bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpChannel.handle([HttpChannel.java:479](http://httpchannel.java:479/)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.server.HttpConnection.onFillable([HttpConnection.java:277](http://httpconnection.java:277/)) [bundleFile:9.4.52.v20230823]
	at [org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)](http://org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)) [bundleFile:9.4.52.v20230823]
	at [org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)](http://org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)) [bundleFile:9.4.52.v20230823]
	at [org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)](http://org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask([EatWhatYouKill.java:338](http://eatwhatyoukill.java:338/)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce([EatWhatYouKill.java:315](http://eatwhatyoukill.java:315/)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce([EatWhatYouKill.java:173](http://eatwhatyoukill.java:173/)) [bundleFile:9.4.52.v20230823]
	at [org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)](http://org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$[ReservedThread.run(ReservedThreadExecutor.java:409)](http://ReservedThread.run(ReservedThreadExecutor.java:409)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob([QueuedThreadPool.java:883](http://queuedthreadpool.java:883/)) [bundleFile:9.4.52.v20230823]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$[Runner.run(QueuedThreadPool.java:1034)](http://Runner.run(QueuedThreadPool.java:1034)) [bundleFile:9.4.52.v20230823]
	at [java.lang.Thread.run(Thread.java:840)](http://java.lang.Thread.run(Thread.java:840)) [?:?]
Caused by: java.lang.NoClassDefFoundError: org/openhab/core/config/discovery/AbstractThingHandlerDiscoveryService
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.getServices([HydrawiseAccountHandler.java:129](http://hydrawiseaccounthandler.java:129/)) ~[?:?]
	at org.openhab.core.thing.binding.BaseThingHandlerFactory.registerServices([BaseThingHandlerFactory.java:146](http://basethinghandlerfactory.java:146/)) ~[?:?]
	at org.openhab.core.thing.binding.BaseThingHandlerFactory.registerHandler([BaseThingHandlerFactory.java:140](http://basethinghandlerfactory.java:140/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.doRegisterHandler([ThingManagerImpl.java:531](http://thingmanagerimpl.java:531/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.registerHandler([ThingManagerImpl.java:512](http://thingmanagerimpl.java:512/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.registerAndInitializeHandler([ThingManagerImpl.java:927](http://thingmanagerimpl.java:927/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.setEnabled([ThingManagerImpl.java:1009](http://thingmanagerimpl.java:1009/)) ~[?:?]
	at [org.openhab.core.io.rest.core.internal.thing.ThingResource.setEnabled(ThingResource.java:609)](http://org.openhab.core.io.rest.core.internal.thing.ThingResource.setEnabled(ThingResource.java:609)) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke([NativeMethodAccessorImpl.java:77](http://nativemethodaccessorimpl.java:77/)) ~[?:?]
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke([DelegatingMethodAccessorImpl.java:43](http://delegatingmethodaccessorimpl.java:43/)) ~[?:?]
	at java.lang.reflect.Method.invoke([Method.java:568](http://method.java:568/)) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation([AbstractInvoker.java:179](http://abstractinvoker.java:179/)) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke([AbstractInvoker.java:96](http://abstractinvoker.java:96/)) ~[?:?]
	... 53 more
Caused by: java.lang.ClassNotFoundException: org.openhab.core.config.discovery.AbstractThingHandlerDiscoveryService cannot be found by org.openhab.binding.hydrawise_4.2.0.202404072314
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.getServices([HydrawiseAccountHandler.java:129](http://hydrawiseaccounthandler.java:129/)) ~[?:?]
	at org.openhab.core.thing.binding.BaseThingHandlerFactory.registerServices([BaseThingHandlerFactory.java:146](http://basethinghandlerfactory.java:146/)) ~[?:?]
	at org.openhab.core.thing.binding.BaseThingHandlerFactory.registerHandler([BaseThingHandlerFactory.java:140](http://basethinghandlerfactory.java:140/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.doRegisterHandler([ThingManagerImpl.java:531](http://thingmanagerimpl.java:531/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.registerHandler([ThingManagerImpl.java:512](http://thingmanagerimpl.java:512/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.registerAndInitializeHandler([ThingManagerImpl.java:927](http://thingmanagerimpl.java:927/)) ~[?:?]
	at org.openhab.core.thing.internal.ThingManagerImpl.setEnabled([ThingManagerImpl.java:1009](http://thingmanagerimpl.java:1009/)) ~[?:?]
	at [org.openhab.core.io.rest.core.internal.thing.ThingResource.setEnabled(ThingResource.java:609)](http://org.openhab.core.io.rest.core.internal.thing.ThingResource.setEnabled(ThingResource.java:609)) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke([NativeMethodAccessorImpl.java:77](http://nativemethodaccessorimpl.java:77/)) ~[?:?]
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke([DelegatingMethodAccessorImpl.java:43](http://delegatingmethodaccessorimpl.java:43/)) ~[?:?]
	at java.lang.reflect.Method.invoke([Method.java:568](http://method.java:568/)) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation([AbstractInvoker.java:179](http://abstractinvoker.java:179/)) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke([AbstractInvoker.java:96](http://abstractinvoker.java:96/)) ~[?:?]
	... 53 more

After Update from openhab 4.1.1 to 4.1.2 and reboot

Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}

@digitaldan
Copy link
Contributor

I'll build a 4.1 version and post back here.

@clown-m
Copy link
Author

clown-m commented Apr 8, 2024

Thanks, tell me if you are ready with and i will test it ...

@digitaldan
Copy link
Contributor

digitaldan commented Apr 8, 2024

ok new jar is at

https://github.com/digitaldan/openhab-addons/releases/download/hydrawise-weather-fix/org.openhab.binding.hydrawise-4.1.3-SNAPSHOT.jar

bundle:update org.openhab.binding.hydrawise https://github.com/digitaldan/openhab-addons/releases/download/hydrawise-weather-fix/org.openhab.binding.hydrawise-4.1.3-SNAPSHOT.jar

@clown-m
Copy link
Author

clown-m commented Apr 9, 2024

Ok, still problems, i loaded it and restarted, it get once the information and then comes back to the old error message:

Console output:
1 │ Resolved │ 80 │ 4.1.3.202404082241 │ openHAB Add-ons :: Bundles :: Hydrawise Binding

Status:
UNINITIALIZED
HANDLER_MISSING_ERROR
Handler factory not found

COMMUNICATION_ERROR
Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}

Log File

2024-04-09 09:22:34.221 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2024-04-09 09:22:34.259 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : BundleComponentActivator : ComponentHolder created.
2024-04-09 09:22:34.263 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Dependency Manager created $000interface=org.openhab.core.io.net.http.HttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=0
2024-04-09 09:22:34.276 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Dependency Manager created $001interface=org.openhab.core.auth.client.oauth2.OAuthFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=service, parameter=1
2024-04-09 09:22:34.278 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-04-09 09:22:34.279 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.hydrawise]
2024-04-09 09:22:34.281 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2024-04-09 09:22:34.286 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-04-09 09:22:34.287 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Querying state disabled
2024-04-09 09:22:34.290 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Querying state disabled
2024-04-09 09:22:34.292 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Component can not be activated since it is in state disabled
2024-04-09 09:22:34.294 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory] : Querying state disabled
2024-04-09 09:22:34.296 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Updating target filters
2024-04-09 09:22:34.310 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : No change in target property for dependency $000: currently registered: false
2024-04-09 09:22:34.313 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] :  No existing service listener to unregister for dependency $000
2024-04-09 09:22:34.319 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Setting target property for dependency $000 to null
2024-04-09 09:22:34.329 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.io.net.http.HttpClientFactory), initialReferenceFilter (objectClass=org.openhab.core.io.net.http.HttpClientFactory)
2024-04-09 09:22:34.331 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $000 tracker reset (closed)
2024-04-09 09:22:34.370 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=285, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159} (enter)
2024-04-09 09:22:34.373 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2024-04-09 09:22:34.409 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $000 tracking 1 SingleStatic added {org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=285, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159} (exit)
2024-04-09 09:22:34.430 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $000 tracker opened
2024-04-09 09:22:34.453 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : registering service listener for dependency $000
2024-04-09 09:22:34.484 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : No change in target property for dependency $001: currently registered: false
2024-04-09 09:22:34.501 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] :  No existing service listener to unregister for dependency $001
2024-04-09 09:22:34.506 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Setting target property for dependency $001 to null
2024-04-09 09:22:34.534 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : New service tracker for $001, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory), initialReferenceFilter (objectClass=org.openhab.core.auth.client.oauth2.OAuthFactory)
2024-04-09 09:22:34.549 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $001 tracker reset (closed)
2024-04-09 09:22:34.554 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=370, service.bundleid=160, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=67} (enter)
2024-04-09 09:22:34.558 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $001 tracking 2 SingleStatic active: false trackerOpened: false optional: false
2024-04-09 09:22:34.559 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $001 tracking 2 SingleStatic added {org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=370, service.bundleid=160, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=67} (exit)
2024-04-09 09:22:34.564 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm $001 tracker opened
2024-04-09 09:22:34.565 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : registering service listener for dependency $001
2024-04-09 09:22:34.566 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-04-09 09:22:34.573 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-04-09 09:22:34.596 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-04-09 09:22:34.599 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-04-09 09:22:34.606 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-04-09 09:22:34.612 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm osgi.ds.satisfying.condition tracking 3 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-04-09 09:22:34.629 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : dm osgi.ds.satisfying.condition tracker opened
2024-04-09 09:22:34.631 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-04-09 09:22:34.632 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Changed state from disabled to unsatisfiedReference
2024-04-09 09:22:34.632 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Component enabled
2024-04-09 09:22:34.633 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : ActivateInternal
2024-04-09 09:22:34.645 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.646 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.647 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Activating component from state unsatisfiedReference
2024-04-09 09:22:34.647 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.659 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.660 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Changed state from unsatisfiedReference to satisfied
2024-04-09 09:22:34.661 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : registration change queue [registered]
2024-04-09 09:22:34.666 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Checking constructor public org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.auth.client.oauth2.OAuthFactory)
2024-04-09 09:22:34.669 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.io.net.http.HttpClientFactory through loader of org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory
2024-04-09 09:22:34.670 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getClassFromComponentClassLoader: Found class org.openhab.core.io.net.http.HttpClientFactory
2024-04-09 09:22:34.683 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getClassFromComponentClassLoader: Looking for interface class org.openhab.core.auth.client.oauth2.OAuthFactory through loader of org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory
2024-04-09 09:22:34.688 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getClassFromComponentClassLoader: Found class org.openhab.core.auth.client.oauth2.OAuthFactory
2024-04-09 09:22:34.692 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Found constructor with 2 arguments : public org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(org.openhab.core.io.net.http.HttpClientFactory,org.openhab.core.auth.client.oauth2.OAuthFactory)
2024-04-09 09:22:34.696 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : This thread collected dependencies
2024-04-09 09:22:34.698 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getService (single component manager) dependencies collected.
2024-04-09 09:22:34.698 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state satisfied
2024-04-09 09:22:34.700 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Querying state satisfied
2024-04-09 09:22:34.701 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.io.net.http.HttpClientFactory, org.openhab.core.io.net.http.WebSocketFactory}={service.id=285, service.bundleid=180, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.io.net.http.internal.WebClientFactoryImpl, component.id=159}] service: [null]]]
2024-04-09 09:22:34.706 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : For dependency $001, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.auth.client.oauth2.OAuthFactory}={service.id=370, service.bundleid=160, service.scope=bundle, osgi.ds.satisfying.condition.target=(osgi.condition.id=true), component.name=org.openhab.core.auth.oauth2client.internal.OAuthFactoryImpl, component.id=67}] service: [null]]]
2024-04-09 09:22:34.709 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : For dependency osgi.ds.satisfying.condition, optional: false; to bind: [[RefPair: ref: [{org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true}] service: [null]]]
2024-04-09 09:22:34.722 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : getting activate: activate
2024-04-09 09:22:34.727 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Locating method activate in class org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory
2024-04-09 09:22:34.730 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Declared Method org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2024-04-09 09:22:34.732 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2024-04-09 09:22:34.735 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2024-04-09 09:22:34.736 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2024-04-09 09:22:34.743 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : invoked activate: activate
2024-04-09 09:22:34.745 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Set implementation object for component
2024-04-09 09:22:34.752 [DEBUG] [ise.internal.HydrawiseHandlerFactory] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.HydrawiseHandlerFactory(404)] : Changed state from satisfied to active
2024-04-09 09:22:34.775 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Dependency Manager created osgi.ds.satisfying.conditioninterface=org.osgi.service.condition.Condition, filter=(osgi.condition.id=true), policy=dynamic, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2024-04-09 09:22:34.781 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Component created: DS=DS13, implementation=org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService]
2024-04-09 09:22:34.782 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerService]
2024-04-09 09:22:34.785 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Component Properties: {osgi.ds.satisfying.condition.target=(osgi.condition.id=true)}
2024-04-09 09:22:34.809 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Querying state disabled
2024-04-09 09:22:34.810 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Querying state disabled
2024-04-09 09:22:34.811 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Component can not be activated since it is in state disabled
2024-04-09 09:22:34.812 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService] : Querying state disabled
2024-04-09 09:22:34.827 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Updating target filters
2024-04-09 09:22:34.831 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] :  No existing service listener to unregister for dependency osgi.ds.satisfying.condition
2024-04-09 09:22:34.835 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Setting target property for dependency osgi.ds.satisfying.condition to (osgi.condition.id=true)
2024-04-09 09:22:34.839 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : New service tracker for osgi.ds.satisfying.condition, initial active: false, previous references: {}, classFilter: (objectClass=org.osgi.service.condition.Condition), initialReferenceFilter (&(objectClass=org.osgi.service.condition.Condition)(osgi.condition.id=true))
2024-04-09 09:22:34.840 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : dm osgi.ds.satisfying.condition tracker reset (closed)
2024-04-09 09:22:34.843 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (enter)
2024-04-09 09:22:34.846 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : dm osgi.ds.satisfying.condition tracking 1 SingleDynamic added {org.osgi.service.condition.Condition}={service.id=6, service.bundleid=0, service.scope=singleton, service.pid=0.org.osgi.service.condition.ConditionImpl, osgi.condition.id=true} (exit)
2024-04-09 09:22:34.849 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : dm osgi.ds.satisfying.condition tracker opened
2024-04-09 09:22:34.851 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : registering service listener for dependency osgi.ds.satisfying.condition
2024-04-09 09:22:34.853 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Changed state from disabled to unsatisfiedReference
2024-04-09 09:22:34.856 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Component enabled
2024-04-09 09:22:34.858 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : ActivateInternal
2024-04-09 09:22:34.864 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.865 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.866 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Activating component from state unsatisfiedReference
2024-04-09 09:22:34.867 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.870 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Querying state unsatisfiedReference
2024-04-09 09:22:34.871 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : Changed state from unsatisfiedReference to satisfied
2024-04-09 09:22:34.873 [DEBUG] [awiseCloudControllerDiscoveryService] - bundle org.openhab.binding.hydrawise:4.1.3.202404082241 (291)[org.openhab.binding.hydrawise.internal.discovery.HydrawiseCloudControllerDiscoveryService(405)] : registration change queue [registered]
2024-04-09 09:22:35.601 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Handler initialized.
2024-04-09 09:22:37.234 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Sending Request: {"query":"{\n  me {\n    email\n    lastContact\n      controllers {\n      location {\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    }\n  }\n}"}
2024-04-09 09:22:38.209 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"me":{"email":"familie@gruth.net","lastContact":"Wed, 03 Apr 24 19:16:42 +0000","controllers":[{"location":{"forecast":[{"time":"Tue, 09 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Bew\u00f6lkt","averageWindSpeed":{"value":22,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":10,"unit":"\u00b0C"},"probabilityOfPrecipitation":35,"precipitation":{"value":0.5,"unit":"mm"},"averageHumidity":63},{"time":"Wed, 10 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":14,"unit":"km\/h"},"highTemperature":{"value":15,"unit":"\u00b0C"},"lowTemperature":{"value":6,"unit":"\u00b0C"},"probabilityOfPrecipitation":9,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":60},{"time":"Thu, 11 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":5,"unit":"\u00b0C"},"probabilityOfPrecipitation":6,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":64}]}},{"location":{"forecast":[{"time":"Tue, 09 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Bew\u00f6lkt","averageWindSpeed":{"value":22,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":11,"unit":"\u00b0C"},"probabilityOfPrecipitation":23,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":63},{"time":"Wed, 10 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":14,"unit":"\u00b0C"},"lowTemperature":{"value":7,"unit":"\u00b0C"},"probabilityOfPrecipitation":9,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":61},{"time":"Thu, 11 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Gr\u00f6\u00dftenteils bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":4,"unit":"\u00b0C"},"probabilityOfPrecipitation":7,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":67}]}}]}}}
2024-04-09 09:22:38.249 [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}"}
2024-04-09 09:22:38.730 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-04-09 09:22:38.742 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Retrying failed poll
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-04-09 09:22:38.760 [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}"}
2024-04-09 09:22:39.124 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-04-09 09:22:39.125 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-04-09 09:22:43.046 [ERROR] [internal.handler.ScriptActionHandler] - Script execution of rule with UID 'my-1' failed: 'floatValue' is not a member of 'null'; line 51, column 65, length 47 in my
2024-04-09 09:22:52.388 [INFO ] [org.openhab.core.model.script.Rule  ] - Cistern update triggered by startup
2024-04-09 09:23:39.128 [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}"}
2024-04-09 09:23:40.126 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-04-09 09:23:40.129 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Retrying failed poll
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-04-09 09:23:40.139 [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}"}
2024-04-09 09:23:40.501 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-04-09 09:23:40.501 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
2024-04-09 09:24:40.504 [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}"}
2024-04-09 09:24:40.507 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - onFailure code: 0 message: null
2024-04-09 09:24:40.511 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - ExecutionException
java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@350e6475::DecryptedEndPoint@1a548dd2{l=/192.168.1.103:55034,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60006/0}
        at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[?:?]
        at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:101) ~[?:?]
        at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:732) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:337) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.EOFException: HttpConnectionOverHTTP@350e6475::DecryptedEndPoint@1a548dd2{l=/192.168.1.103:55034,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60006/0}
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:385) ~[?:?]
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1621) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:269) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:185) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) ~[?:?]
        at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
        at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
        at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[?:?]
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[?:?]
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) ~[?:?]
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) ~[?:?]
        ... 1 more
2024-04-09 09:24:40.521 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - ExecutionException 0 null
2024-04-09 09:24:40.522 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Retrying failed poll
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@350e6475::DecryptedEndPoint@1a548dd2{l=/192.168.1.103:55034,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60006/0}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:362) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@350e6475::DecryptedEndPoint@1a548dd2{l=/192.168.1.103:55034,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60006/0}
        at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[?:?]
        at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:101) ~[?:?]
        at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:732) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:337) ~[?:?]
        ... 10 more
Caused by: java.io.EOFException: HttpConnectionOverHTTP@350e6475::DecryptedEndPoint@1a548dd2{l=/192.168.1.103:55034,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60006/0}
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:385) ~[?:?]
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1621) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:269) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:185) ~[?:?]
        at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) ~[?:?]
        at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
        at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
        at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
        at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[?:?]
        at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[?:?]
        at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[?:?]
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) ~[?:?]
        at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) ~[?:?]
        ... 1 more
2024-04-09 09:24:40.530 [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}"}
2024-04-09 09:24:41.551 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"errors":{"message":"Could not resolve promise"}}
2024-04-09 09:24:41.551 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
        at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
        at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
        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:1136) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
        at java.lang.Thread.run(Thread.java:840) [?:?]

@DenDeXTeR51
Copy link

DenDeXTeR51 commented Apr 9, 2024

New logs after update jar

2024-04-09 10:34:14.400 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - ExecutionException
java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@2d7e3bb::DecryptedEndPoint@5bb130fe{l=/192.168.51.15:37232,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60002/0}
	at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[?:?]
	at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:101) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:732) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:337) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.io.EOFException: HttpConnectionOverHTTP@2d7e3bb::DecryptedEndPoint@5bb130fe{l=/192.168.51.15:37232,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60002/0}
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:385) ~[?:?]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1621) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:269) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:185) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) ~[?:?]
	at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
	at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[?:?]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) ~[?:?]
	... 1 more
2024-04-09 10:34:14.434 [DEBUG] [l.api.graphql.HydrawiseGraphQLClient] - ExecutionException 0 null
2024-04-09 10:34:14.435 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Retrying failed poll
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@2d7e3bb::DecryptedEndPoint@5bb130fe{l=/192.168.51.15:37232,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60002/0}
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:362) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]
Caused by: java.util.concurrent.ExecutionException: java.io.EOFException: HttpConnectionOverHTTP@2d7e3bb::DecryptedEndPoint@5bb130fe{l=/192.168.51.15:37232,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60002/0}
	at org.eclipse.jetty.client.util.FutureResponseListener.getResult(FutureResponseListener.java:118) ~[?:?]
	at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:101) ~[?:?]
	at org.eclipse.jetty.client.HttpRequest.send(HttpRequest.java:732) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:337) ~[?:?]
	... 10 more
Caused by: java.io.EOFException: HttpConnectionOverHTTP@2d7e3bb::DecryptedEndPoint@5bb130fe{l=/192.168.51.15:37232,r=app.hydrawise.com/34.209.70.34:443,OPEN,fill=-,flush=-,to=60002/0}
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.earlyEOF(HttpReceiverOverHTTP.java:385) ~[?:?]
	at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:1621) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.shutdown(HttpReceiverOverHTTP.java:269) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.process(HttpReceiverOverHTTP.java:185) ~[?:?]
	at org.eclipse.jetty.client.http.HttpReceiverOverHTTP.receive(HttpReceiverOverHTTP.java:80) ~[?:?]
	at org.eclipse.jetty.client.http.HttpChannelOverHTTP.receive(HttpChannelOverHTTP.java:131) ~[?:?]
	at org.eclipse.jetty.client.http.HttpConnectionOverHTTP.onFillable(HttpConnectionOverHTTP.java:172) ~[?:?]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[?:?]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410) ~[?:?]
	at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164) ~[?:?]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[?:?]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[?:?]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[?:?]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) ~[?:?]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) ~[?:?]
	... 1 more
2024-04-09 10:34:15.419 [DEBUG] [rnal.handler.HydrawiseAccountHandler] - Will try again during next poll period
org.openhab.binding.hydrawise.internal.api.HydrawiseConnectionException: Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLRequest(HydrawiseGraphQLClient.java:342) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.sendGraphQLQuery(HydrawiseGraphQLClient.java:288) ~[?:?]
	at org.openhab.binding.hydrawise.internal.api.graphql.HydrawiseGraphQLClient.queryControllers(HydrawiseGraphQLClient.java:115) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:232) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:252) ~[?:?]
	at org.openhab.binding.hydrawise.internal.handler.HydrawiseAccountHandler.poll(HydrawiseAccountHandler.java:227) ~[?:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	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:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:840) [?:?]

@digitaldan
Copy link
Contributor

@clown-m , well thats interesting, you can see that when i am sending just a request for weather data , and nothing else it actually works and we get weather data for your region.

2024-04-09 09:22:38.209 [TRACE] [l.api.graphql.HydrawiseGraphQLClient] - Received Response: {"data":{"me":{"email":"*****","lastContact":"Wed, 03 Apr 24 19:16:42 +0000","controllers":[{"location":{"forecast":[{"time":"Tue, 09 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Bew\u00f6lkt","averageWindSpeed":{"value":22,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":10,"unit":"\u00b0C"},"probabilityOfPrecipitation":35,"precipitation":{"value":0.5,"unit":"mm"},"averageHumidity":63},{"time":"Wed, 10 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":14,"unit":"km\/h"},"highTemperature":{"value":15,"unit":"\u00b0C"},"lowTemperature":{"value":6,"unit":"\u00b0C"},"probabilityOfPrecipitation":9,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":60},{"time":"Thu, 11 Apr 24 00:00:00 +0200","updateTime":"Tue, 09 Apr 24 02:15:23 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":5,"unit":"\u00b0C"},"probabilityOfPrecipitation":6,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":64}]}},{"location":{"forecast":[{"time":"Tue, 09 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Bew\u00f6lkt","averageWindSpeed":{"value":22,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":11,"unit":"\u00b0C"},"probabilityOfPrecipitation":23,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":63},{"time":"Wed, 10 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Teilweise bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":14,"unit":"\u00b0C"},"lowTemperature":{"value":7,"unit":"\u00b0C"},"probabilityOfPrecipitation":9,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":61},{"time":"Thu, 11 Apr 24 00:00:00 +0200","updateTime":"Mon, 08 Apr 24 15:14:36 +0200","conditions":"Gr\u00f6\u00dftenteils bew\u00f6lkt","averageWindSpeed":{"value":11,"unit":"km\/h"},"highTemperature":{"value":17,"unit":"\u00b0C"},"lowTemperature":{"value":4,"unit":"\u00b0C"},"probabilityOfPrecipitation":7,"precipitation":{"value":0,"unit":"mm"},"averageHumidity":67}]}}]}}}

So then my code assumes weather is supported and uses the full GraphQL query with weather, which then of course fails. Sounds like its some kind of race condition in their code. I'll update the jar here today to take this into account.

@clown-m
Copy link
Author

clown-m commented Apr 10, 2024

@digitaldan: Did you make further progress in your evaluations?

@digitaldan
Copy link
Contributor

I just updated the 4.1 version, its the same link, so

bundle:update org.openhab.binding.hydrawise https://github.com/digitaldan/openhab-addons/releases/download/hydrawise-weather-fix/org.openhab.binding.hydrawise-4.1.3-SNAPSHOT.jar

I have not had a chance to test yet , so 🤞

@clown-m
Copy link
Author

clown-m commented Apr 11, 2024

Looks much better, as expected, the forecast is all "NULL".

Thanks, will try more test in the next days. It's getting back pretty cool, so no time for sprinkling.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug An unexpected problem or unintended behavior of an add-on
Projects
None yet
Development

No branches or pull requests

3 participants