Modus Binding crashes after several hours

[ssalonen]

@MatziBrow

I was wondering why the binding stops polling?
Shouldn’t it be robust enough to survive it?

Hmm it indeed looks like the binding needs to handle the invalid responses better, to make it try again and not halt.

The fact that we have “Scheduled runnable ended with an exception” looks like not all errors are handled properly, which seems to halt the regular polling java - ScheduledExecutorService Exception handling - Stack Overflow

[MatziBrow]

@rossko57

I’d suspect your poller Things have a terminal error. Have a look in PaperUI and find out what your Things status are.

I checked. All tings are online. They are not terminating.

[MatziBrow]

@rossko57

Strongly suggests the gateway is either messing the data up before sending to openHAB, or it is accepting corrupt serial data and passing that along instead of flagging an error.

Could you recommend a gateway that you know works reliable?
I could order it and check if the error still appears. If not, I take the new gateway, if yes, I will approach the heating manufacturer and explain them the error.

[rossko57]

@MatziBrow

Could you recommend a gateway that you know works reliable?

see

@rossko57

(I use the simpler USR-TCP232-410s okay, by the way)

I have a couple of these NNZN working as well, but the setup software is horrible

Serial RS485 to Ethernet TCP/IP Converter RJ45 Serial Port Serve 8Bits Process...

[ssalonen]

Yes… If my hypothesis above is correct, they would indeed remain online but there is internal error that stopped the polling.

By disabling and then enabling the pollers should resume polling I think.

I will come up with a proper fix for this in a week or two.

But yeah, despite this bug in the binding, the root cause of bad data remains, and it’s probably a good idea to try some other gateway as well.

[MatziBrow]

I ordered a new gateway. Let’s see if this helps.

I found something interesting that might help. After the “crash” I just restarted the binding. Attached is the result. It looks like the “wrong” telegrams are coming again immediately.

After some of these warnings about “wrong” telegrams, only one poller survives and continues polling. This happens a view minutes later, it is not covered by the log.

