Seite 1 von 2

seltsame log Einträge nach Neustart

Verfasst: 17. Okt 2022 08:02
von Quautiputzli
Hallo miteinander

Ich benutze einen pi 4 mit 4GB. Wie ich in meinem anderen Faden angemerkt habe hängt sich mein OH von Zeit zu Zeit auf.
viewtopic.php?t=7427
Nach dem Neustart habe ich diese seltsamen log Einträge entdeckt:

Code: Alles auswählen

2022-10-16 20:15:15.777 [INFO ] [af.deployer.kar.KarArtifactInstaller] - Found a .kar file to deploy.
2022-10-16 20:15:15.780 [INFO ] [af.deployer.kar.KarArtifactInstaller] - KAR openhab-addons-3.3.0.kar is already installed. Please uninstall it first.
Ist das irgendwie doppelt vorhanden? Muss ich hierzu etwas machen?

Außerdem schein die persistence von einem Item nicht mehr zu funktionieren:

Code: Alles auswählen

2022-10-16 20:15:29.757 [ERROR] [d4j.internal.RRD4jPersistenceService] - Could not create rrd4j database file '/var/lib/openhab/persistence/rrd4j/openhabraspberry_CPUTemperature.rrd': /var/lib/openhab/persistence/rrd4j/openhabraspberry_CPUTemperature.rrd
Bei diesem Item ist tatsächlich auch kein Graph vorhanden bei Analyze.
Wie kann das sein? Wie muss ich vorgehen?

Re: seltsame log Einträge nach Neustart

Verfasst: 17. Okt 2022 08:26
von Quautiputzli
Habe mit dem Handy nochmal auf das System geschaut. Was sollte hier ankommen mit:

Code: Alles auswählen

ps -aux | grep openhabian
Bei mir sieht es so aus:

Code: Alles auswählen

openhabian@openhabian:~ $ ps -aux | grep openhabian
avahi      325  0.1  0.0   6916  2628 ?        Ss   Oct16   0:51 avahi-daemon: running [openhabian.local]
root      2628  1.1  0.1  14516  7016 ?        Ss   08:19   0:01 sshd: openhabian [priv]
openhab+  2673  0.0  0.1  14516  4472 ?        S    08:20   0:00 sshd: openhabian@pts/0
openhab+  3181  0.0  0.0   7932   572 pts/0    S+   08:21   0:00 grep --color=auto 
openhabian

Re: seltsame log Einträge nach Neustart

Verfasst: 17. Okt 2022 13:12
von udo1toni
Es sollte für den User openhabian tatsächlich nur die ssh-Verbindung gelistet werden (das sind die zwei unteren Zeilen mit openhab+, weil die Spaltenbreite nicht ausreicht)
avahi wiederum announced seine Dienste unter dem lokalen Namen des Rechners, der vermutlich openhabian heißt.

Zu Deinem eigentlichen Problem:
Bezüglich der .kar Datei: vermutlich liegt die im addons Ordner. Bei mir liegt dort keine *.kar Datei. Es kann sein, dass das mit einer Offline-Option zu tun hat (also Addons installieren ohne aktiven Internetzugriff zu haben). Ich gehe davon aus, dass die Datei eigentlich in einem anderen Ordner liegen müsste, kann Dir aber leider auch keinen Tipp geben, welcher dies wäre.

Was den Fehler mit der rrd-Datei betrifft: Wechsle bitte mal in das übergeordnete Verzeichnis /var/lib/openhab/persistence/ und prüfe, ob der User openhab (nicht openhabian!) Besitzer des Verzeichnisses rrd4j ist und dort auch Schreibrechte hat. Ist das der Fall, wechsle bitte ins Verzeichnnis rrd4j und prüfe, ob die Datei openhabraspberry_CPUTemperature.rrd eventuell schon existiert, aber nicht dem User openhab gehört und/oder die falschen Zugriffsrechte hat.

Re: seltsame log Einträge nach Neustart

Verfasst: 17. Okt 2022 15:05
von Quautiputzli
udo1toni hat geschrieben: 17. Okt 2022 13:12 Bezüglich der .kar Datei: vermutlich liegt die im addons Ordner. Bei mir liegt dort keine *.kar Datei.
Ja, die liegt tatsächlich im addons-ordner. Weiß auch nicht warum. Sollte aber keine Probleme verursachen.
udo1toni hat geschrieben: 17. Okt 2022 13:12 Wechsle bitte mal in das übergeordnete Verzeichnis /var/lib/openhab/persistence/ und prüfe, ob der User openhab (nicht openhabian!) Besitzer des Verzeichnisses rrd4j ist und dort auch Schreibrechte hat. Ist das der Fall, wechsle bitte ins Verzeichnnis rrd4j und prüfe, ob die Datei openhabraspberry_CPUTemperature.rrd eventuell schon existiert, aber nicht dem User openhab gehört und/oder die falschen Zugriffsrechte hat.

Code: Alles auswählen

