Openhab 4.0.x rules need a lot of memory

Wikibear · August 11, 2023, 8:52pm

After upgrading I fight with so many issues and solve some of them step by step. But now I’m in a situation, that I can’t explain why and what exactly happens. After reboot log looks fine. 30-40 minutes later openhab crash. System itselft where openhab is running don’t have any issue or performance problems. As I wrote here Blockly rules after upgrade to OH 4.0.1 very slow - #11 by Wikibear

It starts that rules stay in running. Some rules produce a false and then all clients gone…

I had fixed some rules and hope that this issues gone. I fixed a loop in Symantec Models. I stop SkyQ Binding cause I get errors but that must be something deeper, why openhab crash.


2023-08-11 22:32:32.073 [INFO ] [org.openhab.core.Activator          ] - Star
ting openHAB 4.0.1 (build Release Build)
2023-08-11 22:32:33.317 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Time
 zone set to 'Europe/Berlin'.
2023-08-11 22:32:33.339 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Loca
tion set to '52.37840238793128,10.479669570922853'.
2023-08-11 22:32:33.344 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Loca
le set to 'de_DE'.
2023-08-11 22:32:33.349 [INFO ] [.core.internal.i18n.I18nProviderImpl] - Meas
urement system set to 'SI'.
2023-08-11 22:32:42.595 [WARN ] [core.karaf.internal.FeatureInstaller] - The
transformation add-on 'javascript' does not exist - ignoring it.
2023-08-11 22:32:47.644 [INFO ] [el.core.internal.ModelRepositoryImpl] - Load
ing model 'influxdb.persist'
2023-08-11 22:33:03.756 [INFO ] [.core.model.lsp.internal.ModelServer] - Star
ted Language Server Protocol (LSP) service on port 5007
2023-08-11 22:33:04.375 [INFO ] [el.core.internal.ModelRepositoryImpl] - Load
ing model 'telegrambot.rules'
2023-08-11 22:33:24.262 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trus
ting all certificates configured for Client@c9d587[provider=null,keyStore=nul
l,trustStore=null]
2023-08-11 22:33:24.279 [WARN ] [ty.util.ssl.SslContextFactory.config] - No C
lient EndPointIdentificationAlgorithm configured for Client@c9d587[provider=n
ull,keyStore=null,trustStore=null]
2023-08-11 22:33:27.616 [INFO ] [io.openhabcloud.internal.CloudClient] - Conn
ected to the openHAB Cloud service (UUID = cc...cf, base URL = http://localho
st:8080)
2023-08-11 22:33:27.850 [INFO ] [.influxdb.InfluxDBPersistenceService] - Infl
uxDB persistence service started.
2023-08-11 22:33:28.111 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Star
ting MQTT broker connection to '192.168.179.65' with clientid OpenHAB
2023-08-11 22:33:29.451 [INFO ] [ternal.dhcp.DHCPPacketListenerServer] - DHCP
 request packet listener online
2023-08-11 22:33:32.249 [INFO ] [o.internal.handler.AstroThingHandler] - Sche
duled Positional job astro:sun:local every 300 seconds
2023-08-11 22:33:39.802 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state PAUSE (StringType) on item KuecheEcho_Musikwiedergabe
of type PlayerItem, ignoring it
2023-08-11 22:33:42.522 [WARN ] [core.karaf.internal.FeatureInstaller] - The
transformation add-on 'javascript' does not exist - ignoring it.
2023-08-11 22:33:45.258 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state raw type (image/jpeg): 482752 bytes (StringType) on it
em DusSecureCam_Image of type ImageItem, ignoring it
2023-08-11 22:33:45.687 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state raw type (image/jpeg): 278450 bytes (StringType) on it
em SchildiCamThumbnail of type ImageItem, ignoring it
2023-08-11 22:33:50.062 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state PAUSE (StringType) on item KuecheEcho_Musikwiedergabe
of type PlayerItem, ignoring it
2023-08-11 22:33:51.913 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state PAUSE (StringType) on item EchoUeberall_Musikwiedergab
e of type PlayerItem, ignoring it
2023-08-11 22:33:53.531 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state raw type (image/jpeg): 482752 bytes (StringType) on it
em DusSecureCam_Image of type ImageItem, ignoring it
2023-08-11 22:33:54.308 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state raw type (image/jpeg): 278450 bytes (StringType) on it
em SchildiCamThumbnail of type ImageItem, ignoring it
2023-08-11 22:33:54.486 [ERROR] [org.openhab.core.items.GenericItem  ] - Trie
d to set invalid state PAUSE (StringType) on item WZEcho_Musikwiedergabe of t
ype PlayerItem, ignoring it
2023-08-11 22:34:01.523 [INFO ] [ab.ui.habpanel.internal.HABPanelTile] - Star
ted HABPanel at /habpanel
2023-08-11 22:34:05.036 [WARN ] [ty.util.ssl.SslContextFactory.config] - Trus
ting all certificates configured for Client@8d53a2[provider=null,keyStore=nul
l,trustStore=null]
2023-08-11 22:34:05.042 [WARN ] [ty.util.ssl.SslContextFactory.config] - No C
lient EndPointIdentificationAlgorithm configured for Client@8d53a2[provider=n
ull,keyStore=null,trustStore=null]
2023-08-11 22:34:06.549 [INFO ] [e.automation.internal.RuleEngineImpl] - Rule
 engine started.
