AndrewFG
(Andrew Fiddian-Green)
April 1, 2021, 10:25pm
65
@Kai many thanks for the new release. Can you please advise if PR #10119 [velux] is actually in the release or not? (As it is not mentioned in the release notes).
Kai
(Kai Kreuzer)
April 2, 2021, 8:53am
66
@massi & @AndrewFG Thanks for noticing, there were indeed a few PRs missing as they werenāt correctly tagged on Github (note: PRs turn up in the release notes if they either have ābugā or āenhancementā tag on them. Issues are never listed in the release notes, so please always make sure to have a speaking title for every PR, because thatās what users will see in the end. Thanks!
Thank you @Kai Installed 3.0.1.M3 and all seems to be ok, but I have these new warnings in the log:
2021-04-02 12:31:57.340 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@3f704dc3[provider=null,keyStore=null,trustStore=null]
2021-04-02 12:31:57.343 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@3f704dc3[provider=null,keyStore=null,trustStore=null]
2021-04-02 12:31:57.596 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trusting all certificates configured for Client@7fa5f364[provider=null,keyStore=null,trustStore=null]
2021-04-02 12:31:57.598 [WARN ] [ty.util.ssl.SslContextFactory.config] - No Client EndPointIdentificationAlgorithm configured for Client@7fa5f364[provider=null,keyStore=null,trustStore=null]
This issue persists in 3.1M3:
Edit - forgot link:
opened 07:45PM - 11 Mar 21 UTC
closed 11:03AM - 05 Dec 23 UTC
awaiting feedback
stale
I have a repeatable behaviour that when I open VS code while OH3 is running, I g⦠et a slow system until it freezes:
I'm on openhabian, RPi4 4GB. VS Code OH plugin.
I'm ready to help assist more than the below log if you tell me what to do!
```
==> /var/log/openhab/openhab.log <==
2021-03-11 20:36:04.185 [ERROR] [ypes.access.impl.AbstractClassMirror] - resource is empty: java:/Objects/org.openhab.core.model.script.actions.Semantics
java.lang.IllegalStateException: null
at org.eclipse.xtext.common.types.access.impl.AbstractClassMirror.getEObject(AbstractClassMirror.java:95) [bundleFile:?]
at org.eclipse.xtext.common.types.access.TypeResource.getEObject(TypeResource.java:95) [bundleFile:?]
at org.eclipse.emf.ecore.resource.impl.ResourceSetImpl.getEObject(ResourceSetImpl.java:223) [bundleFile:?]
at org.eclipse.xtext.common.types.access.impl.IndexedJvmTypeAccess.getIndexedJvmType(IndexedJvmTypeAccess.java:80) [bundleFile:?]
at org.eclipse.xtext.common.types.access.impl.IndexedJvmTypeAccess.getIndexedJvmType(IndexedJvmTypeAccess.java:70) [bundleFile:?]
at org.eclipse.xtext.common.types.access.reflect.ReflectionTypeProvider.findTypeByClass(ReflectionTypeProvider.java:146) [bundleFile:?]
at org.eclipse.xtext.common.types.access.reflect.ReflectionTypeProvider.findTypeByName(ReflectionTypeProvider.java:88) [bundleFile:?]
at org.eclipse.xtext.common.types.util.TypeReferences.findDeclaredType(TypeReferences.java:256) [bundleFile:?]
at org.eclipse.xtext.common.types.util.TypeReferences.findDeclaredType(TypeReferences.java:233) [bundleFile:?]
at org.eclipse.xtext.xbase.scoping.batch.ImplicitlyImportedFeatures.getTypes(ImplicitlyImportedFeatures.java:82) [bundleFile:?]
at org.eclipse.xtext.xbase.scoping.batch.ImplicitlyImportedFeatures.getExtensionClasses(ImplicitlyImportedFeatures.java:76) [bundleFile:?]
at org.eclipse.xtext.xbase.scoping.batch.XbaseBatchScopeProvider.newSession(XbaseBatchScopeProvider.java:121) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.DefaultReentrantTypeResolver.resolve(DefaultReentrantTypeResolver.java:164) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.DefaultReentrantTypeResolver.reentrantResolve(DefaultReentrantTypeResolver.java:140) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.CachingBatchTypeResolver$LazyResolvedTypes.resolveTypes(CachingBatchTypeResolver.java:81) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.CachingBatchTypeResolver$2.process(CachingBatchTypeResolver.java:58) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.CachingBatchTypeResolver$2.process(CachingBatchTypeResolver.java:54) [bundleFile:?]
at org.eclipse.xtext.util.concurrent.IUnitOfWork$Void.exec(IUnitOfWork.java:38) [bundleFile:?]
at org.eclipse.xtext.util.OnChangeEvictingCache.execWithoutCacheClear(OnChangeEvictingCache.java:135) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.CachingBatchTypeResolver.doResolveTypes(CachingBatchTypeResolver.java:54) [bundleFile:?]
at org.eclipse.xtext.xbase.typesystem.internal.AbstractBatchTypeResolver.resolveTypes(AbstractBatchTypeResolver.java:70) [bundleFile:?]
at org.eclipse.xtext.xbase.resource.BatchLinkingService.resolveBatched(BatchLinkingService.java:72) [bundleFile:?]
at org.eclipse.xtext.xbase.resource.BatchLinkableResource.resolveLazyCrossReferences(BatchLinkableResource.java:166) [bundleFile:?]
at org.eclipse.xtext.EcoreUtil2.resolveLazyCrossReferences(EcoreUtil2.java:505) [bundleFile:?]
at org.eclipse.xtext.build.IncrementalBuilder$InternalStatefulIncrementalBuilder.lambda$launch$2(IncrementalBuilder.java:266) [bundleFile:?]
at com.google.common.collect.Iterators$6.transform(Iterators.java:783) [bundleFile:?]
at com.google.common.collect.TransformedIterator.next(TransformedIterator.java:47) [bundleFile:?]
at com.google.common.collect.FluentIterable.copyInto(FluentIterable.java:791) [bundleFile:?]
at org.eclipse.xtext.build.ClusteringStorageAwareResourceLoader.executeClustered(ClusteringStorageAwareResourceLoader.java:69) [bundleFile:?]
at org.eclipse.xtext.build.BuildContext.executeClustered(BuildContext.java:55) [bundleFile:?]
at org.eclipse.xtext.build.IncrementalBuilder$InternalStatefulIncrementalBuilder.launch(IncrementalBuilder.java:259) [bundleFile:?]
at org.eclipse.xtext.build.IncrementalBuilder.build(IncrementalBuilder.java:404) [bundleFile:?]
at org.eclipse.xtext.build.IncrementalBuilder.build(IncrementalBuilder.java:386) [bundleFile:?]
at org.eclipse.xtext.ide.server.ProjectManager.doBuild(ProjectManager.java:106) [bundleFile:?]
at org.eclipse.xtext.ide.server.ProjectManager.doInitialBuild(ProjectManager.java:97) [bundleFile:?]
at org.eclipse.xtext.ide.server.BuildManager.doInitialBuild(BuildManager.java:164) [bundleFile:?]
at org.eclipse.xtext.ide.server.WorkspaceManager.refreshWorkspaceConfig(WorkspaceManager.java:233) [bundleFile:?]
at org.eclipse.xtext.ide.server.WorkspaceManager.initialize(WorkspaceManager.java:170) [bundleFile:?]
at org.eclipse.xtext.ide.server.LanguageServerImpl.lambda$initialize$0(LanguageServerImpl.java:225) [bundleFile:?]
at org.eclipse.xtext.ide.server.concurrent.WriteRequest.run(WriteRequest.java:50) [bundleFile:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
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:834) [?:?]
2021-03-11 20:36:22.707 [WARN ] [t.generic.ChannelStateTransformation] - Executing the JSONPATH-transformation failed: Invalid path '$.POWER' in '{"Time":"2021-03-11T20:36:22","ENERGY":{"TotalStartTime":"2020-04-16T16:42:19","Total":0.000,"Yesterday":0.000,"Today":0.000,"Period":0,"Power":0,"ApparentPower":0,"ReactivePower":0,"Factor":0.00,"Voltage":0,"Current":0.000}}'
==> /var/log/openhab/events.log <==
2021-03-11 20:36:27.418 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item 'Date' changed from 2021-03-11T20:35:27.309+0100 to 2021-03-11T20:36:27.386+0100
==> /var/log/openhab/openhab.log <==
2021-03-11 20:36:55.203 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@187cda4' takes more than 5000ms.
2021-03-11 20:37:03.601 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@187cda4' takes more than 5000ms.
2021-03-11 20:37:27.199 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = ba70a670-1830-45a2-a790-b912eec0dc77, base URL = http://localhost:8080)
2021-03-11 20:37:37.447 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.thing.internal.CommunicationManager@dc7d1d' takes more than 5000ms.
2021-03-11 20:37:59.620 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@187cda4' takes more than 5000ms.
2021-03-11 20:38:07.523 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.metrics.ThingStateMetric@11c1dc0' takes more than 5000ms.
2021-03-11 20:38:07.536 [INFO ] [nternal.handler.SpotifyBridgeHandler] - Spotify returned an error during polling: java.util.concurrent.TimeoutException: Total timeout 10000 ms elapsed
2021-03-11 20:38:39.748 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@187cda4' takes more than 5000ms.
2021-03-11 20:39:12.120 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@187cda4' takes more than 5000ms.
2021-03-11 20:39:23.751 [WARN ] [era.internal.handler.IpCameraHandler] - !!!! Camera possibly closed the channel on the binding, cause reported is: Java heap space
2021-03-11 20:39:25.719 [WARN ] [era.internal.handler.IpCameraHandler] - !!!! Camera possibly closed the channel on the binding, cause reported is: Java heap space
```
mstormi
(Markus Storm)
April 2, 2021, 11:54am
69
What āthisā ? Make your post a useful one please.
FutureCow
(Future Cow)
April 2, 2021, 12:26pm
70
AndrewFG
(Andrew Fiddian-Green)
April 2, 2021, 12:59pm
71
It seems that M3 has introduced a missing dependency problem ājcifs.netbios.NbtAddressā in the āHDPowerViewā binding. Does anyone have an idea what I need to change in the binding code to resolve it again?
2021-04-02 13:46:51.356 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.NoClassDefFoundError: jcifs/netbios/NbtAddress
at org.openhab.binding.hdpowerview.internal.discovery.HDPowerViewHubDiscoveryService.lambda$0(HDPowerViewHubDiscoveryService.java:83) ~[?:?]
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:834) [?:?]
Caused by: java.lang.ClassNotFoundException: jcifs.netbios.NbtAddress cannot be found by org.openhab.binding.hdpowerview_3.1.0.M3
at org.eclipse.osgi.internal.loader.BundleLoader.findClassInternal(BundleLoader.java:484) ~[org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.loader.BundleLoader.findClass(BundleLoader.java:395) ~[org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.loader.BundleLoader.findClass(BundleLoader.java:387) ~[org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.loader.ModuleClassLoader.loadClass(ModuleClassLoader.java:150) ~[org.eclipse.osgi-3.12.100.jar:?]
at java.lang.ClassLoader.loadClass(ClassLoader.java:522) ~[?:?]
... 7 more
I have the same warnings after the upgrade to M3.
Is it something to worry about?
Kai
(Kai Kreuzer)
April 2, 2021, 6:27pm
73
I just tested with a plain fresh M3 install and do not see them. I assume they are related to some specific add-on?
Not correct. Tested it and the binding is there as expected:
Please create an issue and we will figure it out. It was probably me who broke that, sorry for thatā¦
Kai:
I just tested with a plain fresh M3 install and do not see them. I assume they are related to some specific add-on?
Probably. How can I check which one ?
The tried and true method is to either unload all of them and slowly add them back until the issue reappears or removing each one, one at a time until the issue disappears. Neither method is easy, painless or quick, in fact quite the opposite.
With that said, the warning appears to have something to do with secure socket layer and client certificates. Suspect any binding which is connecting securely to a cloud server
Maybe list all the bindings you use and weāll take a shot at guessing which one is the culprit
I suspected that. Here is the list, maybe you can point me the most probable candidates
@Kai @Andrew_Rowe The bindings causing these warnings are Daikin, HTTP and Unifi.
@SpaceGlider I thinkI see the same symptom of system getting slower and slower and it seems that when I disconnect VS Code from the OH machine, everything gets better if not normal.
Note that I use VS Code via SSH, not with Samba⦠Not sure if this is relevant, but just in case.
Iām pretty sure there is a known issue with VS code slowing performance, dig around a little (although I donāt think it has been resolved yet)
If it is more then one binding, Iād look at your certificates
so⦠how did you come to this conclusion?
Disabled all, then enabled one by one, and checking the log in between.
Yes, it is mentioned here:
Note that Iām still unsure that in my case it is not openhab itself that is slowing down the Rpi4 entirely like this. Iāll need to spend more time to really understand what is going on. For sure when I stop openhab, then suddenly things get even betterā¦
And if you exit from VSC it also gets better?
Iāve been running on a 3B+, so a quarter of the RAM that youāve got, and with OH2 I found that using VSC with the remote SSH feature Iād run out of RAM, locking up the Pi. What other services are running on your Pi? How much RAM is free on the Pi before you connect via VSC?
Potentially related: I had OH2 on an RPi 3B+, and used VSC from another PC to edit configurations using the Remote SSH feature of VSC.
I found that when active Iād lose almost 400 MB of RAM on the Pi, and with only 1GB total it sometimes locked up completely when it ran out.
I only mention it because I run on exactly the same hardware as you, and have had issues when trying to run too many things. Also, if connecting via SSH using Visual Studio Code to configure files and rules, quite a bit of RAM is used due to the remote server - which has tripped me up in the past!
Do you have any openhab or system logs from around the time that stuff disappears?
How long have these rules been running fine for? Have you added a new rule recently? Have you started using a new biā¦