openhabian@openhabian:/var/lib/openhab/persistence $ ls -al
total 44
drwxr-xr-x  1 openhab openhabian  4096 Oct 16 20:09 .
drwxrwxr-x 17 openhab openhab     4096 Sep 18 11:52 ..
drwxrwxr-x  2 openhab openhab     4096 Sep 18 11:10 db4o
drwxrwxr-x  2 openhab openhab     4096 Sep 18 11:10 mapdb
drwxrwxr-x  1 openhab openhab    24576 Oct 16 20:09 rrd4j
Wenn ich das richtig interpretiere, ist openhab der Besitzer und hat alle Rechte.

Code: Alles auswählen

openhabian@openhabian:/var/lib/openhab/persistence/rrd4j $ ls -al
total 290416
...............
-rw------- 1 root    root       122880 Oct 16 20:09 openhabraspberry_CPUTemperature.rrd
...............
Die openhabraspberry_CPUTemperature.rrd ist die einzige Datei in dem Ordner die wohl nicht passt.

Was ist nun zu tun? Einzeln die Rechte und Besitzer ändern oder mit

Code: Alles auswählen

sudo openhab-clie reset-ownership

Re: seltsame log Einträge nach Neustart

Verfasst: 17. Okt 2022 22:00
von udo1toni
Warum auch immer die Datei den User root als Besitzer hat, das ist das Problem. Entweder

Code: Alles auswählen

sudo openhab-cli reset-ownership
(ohne das e...) oder alternativ kannst Du auch die Datei einzeln korrigieren:

Code: Alles auswählen

sudo chown openhab: /var/lib/openhab/persistence/rrd4j/openhabraspberry_CPUTemperature.rrd
Der Doppelpunkt ist eine Abkürzung, um sowohl Besitzer als auch Gruppe identisch zu setzen.

Gewöhnlich sollten die Rechte -rw-r--r-- gesetzt sein, das wäre dann ein zweiter Befehl:

Code: Alles auswählen

sudo chmod 644 /var/lib/openhab/persistence/rrd4j/openhabraspberry_CPUTemperature.rrd
Ich bin mir nicht sicher, ob reset-ownership das auch korrigiert.

Re: seltsame log Einträge nach Neustart

Verfasst: 18. Okt 2022 16:26
von Quautiputzli
Hab die zwei Befehle ausgeführt. Die Rechte/Besitzer sind nun wie bei den anderen.

Aber nun kommt das:

Code: Alles auswählen

2022-10-18 16:25:23.669 [WARN ] [org.openhab.core.items.GenericItem  ] - failed notifying listener 'org.openhab.core.persistence.internal.PersistenceManagerImpl@b2fadf' about state update of item openhabraspberry_CPUTemperature: null
java.lang.IndexOutOfBoundsException: null
	at java.nio.Buffer.checkIndex(Buffer.java:693) ~[?:?]
	at java.nio.DirectByteBuffer.getInt(DirectByteBuffer.java:758) ~[?:?]
	at org.rrd4j.core.ByteBufferBackend.readInt(ByteBufferBackend.java:130) ~[?:?]
	at org.rrd4j.core.RrdPrimitive.readInt(RrdPrimitive.java:38) ~[?:?]
	at org.rrd4j.core.RrdInt.get(RrdInt.java:35) ~[?:?]
	at org.rrd4j.core.Archive.<init>(Archive.java:45) ~[?:?]
	at org.rrd4j.core.RrdDb.<init>(RrdDb.java:646) ~[?:?]
	at org.rrd4j.core.RrdDb.of(RrdDb.java:500) ~[?:?]
	at org.openhab.persistence.rrd4j.internal.RRD4jPersistenceService.getDB(RRD4jPersistenceService.java:322) ~[?:?]
	at org.openhab.persistence.rrd4j.internal.RRD4jPersistenceService.store(RRD4jPersistenceService.java:140) ~[?:?]
	at org.openhab.core.persistence.internal.PersistenceManagerImpl.handleStateEvent(PersistenceManagerImpl.java:152) ~[?:?]
	at org.openhab.core.persistence.internal.PersistenceManagerImpl.stateChanged(PersistenceManagerImpl.java:473) ~[?:?]
	at org.openhab.core.items.GenericItem.lambda$1(GenericItem.java:259) ~[?:?]
	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) [?:?]
und ab und zu das:

Code: Alles auswählen

