search

openhab / openhab-addons

Add-ons for openHAB
https://www.openhab.org/
Eclipse Public License 2.0
1.88k stars 3.59k forks source link

[hydrawise] Communication error #16570

Closed clown-m closed 2 months ago

clown-m commented 7 months ago

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?

digitaldan commented 7 months ago

{"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.

clown-m commented 7 months ago

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

clown-m commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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

digitaldan commented 7 months ago

also make sure these are selected

image
clown-m commented 7 months ago

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

image

clown-m commented 7 months ago

image

digitaldan commented 7 months ago

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

image
clown-m commented 7 months ago

Yes both Hydrawise Controller are online and attached to the WLAN

clown-m commented 7 months ago

image

digitaldan commented 7 months ago

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 commented 7 months ago

I reduced the forecast up to this

forecast(days: 3) { time }

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

digitaldan commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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

image

digitaldan commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

Thanks for your support i really appreciate your work.

clown-m commented 7 months ago

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

DenDeXTeR51 commented 7 months ago

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

clown-m commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

@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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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

digitaldan commented 7 months ago

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

DenDeXTeR51 commented 7 months ago

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

DenDeXTeR51 commented 7 months ago

@digitaldan Any news?

digitaldan commented 7 months ago

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 commented 7 months ago

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

DenDeXTeR51 commented 7 months ago

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 commented 7 months ago

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

clown-m commented 7 months ago

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

digitaldan commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

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 commented 7 months ago

@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 commented 6 months ago

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

digitaldan commented 6 months ago

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 commented 6 months ago

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.

DenDeXTeR51 commented 3 months ago

I update system to openhab 4.2.0 Get - Request failed with HTTP status code: 400 response: {"errors":{"message":"Could not resolve promise"}}