2023-08-11 22:35:10.387 [INFO ] [ar.internal.handler.ThingBaseHandler] - VSSZ
ZZKLZ: Reverse address lookup based on vehicle's geo position is enab
led (using OpenStreetMap)
2023-08-11 22:35:13.350 [INFO ] [ar.internal.handler.ThingBaseHandler] - Ibiz
a: Reverse address lookup based on vehicle's geo position is enabled (using O
penStreetMap)
2023-08-11 22:36:52.012 [WARN ] [core.thing.internal.ThingManagerImpl] - Chan
nel types or config descriptions for thing 'miio:generic:16D5DC17' are missin
g in the respective registry for more than 120s. In case it does not happen i
mmediately after an upgrade, it should be fixed in the binding.

I’ve updated all bindings to the latest versions.

Thanks
Swen

rlkoshak · August 11, 2023, 9:23pm

Please use code fences when posting logs or code.

```
code goes here
```

You have a lot of Items being updated to invalid states.

Something is updating this Item with a String but it expects an Image.

Something is trying to send a String “PAUSE” ti this Item.

However, that shouldn’t really be a problem because OH should try to parse that.

Delete and recreate the Thing. There was a config change between OH 3 and OH 4 that didn’t get updated on this Thing. Take note of the ID and when you recreate/rediscover it use the same ID again and your links will still be valid.

None of these errors would point to a system crash on their own. Is there anything in sudo systemctl status openhab?

Wikibear · August 12, 2023, 7:39am

SystemCTL show:

● openhab.service - openHAB instance, reachable at http://openhabian:8080
     Loaded: loaded (/lib/systemd/system/openhab.service; enabled; vendor preset: enabled)
    Drop-In: /etc/systemd/system/openhab.service.d
             └─override.conf
     Active: active (running) since Sat 2023-08-12 08:59:44 CEST; 33min ago
       Docs: https://www.openhab.org/docs/
             https://community.openhab.org
   Main PID: 16195 (java)
      Tasks: 370 (limit: 2176)
        CPU: 57min 18.282s
     CGroup: /system.slice/openhab.service
             └─16195 /usr/bin/java -XX:-UsePerfData -Dopenhab.home=/usr/share/openhab -Dopenhab.conf=/etc/openhab -Dopenhab.runtime=/usr/share/openhab/runtime -Dopenhab.userdata=/var/lib/op>

Aug 12 09:25:30 openhabian karaf[16195]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because>
Aug 12 09:25:30 openhabian karaf[16195]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 12 09:26:05 openhabian karaf[16195]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because>
Aug 12 09:26:05 openhabian karaf[16195]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 12 09:26:38 openhabian karaf[16195]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because>
Aug 12 09:26:38 openhabian karaf[16195]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 12 09:27:12 openhabian karaf[16195]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because>
Aug 12 09:27:12 openhabian karaf[16195]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 12 09:27:47 openhabian karaf[16195]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because>
Aug 12 09:27:47 openhabian karaf[16195]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)

The AVR is without power at the moment and that wasn’t a problem in the past.

The log show:

