mksway / openhab-lgessenervu-dist

LGEssEnervu addon for openhab 3.x
1 stars 0 forks source link

Errors getNumericValueOfString empty String #2

Open meschmesch opened 1 year ago

meschmesch commented 1 year ago

I run openhab 3.4.4 and just installed the latest openhab-lgessenervu-dist-binding (3.4.3-SNAPSHOT). I get the following errors every 20 seconds: 2023-06-23 10:03:23.943 [ERROR] [ssenervu.internal.LGEssenervuHandler] - getNumericValueOfString empty String java.lang.NumberFormatException: empty String at jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1842) ~[?:?] at jdk.internal.math.FloatingDecimal.parseDouble(FloatingDecimal.java:110) ~[?:?] at java.lang.Double.parseDouble(Double.java:543) ~[?:?] at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.getNumericValueOfString(LGEssenervuHandler.java:322) [bundleFile:?] at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.responseCallbackCurrentData(LGEssenervuHandler.java:483) [bundleFile:?] at org.openhab.binding.lgessenervu.internal.client.LGLanClient.getCurrentData(LGLanClient.java:174) [bundleFile:?] at org.openhab.binding.lgessenervu.internal.job.SnapshotJob.run(SnapshotJob.java:35) [bundleFile:?] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] at java.lang.Thread.run(Thread.java:829) [?:?]

Uninstalling the binding and using the old version 3.1.0 solves the problem, there I have no errors.

mksway commented 1 year ago

Hi,