2020-03-10 21:53:02.833 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'modbus.things'
2020-03-10 21:53:02.917 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus tcp
2020-03-10 21:53:02.923 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.926 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.927 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.930 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.929 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadMultipleRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadInputRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:287) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_163]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) ~[?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_163]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_163]
2020-03-10 21:53:02.932 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.935 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.936 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.939 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.940 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.943 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.944 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.947 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.948 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.951 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.952 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.955 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.956 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.959 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.960 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
2020-03-10 21:53:02.964 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Registering polling with ModbusManager
2020-03-10 21:53:02.965 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.969 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.973 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:02.973 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:02.974 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.978 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:02.978 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.982 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.986 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.988 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:02.991 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.994 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:02.995 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:02.999 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.001 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.004 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.008 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.012 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.017 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.021 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenWerte2 received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.030 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.036 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:HeizkreisWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.039 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.046 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:HeizkreisWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.047 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.057 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:HeizkreisWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.058 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.068 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:HeizkreisWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.069 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.074 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:BoilerWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.075 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.081 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:BoilerWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.082 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.087 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:PufferWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.088 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.092 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:PufferWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.093 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.098 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:PufferWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.099 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.103 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:PufferWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.104 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.108 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:PufferWerte received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.108 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.113 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.117 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Poller modbus:poller:Heizungsanlage:AnlagenParameter2 received refresh() but the cache is not applicable. Polling new data
2020-03-10 21:53:03.118 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.122 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.125 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.129 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.134 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenWerte2 received registers RegisterArrayWrappingInputRegister(00 5e 00 29 00 37 00 00 00 62 00 5f 00 00 00 5f ff 9e 00 00 01 c6 00 00 00 00 00 00 00 58 00 33 00 40 00 3a ff 9e 02 fd 09 ef 50 dc 05 18 05 18 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 48 00 00 00 00 00 00 1b 38 00 0b 00 34 01 09 0a 1a 00 08 00 00 00 00 00 00 00 00 00 00 15 54 09 ef 00 00 00 00 00 00 00 00 00 00 15 54 07 62 07 62 00 00 00 00 00 00 00 00 00 00 00 00 09 c1 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 73 00 00 00 3e 00 00 00 00 02 79 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@392756f9[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-10 21:53:03.141 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur channels updated: {modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur:number=47.0}. readValueType=int16, readIndex=Optional[1000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 94 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 5e 00 29 00 37 00 00 00 62 00 5f 00 00 00 5f ff 9e 00 00 01 c6 00 00 00 00 00 00 00 58 00 33 00 40 00 3a ff 9e 02 fd 09 ef 50 dc 05 18 05 18 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 48 00 00 00 00 00 00 1b 38 00 0b 00 34 01 09 0a 1a 00 08 00 00 00 00 00 00 00 00 00 00 15 54 09 ef 00 00 00 00 00 00 00 00 00 00 15 54 07 62 07 62 00 00 00 00 00 00 00 00 00 00 00 00 09 c1 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 73 00 00 00 3e 00 00 00 00 02 79 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@392756f9[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-10 21:53:03.142 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.147 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.151 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.156 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.160 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.165 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.169 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.173 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
2020-03-10 21:53:03.345 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisWerte received registers RegisterArrayWrappingInputRegister(00 14 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@531526a9[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-10 21:53:03.348 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur:number=10.0}. readValueType=int16, readIndex=Optional[1030], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 20 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 14 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@531526a9[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-10 21:53:03.350 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur:number=-0.5}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 14 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@531526a9[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-10 21:53:03.351 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.IllegalArgumentException: Index=30 with type=int16 is out-of-bounds given registers of size 2
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at java.util.concurrent.CopyOnWriteArrayList.forEach(CopyOnWriteArrayList.java:891) ~[?:1.8.0_163]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:286) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_163]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) ~[?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_163]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_163]
2020-03-10 21:53:03.558 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerWerte received registers RegisterArrayWrappingInputRegister(00 3f 00 5a 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 46 00 3c 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6e9cb1e0[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-10 21:53:03.560 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=31.5}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 63 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 3f 00 5a 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 46 00 3c 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6e9cb1e0[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-10 21:53:03.562 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung:number=40}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 40 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 3f 00 5a 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 46 00 3c 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6e9cb1e0[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-10 21:53:03.765 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:PufferWerte received registers RegisterArrayWrappingInputRegister(00 6b 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c98663e[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]
2020-03-10 21:53:03.768 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturOben channels updated: {modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturOben:number=53.5}. readValueType=int16, readIndex=Optional[2000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 107 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 6b 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c98663e[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]
2020-03-10 21:53:03.771 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturMitte channels updated: {modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturMitte:number=127.0}. readValueType=int16, readIndex=Optional[2001], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 254 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 6b 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c98663e[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]
2020-03-10 21:53:03.774 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturUnten channels updated: {modbus:data:Heizungsanlage:PufferWerte:cPufferTemperaturUnten:number=0.0}. readValueType=int16, readIndex=Optional[2002], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 6b 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c98663e[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]
2020-03-10 21:53:03.775 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:PufferWerte:cPufferPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:PufferWerte:cPufferPumpenansteuerung:number=-1}. readValueType=int16, readIndex=Optional[2003], readSubIndex(or 0)=0, extractIndex=3 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 6b 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c98663e[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]
2020-03-10 21:53:03.777 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.IllegalArgumentException: Index=6 with type=int16 is out-of-bounds given registers of size 4
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at java.util.concurrent.CopyOnWriteArrayList.forEach(CopyOnWriteArrayList.java:891) ~[?:1.8.0_163]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:286) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_163]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) ~[?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_163]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_163]
2020-03-10 21:53:03.982 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadInputRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadMultipleRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:284) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_163]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) ~[?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_163]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_163]
2020-03-10 21:53:04.185 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenParameter2 received registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@37e0afad[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-10 21:53:04.197 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand channels updated: {modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand:number=14.0}. readValueType=int16, readIndex=Optional[319], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 140 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@37e0afad[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-10 21:53:04.405 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:KesselFernsteuerungParameter received registers RegisterArrayWrappingInputRegister(00 1e 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@3113f18d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-10 21:53:04.407 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisWandVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 30 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 1e 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@3113f18d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-10 21:53:04.408 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisFussbodenVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8001], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 1e 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@3113f18d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-10 21:53:04.409 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
java.lang.IllegalArgumentException: Index=18 with type=int16 is out-of-bounds given registers of size 2
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at java.util.concurrent.CopyOnWriteArrayList.forEach(CopyOnWriteArrayList.java:891) ~[?:1.8.0_163]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:283) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:1.8.0_163]
        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) ~[?:1.8.0_163]
        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) ~[?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_163]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_163]
        at java.lang.Thread.run(Thread.java:748) [?:1.8.0_163]