2023-08-12 09:35:25.794 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@1b1f4fe' takes more than 5000ms.
2023-08-12 09:35:36.127 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@e90cb5' takes more than 5000ms.
2023-08-12 09:35:36.167 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=pppUptime, getAction=GetInfo, dataType='ui4, parameter='null'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2023-08-12 09:35:39.477 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@1b1f4fe' takes more than 5000ms.
2023-08-12 09:35:43.325 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=dslDownstreamNoiseMargin, getAction=GetInfo, dataType='ui4, parameter='null'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2023-08-12 09:35:49.944 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@1b1f4fe' takes more than 5000ms.
2023-08-12 09:35:53.326 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=wanMaxDownstreamRate, getAction=GetCommonLinkProperties, dataType='ui4, parameter='null'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2023-08-12 09:35:56.690 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@1b1f4fe' takes more than 5000ms.
2023-08-12 09:36:03.506 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=macOnline, getAction=GetSpecificHostEntry, dataType='boolean, parameter='E0:DC:FF:F6:D0:87'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2023-08-12 09:36:13.864 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.io.monitor.internal.EventLogger@e90cb5' takes more than 5000ms.
2023-08-12 09:36:13.910 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=dslUpstreamAttenuation, getAction=GetInfo, dataType='ui4, parameter='null'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed
2023-08-12 09:36:20.888 [WARN ] [ng.tr064.internal.soap.SOAPConnector] - Failed to get Tr064ChannelConfig{channelType=macOnline, getAction=GetSpecificHostEntry, dataType='boolean, parameter='C4:95:00:44:73:0D'}: java.util.concurrent.TimeoutException: Total timeout 5000 ms elapsed

For me it’s look like openhab try to fetch network data and failed. UI works only for a short time fast. After rules are triggert the UI is unusable.

EDIT: I have removed MIIO Thing and Binding at the moment it looks stable. But it’s under investigation.

Wikibear · August 12, 2023, 1:43pm

MIIO Binding stop Openhab to work.

Will discuss here: Xiaomi Robot Vacuum Binding - #2120 by marcel_verpaalen

Wikibear · August 13, 2023, 7:35am

I must reopen this thread… After hours of running, openhab get slower and slower. All http request will stop after a while and openhab itself isn’t really crashing, but stop to handle all requests. Logs will be filled with that what you can see above. I removed MIIO and SYKQ Binding at the moment, to keep clear that there isn’t any issues with that bindings. For me they look not stable, but they can’t!

What i observed is that many rules stay on running. Rules will be run slow and some rules will go to false or i get a message that the rule is running.

I changed the header to unstable, 'cause it isn’t really crashing.

Wolfgang_S · August 13, 2023, 7:41am

How do CPU load and memory usage behave over time ?
How does your system configuration look like ( hardware, OS, memory ) ?

Wikibear · August 13, 2023, 7:57am

Raspberry Pi 3 with SSD

At the moment I can show you only the normal working state:

Edit: Maybe this can be a solution for my issue: Consistent 100% CPU use of safeCall-queue thread - #44 by wborn

Wikibear · August 13, 2023, 8:41am

After installing SKYQ and MIIO Binding i get this problem back:

CPU load is extrem high on one CPU. And it’s hopping from one to other CPU. I will install now the CPU fix.

Wikibear · August 13, 2023, 12:58pm

Duno if the fix is working, but Openhab is restarting by itself. Sometimes I got hick ups like this:

2023-08-13 14:43:19.216 [WARN ] [ab.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.openhab.core.internal.items.ItemUpdater@5432dd' takes more than 5000ms.

For me Openhab isn’t really stable. Something must drive this issue to death. But what?

Andrew_Rowe · August 13, 2023, 1:05pm

What is http requesting? Can you try in a browser and see if it works?

Wikibear · August 13, 2023, 1:07pm

Everything in my logs to try a query in or out it doesn’t matter. TR064 error, openweather error, etc.
You can’t open UI. That will end in a blank white page. I let open a terminal window that i see what happen and post it here with more information. Maybe this can help…

Wolfgang_S · August 13, 2023, 2:14pm

Check /var/log/system.log and output of sudo journalctl for out of memory messages.
What is the content of EXTRA_JAVA_OPTS in /etc/default/openhab ?

Wikibear · August 13, 2023, 2:38pm

EXTRA_JAVA_OPTS="-Xms192m -Xmx384m -XX:+ExitOnOutOfMemoryError"

Aug 13 09:28:25 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:28:25 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:28:59 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:28:59 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:29:32 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:29:32 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:30:05 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:30:05 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:30:38 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:30:38 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:31:11 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:31:11 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:31:44 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:31:44 openhabian karaf[18960]: #011at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 10:44:35 openhabian karaf[18960]: Terminating due to java.lang.OutOfMemoryError: Java heap space
Aug 13 13:27:13 openhabian karaf[3846]: Terminating due to java.lang.OutOfMemoryError: Java heap space

Aug 13 13:27:13 openhabian karaf[3846]: Terminating due to java.lang.OutOfMemoryError: Java heap space
Aug 13 10:44:34 openhabian karaf[18960]: Terminating due to java.lang.OutOfMemoryError: Java heap space
Aug 13 09:31:44 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:31:44 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:31:11 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:31:11 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:30:38 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:30:38 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:30:05 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:30:05 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:29:32 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:29:32 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:28:59 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:28:59 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:28:25 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:28:25 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:27:52 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:27:52 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:27:17 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:27:17 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:26:43 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)
Aug 13 09:26:43 openhabian karaf[18960]: Exception in thread "OH-binding-denonmarantz:avr:2b74ec1a" java.lang.NullPointerException: Cannot invoke "java.io.BufferedReader.readLine()" because "this.in" is null
Aug 13 09:26:09 openhabian karaf[18960]:         at org.openhab.binding.denonmarantz.internal.connector.telnet.DenonMarantzTelnetClientThread.run(DenonMarantzTelnetClientThread.java:68)

at 10:44 as you can see i do the fix. After this fix i get OOM, but with unknown reason.

Thanks for your help.

hmerk · August 13, 2023, 2:53pm

Try giving it more memory by changing from 384m to 768m.
Java 17 needs more than Java 11.

Lolodomo · August 13, 2023, 3:19pm

And could worth trying to uninstall denonmarrantz binding.

Wikibear · August 13, 2023, 3:21pm

Thanks Guys, will give a try. If i lost the Denon Binding it was a little bit bad.

Wikibear · August 14, 2023, 4:00pm

I don’t touch the system after setting java opts to 768m. Looks a little bit more stable today at first. I saw some rules errors and i try to fix. The rule i try to fix goes to running after edit and stay there. Nothing happens… It needs 5-10min that Openhab maybe recover by itself. Check Syslog and get this:

Aug 14 17:55:07 openhabian karaf[4024]: Exception in thread "Timer-522" org.eclipse.jetty.websocket.api.WebSocketException: Session closed
Aug 14 17:55:15 openhabian karaf[4024]: #011at org.eclipse.jetty.websocket.common.WebSocketSession.outgoingFrame(WebSocketSession.java:350)
Aug 14 17:55:15 openhabian karaf[4024]: #011at org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint.uncheckedSendFrame(WebSocketRemoteEndpoint.java:322)
Aug 14 17:55:15 openhabian karaf[4024]: #011at org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint.blockingWrite(WebSocketRemoteEndpoint.java:109)
Aug 14 17:55:16 openhabian karaf[4024]: #011at org.eclipse.jetty.websocket.common.WebSocketRemoteEndpoint.sendBytes(WebSocketRemoteEndpoint.java:260)
Aug 14 17:55:16 openhabian karaf[4024]: #011at org.smarthomej.binding.amazonechocontrol.internal.websocket.AlexaWebSocket.sendMessage(AlexaWebSocket.java:162)
Aug 14 17:55:16 openhabian karaf[4024]: #011at org.smarthomej.binding.amazonechocontrol.internal.websocket.AlexaWebSocket.sendPing(AlexaWebSocket.java:175)
Aug 14 17:55:16 openhabian karaf[4024]: #011at org.smarthomej.binding.amazonechocontrol.internal.websocket.WebSocketConnection$1.run(WebSocketConnection.java:186)
Aug 14 17:55:16 openhabian karaf[4024]: #011at java.base/java.util.TimerThread.mainLoop(Timer.java:566)
Aug 14 17:55:16 openhabian karaf[4024]: #011at java.base/java.util.TimerThread.run(Timer.java:516)

wborn · August 14, 2023, 4:30pm

If you still have CPU issues with the fix, configure htop to show custom thread names so it is more clear what is consuming your CPU, see:

You can also do this with top as described in the FAQ:

openHAB 4 migration FAQ

Example

top:

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
11216 openhab   20   0 1318172 472096   4528 S 256.2  47.4   4740:45 java

top -H -p 11216:

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
25409 openhab   20   0 1318172 471856   4528 R  99.7  47.4  93:36.83 upnp-main-queue
30546 openhab   20   0 1318172 471856   4528 R  99.7  47.4  15:19.09 safeCall-queue

Wikibear · August 15, 2023, 10:17am

That’s strange… OH stuck i don’t get acces via http to basic ui and so many different process are open and CPU isn’t really high…

Edit: Wondering that there so many rules will be showed with htop but not running… But the running time of some rules confuses me really. If a rule runs 25-50 sec so what happens if you have 2,3 or more rules they are running in same time.

rlkoshak · August 15, 2023, 3:04pm

Each rule has it’s very own thread. That thread will only be shown as running when the rule is actively executing. If you have 2+ rules running at the same time, each thread should be shown running independently.