CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Das Langzeitarchiv für HomeMatic

Moderator: Co-Administratoren

alexgut
Beiträge: 10
Registriert: 12.05.2017, 08:46
Hat sich bedankt: 1 Mal

CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von alexgut » 01.11.2018, 20:59

Hallo,

ich habe ein Problem mit dem Umstieg des Historian auf die CCU3.

CCU-Historian 2.0.0 Beta4 auf Raspi mit CCU2 läuft.
Neue CCU3 mit gleicher IP wie CCU2 in Betrieb genommen. Backup von der CCU2 drauf gespielt - alles funktionert so weit. Nur ist jetzt der CCU Historian nicht mehr erreichbar :( .

Wenn ich mir den Start des Historian auf Raspi anschaue bekomme ich folgendes (Achtung jetzt kommt viel Text, aber vielleicht hilft's bei der Analyse :wink: ):

pi@Raspi-Keller:~ $ sudo java -jar /opt/ccu-historian/ccu-historian.jar -config /opt/ccu-historian/ccu-historian.config
2018-11-01 20:53:39|INFO |CCU-Historian V2.0.0-beta.4
2018-11-01 20:53:39|INFO |(C)MDZ (info@ccu-historian.de)
2018-11-01 20:53:41|INFO |Starting base services
2018-11-01 20:53:41|INFO |Connecting to database
2018-11-01 20:53:43|INFO |Starting database web server
2018-11-01 20:53:44|INFO |Setting up device 1
2018-11-01 20:53:44|INFO |Creating HM script client for http://192.168.178.43:8181/tclrega.exe
2018-11-01 20:53:44|INFO |Setting up plug-in 1
2018-11-01 20:53:44|INFO |Configured following interfaces: BidCos-RF, HmIP-RF, SysVar, CUxD
2018-11-01 20:53:44|INFO |Starting interfaces
2018-11-01 20:53:44|INFO |Starting BIN-RPC server on port 2099
2018-11-01 20:53:44|INFO |Starting XML-RPC server on port 2098
2018-11-01 20:53:44|INFO |Connecting to 192.168.178.43:2001
2018-11-01 20:53:54|INFO |Stopping BIN-RPC server on port 2099
2018-11-01 20:53:54|INFO |Stopping database
2018-11-01 20:53:54|INFO |Stopping base services
2018-11-01 20:53:54|SEVERE |Exception: Read timed out
2018-11-01 20:53:54|SEVERE |Detail: java.net.SocketTimeoutException: Read timed out
at mdz.hc.itf.binrpc.BinRpcClient.receive(BinRpcClient.groovy:104)
at mdz.hc.itf.binrpc.BinRpcClient.call(BinRpcClient.groovy:144)
at mdz.hc.itf.hm.HmBinRpcInterface.init(HmBinRpcInterface.groovy:121)
at mdz.hc.itf.hm.HmBinRpcInterface.start(HmBinRpcInterface.groovy:79)
at mdz.hc.itf.Manager$_start_closure1.doCall(Manager.groovy:60)
at mdz.hc.itf.Manager.start(Manager.groovy:58)
at mdz.ccuhistorian.HistorianSystem.<init>(HistorianSystem.groovy:39)
at mdz.ccuhistorian.Main.start(Main.groovy:100)
at mdz.ccuhistorian.Main.access$0(Main.groovy)
at mdz.ccuhistorian.Main$_run_closure4.doCall(Main.groovy:77)
at mdz.ccuhistorian.Main$_run_closure4.call(Main.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.Main.run(Main.groovy:77)
at mdz.ccuhistorian.Main.main(Main.groovy:45)


in der Zeile bleibt er anscheinden hängen:
2018-11-01 20:53:44|INFO |Connecting to 192.168.178.43:2001

Kann mir hier vielleicht Jemand weiterhelfen?

frantic
Beiträge: 16
Registriert: 30.11.2010, 20:31
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von frantic » 01.11.2018, 22:00

Ich bin noch Anfänger, was das Lesen der Logs angeht... Meine Vermutung ist, dass bereits der erste Verbindungsaufbau zur CCU scheitert.
Vielleicht testen, ob man überhaupt durchkommt:

Code: Alles auswählen

telnet 192.168.178.43 80
telnet 192.168.178.43 2001

alexgut
Beiträge: 10
Registriert: 12.05.2017, 08:46
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von alexgut » 01.11.2018, 23:17

Da bin ich leider noch mehr Anfänger...

telnet geht auf meinem Pi nicht. Gibt es da noch eine andere Möglichkeit das zu testen?
Ich kannte noch den Ping-Befehl. Das Ping vom Pi zur CCU3 mit der IP-Adresse geht - CCU3 antwortet. Da kann ich aber keine Port angeben.

alexgut
Beiträge: 10
Registriert: 12.05.2017, 08:46
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von alexgut » 01.11.2018, 23:47

Ich habe noch einen LOG mit der Einstellung FINEST gemacht

Code: Alles auswählen

2018-11-01 23:42:05|INFO   |CCU-Historian V2.0.0-beta.4
2018-11-01 23:42:05|INFO   |(C)MDZ (info@ccu-historian.de)
2018-11-01 23:42:07|FINE   |Log system configuration:
2018-11-01 23:42:07|FINE   |logSystem.consoleLevel=Level.FINEST
2018-11-01 23:42:07|FINE   |logSystem.fileLevel=Level.FINEST
2018-11-01 23:42:07|FINE   |logSystem.fileName='./ccu-historian-%g.log'
2018-11-01 23:42:07|FINE   |logSystem.fileLimit=1000000
2018-11-01 23:42:07|FINE   |logSystem.fileCount=5
2018-11-01 23:42:07|FINE   |logSystem.binRpcLevel=Level.FINEST
2018-11-01 23:42:07|INFO   |Starting base services
2018-11-01 23:42:07|INFO   |Connecting to database
2018-11-01 23:42:07|FINE   |database.dir='/opt/ccu-historian/data'
2018-11-01 23:42:07|FINE   |database.name='history_6'
2018-11-01 23:42:07|FINE   |database.user='sa'
2018-11-01 23:42:07|FINE   |database.webEnable=true
2018-11-01 23:42:07|FINE   |database.webPort=8082
2018-11-01 23:42:07|FINE   |database.webAllowOthers=false
2018-11-01 23:42:07|FINE   |database.tcpEnable=false
2018-11-01 23:42:07|FINE   |database.tcpPort=9092
2018-11-01 23:42:07|FINE   |database.tcpAllowOthers=false
2018-11-01 23:42:07|FINE   |database.pgEnable=false
2018-11-01 23:42:07|FINE   |database.pgPort=5435
2018-11-01 23:42:07|FINE   |database.pgAllowOthers=false
2018-11-01 23:42:07|FINE   |database.backup=''
2018-11-01 23:42:09|FINE   |Preparing database
2018-11-01 23:42:09|FINE   |Read config: internal.databaseVersion=1
2018-11-01 23:42:09|FINE   |Read config: internal.databaseVersion=1
2018-11-01 23:42:09|INFO   |Starting database web server
2018-11-01 23:42:09|FINE   |Database management URL: http://127.0.1.1:8082
2018-11-01 23:42:09|FINE   |Configuring interfaces
2018-11-01 23:42:09|FINE   |All devices: Reading configuration option 'historianBinRpcPort': 
2018-11-01 23:42:09|FINE   |All devices: Reading configuration option 'historianXmlRpcPort': 
2018-11-01 23:42:09|FINE   |All devices: Reading configuration option 'historianAddress': 192.168.178.56
2018-11-01 23:42:09|INFO   |Setting up device 1
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'type': CCU2
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'address': 192.168.178.43
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'reinitTimeout': 
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'prefix': 
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'writeAccess': 
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'sysVarDataCycle': 
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'timeout': 
2018-11-01 23:42:09|INFO   |Creating HM script client for http://192.168.178.43:8181/tclrega.exe
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'watchdogProgram': 
2018-11-01 23:42:09|FINE   |Device 1: Reading configuration option 'watchdogCycle': 
2018-11-01 23:42:09|INFO   |Configured following interfaces: BidCos-RF, HmIP-RF, SysVar
2018-11-01 23:42:09|INFO   |Starting interfaces
2018-11-01 23:42:09|INFO   |Starting BIN-RPC server on port 2099
2018-11-01 23:42:09|INFO   |Starting XML-RPC server on port 2098
2018-11-01 23:42:09|FINE   |XML-RPC server address is http://192.168.178.56:2098
2018-11-01 23:42:09|FINE   |Calling method 'init' with parameters [binary://192.168.178.56:2099, BidCos-RF]
2018-11-01 23:42:09|INFO   |Connecting to 192.168.178.43:2001
2018-11-01 23:42:10|FINEST |Sending 73 bytes to 192.168.178.43:2001:
0000: Bin....A....init............bina  42 69 6E 00 00 00 00 41 00 00 00 04 69 6E 69 74 00 00 00 02 00 00 00 03 00 00 00 1C 62 69 6E 61 
0020: ry://192.168.178.56:2099........  72 79 3A 2F 2F 31 39 32 2E 31 36 38 2E 31 37 38 2E 35 36 3A 32 30 39 39 00 00 00 03 00 00 00 09 
0040: BidCos-RF                         42 69 64 43 6F 73 2D 52 46 
2018-11-01 23:42:10|FINEST |Receiving 8 bytes from 192.168.178.43:2001
2018-11-01 23:42:20|FINE   |Stopping XML-RPC server
2018-11-01 23:42:20|INFO   |Stopping BIN-RPC server on port 2099
2018-11-01 23:42:20|FINE   |Stopping main scheduler
2018-11-01 23:42:20|INFO   |Stopping database
2018-11-01 23:42:20|INFO   |Stopping base services
2018-11-01 23:42:20|SEVERE |Exception: Read timed out
2018-11-01 23:42:20|SEVERE |Detail: java.net.SocketTimeoutException: Read timed out
	at mdz.hc.itf.binrpc.BinRpcClient.receive(BinRpcClient.groovy:104)
	at mdz.hc.itf.binrpc.BinRpcClient.call(BinRpcClient.groovy:144)
	at mdz.hc.itf.hm.HmBinRpcInterface.init(HmBinRpcInterface.groovy:121)
	at mdz.hc.itf.hm.HmBinRpcInterface.start(HmBinRpcInterface.groovy:79)
	at mdz.hc.itf.Manager$_start_closure1.doCall(Manager.groovy:60)
	at mdz.hc.itf.Manager.start(Manager.groovy:58)
	at mdz.ccuhistorian.HistorianSystem.<init>(HistorianSystem.groovy:39)
	at mdz.ccuhistorian.Main.start(Main.groovy:100)
	at mdz.ccuhistorian.Main.access$0(Main.groovy)
	at mdz.ccuhistorian.Main$_run_closure4.doCall(Main.groovy:77)
	at mdz.ccuhistorian.Main$_run_closure4.call(Main.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.Main.run(Main.groovy:77)
	at mdz.ccuhistorian.Main.main(Main.groovy:45)
2018-11-01 23:42:20|FINEST |Sleeping

frantic
Beiträge: 16
Registriert: 30.11.2010, 20:31
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von frantic » 02.11.2018, 09:46

Wie gesagt, bin hier kein Experte... Telnet wäre eine Möglichkeit, von einem beliebigen Rechnern im Netz aus zu prüfen, ob Ports erreichbar sind und irgendwie antworten (Timeout wäre schlecht). Das muss nicht der Pi sein. Wobei Microsoft glaube ich Telnet bei Windows standardmäßig nicht mit installiert. Es gibt andere Programme / Befehle für Tests auf Netzwerkebene usw. - die sind mir nicht geläufig :-)
Ping tut es nicht, das prüft keine Ports. Es ist gut, wenn es durchkommt. Ist jedoch weder nötig noch hinreichend.

Die nötigen Ports auf CCU und Historian sind hier unter Firewall-Einstellungen beschrieben.
Beim mir hatte ich diese Woche ein Problem mit der Firewall, die auf einer neuen CCU3 anscheinend teilweise aktiv war, ohne dass ich das gewusst oder gewollt hätte.
Zum Vergleich der passende Ausschnitt aus Logs, wie es bei meinem funktionierenden System aussieht.

Code: Alles auswählen

2018-11-02 09:25:06|INFO   |Starting interfaces
2018-11-02 09:25:06|INFO   |Starting BIN-RPC server on port 2099
2018-11-02 09:25:06|INFO   |Starting XML-RPC server on port 2098
2018-11-02 09:25:06|FINE   |XML-RPC server address is http://10.0.0.10:2098
2018-11-02 09:25:06|FINE   |Calling method 'init' with parameters [binary://10.0.0.10:2099, BidCos-RF]
2018-11-02 09:25:06|INFO   |Connecting to 10.0.0.11:2001
2018-11-02 09:25:06|FINEST |Sending 68 bytes to 10.0.0.11:2001:
0000: Bin....<....init............bina  42 69 6E 00 00 00 00 3C 00 00 00 04 69 6E 69 74 00 00 00 02 00 00 00 03 00 00 00 17 62 69 6E 61
0020: ry://10.0.0.10:2099........BidCo  72 79 3A 2F 2F 31 30 2E 30 2E 30 2E 31 30 3A 32 30 39 39 00 00 00 03 00 00 00 09 42 69 64 43 6F
0040: s-RF                              73 2D 52 46
2018-11-02 09:25:06|FINEST |Receiving 8 bytes from 10.0.0.11:2001
2018-11-02 09:25:06|FINE   |Client 10.0.0.11:41256 has connected
2018-11-02 09:25:06|FINEST |Receiving 8 bytes
2018-11-02 09:25:06|FINEST |Received data:
0000: Bin....+                          42 69 6E 00 00 00 00 2B
2018-11-02 09:25:06|FINEST |Receiving 43 bytes
2018-11-02 09:25:06|FINEST |Received data:
0000: ....system.listMethods..........  00 00 00 12 73 79 73 74 65 6D 2E 6C 69 73 74 4D 65 74 68 6F 64 73 00 00 00 01 00 00 00 03 00 00
0020: ..BidCos-RF                       00 09 42 69 64 43 6F 73 2D 52 46
2018-11-02 09:25:06|FINE   |Call of method 'system.listMethods' received with parameters [BidCos-RF]

michael.o
Beiträge: 20
Registriert: 18.01.2017, 11:38

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von michael.o » 02.11.2018, 10:41

Hallo zusammen,

ich habe das gleiche Problem seit dem Update der CCU auf 3.41.7. Ich habe alle benötigten Ports auf der CCU3 freigegeben (2000; 2001; 2002; 2010; 8181; 8701;), und auch mit Vollzugriff ausprobiert, und erhalte die Fehlermeldung (192.168.2.16 ist die CCU3):

Code: Alles auswählen

2018-11-02 10:39:43|INFO   |Connecting to 192.168.2.16:2001
2018-11-02 10:39:43|FINEST |Sending 71 bytes to 192.168.2.16:2001:
0000: Bin....?....init............bina  42 69 6E 00 00 00 00 3F 00 00 00 04 69 6E 69 74 00 00 00 02 00 00 00 03 00 00 00 1A 62 69 6E 61 
0020: ry://192.168.2.10:2099........Bi  72 79 3A 2F 2F 31 39 32 2E 31 36 38 2E 32 2E 31 30 3A 32 30 39 39 00 00 00 03 00 00 00 09 42 69 
0040: dCos-RF                           64 43 6F 73 2D 52 46 
2018-11-02 10:39:43|FINEST |Receiving 8 bytes from 192.168.2.16:2001
2018-11-02 10:39:53|FINE   |Stopping XML-RPC server
2018-11-02 10:39:53|INFO   |Stopping BIN-RPC server on port 2099
2018-11-02 10:39:53|FINE   |Stopping main scheduler
2018-11-02 10:39:53|INFO   |Stopping database
2018-11-02 10:39:53|INFO   |Stopping base services
2018-11-02 10:39:53|SEVERE |Exception: Read timed out
2018-11-02 10:39:53|SEVERE |Detail: java.net.SocketTimeoutException: Read timed out
	at mdz.hc.itf.binrpc.BinRpcClient.receive(BinRpcClient.groovy:104)
	at mdz.hc.itf.binrpc.BinRpcClient.call(BinRpcClient.groovy:144)
	at mdz.hc.itf.hm.HmBinRpcInterface.init(HmBinRpcInterface.groovy:121)
	at mdz.hc.itf.hm.HmBinRpcInterface.start(HmBinRpcInterface.groovy:79)
	at mdz.hc.itf.Manager$_start_closure1.doCall(Manager.groovy:60)
	at mdz.hc.itf.Manager.start(Manager.groovy:58)
	at mdz.ccuhistorian.HistorianSystem.<init>(HistorianSystem.groovy:39)
	at mdz.ccuhistorian.Main.start(Main.groovy:100)
	at mdz.ccuhistorian.Main.access$0(Main.groovy)
	at mdz.ccuhistorian.Main$_run_closure4.doCall(Main.groovy:77)
	at mdz.ccuhistorian.Main$_run_closure4.call(Main.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.Main.run(Main.groovy:77)
	at mdz.ccuhistorian.Main.main(Main.groovy:45)
Ich vermute, das hat etwas mit dem Lighty-Reverse-Proxy zu tun, von dem Jens Maus schreibt, jedenfalls antwortet Lighty auf dem Port 2001.

Gruß,

Michael
Zuletzt geändert von michael.o am 02.11.2018, 13:43, insgesamt 2-mal geändert.

michael.o
Beiträge: 20
Registriert: 18.01.2017, 11:38

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von michael.o » 02.11.2018, 12:10

Es liegt reproduzierbar an Firmware 3.41.7 - nach Downgrade auf die vorherige Version funktioniert CCU-Historian wieder.

Gruß Michael
Zuletzt geändert von michael.o am 02.11.2018, 13:24, insgesamt 1-mal geändert.

frantic
Beiträge: 16
Registriert: 30.11.2010, 20:31
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von frantic » 02.11.2018, 13:02

In der Tat. Mit Firmware 3.41.7 (soeben ausprobiert) bekomme ich auch solche Fehler.
Vielleicht hat sich das Verhalten der CCU so verändert, dass der Historian nicht die erwartete Antwort bekommt.
Dann gehe ich mal zurück auf 3.37.8...

Hier der Log:

Code: Alles auswählen

2018-11-02 12:55:17|INFO   |Starting interfaces
2018-11-02 12:55:17|INFO   |Starting BIN-RPC server on port 2099
2018-11-02 12:55:17|INFO   |Starting XML-RPC server on port 2098
2018-11-02 12:55:17|FINE   |XML-RPC server address is http://10.0.0.10:2098
2018-11-02 12:55:17|FINE   |Calling method 'init' with parameters [binary://10.0.0.10:2099, BidCos-RF]
2018-11-02 12:55:17|INFO   |Connecting to 10.0.0.11:2001
2018-11-02 12:55:17|FINEST |Sending 68 bytes to 10.0.0.11:2001:
0000: Bin....<....init............bina  42 69 6E 00 00 00 00 3C 00 00 00 04 69 6E 69 74 00 00 00 02 00 00 00 03 00 00 00 17 62 69 6E 61
0020: ry://10.0.0.10:2099........BidCo  72 79 3A 2F 2F 31 30 2E 30 2E 30 2E 31 30 3A 32 30 39 39 00 00 00 03 00 00 00 09 42 69 64 43 6F
0040: s-RF                              73 2D 52 46
2018-11-02 12:55:17|FINEST |Receiving 8 bytes from 10.0.0.11:2001
2018-11-02 12:55:27|FINE   |Stopping XML-RPC server
2018-11-02 12:55:27|INFO   |Stopping BIN-RPC server on port 2099
2018-11-02 12:55:27|FINE   |Stopping main scheduler
2018-11-02 12:55:27|INFO   |Stopping database
2018-11-02 12:55:27|INFO   |Stopping base services
2018-11-02 12:55:27|SEVERE |Exception: Read timed out
2018-11-02 12:55:27|SEVERE |Detail: java.net.SocketTimeoutException: Read timed out
        at mdz.hc.itf.binrpc.BinRpcClient.receive(BinRpcClient.groovy:104)
        at mdz.hc.itf.binrpc.BinRpcClient.call(BinRpcClient.groovy:144)
        at mdz.hc.itf.hm.HmBinRpcInterface.init(HmBinRpcInterface.groovy:121)
        at mdz.hc.itf.hm.HmBinRpcInterface.start(HmBinRpcInterface.groovy:79)
        at mdz.hc.itf.Manager$_start_closure1.doCall(Manager.groovy:60)
        at mdz.hc.itf.Manager.start(Manager.groovy:58)
        at mdz.ccuhistorian.HistorianSystem.<init>(HistorianSystem.groovy:39)
        at mdz.ccuhistorian.Main.start(Main.groovy:100)
        at mdz.ccuhistorian.Main.access$0(Main.groovy)
        at mdz.ccuhistorian.Main$_run_closure4.doCall(Main.groovy:77)
        at mdz.ccuhistorian.Main$_run_closure4.call(Main.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.Main.run(Main.groovy:77)
        at mdz.ccuhistorian.Main.main(Main.groovy:45)
2018-11-02 12:55:27|FINEST |Sleeping

frantic
Beiträge: 16
Registriert: 30.11.2010, 20:31
Hat sich bedankt: 1 Mal

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von frantic » 02.11.2018, 13:39

etwas off-topic:
bei mir ist der Downgrade von 3.41.7 auf 3.37.8 gründlich schief gegangen. Es lassen sich keine Geräte mehr bedienen, ip-Geräte sind komplett verschwunden. Eine Sicherung habe ich nicht. Na, nicht so schlimm, ich hatte die Installation sowieso gerade erst begonnen. Also noch mal von vorne ;-)

Mathias
Beiträge: 1796
Registriert: 03.11.2010, 10:25
System: CCU
Wohnort: Aachen
Hat sich bedankt: 58 Mal
Danksagung erhalten: 262 Mal
Kontaktdaten:

Re: CCU-Historian 2.0.0 Beta4 mit CCU3 startet nicht

Beitrag von Mathias » 02.11.2018, 14:11

Es liegt an der neuen Firmware der CCU3 (siehe auch hier).

Gruß
Mathias

Antworten

Zurück zu „CCU-Historian“