2020-03-10 21:53:04.645 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisParameter received registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@605b5243[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]

[rossko57]

@MatziBrow

It looks like the “wrong” telegrams are coming again immediately.

Pretty much what we’d expect.

I had a closer look at the M511 ; it’s not a new model, wouldn’t really expect troubles. You might try asking USR if your firmware is up to date.

The docs are a bit sparse (but beat many Chinese products). They don’t update docs to keep up with firmware changes.

One thing the docs show is an “Enable Memory Function” option for the MODBUS_RTU_SLAVE mode screen. You don’t seem to have that option (I’d want to turn it off and stop caching!) but maybe that option only appears when you have the serial command store thingy ticked as well. We don’t want that either, that’s the autonomous polling thing.

The UART packet time/length settings look good ; the gateway should detect the end of a data packet on serial by a 4-byte silence, and build a TCP packet out of it. Seems reasonable.
I would turn off sync baudrate but do not expect that to help.

I’m pretty sure that unless there is a serious design flaw in the gateway, if you were getting rubbish data on the serial line (e.g. interference or bad termination) you would be seeing plenty of TCP timeouts (if it discarded rubbish) or modbus Exception Code rejections in your openhab.log in between successful transfers. No sign of that??

EDIT - thinking on it, that might be exactly the part that goes wrong. Say there’s a transient serial error, not the end of the world, but instead of failing the transfer, the gateway serves data from its cache instead. It’s gone wrong already in doing that, no reason it’s going to serve anything like the correct data. And the unwanted transfer could mess up cache pointers forever.

[ssalonen]

Thank you @MatziBrow

This indeed fits with the story we have been discussing above.

I should be able to fix the error recovery for these errors in a pretty straightforward way. Then at least would keep on retrying the polling… Might not be enough to solve the root issue considering that the issues seem to stick with the gateway. Hopefully configuration advice above helps out

[ssalonen]

Fix introduced for the error recovery https://github.com/openhab/openhab-addons/pull/7160 . With these fixes the polling should not halt.

The system should build snapshot versions of the org.openhab.io.transport.modbus and org.openhab.binding.modbus.

@MatziBrow appreciate if you can test with this version: https://github.com/openhab/openhab-addons/files/4332494/pr-7160-modbus-gracefully-handle-unexpected-slave-responses.zip

[MatziBrow]

@rossko57

I’m pretty sure that unless there is a serious design flaw in the gateway, if you were getting rubbish data on the serial line (e.g. interference or bad termination) you would be seeing plenty of TCP timeouts (if it discarded rubbish) or modbus Exception Code rejections in your openhab.log in between successful transfers. No sign of that??

Thanks for all the hints. I disabled sync function.
No sign of TCP timeouts.
I also asked for the latest firmware. Maybe this helps.

[MatziBrow]

@ssalonen

Fix introduced for the error recovery [modbus] gracefully handle unexpected slave responses by ssalonen · Pull Request #7160 · openhab/openhab-addons · GitHub . With these fixes the polling should not halt.

