Fehler im Log nach Reboot RM

Das Langzeitarchiv für HomeMatic

Moderator: Co-Administratoren

Antworten
g55
Beiträge: 235
Registriert: 02.10.2018, 19:24
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 59 Mal
Danksagung erhalten: 11 Mal

Fehler im Log nach Reboot RM

Beitrag von g55 » 28.05.2022, 22:54

Ich habe heute abend meine Raspberrymatic sowie auch Proxmox aktualisiert inkl. den Reboots.
Bei jedem Reboot der RM als VM bekomme ich im Historian-Log folgende 503-Meldungen, die mWn. "service not available" bedeuten ... verstehe ich grad net ...
nach nem erneuten Neustart des Historian is alles wieder "ruhig"
hier das letzte Log :

Code: Alles auswählen

2022-05-28 22:27:18|INFO   |Starting base services
2022-05-28 22:27:19|INFO   |Connecting to database
2022-05-28 22:27:24|INFO   |Starting database web server
2022-05-28 22:27:24|INFO   |Setting up device 1
2022-05-28 22:27:24|INFO   |Creating HM script client for http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:27:25|INFO   |Setting up plug-in 1
2022-05-28 22:27:25|INFO   |Configured following interfaces: BidCos-RF, HmIP-RF, SysVar, CUxD
2022-05-28 22:27:25|INFO   |Starting interfaces
2022-05-28 22:27:25|INFO   |Starting BIN-RPC server on port 2099
2022-05-28 22:27:25|INFO   |Starting XML-RPC server on port 2098
2022-05-28 22:27:25|INFO   |Connecting to 127.0.0.1:8701
2022-05-28 22:27:25|INFO   |Starting historian
2022-05-28 22:27:26|INFO   |Starting web server
2022-05-28 22:27:26|INFO   |Logging initialized @12874ms to org.eclipse.jetty.util.log.JavaUtilLog
2022-05-28 22:27:26|INFO   |jetty-9.4.45.v20220203; built: 2022-02-03T09:14:34.105Z; git: 4a0c91c0be53805e3fcffdcdcc9587d5301863db; jvm 1.8.0_332-b09
2022-05-28 22:27:27|INFO   |NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
2022-05-28 22:27:27|INFO   |DefaultSessionIdManager workerName=node0
2022-05-28 22:27:27|INFO   |No SessionScavenger set, using defaults
2022-05-28 22:27:27|INFO   |node0 Scavenging every 600000ms
2022-05-28 22:27:27|INFO   |Started o.e.j.w.WebAppContext@1d5d5621{CCU-Historian Web Application,/,file:///usr/local/addons/ccu-historian/ccu-historian/webapp/,AVAILABLE}
2022-05-28 22:27:27|INFO   |Started ServerConnector@36fc05ff{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2022-05-28 22:27:27|INFO   |Started @13887ms
2022-05-28 22:27:27|INFO   |Web server port: 8082
2022-05-28 22:27:28|SEVERE |Exception: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:27:28|SEVERE |Detail: java.io.IOException: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
	at mdz.hc.itf.hm.HmScriptClient.execute(HmScriptClient.groovy:360)
	at mdz.hc.itf.hm.HmScriptClient.retrieveDevices(HmScriptClient.groovy:216)
	at mdz.hc.itf.hm.HmScriptClient.getModel(HmScriptClient.groovy:305)
	at mdz.hc.itf.hm.HmXmlRpcInterface.updateLogicProperties(HmXmlRpcInterface.groovy:135)
	at mdz.hc.itf.hm.HmXmlRpcInterface.updateProperties(HmXmlRpcInterface.groovy:244)
	at mdz.hc.itf.Manager$_updateProperties_closure4.doCall(Manager.groovy:119)
	at mdz.hc.itf.Manager.updateProperties(Manager.groovy:116)
	at mdz.ccuhistorian.Historian.update(Historian.groovy:156)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy:114)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3.doCall(Historian.groovy:110)
	at mdz.ccuhistorian.Historian.updateDataPointMeta(Historian.groovy:109)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy:103)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian.run(Historian.groovy:102)