I am currently checking what might be the cause. Do you have a battery connected to your powerrouter? Do you see the new channels introduced in the newer version (and configuration parameters)? If not openhab maybe somehow stored old values. According to the stacktrace it fails reading the safetysoc data for some reason :( Though this should not be the case since it was present in 3.1.0 already.

I have reuploaded the bundle where this message is only visible @ debug level in order to get rid of the annoying message...

If you would be so kind and use the new .jar (https://github.com/mksway/openhab-lgessenervu-dist/blob/main/org.openhab.binding.lgessenervu-3.4.3-SNAPSHOT.jar) and turn the loglevel for this plugin to debug and post me the json response from the webservice it would be great.

Best

meschmesch commented 1 year ago

Yes, I do have a battery. Regarding the question of new channels - which one should I look for? Which is new? In the configuration, I see a "Powergeneration trigger". I think that one is new.

How do I access the json of the webservice? Which webservice?

Here is the debug:

`2023-06-25 14:47:15.544 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : BundleComponentActivator : ComponentHolder created.
2023-06-25 14:47:15.546 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : BundleComponentActivator : ComponentHolder created.
2023-06-25 14:47:15.550 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : BundleComponentActivator : ComponentHolder created.
2023-06-25 14:47:15.554 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Component created: DS=DS13, implementation=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl]
2023-06-25 14:47:15.556 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Component Services: scope=singleton, services=[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory]
2023-06-25 14:47:15.557 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Component Properties: {}
2023-06-25 14:47:15.558 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Querying state disabled
2023-06-25 14:47:15.559 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Querying state disabled
2023-06-25 14:47:15.560 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Component can not be activated since it is in state disabled
2023-06-25 14:47:15.561 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl] : Querying state disabled
2023-06-25 14:47:15.562 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Updating target filters
2023-06-25 14:47:15.563 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Changed state from disabled to unsatisfiedReference
2023-06-25 14:47:15.564 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Component enabled
2023-06-25 14:47:15.565 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : ActivateInternal
2023-06-25 14:47:15.566 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.567 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.567 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Activating component from state unsatisfiedReference
2023-06-25 14:47:15.569 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.569 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.570 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Changed state from unsatisfiedReference to satisfied
2023-06-25 14:47:15.571 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : registration change queue [registered]
2023-06-25 14:47:15.574 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Dependency Manager created $000interface=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=null, field-option=null, collection-type=null, parameter=0
2023-06-25 14:47:15.575 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Dependency Manager created CronSchedulerinterface=org.openhab.core.scheduler.CronScheduler, filter=null, policy=static, cardinality=1..1, bind=setCronScheduler, unbind=unsetCronScheduler, updated=null, field=null, field-option=null, collection-type=null, parameter=null
2023-06-25 14:47:15.576 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Component created: DS=DS14, implementation=org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[binding.lgessenervu]
2023-06-25 14:47:15.577 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Component Services: scope=singleton, services=[org.openhab.core.thing.binding.ThingHandlerFactory]
2023-06-25 14:47:15.577 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Component Properties: {}
2023-06-25 14:47:15.578 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Querying state disabled
2023-06-25 14:47:15.579 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Querying state disabled
2023-06-25 14:47:15.580 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Component can not be activated since it is in state disabled
2023-06-25 14:47:15.582 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory] : Querying state disabled
2023-06-25 14:47:15.582 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Updating target filters
2023-06-25 14:47:15.583 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : No change in target property for dependency $000: currently registered: false
2023-06-25 14:47:15.584 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] :  No existing service listener to unregister for dependency $000
2023-06-25 14:47:15.585 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Setting target property for dependency $000 to null
2023-06-25 14:47:15.586 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : New service tracker for $000, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory), initialReferenceFilter (objectClass=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory)
2023-06-25 14:47:15.587 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm $000 tracker reset (closed)
2023-06-25 14:47:15.591 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm $000 tracking 1 SingleStatic added {org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory}={service.id=794, service.bundleid=310, service.scope=bundle, component.name=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl, component.id=392} (enter)
2023-06-25 14:47:15.592 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm $000 tracking 1 SingleStatic active: false trackerOpened: false optional: false
2023-06-25 14:47:15.593 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm $000 tracking 1 SingleStatic added {org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory}={service.id=794, service.bundleid=310, service.scope=bundle, component.name=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl, component.id=392} (exit)
2023-06-25 14:47:15.593 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm $000 tracker opened
2023-06-25 14:47:15.594 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : registering service listener for dependency $000
2023-06-25 14:47:15.595 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : No change in target property for dependency CronScheduler: currently registered: false
2023-06-25 14:47:15.596 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] :  No existing service listener to unregister for dependency CronScheduler
2023-06-25 14:47:15.597 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Setting target property for dependency CronScheduler to null
2023-06-25 14:47:15.597 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : New service tracker for CronScheduler, initial active: false, previous references: {}, classFilter: (objectClass=org.openhab.core.scheduler.CronScheduler), initialReferenceFilter (objectClass=org.openhab.core.scheduler.CronScheduler)
2023-06-25 14:47:15.598 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm CronScheduler tracker reset (closed)
2023-06-25 14:47:15.600 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm CronScheduler tracking 2 SingleStatic added {org.openhab.core.scheduler.CronScheduler}={service.id=190, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.scheduler.CronSchedulerImpl, component.id=38} (enter)
2023-06-25 14:47:15.601 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm CronScheduler tracking 2 SingleStatic active: false trackerOpened: false optional: false
2023-06-25 14:47:15.602 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm CronScheduler tracking 2 SingleStatic added {org.openhab.core.scheduler.CronScheduler}={service.id=190, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.scheduler.CronSchedulerImpl, component.id=38} (exit)
2023-06-25 14:47:15.603 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : dm CronScheduler tracker opened
2023-06-25 14:47:15.603 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : registering service listener for dependency CronScheduler
2023-06-25 14:47:15.604 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Changed state from disabled to unsatisfiedReference
2023-06-25 14:47:15.605 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Component enabled
2023-06-25 14:47:15.606 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : ActivateInternal
2023-06-25 14:47:15.606 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.607 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.608 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Activating component from state unsatisfiedReference
2023-06-25 14:47:15.609 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.609 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.610 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Changed state from unsatisfiedReference to satisfied
2023-06-25 14:47:15.611 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : registration change queue [registered]
2023-06-25 14:47:15.615 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Checking constructor public org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory)
2023-06-25 14:47:15.616 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getReferenceClass: Looking for interface class org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory through loader of org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory
2023-06-25 14:47:15.617 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getParameterClass: Found class org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory
2023-06-25 14:47:15.617 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Found constructor with 1 arguments : public org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory)
2023-06-25 14:47:15.618 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getting bind: setCronScheduler
2023-06-25 14:47:15.619 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Locating method setCronScheduler in class org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory
2023-06-25 14:47:15.620 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : doFindMethod: Looking for method org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.setCronScheduler
2023-06-25 14:47:15.621 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Declared Method org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.setCronScheduler([interface org.osgi.framework.ServiceReference]) not found
2023-06-25 14:47:15.622 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Declared Method org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.setCronScheduler([interface org.osgi.service.component.ComponentServiceObjects]) not found
2023-06-25 14:47:15.623 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getReferenceClass: Looking for interface class org.openhab.core.scheduler.CronScheduler through loader of org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory
2023-06-25 14:47:15.625 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getParameterClass: Found class org.openhab.core.scheduler.CronScheduler
2023-06-25 14:47:15.626 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : doFindMethod: No method taking ServiceReference found, checking method taking org.openhab.core.scheduler.CronScheduler
2023-06-25 14:47:15.626 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : doFindMethod: Found Method protected void org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.setCronScheduler(org.openhab.core.scheduler.CronScheduler)
2023-06-25 14:47:15.627 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Found bind method: protected void org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.setCronScheduler(org.openhab.core.scheduler.CronScheduler)
2023-06-25 14:47:15.628 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : This thread collected dependencies
2023-06-25 14:47:15.628 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getService (single component manager) dependencies collected.
2023-06-25 14:47:15.629 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state satisfied
2023-06-25 14:47:15.630 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Querying state satisfied
2023-06-25 14:47:15.631 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : For dependency $000, optional: false; to bind: [[RefPair: ref: [{org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactory}={service.id=794, service.bundleid=310, service.scope=bundle, component.name=org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl, component.id=392}] service: [null]]]
2023-06-25 14:47:15.631 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : For dependency CronScheduler, optional: false; to bind: [[RefPair: ref: [{org.openhab.core.scheduler.CronScheduler}={service.id=190, service.bundleid=149, service.scope=bundle, component.name=org.openhab.core.internal.scheduler.CronSchedulerImpl, component.id=38}] service: [org.openhab.core.internal.scheduler.CronSchedulerImpl@1f4aa9c]]]
2023-06-25 14:47:15.633 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Checking constructor public org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl()
2023-06-25 14:47:15.634 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Found constructor with 0 arguments : public org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl()
2023-06-25 14:47:15.634 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : This thread collected dependencies
2023-06-25 14:47:15.635 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : getService (single component manager) dependencies collected.
2023-06-25 14:47:15.636 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state satisfied
2023-06-25 14:47:15.637 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Querying state satisfied
2023-06-25 14:47:15.638 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : getting activate: activate
2023-06-25 14:47:15.638 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Locating method activate in class org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl
2023-06-25 14:47:15.639 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Declared Method org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-25 14:47:15.640 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Locating method activate in class java.lang.Object
2023-06-25 14:47:15.641 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-25 14:47:15.641 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : activate method [activate] not found, ignoring
2023-06-25 14:47:15.642 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Set implementation object for component
2023-06-25 14:47:15.643 [DEBUG] [.internal.LGEssHttpClientFactoryImpl] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssHttpClientFactoryImpl(392)] : Changed state from satisfied to active
2023-06-25 14:47:15.654 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@1202bb[provider=null,keyStore=null,trustStore=null]
2023-06-25 14:47:15.656 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@1202bb[provider=null,keyStore=null,trustStore=null]
2023-06-25 14:47:15.658 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : invoking bind: setCronScheduler: parameters [org.openhab.core.internal.scheduler.CronSchedulerImpl]
2023-06-25 14:47:15.660 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : invoked bind: setCronScheduler
2023-06-25 14:47:15.661 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : getting activate: activate
2023-06-25 14:47:15.662 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Locating method activate in class org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory
2023-06-25 14:47:15.663 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Declared Method org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-25 14:47:15.664 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Locating method activate in class org.openhab.core.thing.binding.BaseThingHandlerFactory
2023-06-25 14:47:15.664 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Found activate method: protected void org.openhab.core.thing.binding.BaseThingHandlerFactory.activate(org.osgi.service.component.ComponentContext)
2023-06-25 14:47:15.665 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : invoking activate: activate: parameters [org.apache.felix.scr.impl.manager.ComponentContextImpl]
2023-06-25 14:47:15.667 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : invoked activate: activate
2023-06-25 14:47:15.667 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Set implementation object for component
2023-06-25 14:47:15.668 [DEBUG] [u.internal.LGEssenervuHandlerFactory] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.LGEssenervuHandlerFactory(393)] : Changed state from satisfied to active
2023-06-25 14:47:15.670 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Component created: DS=DS13, implementation=org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant]
2023-06-25 14:47:15.671 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Component Services: scope=singleton, services=[org.openhab.core.config.discovery.mdns.MDNSDiscoveryParticipant]
2023-06-25 14:47:15.672 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Component Properties: {}
2023-06-25 14:47:15.673 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Querying state disabled
2023-06-25 14:47:15.674 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Querying state disabled
2023-06-25 14:47:15.674 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Component can not be activated since it is in state disabled
2023-06-25 14:47:15.676 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant] : Querying state disabled
2023-06-25 14:47:15.676 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Updating target filters
2023-06-25 14:47:15.677 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Changed state from disabled to unsatisfiedReference
2023-06-25 14:47:15.678 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Component enabled
2023-06-25 14:47:15.679 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : ActivateInternal
2023-06-25 14:47:15.680 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.681 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.682 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Activating component from state unsatisfiedReference
2023-06-25 14:47:15.683 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.684 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state unsatisfiedReference
2023-06-25 14:47:15.685 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Changed state from unsatisfiedReference to satisfied
2023-06-25 14:47:15.685 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : registration change queue [registered]
2023-06-25 14:47:15.692 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Checking constructor public org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant()
2023-06-25 14:47:15.693 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Found constructor with 0 arguments : public org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant()
2023-06-25 14:47:15.694 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : This thread collected dependencies
2023-06-25 14:47:15.695 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : getService (single component manager) dependencies collected.
2023-06-25 14:47:15.696 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state satisfied
2023-06-25 14:47:15.696 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Querying state satisfied
2023-06-25 14:47:15.697 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : getting activate: activate
2023-06-25 14:47:15.698 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Locating method activate in class org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant
2023-06-25 14:47:15.699 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Declared Method org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-25 14:47:15.699 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Locating method activate in class java.lang.Object
2023-06-25 14:47:15.700 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Declared Method java.lang.Object.activate([interface org.osgi.service.component.ComponentContext]) not found
2023-06-25 14:47:15.701 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : activate method [activate] not found, ignoring
2023-06-25 14:47:15.702 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Set implementation object for component
2023-06-25 14:47:15.703 [DEBUG] [very.LGEssEnervuDiscoveryParticipant] - bundle org.openhab.binding.lgessenervu:3.4.3.202306241810 (310)[org.openhab.binding.lgessenervu.internal.discovery.LGEssEnervuDiscoveryParticipant(394)] : Changed state from satisfied to active
2023-06-25 14:47:15.879 [DEBUG] [ssenervu.internal.client.LGEssClient] - LGEssClient client started
2023-06-25 14:47:15.911 [DEBUG] [ssenervu.internal.client.LGEssClient] - LGEssClient client started
2023-06-25 14:47:15.913 [INFO ] [ssenervu.internal.LGEssenervuHandler] - LGEssEnervu Thing initialized
2023-06-25 14:47:16.283 [DEBUG] [ssenervu.internal.client.LGLanClient] - {
    "PV":
    {
        "brand": "LGE-SOLAR",
        "capacity": "11315",
        "pv1_voltage": "481.899994",
        "pv2_voltage": "272.899994",
        "pv3_voltage": "196.199997",
        "pv1_power": "4272",
        "pv2_power": "2380",
        "pv3_power": "896",
        "pv1_current": "9.490000",
        "pv2_current": "9.330000",
        "pv3_current": "4.890000",
        "today_pv_generation_sum": "39593",
        "today_month_pv_generation_sum": "1547867"
    },
    "BATT":
    {
        "status": "0",
        "soc": "100.0",
        "dc_power": "0",
        "winter_setting": "off",
        "winter_status": "off",
        "safety_soc": "10",
        "backup_setting": "off",
        "backup_status": "off",
        "backup_soc": "15",
        "today_batt_discharge_enery": "4241",
        "today_batt_charge_energy": "8566",
        "month_batt_charge_energy": "184966",
        "month_batt_discharge_energy": "139666"
    },
    "GRID":
    {
        "active_power": "4501",
        "a_phase": "243.399994",
        "freq": "50.060001",
        "today_grid_feed_in_energy": "21104",
        "today_grid_power_purchase_energy": "147",
        "month_grid_feed_in_energy": "1043422",
        "month_grid_power_purchase_energy": "6919"
    },
    "LOAD":
    {
        "load_power": "3047",
        "today_load_consumption_sum": "14311",
        "today_pv_direct_consumption_enegy": "9923",
        "today_batt_discharge_enery": "4241",
        "today_grid_power_purchase_energy": "147",
        "month_load_consumption_sum": "466064",
        "month_pv_direct_consumption_energy": "319479",
        "month_batt_discharge_energy": "139666",
        "month_grid_power_purchase_energy": "6919"
    },
    "PCS":
    {
        "today_self_consumption": "46.7",
        "month_co2_reduction_accum": "1098985",
        "today_pv_generation_sum": "39593",
        "month_pv_generation_sum": "1547867",
        "today_grid_feed_in_energy": "21104",
        "month_grid_feed_in_energy": "1043422",
        "pcs_stauts": "3",
        "feed_in_limitation": "90",
        "operation_mode": "1"
    }
}

2023-06-25 14:47:16.285 [DEBUG] [ssenervu.internal.client.LGLanClient] - {
    "statistics":
    {
        "pcs_pv_total_power": "7548",
        "batconv_power": "0",
        "bat_use": "1",
        "bat_status": "0",
        "bat_user_soc": "100.0",
        "load_power": "3042",
        "ac_output_power": "10",
        "load_today": "0.0",
        "grid_power": "4506",
        "current_day_self_consumption": "46.7",
        "current_pv_generation_sum": "39593",
        "current_grid_feed_in_energy": "21104"
    },
    "direction":
    {
        "is_direct_consuming_": "1",
        "is_battery_charging_": "0",
        "is_battery_discharging_": "0",
        "is_grid_selling_": "1",
        "is_grid_buying_": "0",
        "is_charging_from_grid_": "0",
        "is_discharging_to_grid_": "0"
    },
    "operation":
    {
        "status": "start",
        "mode": "1",
        "pcs_standbymode": "false",
        "drm_mode0": "0",
        "remote_mode": "0",
        "drm_control": "255"
    },
    "wintermode":
    {
        "winter_status": "off",
        "backup_status": "off"
    },
    "backupmode": "",
    "pcs_fault":
    {
        "pcs_status": "pcs_ok",
        "pcs_op_status": "pcs_run"
    },
    "heatpump":
    {
        "heatpump_protocol": "0",
        "heatpump_activate": "off",
        "current_temp": "0",
        "heatpump_working": "off"
    },
    "evcharger":
    {
        "ev_activate": "off",
        "ev_power": "0"
    },
    "gridWaitingTime": "0"
}

2023-06-25 14:47:16.315 [WARN ] [ssenervu.internal.LGEssenervuHandler] - Event CHARGED in thing lgessenervu:powerrouter:Solaranlage does not exist, please recreate the thing
2023-06-25 14:47:16.317 [DEBUG] [ssenervu.internal.LGEssenervuHandler] - getNumericValueOfString empty String
java.lang.NumberFormatException: empty String
        at jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1842) ~[?:?]
        at jdk.internal.math.FloatingDecimal.parseDouble(FloatingDecimal.java:110) ~[?:?]
        at java.lang.Double.parseDouble(Double.java:543) ~[?:?]
        at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.getNumericValueOfString(LGEssenervuHandler.java:322) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.responseCallbackCurrentData(LGEssenervuHandler.java:483) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.client.LGLanClient.getCurrentData(LGLanClient.java:177) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.job.SnapshotJob.run(SnapshotJob.java:35) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
        at java.lang.Thread.run(Thread.java:829) [?:?]
2023-06-25 14:47:36.402 [DEBUG] [ssenervu.internal.client.LGLanClient] - {
    "PV":
    {
        "brand": "LGE-SOLAR",
        "capacity": "11315",
        "pv1_voltage": "484.200012",
        "pv2_voltage": "275.399994",
        "pv3_voltage": "194.000000",
        "pv1_power": "4275",
        "pv2_power": "2379",
        "pv3_power": "895",
        "pv1_current": "9.450000",
        "pv2_current": "9.240000",
        "pv3_current": "4.940000",
        "today_pv_generation_sum": "39593",
        "today_month_pv_generation_sum": "1547867"
    },
    "BATT":
    {
        "status": "0",
        "soc": "100.0",
        "dc_power": "0",
        "winter_setting": "off",
        "winter_status": "off",
        "safety_soc": "10",
        "backup_setting": "off",
        "backup_status": "off",
        "backup_soc": "15",
        "today_batt_discharge_enery": "4241",
        "today_batt_charge_energy": "8566",
        "month_batt_charge_energy": "184966",
        "month_batt_discharge_energy": "139666"
    },
    "GRID":
    {
        "active_power": "4551",
        "a_phase": "243.699997",
        "freq": "50.040001",
        "today_grid_feed_in_energy": "21104",
        "today_grid_power_purchase_energy": "147",
        "month_grid_feed_in_energy": "1043422",
        "month_grid_power_purchase_energy": "6919"
    },
    "LOAD":
    {
        "load_power": "2995",
        "today_load_consumption_sum": "14311",
        "today_pv_direct_consumption_enegy": "9923",
        "today_batt_discharge_enery": "4241",
        "today_grid_power_purchase_energy": "147",
        "month_load_consumption_sum": "466064",
        "month_pv_direct_consumption_energy": "319479",
        "month_batt_discharge_energy": "139666",
        "month_grid_power_purchase_energy": "6919"
    },
    "PCS":
    {
        "today_self_consumption": "46.7",
        "month_co2_reduction_accum": "1098985",
        "today_pv_generation_sum": "39593",
        "month_pv_generation_sum": "1547867",
        "today_grid_feed_in_energy": "21104",
        "month_grid_feed_in_energy": "1043422",
        "pcs_stauts": "3",
        "feed_in_limitation": "90",
        "operation_mode": "1"
    }
}

2023-06-25 14:47:36.405 [DEBUG] [ssenervu.internal.client.LGLanClient] - {
    "statistics":
    {
        "pcs_pv_total_power": "7546",
        "batconv_power": "0",
        "bat_use": "1",
        "bat_status": "0",
        "bat_user_soc": "100.0",
        "load_power": "2995",
        "ac_output_power": "10",
        "load_today": "0.0",
        "grid_power": "4551",
        "current_day_self_consumption": "46.7",
        "current_pv_generation_sum": "39593",
        "current_grid_feed_in_energy": "21104"
    },
    "direction":
    {
        "is_direct_consuming_": "1",
        "is_battery_charging_": "0",
        "is_battery_discharging_": "0",
        "is_grid_selling_": "1",
        "is_grid_buying_": "0",
        "is_charging_from_grid_": "0",
        "is_discharging_to_grid_": "0"
    },
    "operation":
    {
        "status": "start",
        "mode": "1",
        "pcs_standbymode": "false",
        "drm_mode0": "0",
        "remote_mode": "0",
        "drm_control": "255"
    },
    "wintermode":
    {
        "winter_status": "off",
        "backup_status": "off"
    },
    "backupmode": "",
    "pcs_fault":
    {
        "pcs_status": "pcs_ok",
        "pcs_op_status": "pcs_run"
    },
    "heatpump":
    {
        "heatpump_protocol": "0",
        "heatpump_activate": "off",
        "current_temp": "0",
        "heatpump_working": "off"
    },
    "evcharger":
    {
        "ev_activate": "off",
        "ev_power": "0"
    },
    "gridWaitingTime": "0"
}

2023-06-25 14:47:36.432 [DEBUG] [ssenervu.internal.LGEssenervuHandler] - getNumericValueOfString empty String
java.lang.NumberFormatException: empty String
        at jdk.internal.math.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:1842) ~[?:?]
        at jdk.internal.math.FloatingDecimal.parseDouble(FloatingDecimal.java:110) ~[?:?]
        at java.lang.Double.parseDouble(Double.java:543) ~[?:?]
        at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.getNumericValueOfString(LGEssenervuHandler.java:322) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.responseCallbackCurrentData(LGEssenervuHandler.java:483) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.client.LGLanClient.getCurrentData(LGLanClient.java:177) [bundleFile:?]
        at org.openhab.binding.lgessenervu.internal.job.SnapshotJob.run(SnapshotJob.java:35) [bundleFile:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?]
        at java.lang.Thread.run(Thread.java:829) [?:?]
`
mksway commented 1 year ago

Hey,

thx for the data. I think I found the issue :) When I wrote this plugin I autogenerated some pojos from the json response and the data for the safetySOC was called "safty_soc" (wrong spelled) back then. Seems like you have a newer firmware on your powerrouter then me because it's called "safety_soc" now.

I still wonder why you had no error messages with the old version. Anyway - I am going to correct it asap but since I noticed that you have a few more values available I wanted to ask if you want them available as well (see below).

"heatpump":
{
    "heatpump_protocol": "0",
    "heatpump_activate": "off",
    "current_temp": "0",
    "heatpump_working": "off"
},
"evcharger":
{
    "ev_activate": "off",
    "ev_power": "0"
},
"gridWaitingTime": "0"

Best

meschmesch commented 1 year ago

I unfortunately don't have a hear pump or an EV charger attached. I also don't know what grid waiting time is. But if I can be of any help here please let me know.Interesting would be the possibility of controlling the ESS, i.e. turn on and off the ESS, change the charging mode, activate backup etc. Do you think this is feasible?

meschmesch commented 1 year ago

Another question, do you plan on implementing the binding for openhab 4? I just upgraded but the binding terminates with the following:

2023-07-28 16:42:14.116 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: java.lang.NoSuchMethodError: 'void org.openhab.core.library.types.DecimalType.(double)' at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.publishChannelIfLinked(LGEssenervuHandler.java:286) ~[?:?] at org.openhab.binding.lgessenervu.internal.LGEssenervuHandler.responseCallbackDaily(LGEssenervuHandler.java:731) ~[?:?] at org.openhab.binding.lgessenervu.internal.client.LGLanClient.getCurrentData(LGLanClient.java:167) ~[?:?] at org.openhab.binding.lgessenervu.internal.job.SnapshotJob.run(SnapshotJob.java:35) ~[?:?] 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:833) ~[?:?]