The system should build snapshot versions of the org.openhab.io.transport.modbus and org.openhab.binding.modbus.

@MatziBrow appreciate if you can test with this version: https://github.com/openhab/openhab-addons/files/4332494/pr-7160-modbus-gracefully-handle-unexpected-slave-responses.zip

Thanks so much for your support! Really great!
I downloaded the version. Test is running!
I will keep you posted!

[MatziBrow]

Here is the result.
It looks like the first time it recovers, but a few seconds later the error happens again, then polling terminates. I could not post the whole log as it was too big. I hope this is enough as the part that I cut out was only showing regular polling.

2020-03-15 08:38:25.455 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=57.0}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 114 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 72 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:25.456 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung:number=0}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 72 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:28.665 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadInputRegistersRequest@74e03be6 (unit id 1 & transaction 23577). Address: /192.168.179.132:502
2020-03-15 08:38:28.666 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 1, throwing last error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadInputRegistersRequest@74e03be6 (unit id 1 & transaction 23577). Address: /192.168.179.132:502
2020-03-15 08:38:28.667 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 5 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@32e31e17[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID d7334157-27de-4fa5-869b-9fc00b036f06]
2020-03-15 08:38:31.877 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@720b6ebe (unit id 1 & transaction 23578). Address: /192.168.179.132:502
2020-03-15 08:38:31.877 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 1, throwing last error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@720b6ebe (unit id 1 & transaction 23578). Address: /192.168.179.132:502
2020-03-15 08:38:31.878 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 5 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@39431596[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=2,maxTries=5]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 227d8e60-d39f-4be0-8e41-3a4c1252b3d3]
2020-03-15 08:38:32.085 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenParameter2 received registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6a0c130c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-15 08:38:32.089 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand channels updated: {modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand:number=14.0}. readValueType=int16, readIndex=Optional[319], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 140 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6a0c130c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-15 08:38:32.297 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:KesselFernsteuerungParameter received registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-15 08:38:32.298 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisWandVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 29 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-15 08:38:32.299 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisFussbodenVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8001], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:283) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:32.509 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisParameter received registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.511 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschPlus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.512 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschMinus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1032], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.513 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufTemperaturAbsenkung channels updated: {}. readValueType=int16, readIndex=Optional[1033], readSubIndex(or 0)=0, extractIndex=3 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.513 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cFussbodenVorlaufWunschMinus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1062], readSubIndex(or 0)=0, extractIndex=32 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.514 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cFussbodenVorlaufTemperaturAbsenkung channels updated: {}. readValueType=int16, readIndex=Optional[1063], readSubIndex(or 0)=0, extractIndex=33 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadMultipleRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadInputRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:287) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadInputRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadMultipleRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:284) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:33.125 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerParameter received registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:33.126 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerSollTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1631], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 140 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:33.127 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerNachladenStartTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 16 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadMultipleRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadInputRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:287) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:34.966 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenWerte2 received registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c4 07 0a 09 c4 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e759b9b[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-15 08:38:34.970 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur channels updated: {modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur:number=56.5}. readValueType=int16, readIndex=Optional[1000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 113 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c4 07 0a 09 c4 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e759b9b[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-15 08:38:35.234 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisWerte received registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-15 08:38:35.238 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur:number=5.5}. readValueType=int16, readIndex=Optional[1030], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 11 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-15 08:38:35.240 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur:number=-0.5}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:286) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:35.462 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerWerte received registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:35.465 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=33.5}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 67 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:35.468 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung:number=40}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 40 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadInputRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadMultipleRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:284) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:42.517 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisParameter received registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:42.518 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschPlus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:283) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:43.130 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerParameter received registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:43.131 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerSollTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1631], readSubIndex(or 0)=0, extractIndex=0 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:43.131 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerNachladenStartTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 90 and boolValue=true. Registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadMultipleRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadInputRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:287) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
2020-03-15 08:38:45.473 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerWerte received registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:45.477 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=5.5}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 11 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
        at org.openhab.io.transport.modbus.ModbusBitUtilities.extractStateFromRegisters(ModbusBitUtilities.java:108) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusDataThingHandler.onRegisters(ModbusDataThingHandler.java:686) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.lambda$0(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.binding.modbus.internal.handler.ModbusPollerThingHandlerImpl$ReadCallbackDelegator.onRegisters(ModbusPollerThingHandlerImpl.java:95) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:286) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