2022-05-28 22:27:29|SEVERE |Exception: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:27:29|SEVERE |Detail: java.io.IOException: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
	at mdz.hc.itf.hm.HmScriptClient.execute(HmScriptClient.groovy:360)
	at mdz.hc.itf.hm.HmScriptClient.retrieveDevices(HmScriptClient.groovy:216)
	at mdz.hc.itf.hm.HmScriptClient.getModel(HmScriptClient.groovy:305)
	at mdz.hc.itf.hm.HmXmlRpcInterface.updateLogicProperties(HmXmlRpcInterface.groovy:135)
	at mdz.hc.itf.hm.HmXmlRpcInterface.updateProperties(HmXmlRpcInterface.groovy:244)
	at mdz.hc.itf.Manager$_updateProperties_closure4.doCall(Manager.groovy:119)
	at mdz.hc.itf.Manager.updateProperties(Manager.groovy:116)
	at mdz.ccuhistorian.Historian.update(Historian.groovy:156)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy:114)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3.doCall(Historian.groovy:110)
	at mdz.ccuhistorian.Historian.updateDataPointMeta(Historian.groovy:109)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy:103)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian.run(Historian.groovy:102)
2022-05-28 22:27:29|SEVERE |Exception: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:27:29|SEVERE |Detail: java.io.IOException: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
	at mdz.hc.itf.hm.HmScriptClient.execute(HmScriptClient.groovy:360)
	at mdz.hc.itf.hm.HmScriptClient.getSystemVariables(HmScriptClient.groovy:60)
	at mdz.hc.itf.hm.HmSysVarInterface.getCache(HmSysVarInterface.groovy:79)
	at mdz.hc.itf.hm.HmSysVarInterface.getAllDataPoints(HmSysVarInterface.groovy:140)
	at mdz.ccuhistorian.Historian.browse(Historian.groovy:124)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy:112)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3.doCall(Historian.groovy:110)
	at mdz.ccuhistorian.Historian.updateDataPointMeta(Historian.groovy:109)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy:103)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian.run(Historian.groovy:102)
2022-05-28 22:27:29|SEVERE |Exception: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:27:29|SEVERE |Detail: java.io.IOException: Server returned HTTP response code: 503 for URL: http://127.0.0.1:8181/tclrega.exe
	at mdz.hc.itf.hm.HmScriptClient.execute(HmScriptClient.groovy:360)
	at mdz.hc.itf.hm.HmScriptClient.retrieveDevices(HmScriptClient.groovy:216)
	at mdz.hc.itf.hm.HmScriptClient.getModel(HmScriptClient.groovy:305)
	at mdz.hc.itf.hm.HmBinRpcInterface.updateLogicProperties(HmBinRpcInterface.groovy:136)
	at mdz.hc.itf.hm.HmBinRpcInterface.updateProperties(HmBinRpcInterface.groovy:246)
	at mdz.hc.itf.Manager$_updateProperties_closure4.doCall(Manager.groovy:119)
	at mdz.hc.itf.Manager.updateProperties(Manager.groovy:116)
	at mdz.ccuhistorian.Historian.update(Historian.groovy:156)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy:114)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3$_closure12.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian$_updateDataPointMeta_closure3.doCall(Historian.groovy:110)
	at mdz.ccuhistorian.Historian.updateDataPointMeta(Historian.groovy:109)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy:103)
	at mdz.ccuhistorian.Historian$_run_closure2.doCall(Historian.groovy)
	at mdz.Exceptions.lambda$0(Exceptions.java:84)
	at mdz.Exceptions.catchToLog(Exceptions.java:74)
	at mdz.Exceptions.catchToLog(Exceptions.java:84)
	at mdz.ccuhistorian.Historian.run(Historian.groovy:102)
