Syslog and daemon.log flood with Java-Error

manswiss · February 3, 2021, 2:12pm

After a while when openhab is running (some hours) it begins to fill daemon.log and syslog. Around 5Gb every hour. Here is the first lines when it starts:

Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-1" java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:217)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-2" Exception in thread "pool-5-thread-3" Exception in thread "pool-5-thread-4" java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-5" #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-6" #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-7" java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-8" java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-9" java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-10" #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-11" #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.LinkedBlockingQueue.dequeue(LinkedBlockingQueue.java:214)
Feb  3 07:51:43 openHAB karaf[573]: Exception in thread "pool-5-thread-12" #011at java.base/java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:435)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb  3 07:51:43 openHAB karaf[573]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb  3 07:51:43 openHAB karaf[573]: java.lang.NullPointerException

What can be the source of this errors?

My System:

runtimeInfo:
version: 3.0.1
buildString: Release Build
locale: de_CH
systemInfo:
configFolder: /etc/openhab
userdataFolder: /var/lib/openhab
logFolder: /var/log/openhab
javaVersion: 11.0.9.1
javaVendor: Azul Systems, Inc.
javaVendorVersion: Zulu11.43+100-CA
osName: Linux
osVersion: 5.4.83-v7l+
osArchitecture: arm
availableProcessors: 4
freeMemory: 84565600
totalMemory: 259522560
bindings:

astro

mail

meteoblue

mqtt

rfxcom

samsungtv

shelly

systeminfo

RhavoX · February 22, 2021, 1:23am

Seeing same thing since the last update I think.

Wolfgang1966 · March 14, 2021, 9:15am

Same for me. Only restarting openhab seems to help, created a cron job which checks for the error every three minutes and does the restart if necessary.

I use totally different bindings (modbus, tacmi, fritzbox, openweathermap), but also Java on ARM.

Java version is:

openjdk version "11.0.10" 2021-01-19 LTS
OpenJDK Runtime Environment Zulu11.45+27-CA (build 11.0.10+9-LTS)
OpenJDK Client VM Zulu11.45+27-CA (build 11.0.10+9-LTS, mixed mode)

Same problem is also discussed here: Syslog Errors (100 GB) - #7 by manswiss

Posted a workaround there. Also one user reports that the problem is gone with plain openjdk instead of Zulu, trying this also now.