Hi All,
I am running openhabian on a Raspberry pi 4 with openHAB 3.2.0.M5. Since a couple of days the connction to the CCU gets lost after some 6-8 hours. It might be a coincident but this tarted close to when I add a second Openhab instance for testing. I meanwhile shut this down however the issue persisted. I saw a couple of older posts but those issues were fixed with newer versions of the binding and I already switched from stable to milestone without any success.
I turned on DEBUG and below is what I see in the logs when the connection is lost.
2021-12-08 09:20:20.742 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘4.3’ for ‘OEQ1015698:1#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:20:20.744 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘OEQ1015698:1#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘4.3’) with QuantityTypeConverter
2021-12-08 09:20:20.753 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Integer) value ‘81’ for ‘OEQ1015698:1#HUMIDITY’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:20:20.755 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘OEQ1015698:1#HUMIDITY’ (dpType=‘INTEGER’, dpUnit=’%’, dpValue=‘81’) with QuantityTypeConverter
2021-12-08 09:20:32.329 [DEBUG] [ommunicator.AbstractHomematicGateway] - Unable to release the connection to the gateway with id ‘3014F711A0001F98A9AA1402’: java.util.concurrent.TimeoutException
java.io.IOException: java.util.concurrent.TimeoutException
at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.send(XmlRpcClient.java:122) ~[bundleFile:?]
at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.sendMessage(XmlRpcClient.java:77) ~[bundleFile:?]
at org.openhab.binding.homematic.internal.communicator.client.RpcClient.release(RpcClient.java:102) ~[bundleFile:?]
at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway.stopServers(AbstractHomematicGateway.java:267) [bundleFile:?]
at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway$ConnectionTrackerThread.handleInvalidConnection(AbstractHomematicGateway.java:933) [bundleFile:?]
at org.openhab.binding.homematic.internal.communicator.AbstractHomematicGateway$ConnectionTrackerThread.run(AbstractHomematicGateway.java:912) [bundleFile:?]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) [?:?]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
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) [?:?]
Caused by: java.util.concurrent.TimeoutException
at org.eclipse.jetty.client.util.FutureResponseListener.get(FutureResponseListener.java:109) ~[bundleFile:9.4.43.v20210629]
at org.openhab.binding.homematic.internal.communicator.client.XmlRpcClient.send(XmlRpcClient.java:112) ~[bundleFile:?]
… 11 more
2021-12-08 09:20:32.332 [DEBUG] [nal.communicator.server.XmlRpcServer] - Stopping XML-RPC server
2021-12-08 09:20:32.342 [DEBUG] [nal.communicator.server.XmlRpcServer] - Initializing XML-RPC server at port 9125
2021-12-08 09:21:07.740 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘23.5’ for ‘REQ1336633:1#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:07.743 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘REQ1336633:1#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘23.5’) with QuantityTypeConverter
2021-12-08 09:21:17.355 [DEBUG] [nal.communicator.client.XmlRpcClient] - XmlRpcMessage failed, sending message again 1/3
2021-12-08 09:21:31.222 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘20.3’ for ‘OEQ0477443:1#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:31.226 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘OEQ0477443:1#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘20.3’) with QuantityTypeConverter
2021-12-08 09:21:32.357 [DEBUG] [nal.communicator.client.XmlRpcClient] - XmlRpcMessage failed, sending message again 2/3
2021-12-08 09:21:34.676 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘4.3’ for ‘SEQ0026473:1#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.677 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘SEQ0026473:1#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘4.3’) with QuantityTypeConverter
2021-12-08 09:21:34.690 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘38.0’ for ‘SEQ0026473:2#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.692 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘SEQ0026473:2#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘38.0’) with QuantityTypeConverter
2021-12-08 09:21:34.694 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘-33.7’ for ‘SEQ0026473:3#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.696 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘SEQ0026473:3#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=’-33.7’) with QuantityTypeConverter
2021-12-08 09:21:34.698 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Double) value ‘33.7’ for ‘SEQ0026473:4#TEMPERATURE’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.700 [DEBUG] [converter.type.AbstractTypeConverter] - Converting datapoint ‘SEQ0026473:4#TEMPERATURE’ (dpType=‘FLOAT’, dpUnit=‘°C’, dpValue=‘33.7’) with QuantityTypeConverter
2021-12-08 09:21:34.702 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:0#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.705 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:1#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.707 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:2#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.709 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:3#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.711 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:4#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:34.713 [DEBUG] [ommunicator.AbstractHomematicGateway] - Received new (Boolean) value ‘false’ for ‘SEQ0026473:5#LOWBAT’ from gateway with id ‘3014F711A0001F98A9AA1402’
2021-12-08 09:21:47.359 [DEBUG] [nal.communicator.client.XmlRpcClient] - XmlRpcMessage failed, sending message again 3/3