2022-05-28 22:28:33|INFO   |Groovy servlet initialized on groovy.util.GroovyScriptEngine@13337766.
2022-05-28 22:31:23|INFO   |Starting base services
2022-05-28 22:31:23|INFO   |Connecting to database
2022-05-28 22:31:25|INFO   |Starting database web server
2022-05-28 22:31:25|INFO   |Setting up device 1
2022-05-28 22:31:25|INFO   |Creating HM script client for http://127.0.0.1:8181/tclrega.exe
2022-05-28 22:31:25|INFO   |Setting up plug-in 1
2022-05-28 22:31:26|INFO   |Configured following interfaces: BidCos-RF, HmIP-RF, SysVar, CUxD
2022-05-28 22:31:26|INFO   |Starting interfaces
2022-05-28 22:31:26|INFO   |Starting BIN-RPC server on port 2099
2022-05-28 22:31:26|INFO   |Starting XML-RPC server on port 2098
2022-05-28 22:31:26|INFO   |Connecting to 127.0.0.1:8701
2022-05-28 22:31:26|INFO   |Starting historian
2022-05-28 22:31:26|INFO   |Starting web server
2022-05-28 22:31:26|INFO   |Logging initialized @6314ms to org.eclipse.jetty.util.log.JavaUtilLog
2022-05-28 22:31:27|INFO   |jetty-9.4.45.v20220203; built: 2022-02-03T09:14:34.105Z; git: 4a0c91c0be53805e3fcffdcdcc9587d5301863db; jvm 1.8.0_332-b09
2022-05-28 22:31:27|INFO   |NO JSP Support for /, did not find org.eclipse.jetty.jsp.JettyJspServlet
2022-05-28 22:31:27|INFO   |DefaultSessionIdManager workerName=node0
2022-05-28 22:31:27|INFO   |No SessionScavenger set, using defaults
2022-05-28 22:31:27|INFO   |node0 Scavenging every 660000ms
2022-05-28 22:31:27|INFO   |Started o.e.j.w.WebAppContext@1d5d5621{CCU-Historian Web Application,/,file:///usr/local/addons/ccu-historian/ccu-historian/webapp/,AVAILABLE}
2022-05-28 22:31:27|INFO   |Started ServerConnector@36fc05ff{HTTP/1.1, (http/1.1)}{0.0.0.0:8082}
2022-05-28 22:31:27|INFO   |Started @7157ms
2022-05-28 22:31:27|INFO   |Web server port: 8082
2022-05-28 22:32:15|INFO   |Groovy servlet initialized on groovy.util.GroovyScriptEngine@7357594f.
also muss ich grad nach nem Neustart vom RM den Historian noch mal neu starten, damit alles i.O is. Des kanns net sei :roll:
Mach ich was falsch oder hat jemand das gleiche Problem oder welche Infos werden noch benötigt ?
Ich würde mich freuen über jegwede Rückmeldung.
Vielen Dank.
Proxmox-MiniServer (J4125, 12GB RAM, nur SSDs, Proxmox 7.4-3), RM v3.69.7.20230506, abgesetztes, altes Funkmodul HM-MOD-RPI-PCB am RB-RF-ETH, ca. 5 HM- und 107 HMIP-Geräte, Addons : CUxD v2.10.1, eMail v1.7.6, XML-API v1.22, JB-HB v6.0, ProgrammeDrucken v2.6, CCU-Historian v3.3.1
Nach oben
Benutzeravatar
Roland M.
Beiträge: 9784
Registriert: 08.12.2012, 15:53
System: CCU
Wohnort: Graz, Österreich
Hat sich bedankt: 252 Mal
Danksagung erhalten: 1373 Mal

Re: Fehler im Log nach Reboot RM

Beitrag von Roland M. » 28.05.2022, 23:26

Hallo!
g55 hat geschrieben: ↑
28.05.2022, 22:54
 also muss ich grad nach nem Neustart vom RM den Historian noch mal neu starten, damit alles i.O is.
Hab ich bei mir auch von Zeit zu Zeit.
Bei mir läuft der Historian als eigene VM unter Proxmox und holt sich die Daten von drei CCU2, wovon zwei davon per VPS angebunden sind.
Da kommt es immer wieder einmal vor, dass der Historian vor allem von einer CCU2 keine Daten mehr abholt, obwohl der VPN-Tunnel steht und ganz normal auf die CCU zugegriffen werden kann. Ich vermute, dass da der Historian in ein Timeout läuft, wenn eine Zwangstrennung der Internetverbindung durch den Provider sattfindet und es zu lange dauert, bis die Fritzboxen wieder den Tunnel aufbauen.

Wenn ich daran denke, mache ich nach einem Neustart einer CCU eben einen Blick auf den Historian. Ist dann meist auch eine passende Gelegenheit, ein Betriebssystem-Update zu machen, auf das vergisst man ja auch gerne, wenn alles unauffällig und problemlos läuft... ;)


Roland
Zur leichteren Hilfestellung bitte unbedingt beachten:
  • Bezeichnung (HM-... bzw. HmIP-...) der betroffenen Geräte angeben (nicht Artikelnummer)
  • Kurzbeschreibung des Soll-Zustandes (Was soll erreicht werden?)
  • Kurzbeschreibung des Ist-Zustandes (Was funktioniert nicht?)
  • Fehlermeldungen genau abschreiben, besser noch...
  • Screenshots von Programmen, Geräteeinstellungen und Fehlermeldungen (direkt als jpg/png) einstellen!

-----------------------------------------------------------------------
1. CCU2 mit ~100 Geräten (in Umstellung auf RaspberryMatic-OVA auf Proxmox-Server)
2. CCU2 per VPN mit ~50 Geräten (geplant: RaspberryMatic auf Charly)
3. CCU2 per VPN mit ~40 Geräten (geplant: RaspberryMatic auf CCU3)
CCU1, Test-CCU2, Raspi 1 mit kleinem Funkmodul, RaspberryMatic als VM unter Proxmox, Access Point,...
Nach oben
Antworten

Zurück zu „CCU-Historian“