I realized that the binding provides correct values on start, but then crashes. For now, since I am using openhab4, I circumvented the problem by using a rule which disables and re-enables the thing every 30s. The warnings in the log can be ignored.

The rule used to disable and re-enable the thing makes use of a sendHttpPutRequest which in my case requires a token. One can generate the token vie the web interface (left lower portion "root"). The code I use in the rule is:

var headers = new java.util.HashMap(); headers.put("Authorization", "Bearer " + "YOUR_TOKEN"); sendHttpPutRequest("http://localhost:8080/rest/things/YOUR_THING_ID/enable", "text/plain", 'false', headers, 5000); Thread::sleep(2000) sendHttpPutRequest("http://localhost:8080/rest/things/YOUR_THING_ID/enable", "text/plain", 'true', headers, 5000);

The YOUR_THING_ID is in my example lgessenervu:powerrouter:Solaranlage.

mksway commented 1 year ago

I just uploaded a version for 4.0.x and will push a version for 4.1.x soon but I somehow don't like my code anymore and plan to clean it up.

meschmesch commented 1 year ago

With the update the thing shows up, but it is empty, no configuration data is shown.

[WARN ] [core.thing.internal.ThingManagerImpl] - Could not normalize configuration for 'lgessenervu:powerrouter:Solaranlage' because the thing type was not found in registry.

Do I have to recreate all my items now and create a new thing? Any workaround?