java.lang.ClassCastException: net.wimpi.modbus.msg.ReadInputRegistersResponse cannot be cast to net.wimpi.modbus.msg.ReadMultipleRegistersResponse
        at org.openhab.io.transport.modbus.internal.ModbusLibraryWrapper.invokeCallbackWithResponse(ModbusLibraryWrapper.java:284) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.lambda$1(ModbusManagerImpl.java:167) ~[?:?]
        at org.openhab.io.transport.modbus.internal.SimpleStopWatch.timeRunnable(SimpleStopWatch.java:152) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:166) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl$PollOperation.accept(ModbusManagerImpl.java:1) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOperation(ModbusManagerImpl.java:575) ~[?:?]
        at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$15(ModbusManagerImpl.java:723) ~[?:?]
matthias@openhabnuc:~$ cat /var/log/openhab2/openhab.log | grep "08:38:"
2020-03-15 08:38:04.585 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenWerte received registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c7 07 0a 09 c4 ff fb 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71585957[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=0,length=88,maxTries=5]
2020-03-15 08:38:04.589 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte:cKesselTemperatur channels updated: {modbus:data:Heizungsanlage:AnlagenWerte:cKesselTemperatur:number=56.5}. readValueType=int16, readIndex=Optional[0], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 113 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c7 07 0a 09 c4 ff fb 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71585957[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=0,length=88,maxTries=5]
2020-03-15 08:38:04.592 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte:cRestsauerstoffgehalt channels updated: {modbus:data:Heizungsanlage:AnlagenWerte:cRestsauerstoffgehalt:number=2.3}. readValueType=int16, readIndex=Optional[3], readSubIndex(or 0)=0, extractIndex=3 -> numeric value 23 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c7 07 0a 09 c4 ff fb 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71585957[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=0,length=88,maxTries=5]
2020-03-15 08:38:04.593 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte:cBetriebsstunden channels updated: {}. readValueType=int16, readIndex=Optional[20], readSubIndex(or 0)=0, extractIndex=20 -> numeric value 2560 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c7 07 0a 09 c4 ff fb 00 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71585957[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=0,length=88,maxTries=5]
 
[...]
... here is it polls as expected...

[MatziBrow]

Here it starts again a few seconds later…