2022-10-18 16:24:48.831 [ERROR] [internal.JSONResponseExceptionMapper] - Unexpected exception occurred while processing REST request.
java.lang.IndexOutOfBoundsException: null
	at java.nio.Buffer.checkIndex(Buffer.java:693) ~[?:?]
	at java.nio.DirectByteBuffer.getInt(DirectByteBuffer.java:758) ~[?:?]
	at org.rrd4j.core.ByteBufferBackend.readInt(ByteBufferBackend.java:130) ~[?:?]
	at org.rrd4j.core.RrdPrimitive.readInt(RrdPrimitive.java:38) ~[?:?]
	at org.rrd4j.core.RrdInt.get(RrdInt.java:35) ~[?:?]
	at org.rrd4j.core.Archive.<init>(Archive.java:45) ~[?:?]
	at org.rrd4j.core.RrdDb.<init>(RrdDb.java:646) ~[?:?]
	at org.rrd4j.core.RrdDb.of(RrdDb.java:500) ~[?:?]
	at org.openhab.persistence.rrd4j.internal.RRD4jPersistenceService.getDB(RRD4jPersistenceService.java:322) ~[?:?]
	at org.openhab.persistence.rrd4j.internal.RRD4jPersistenceService.query(RRD4jPersistenceService.java:235) ~[?:?]
	at org.openhab.core.io.rest.core.internal.persistence.PersistenceResource.createDTO(PersistenceResource.java:322) ~[?:?]
	at org.openhab.core.io.rest.core.internal.persistence.PersistenceResource.getItemHistoryDTO(PersistenceResource.java:226) ~[?:?]
	at org.openhab.core.io.rest.core.internal.persistence.PersistenceResource.httpGetPersistenceItemData(PersistenceResource.java:175) ~[?:?]
	at jdk.internal.reflect.GeneratedMethodAccessor139.invoke(Unknown Source) ~[?:?]
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
	at org.apache.cxf.service.invoker.AbstractInvoker.performInvocation(AbstractInvoker.java:179) ~[bundleFile:3.4.5]
	at org.apache.cxf.service.invoker.AbstractInvoker.invoke(AbstractInvoker.java:96) ~[bundleFile:3.4.5]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:201) ~[bundleFile:3.4.5]
	at org.apache.cxf.jaxrs.JAXRSInvoker.invoke(JAXRSInvoker.java:104) ~[bundleFile:3.4.5]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor$1.run(ServiceInvokerInterceptor.java:59) ~[bundleFile:3.4.5]
	at org.apache.cxf.interceptor.ServiceInvokerInterceptor.handleMessage(ServiceInvokerInterceptor.java:96) ~[bundleFile:3.4.5]
	at org.apache.cxf.phase.PhaseInterceptorChain.doIntercept(PhaseInterceptorChain.java:308) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.ChainInitiationObserver.onMessage(ChainInitiationObserver.java:121) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.http.AbstractHTTPDestination.invoke(AbstractHTTPDestination.java:265) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.ServletController.invokeDestination(ServletController.java:234) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:208) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.ServletController.invoke(ServletController.java:160) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.CXFNonSpringServlet.invoke(CXFNonSpringServlet.java:225) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.handleRequest(AbstractHTTPServlet.java:298) ~[bundleFile:3.4.5]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.doGet(AbstractHTTPServlet.java:222) ~[bundleFile:3.4.5]
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:687) ~[bundleFile:3.1.0]
	at org.apache.cxf.transport.servlet.AbstractHTTPServlet.service(AbstractHTTPServlet.java:273) ~[bundleFile:3.4.5]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:550) ~[bundleFile:9.4.46.v20220331]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:74) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[bundleFile:9.4.46.v20220331]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:294) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[bundleFile:9.4.46.v20220331]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:90) ~[bundleFile:?]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [bundleFile:9.4.46.v20220331]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [bundleFile:9.4.46.v20220331]
	at java.lang.Thread.run(Thread.java:829) [?:?]
Muss ich evtl. openhab mal neu starten.

Re: seltsame log Einträge nach Neustart

Verfasst: 18. Okt 2022 16:50
von Quautiputzli
Hab nun hinterher auch noch

Code: Alles auswählen

sudo systemctl stop openhab

Code: Alles auswählen

sudo openhab-cli reset-ownership

Code: Alles auswählen

sudo systemctl start openhab
ausgeführt, aber keine Änderung.

Re: seltsame log Einträge nach Neustart

Verfasst: 18. Okt 2022 22:53
von udo1toni
Mutmaßlich ist die Datei einfach kaputt, weil openHAB zumindest eine Zeit lang nicht hinein schreiben konnte. Beende bitte openHAB, lösche die Datei (oder verschiebe sie in ein anderes Verzeichnis außerhalb von openhab) und starte openHAB anschließend wieder:

Code: Alles auswählen

sudo systemctl stop openhab.service
mv /var/lib/openhab/persistence/rrd4j/openhabraspberry_CPUTemperature.rrd /home/openhabian/
sudo systemctl start openhab.service
openHAB sollte dann die Datei neu anlegen und beginnen neu zu füllen.

Re: seltsame log Einträge nach Neustart

Verfasst: 19. Okt 2022 07:35
von Quautiputzli
Ja, das werde ich testen, wenn ich zuhause bin.
Seltsamerweise funktioniert die Persistence seit dem Neustart von OH gestern bei einigen anderen Items auch nicht mehr. Sehe dazu aber keine Fehler im log.
Bild_2022-10-19_073430076.png

Re: seltsame log Einträge nach Neustart

Verfasst: 19. Okt 2022 18:35
von Quautiputzli
Scheint wieder zu funktionieren
Bild_2022-10-19_183435044.png
Bild_2022-10-19_183528281.png