Mal wieder : eBus

[udo1toni]

Na ja, mehr als "Connection reset" steht ja nicht da, das ist eindeutig genug, hilft aber nicht.

[guinnes]

So, es ist heute wieder passiert, der eBus ist ausgefallen, die Rule ist gelaufen, der eBus ist nicht wierder angelaufen, es gibt keine Meldungen, die einen Grund dafür liefern. Hier das Log :

Code: Alles auswählen

2021-06-30 17:10:08.023 [ERROR] [dev.ebus.core.EBusLowLevelController] - An IO exception has occured! Try to reconnect eBUS connector ...
java.net.SocketException: Connection reset
	at java.net.SocketInputStream.read(SocketInputStream.java:210) ~[?:1.8.0_252]
	at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_252]
	at java.net.SocketInputStream.read(SocketInputStream.java:127) ~[?:1.8.0_252]
	at de.csdev.ebus.core.connection.AbstractEBusConnection.readBytes(AbstractEBusConnection.java:56) ~[bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.run(EBusLowLevelController.java:200) [bundleFile:?]
2021-06-30 17:10:08.068 [WARN ] [dev.ebus.core.EBusLowLevelController] - Retry to connect to eBUS adapter in 5 seconds ...
2021-06-30 17:12:45.561 [INFO ] [.eclipse.smarthome.model.script.eBus] - eBus Abgelaufen
2021-06-30 17:12:46.184 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 27 00 1A
2021-06-30 17:12:46.186 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 42 00 EC
2021-06-30 17:12:46.187 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 00 00 89
2021-06-30 17:12:46.189 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 31 00 44
2021-06-30 17:12:46.190 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 30 00 DF
2021-06-30 17:12:46.192 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 01 00 12
2021-06-30 17:12:46.193 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 5D 00 30
2021-06-30 17:12:46.195 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 44 00 80
2021-06-30 17:12:46.196 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Remove polling job for FF 15 B5 09 03 0D 2F 00 03
2021-06-30 17:12:46.282 [INFO ] [.eclipse.smarthome.model.script.eBus] - eBus Stop 
2021-06-30 17:13:12.370 [INFO ] [internal.things.EBusTypeProviderImpl] - Load custom 'bundleUrl' configuration bundle 'file:///etc/openhab2/ebus/configuration-bundle.json' ...
2021-06-30 17:13:12.490 [WARN ] [internal.things.EBusTypeProviderImpl] - eBUS command boiler.control.setopdata only contains a setter channel!
2021-06-30 17:13:12.578 [WARN ] [internal.things.EBusTypeProviderImpl] - eBUS command boiler.control.setopdata only contains a setter channel!
2021-06-30 17:13:12.606 [INFO ] [ing.ebus.internal.EBusHandlerFactory] - Use eBUS binding 2.50.11 [eBUS core: 1.1.5, eBUS configuration: 1.1.4]
2021-06-30 17:13:12.610 [INFO ] [ing.ebus.internal.EBusHandlerFactory] - eBUS core -> timestamp 202101311144, commit: #79c19b7, build-no: #null
2021-06-30 17:13:12.614 [INFO ] [ing.ebus.internal.EBusHandlerFactory] - eBUS configuration -> timestamp 202012271447, commit: #101042c, build-no: #null
2021-06-30 17:13:12.669 [WARN ] [s.internal.handler.EBusBridgeHandler] - Enable advanced logging for eBUS commands!
2021-06-30 17:13:12.709 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "dhw.program_dhw_circuit" every 60 sec. (initial delay 6 sec.)
2021-06-30 17:13:12.713 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.temp_hcurve" every 60 sec. (initial delay 4 sec.)
2021-06-30 17:13:12.714 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.program_heating_circuit_special" every 60 sec. (initial delay 1 sec.)
2021-06-30 17:13:12.710 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "controller.temp_room" every 60 sec. (initial delay 2 sec.)
2021-06-30 17:13:12.711 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.temp_d_day" every 60 sec. (initial delay 13 sec.)
2021-06-30 17:13:12.711 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "controller.temp_room" every 60 sec. (initial delay 16 sec.)
2021-06-30 17:13:12.714 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "dhw.temp_d_dhw" every 60 sec. (initial delay 10 sec.)
2021-06-30 17:13:12.717 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.status_global_system_off" every 60 sec. (initial delay 1 sec.)
2021-06-30 17:13:12.717 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.temp_d_night" every 60 sec. (initial delay 26 sec.)
2021-06-30 17:13:12.717 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "heating.program_heating_circuit" every 60 sec. (initial delay 5 sec.)
2021-06-30 17:13:12.736 [INFO ] [ng.ebus.internal.handler.EBusHandler] - (Re)Initialize all eBUS pollings for ebus:abgestaubt:guinnes:08 ...
2021-06-30 17:13:12.752 [INFO ] [ng.ebus.internal.handler.EBusHandler] - (Re)Initialize all eBUS pollings for ebus:broadcastguinnes:guinnes:FE ...
2021-06-30 17:13:12.857 [INFO ] [.eclipse.smarthome.model.script.eBus] - eBus Restart 

Wieder einmal bin ich mit Putty nicht mehr reingekommen, nachdem der Fehler aufgetreten ist. Hat jemand dazu vielleicht eine Idee ?
Leider kann ich dem Stack-Trace nur innerhalb des Bindings folgen, java.net.socketinputstream wüsste ich nicht, wo das zu finden ist, jemand dazu eine Idee ?
Auch das "Retry to connect to eBUS adapter in 5 seconds ..." hat nicht stattgefunden

[udo1toni]

Und die Weboberfläche funktioniert noch?

[guinnes]

Und die Weboberfläche funktioniert noch?

Alles an Openhab funktioniert anscheinend, auch das BasicUI und der Logviewer im Browser. Gibts da ne Möglichkeit, sich einzuloggen ?

[udo1toni]

Der Punkt ist, ich hätte erwartet, dass, wenn schon Crash von Netz (in dem Fall sshd), dann alles Crash.

[guinnes]

Der Punkt ist, ich hätte erwartet, dass, wenn schon Crash von Netz (in dem Fall sshd), dann alles Crash.

Das ist jas nicht immer gleich, heute Morgen z.B. ging Putty noch und mit einem einfachen Sudo reboot konnte das System wieder gestartet werden.
Gestern war viel mehr Aufwand nötig :
Ausschalten, einschalten : Danach lief das Systeminfo-Binding nicht an, also
sudo poweroff Ausschalten, einschalten : Systeminfo-Binding lief immer noch nicht an, also
sudo reboot : danach war alles wieder ok, bis heute Morgen um 03:10

[udo1toni]

Also, das spricht doch eher für ein grundsätzliches Problem (könntest Du allenfalls ausschließen, indem Du nur dieses eine Binding deaktivierst...)

[guinnes]

Also, das spricht doch eher für ein grundsätzliches Problem (könntest Du allenfalls ausschließen, indem Du nur dieses eine Binding deaktivierst...)

Wäre natürlich die beste Zeit ( Sommer ) dafür. Aber da alle anderen Bindings tuen, kann ich dann ja auch nichts feststellen. Ich werde erst mal versuchen, die anderen Quelltextstücke zu finden, die im Stack-Trace angegeben sind. Vielleicht werde ich ja da fündig.
Do weisst nicht zufälligerweise, wo auf dem Raspberry ich Java finde ?

[guinnes]

Dieses Mal hat das Reconnect getan :

Code: Alles auswählen

2021-07-01 19:37:38.858 [ERROR] [dev.ebus.core.EBusLowLevelController] - An IO exception has occured! Try to reconnect eBUS connector ...
java.net.SocketException: Connection reset
	at java.net.SocketInputStream.read(SocketInputStream.java:210) ~[?:1.8.0_252]
	at java.net.SocketInputStream.read(SocketInputStream.java:141) ~[?:1.8.0_252]
	at java.net.SocketInputStream.read(SocketInputStream.java:127) ~[?:1.8.0_252]
	at de.csdev.ebus.core.connection.AbstractEBusConnection.readBytes(AbstractEBusConnection.java:56) ~[bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.run(EBusLowLevelController.java:200) [bundleFile:?]
2021-07-01 19:37:38.867 [WARN ] [dev.ebus.core.EBusLowLevelController] - Retry to connect to eBUS adapter in 5 seconds ...
2021-07-01 19:37:46.972 [WARN ] [us.core.connection.EBusTCPConnection] - Keine Route zum Zielrechner (Host unreachable)
java.net.NoRouteToHostException: Keine Route zum Zielrechner (Host unreachable)
	at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:1.8.0_252]
	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:1.8.0_252]
	at java.net.Socket.connect(Socket.java:607) ~[?:1.8.0_252]
	at java.net.Socket.connect(Socket.java:556) ~[?:1.8.0_252]
	at java.net.Socket.<init>(Socket.java:452) ~[?:1.8.0_252]
	at java.net.Socket.<init>(Socket.java:229) ~[?:1.8.0_252]
	at de.csdev.ebus.core.connection.EBusTCPConnection.open(EBusTCPConnection.java:55) [bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.reconnect(EBusLowLevelController.java:135) [bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.run(EBusLowLevelController.java:226) [bundleFile:?]
2021-07-01 19:37:46.977 [WARN ] [dev.ebus.core.EBusLowLevelController] - Retry to connect to eBUS adapter in 10 seconds ...
2021-07-01 19:37:56.980 [WARN ] [us.core.connection.EBusTCPConnection] - Das Netzwerk ist nicht erreichbar (connect failed)
java.net.SocketException: Das Netzwerk ist nicht erreichbar (connect failed)
	at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:1.8.0_252]
	at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:1.8.0_252]
	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:1.8.0_252]
	at java.net.Socket.connect(Socket.java:607) ~[?:1.8.0_252]
	at java.net.Socket.connect(Socket.java:556) ~[?:1.8.0_252]
	at java.net.Socket.<init>(Socket.java:452) ~[?:1.8.0_252]
	at java.net.Socket.<init>(Socket.java:229) ~[?:1.8.0_252]
	at de.csdev.ebus.core.connection.EBusTCPConnection.open(EBusTCPConnection.java:55) [bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.reconnect(EBusLowLevelController.java:135) [bundleFile:?]
	at de.csdev.ebus.core.EBusLowLevelController.run(EBusLowLevelController.java:192) [bundleFile:?]
2021-07-01 19:37:56.984 [WARN ] [dev.ebus.core.EBusLowLevelController] - Retry to connect to eBUS adapter in 15 seconds ...

Ich werde es wohl mal mit dem eBusD versuchen um die Low Level-Zugriffe zu vermeiden