[...]
2020-03-15 08:38:25.455 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=57.0}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 114 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 72 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:25.456 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung:number=0}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 72 00 fe 00 00 ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:28.665 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadInputRegistersRequest@74e03be6 (unit id 1 & transaction 23577). Address: /192.168.179.132:502
2020-03-15 08:38:28.666 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 1, throwing last error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadInputRegistersRequest@74e03be6 (unit id 1 & transaction 23577). Address: /192.168.179.132:502
2020-03-15 08:38:28.667 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 5 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@32e31e17[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=2000,length=10,maxTries=5]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID d7334157-27de-4fa5-869b-9fc00b036f06]
2020-03-15 08:38:31.877 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@720b6ebe (unit id 1 & transaction 23578). Address: /192.168.179.132:502
2020-03-15 08:38:31.877 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 1, throwing last error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@720b6ebe (unit id 1 & transaction 23578). Address: /192.168.179.132:502
2020-03-15 08:38:31.878 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 5 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@39431596[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=2,maxTries=5]). Will try again soon. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 227d8e60-d39f-4be0-8e41-3a4c1252b3d3]
2020-03-15 08:38:32.085 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenParameter2 received registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6a0c130c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-15 08:38:32.089 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand channels updated: {modbus:data:Heizungsanlage:AnlagenParameter2:cPelletRestbestand:number=14.0}. readValueType=int16, readIndex=Optional[319], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 140 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@6a0c130c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=319,length=2,maxTries=5]
2020-03-15 08:38:32.297 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:KesselFernsteuerungParameter received registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-15 08:38:32.298 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisWandVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 29 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-15 08:38:32.299 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:KesselFernsteuerungParameter:cHeizkreisFussbodenVorlaufSolltemperatur channels updated: {}. readValueType=int16, readIndex=Optional[8001], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@7c43af4e[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=8000,length=50,maxTries=5]
2020-03-15 08:38:32.306 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:32.509 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisParameter received registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.511 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschPlus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.512 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschMinus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1032], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.513 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufTemperaturAbsenkung channels updated: {}. readValueType=int16, readIndex=Optional[1033], readSubIndex(or 0)=0, extractIndex=3 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.513 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cFussbodenVorlaufWunschMinus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1062], readSubIndex(or 0)=0, extractIndex=32 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.514 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cFussbodenVorlaufTemperaturAbsenkung channels updated: {}. readValueType=int16, readIndex=Optional[1063], readSubIndex(or 0)=0, extractIndex=33 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 01 00 01 00 00 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:32.719 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:32.922 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:33.125 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerParameter received registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:33.126 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerSollTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1631], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 140 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:33.127 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerNachladenStartTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 16 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 8c 00 10) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:34.743 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:34.752 [INFO ] [e.smarthome.model.script.Beleuchtung] - Wohnzimmer Trigger ausgelöst: 288.7804326113128
2020-03-15 08:38:34.759 [INFO ] [e.smarthome.model.script.Beleuchtung] - Wohnzimmer Status Lampen: OFF
2020-03-15 08:38:34.966 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:AnlagenWerte2 received registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c4 07 0a 09 c4 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e759b9b[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-15 08:38:34.970 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur channels updated: {modbus:data:Heizungsanlage:AnlagenWerte2:cAussenTemperatur:number=56.5}. readValueType=int16, readIndex=Optional[1000], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 113 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 71 00 33 00 3a 00 17 00 61 00 5e 00 00 00 73 ff 9e 00 00 01 c6 00 00 00 00 00 00 00 60 00 3d 00 41 00 3b ff 9e 02 13 0a 00 39 a1 05 21 05 21 00 00 00 00 00 00 00 8c 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 04 51 00 00 00 00 00 00 1b 6a 00 0c 00 35 01 0b 0a 2b 00 08 00 00 00 00 00 00 00 00 00 00 15 54 0a 00 00 00 00 00 00 00 00 00 00 00 15 54 07 6f 07 6f 00 00 00 00 00 00 00 00 01 c4 07 0a 09 c4 ff fb 00 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 03 a2 00 00 00 3f 00 00 00 00 02 6c ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e759b9b[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1000,length=2,maxTries=5]
2020-03-15 08:38:35.234 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisWerte received registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-15 08:38:35.238 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufIstTemperatur:number=5.5}. readValueType=int16, readIndex=Optional[1030], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 11 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-15 08:38:35.240 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur channels updated: {modbus:data:Heizungsanlage:HeizkreisWerte:cHeizkreisWandVorlaufSollTemperatur:number=-0.5}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@71379d88[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1030,length=65,maxTries=5]
2020-03-15 08:38:35.241 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:35.462 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerWerte received registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:35.465 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=33.5}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 67 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:35.468 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerPumpenansteuerung:number=40}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=2 -> numeric value 40 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 43 00 60 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3b 00 3d 00 28 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 00 00 00 00 00 ff ff ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:42.098 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:42.517 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:HeizkreisParameter received registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:42.518 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:HeizkreisParameter:cWandVorlaufWunschPlus10GRad channels updated: {}. readValueType=int16, readIndex=Optional[1031], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 0 and boolValue=false. Registers RegisterArrayWrappingInputRegister(00 1d 00 00) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4e7cecbd[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1030,length=64,maxTries=5]
2020-03-15 08:38:42.519 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:43.130 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerParameter received registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:43.131 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerSollTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1631], readSubIndex(or 0)=0, extractIndex=0 -> numeric value -1 and boolValue=true. Registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:43.131 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerParameter:cBoilerNachladenStartTemperatur channels updated: {}. readValueType=int16, readIndex=Optional[1632], readSubIndex(or 0)=0, extractIndex=1 -> numeric value 90 and boolValue=true. Registers RegisterArrayWrappingInputRegister(ff ff 00 5a 00 78 00 14 00 96 00 28 00 24 00 1a 00 14 00 28 00 24 00 3c 00 8c 00 00 00 01 00 00 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 3c 00 46 00 10 00 5a 00 28 00 24 00 1a 00 14 00 28 00 20 00 14 00 8c 00 00 00 01 00 00 ff ff 00 96 00 78 ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff 00 50 00 78 00 1e) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@4b6805ec[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1631,length=4,maxTries=5]
2020-03-15 08:38:44.977 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:45.473 [DEBUG] [handler.ModbusPollerThingHandlerImpl] - Thing modbus:poller:Heizungsanlage:BoilerWerte received registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:45.477 [DEBUG] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur channels updated: {modbus:data:Heizungsanlage:BoilerWerte:cBoilerTemperatur:number=5.5}. readValueType=int16, readIndex=Optional[1630], readSubIndex(or 0)=0, extractIndex=0 -> numeric value 11 and boolValue=true. Registers RegisterArrayWrappingInputRegister(00 0b ff ff) for request ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@40bb1ada[slaveId=1,functionCode=READ_INPUT_REGISTERS,start=1630,length=4,maxTries=5]
2020-03-15 08:38:45.478 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:
2020-03-15 08:38:53.137 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception:

[ssalonen]

Hi @MatziBrow, thank you for the test.

It looks like you still have the old version(s) activated – the line numbers in the error messages do not match the code I’m expecting…

Can you please check bundle versions with
bundle:list -s |grep modbus

Update should go like this

1. place jars in addons folder
2. bundle:list -s |grep modbus and check the id of the modbus binding and modbus transport
3. Execute bundle:update <ID> for both the binding and transport.

[MatziBrow]

@ssalonen

Can you please check bundle versions with
bundle:list -s |grep modbus

I did already before like you described.
The output is this:

265 | Active |  80 | 2.5.2                   | org.openhab.binding.modbus
302 | Active |  80 | 2.5.2                   | org.openhab.io.transport.modbus
324 | Active |  80 | 2.5.3.202003140948      | org.openhab.binding.modbus
325 | Active |  80 | 2.5.3.202003140947      | org.openhab.io.transport.modbus

After I do update it still shows the same output.
Anything I do wrong? It seems like the old and the new version are active…

[ssalonen]

Only new ones should be active. Try to stop or “uninstall” the old ones with bundle:stop and bundle:uninstall ID

[MatziBrow]

I tested it and it seems to work as far as I can see. However, once the error occurs, it does not recover and therefore it was difficult to check if everything went well. Tons of log messages and in the end still corrupted data. Sorry. Any recommendation welcome

I have received the new Serial-TCP Interface, the USR-TCP232-410S and I am currently checking if the error still occurs.

[ssalonen]

Thank you for testing out, it means the fixes work as expected…But it cannot fix the gateway issues as discussed above.
Hopefully the new device works

[MatziBrow]

It runs now for 3 days without any trouble.
It really seems that the Gateway was the problem.
A good learning! I think we can consider this issue as closed!

Thanks so much for all your support! It really helped to narrow down the problem!
I really appreciate it!! This was great!