Modbus TCP Binding Version 2 Crashes in OH2.4 RPi3A Setup

Tags: #<Tag:0x00007f434a417118> #<Tag:0x00007f434a417028> #<Tag:0x00007f434a416f60>

I’m having issues with my new Modbus TCP binding … similar … after 20 to 30 hours the raspberry pi system crashes and needs a hard reboot.

The Modbus Binding version as follows … binding-modbus - 2.4.0

The previous Modbus binding version 1.x ran for months.

I have 2 systems presently setup with new settings and if (when) they crash I’ll post errors.

Seems like a memory leak … anybody else experiencing similar with Modbus 2.x ?


==> /var/log/openhab2/openhab.log <==

2020-01-09 13:13:03.068 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@10323a6 (unit id 1 & transaction 20508). Address: /192.168.8.120:8522

2020-01-09 13:13:03.085 [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@10323a6 (unit id 1 & transaction 20508). Address: /192.168.8.120:8522

2020-01-09 13:13:03.091 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@1a331f0[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1180,length=15,maxTries=3]). 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 54d7eb9c-1956-4746-88b7-8394bf0d48c7]

==> /var/log/openhab2/events.log <==

The modbus.cfg setup as follows

poll=300
# Following are external 'Input' regs
tcp.slave1.connection=192.168.8.120:8512
tcp.slave1.id=1
tcp.slave1.start=1100
tcp.slave1.length=8
tcp.slave1.type=holding
tcp.slave1.valuetype=uint16

# Following are for external 'write' regs
tcp.slave2.connection=192.168.8.120:8512
tcp.slave2.id=1
tcp.slave2.start=1001
tcp.slave2.length=15
tcp.slave2.type=holding
tcp.slave2.valuetype=uint16

# Following are internal 'Switch' regs
tcp.slave3.connection=192.168.8.120:8512
tcp.slave3.id=1
tcp.slave3.start=1031
tcp.slave3.length=10
tcp.slave3.type=holding
tcp.slave3.valuetype=uint16

# Following is for Temp and Lighting Control  regs
tcp.slave4.connection=192.168.8.120:8512
tcp.slave4.id=1
tcp.slave4.start=1150
tcp.slave4.length=10
tcp.slave4.type=holding
tcp.slave4.valuetype=uint16

# Following are internal 'Time Zone' regs
tcp.slave5.connection=192.168.8.120:8512
tcp.slave5.id=1
tcp.slave5.start=1170
tcp.slave5.length=10
tcp.slave5.type=holding
tcp.slave5.valuetype=uint16

# Following are internal Tint Float
tcp.slave6.connection=192.168.8.120:8512
tcp.slave6.id=1
tcp.slave6.start=1180
tcp.slave6.length=5
tcp.slave6.type=holding
tcp.slave6.valuetype=float32_swap

and Things setup file

Bridge modbus:tcp:iwr2a [ host="192.168.8.120", port=8522, id=1 ] {

    Bridge poller iwr2a_slave1 [ start=1100, length=8, refresh=500, type="holding" ] {
		Thing data iwr2a_s1_000 [ readStart="1101", readValueType="uint16", writeStart="1101", writeValueType="uint16", writeType="holding" ]
        Thing data iwr2a_s1_001 [ readStart="1102", readValueType="uint16", writeStart="1102", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_002 [ readStart="1103", readValueType="uint16", writeStart="1103", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_003 [ readStart="1104", readValueType="uint16", writeStart="1104", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_004 [ readStart="1105", readValueType="uint16", writeStart="1105", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_005 [ readStart="1106", readValueType="uint16", writeStart="1106", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_006 [ readStart="1107", readValueType="uint16", writeStart="1107", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_007 [ readStart="1108", readValueType="uint16", writeStart="1108", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_008 [ readStart="1109", readValueType="uint16", writeStart="1109", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s1_009 [ readStart="1110", readValueType="uint16", writeStart="1110", writeValueType="uint16", writeType="holding" ]
    }

	Bridge poller iwr2a_slave2 [ start=1001, length=18, refresh=500, type="holding" ] {
        Thing data iwr2a_s2_000 [ readStart="1001", readValueType="uint16", writeStart="1001", writeValueType="uint16", writeType="holding" ]
        Thing data iwr2a_s2_001 [ readStart="1002", readValueType="uint16", writeStart="1002", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_002 [ readStart="1003", readValueType="uint16", writeStart="1003", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_003 [ readStart="1004", readValueType="uint16", writeStart="1004", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_004 [ readStart="1005", readValueType="uint16", writeStart="1005", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_005 [ readStart="1006", readValueType="uint16", writeStart="1006", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_006 [ readStart="1007", readValueType="uint16", writeStart="1007", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_007 [ readStart="1008", readValueType="uint16", writeStart="1008", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_008 [ readStart="1009", readValueType="uint16", writeStart="1009", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_009 [ readStart="1010", readValueType="uint16", writeStart="1010", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_010 [ readStart="1011", readValueType="uint16", writeStart="1011", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_011 [ readStart="1012", readValueType="uint16", writeStart="1012", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_012 [ readStart="1013", readValueType="uint16", writeStart="1013", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_013 [ readStart="1014", readValueType="uint16", writeStart="1014", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_014 [ readStart="1015", readValueType="uint16", writeStart="1015", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s2_015 [ readStart="1016", readValueType="uint16", writeStart="1016", writeValueType="uint16", writeType="holding" ]
    }

	Bridge poller iwr2a_slave3 [ start=1031, length=15, refresh=500, type="holding" ] {
        Thing data iwr2a_s3_000 [ readStart="1031", readValueType="uint16", writeStart="1031", writeValueType="uint16", writeType="holding" ]
        Thing data iwr2a_s3_001 [ readStart="1032", readValueType="uint16", writeStart="1032", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_002 [ readStart="1033", readValueType="uint16", writeStart="1033", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_003 [ readStart="1034", readValueType="uint16", writeStart="1034", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_004 [ readStart="1035", readValueType="uint16", writeStart="1035", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_005 [ readStart="1036", readValueType="uint16", writeStart="1036", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_006 [ readStart="1037", readValueType="uint16", writeStart="1037", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_007 [ readStart="1038", readValueType="uint16", writeStart="1038", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_008 [ readStart="1039", readValueType="uint16", writeStart="1039", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s3_009 [ readStart="1040", readValueType="uint16", writeStart="1040", writeValueType="uint16", writeType="holding" ]
    }
	
	Bridge poller iwr2a_slave4 [ start=1050, length=15, refresh=500, type="holding" ] {
        Thing data iwr2a_s4_000 [ readStart="1050", readValueType="uint16", writeStart="1050", writeValueType="uint16", writeType="holding" ]
        Thing data iwr2a_s4_001 [ readStart="1051", readValueType="uint16", writeStart="1051", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_002 [ readStart="1052", readValueType="uint16", writeStart="1052", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_003 [ readStart="1053", readValueType="uint16", writeStart="1053", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_004 [ readStart="1054", readValueType="uint16", writeStart="1054", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_005 [ readStart="1055", readValueType="uint16", writeStart="1055", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_006 [ readStart="1056", readValueType="uint16", writeStart="1056", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_007 [ readStart="1057", readValueType="uint16", writeStart="1057", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_008 [ readStart="1058", readValueType="uint16", writeStart="1058", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s4_009 [ readStart="1059", readValueType="uint16", writeStart="1059", writeValueType="uint16", writeType="holding" ]
    }
	
	Bridge poller iwr2a_slave5 [ start=1170, length=15, refresh=500, type="holding" ] {
        Thing data iwr2a_s5_000 [ readStart="1170", readValueType="uint16", writeStart="1170", writeValueType="uint16", writeType="holding" ]
        Thing data iwr2a_s5_001 [ readStart="1171", readValueType="uint16", writeStart="1171", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_002 [ readStart="1172", readValueType="uint16", writeStart="1172", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_003 [ readStart="1173", readValueType="uint16", writeStart="1173", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_004 [ readStart="1174", readValueType="uint16", writeStart="1174", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_005 [ readStart="1175", readValueType="uint16", writeStart="1175", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_006 [ readStart="1176", readValueType="uint16", writeStart="1176", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_007 [ readStart="1177", readValueType="uint16", writeStart="1177", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_008 [ readStart="1178", readValueType="uint16", writeStart="1178", writeValueType="uint16", writeType="holding" ]
		Thing data iwr2a_s5_009 [ readStart="1179", readValueType="uint16", writeStart="1179", writeValueType="uint16", writeType="holding" ]
    }
	
	Bridge poller iwr2a_slave6 [ start=1180, length=15, refresh=500, type="holding" ] {
        Thing data iwr2a_s6_000 [ readStart="1180", readValueType="float32_swap" ]
       
    }
	
}

The system usually crashes within a few hours of the first errors being logged.

Following is system setup and present memory …

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Thu Jan  9 06:08:27 2020 from 192.168.8.22

###############################################################################
################# openHABianPi ################################################
###############################################################################
##             Ip = 192.168.8.25
##        Release = Raspbian GNU/Linux 9 (stretch)
##         Kernel = Linux 4.19.58-v7+
##       Platform = Raspberry Pi 3 Model A Plus Rev 1.0
##         Uptime = 1 day(s). 4:50:19
##      CPU Usage = 12.47 % avg over 4 cpu(s)  (4 core(s) x 1 socket(s))
##       CPU Load = 1m: 1.11, 5m: 1.01, 15m: 1.11
##         Memory = Free: 0.05GB (12%), Used: 0.41GB (88%), Total: 0.46GB
##           Swap = Free: 0.05GB (58%), Used: 0.04GB (42%), Total: 0.09GB
##           Root = Free: 11.23GB (80%), Used: 2.68GB (20%), Total: 14.53GB
##        Updates = 56 apt updates available.
##       Sessions = 1 sessions
##      Processes = 116 running processes of 32768 maximum processes
###############################################################################

              Welcome to            __  _____    ____  _
            ____  ____  ___  ____  / / / /   |  / __ )(_)___ _____
           / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __  / / __ `/ __ \
          / /_/ / /_/ /  __/ / / / __  / ___ |/ /_/ / / /_/ / / / /
          \____/ .___/\___/_/ /_/_/ /_/_/  |_/_____/_/\__,_/_/ /_/
              /_/
                  openHAB 2.4.0-1 (Release Build)

Thanks

May we see more of the errors that do not result in immediate crash, then?
The one we can see is normal enough, and ends ready for a retry.

This v1 configuration is completely ignored by Modbus binding v2

Are you sure which one you are using?
Do not use both versions alongside each other.

1 Like

I second everything Rossko57 says and I have to ask what makes you think it’s a memory leak?

Updates = 56 apt updates available.

Looks like your system needs to be updated and also OH itself has a newer stable version available.

Thanks for getting back.

As I said previously, I have a number of configurations running to identify the issue. The following is a setup as follows;
rpi3A running OH2.4 192.168.8.8 ip … with our modbus server slave software also running on same rpi3A using the local ip 127.0.0.1:8180 socket to communicate.

It is now getting errors after approx 20+ hours …

2020-01-10 05:32:55.986 [vent.ItemStateChangedEvent] - Tint1aa changed from 24.899999618530273 to 24.600000381469727

2020-01-10 05:33:46.198 [vent.ItemStateChangedEvent] - Tint1aa changed from 24.600000381469727 to 24.700000762939453

2020-01-10 05:41:08.261 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_003' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:41:08.264 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_006 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:41:08.268 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_004' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:41:08.273 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_005' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:41:08.287 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_008 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:41:08.293 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_006' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:41:08.309 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_009 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:41:08.316 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_008' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:41:08.330 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_007 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:41:08.334 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_009' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:41:08.364 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_007' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:41:15.904 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@141352f (unit id 1 & transaction 49231). Address: /127.0.0.1:8502

2020-01-10 05:41:16.042 [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@141352f (unit id 1 & transaction 49231). Address: /127.0.0.1:8502

2020-01-10 05:41:16.045 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@db6f1a[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1180,length=15,maxTries=3]). 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 75ae06f1-2859-4c9c-a651-9710c371736d]

2020-01-10 05:41:22.085 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@8cba0 (unit id 1 & transaction 49232). Address: /127.0.0.1:8502

2020-01-10 05:41:22.250 [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@8cba0 (unit id 1 & transaction 49232). Address: /127.0.0.1:8502

2020-01-10 05:41:23.127 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@115462c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1001,length=18,maxTries=3]). 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 6ec86113-9370-4f2f-ab69-2a40e6037bf6]

2020-01-10 05:41:27.855 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@ba84fe (unit id 1 & transaction 49233). Address: /127.0.0.1:8502

2020-01-10 05:41:27.956 [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@ba84fe (unit id 1 & transaction 49233). Address: /127.0.0.1:8502

2020-01-10 05:41:27.960 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@806504[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1170,length=15,maxTries=3]). 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 3d6da01e-b7f7-4c6f-b140-bfe9c8cf5a29]

2020-01-10 05:41:31.677 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@ed0a1e (unit id 1 & transaction 49234). Address: /127.0.0.1:8502

2020-01-10 05:41:31.699 [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@ed0a1e (unit id 1 & transaction 49234). Address: /127.0.0.1:8502

2020-01-10 05:41:31.702 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@8fa77d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1100,length=8,maxTries=3]). 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 a4025450-d43b-4273-a07c-c5d6daa6d8b8]

2020-01-10 05:41:38.273 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1660e57 (unit id 1 & transaction 49235). Address: /127.0.0.1:8502

2020-01-10 05:41:38.808 [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@1660e57 (unit id 1 & transaction 49235). Address: /127.0.0.1:8502

2020-01-10 05:41:39.274 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@922003[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1031,length=15,maxTries=3]). 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 b40ee08a-123b-4fe3-a09e-c8fb1b59d908]

2020-01-10 05:41:43.179 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@13711e4 (unit id 1 & transaction 49236). Address: /127.0.0.1:8502

2020-01-10 05:41:43.244 [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@13711e4 (unit id 1 & transaction 49236). Address: /127.0.0.1:8502

2020-01-10 05:41:43.250 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@db6f1a[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1180,length=15,maxTries=3]). 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 75ae06f1-2859-4c9c-a651-9710c371736d]

2020-01-10 05:41:47.210 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1c43bd (unit id 1 & transaction 49237). Address: /127.0.0.1:8502

2020-01-10 05:41:47.284 [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@1c43bd (unit id 1 & transaction 49237). Address: /127.0.0.1:8502

2020-01-10 05:41:47.286 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@115462c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1001,length=18,maxTries=3]). 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 6ec86113-9370-4f2f-ab69-2a40e6037bf6]

2020-01-10 05:41:51.562 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@fd91d1 (unit id 1 & transaction 49238). Address: /127.0.0.1:8502

2020-01-10 05:41:51.598 [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@fd91d1 (unit id 1 & transaction 49238). Address: /127.0.0.1:8502

2020-01-10 05:41:51.602 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@806504[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1170,length=15,maxTries=3]). 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 3d6da01e-b7f7-4c6f-b140-bfe9c8cf5a29]

2020-01-10 05:41:55.854 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@17b3cba (unit id 1 & transaction 49239). Address: /127.0.0.1:8502

2020-01-10 05:41:56.015 [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@17b3cba (unit id 1 & transaction 49239). Address: /127.0.0.1:8502

2020-01-10 05:41:56.239 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@8fa77d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1100,length=8,maxTries=3]). 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 a4025450-d43b-4273-a07c-c5d6daa6d8b8]

2020-01-10 05:41:59.675 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@352af4 (unit id 1 & transaction 49240). Address: /127.0.0.1:8502

2020-01-10 05:41:59.885 [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@352af4 (unit id 1 & transaction 49240). Address: /127.0.0.1:8502

2020-01-10 05:42:00.279 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@922003[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1031,length=15,maxTries=3]). 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 b40ee08a-123b-4fe3-a09e-c8fb1b59d908]

2020-01-10 05:42:06.681 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1d1cab5 (unit id 1 & transaction 49241). Address: /127.0.0.1:8502

2020-01-10 05:42:10.422 [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@1d1cab5 (unit id 1 & transaction 49241). Address: /127.0.0.1:8502

2020-01-10 05:42:10.775 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@db6f1a[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1180,length=15,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 75ae06f1-2859-4c9c-a651-9710c371736d]

2020-01-10 05:42:10.990 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave6:iwr3a_s6_000 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:11.002 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave6:iwr3a_s6_000' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:15.421 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1a91a79 (unit id 1 & transaction 49242). Address: /127.0.0.1:8502

2020-01-10 05:42:15.651 [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@1a91a79 (unit id 1 & transaction 49242). Address: /127.0.0.1:8502

2020-01-10 05:42:15.655 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@115462c[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1001,length=18,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 6ec86113-9370-4f2f-ab69-2a40e6037bf6]

2020-01-10 05:42:15.709 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_000 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.760 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_001 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.770 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_002 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.780 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_003 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.790 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_004 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.799 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_005 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.809 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_006 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:15.889 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_007 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:15.863 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_000' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.085 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_001' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.091 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_002' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.097 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_008 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.098 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_003' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.104 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_004' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.106 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_009 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.111 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_005' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.116 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_006' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.118 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_010 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.121 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_007' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.128 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_011 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.131 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_008' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.139 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_012 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.137 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_009' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.143 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_010' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.146 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_013 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.149 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_011' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.154 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_014 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.155 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_012' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.162 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_013' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:16.162 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_015 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:16.168 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_014' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

2020-01-10 05:42:16.174 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_015' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)

==> /var/log/openhab2/openhab.log <==

2020-01-10 05:42:21.529 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1b0336a (unit id 1 & transaction 49243). Address: /127.0.0.1:8502

2020-01-10 05:42:21.561 [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@1b0336a (unit id 1 & transaction 49243). Address: /127.0.0.1:8502

2020-01-10 05:42:21.594 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@806504[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1170,length=15,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID 3d6da01e-b7f7-4c6f-b140-bfe9c8cf5a29]

2020-01-10 05:42:21.960 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_000 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.011 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_001 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.016 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_002 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.022 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_003 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.027 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_004 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.033 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_005 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.039 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_006 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.045 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_007 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.050 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_008 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:22.056 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_009 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:26.497 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@fafb92 (unit id 1 & transaction 49244). Address: /127.0.0.1:8502

2020-01-10 05:42:27.531 [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@fafb92 (unit id 1 & transaction 49244). Address: /127.0.0.1:8502

2020-01-10 05:42:28.029 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@8fa77d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1100,length=8,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID a4025450-d43b-4273-a07c-c5d6daa6d8b8]

2020-01-10 05:42:32.370 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@2de6fe (unit id 1 & transaction 49245). Address: /127.0.0.1:8502

2020-01-10 05:42:32.373 [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@2de6fe (unit id 1 & transaction 49245). Address: /127.0.0.1:8502

2020-01-10 05:42:32.376 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Last try 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@922003[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1031,length=15,maxTries=3]). Aborting. Error was I/O error, so reseting the connection. Error details: net.wimpi.modbus.ModbusIOException I/O exception: SocketTimeoutException Read timed out [operation ID b40ee08a-123b-4fe3-a09e-c8fb1b59d908]

2020-01-10 05:42:32.501 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_000 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.507 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_001 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.512 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_004 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.518 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_005 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.524 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_006 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.529 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_007 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.536 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_008 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.542 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_009 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.548 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_002 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:32.556 [ERROR] [ernal.handler.ModbusDataThingHandler] - Thing modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_003 'Modbus data' had ModbusSlaveIOExceptionImpl error on read: ModbusSlaveIOException(cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null)

2020-01-10 05:42:37.500 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@680e94 (unit id 1 & transaction 49246). Address: /127.0.0.1:8502

2020-01-10 05:42:37.530 [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@680e94 (unit id 1 & transaction 49246). Address: /127.0.0.1:8502

2020-01-10 05:42:37.534 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@16d17c9[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1050,length=15,maxTries=3]). 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 ddffa629-43d3-46f3-b796-2e4a9d0a54d7]

2020-01-10 05:42:42.319 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1621178 (unit id 1 & transaction 49247). Address: /127.0.0.1:8502

2020-01-10 05:42:42.451 [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@1621178 (unit id 1 & transaction 49247). Address: /127.0.0.1:8502

2020-01-10 05:42:42.927 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@db6f1a[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1180,length=15,maxTries=3]). 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 5ab79e7c-9f10-4cac-9641-c03adaa21bfb]

==> /var/log/openhab2/events.log <==

2020-01-10 05:42:46.161 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave2' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:46.291 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.295 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_001' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.299 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_003' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.363 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_006' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.367 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_004' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.371 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_007' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.374 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_005' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.378 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_010' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.382 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_008' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.385 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_012' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.389 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_013' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.397 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_002' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.401 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_011' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.406 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_009' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.410 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_014' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.414 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave2:iwr3a_s2_015' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.812 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave5' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:46.832 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.842 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_001' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.855 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_006' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.862 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_002' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.866 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_003' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.873 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_004' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.878 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_008' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.888 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_009' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.892 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_005' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:46.899 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave5:iwr3a_s5_007' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.025 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave4' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:47.045 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_001' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.050 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_002' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.053 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_003' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.059 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_006' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.065 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_004' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.071 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.088 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_007' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.107 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_009' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.112 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_005' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.138 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave4:iwr3a_s4_008' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.151 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave1' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:47.233 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave6' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:47.345 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave6:iwr3a_s6_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.357 [vent.ItemStateChangedEvent] - Tint1aa changed from 24.700000762939453 to 25.100000381469727

2020-01-10 05:42:47.635 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave3' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 05:42:47.650 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_001' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.656 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_005' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.683 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_002' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.693 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_003' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.700 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_004' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.711 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_007' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.716 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.722 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_006' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.726 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_008' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:42:47.737 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave3:iwr3a_s3_009' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 05:43:17.606 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.100000381469727 to 25.200000762939453

2020-01-10 05:44:58.165 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.200000762939453 to 25.399999618530273

With this amount of errors, a crash is imminent.

The modbus.cfg and things file settings as previously.

Last year we had this setup of both OH2 and our server running together on a rpi zero using 127.0.0.1:8180 with the modbus add-on at version 1 … and as previously it ran for months.

Unfortunately the cpu of the rpi0 was maxed out and was running too hot! :frowning:

I have just setup such a system using last years image yesterday and will advise its progress.

Any thoughts and tips appreciated.

PS. I’m trying to Putty in as we speak to look at the memory but can’t so far. :frowning:

thanks

Yea … we did do a complete update upgrade and to oh2.5 but got some config errors.

Nothing too serious but just annoying … so stuck with oh2.4 just to identify our problem.

Planning to do a complete clean install to oh2.5 soon.

cheers

1 Like

In this case, IMO, error count has nothing to do with crashing. The modbus binding handels them gracefully. Have you looked into retry counts etc. and tried to “fine tune” your setup?

Yea … we have played around with the settings such as polling and retries … from 0 up to 10 … but to no avail. :frowning:

This v1 configuration is completely ignored by Modbus binding v2

The v1 modbus was on our older oh setup last year. See following comments.

We just replicated this older setup yesterday and I will send its configuration when it completes 24+ hours. (I hope :neutral_face: )

thanks

Could you be more clear on:
Which modbus version are you using on the OH 2.4 setup. V.1 or V.2 ?
Is it OH 2.4 stable release?

Did you try a higher refresh rate?
This is my bridge setting:

Bridge modbus:tcp:inverter1 [ host="10.4.28.248", port=502, id=3, connectMaxTries=3] 

Notice I have connectMaxTries=3.
On all my pollers I have refresh rate 5000, like this:

// SMA Inverter Device Type
	Bridge poller DevType [ start=30053, length=4, refresh=5000, type="input" ] {
	    
	    	 Thing data DevType [ readStart="30053", readValueType="int32" ]
	   	 
  	 }

I have no issues at all.
I use V.2 modbus binding and OH 2.5 stable release.

Apols for lack of clarity … using modbus v2 on OH2.4 .

Yea we did play with refresh rate from 100 to 1000 but made no difference … still crashed after 24hrs approx.

Maybe the much higher rate 5000 can make a difference … will give it a go thanks.

Yay! … OK … Putty in …

ogin as: openhabian
openhabian@192.168.8.8's password:
Linux openHABianPi 4.19.58-v7+ #1245 SMP Fri Jul 12 17:25:51 BST 2019 armv7l

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Fri Jan 10 06:03:02 2020 from 192.168.8.22

###############################################################################
################# openHABianPi ################################################
###############################################################################
##             Ip = 192.168.8.8
##        Release = Raspbian GNU/Linux 9 (stretch)
##         Kernel = Linux 4.19.58-v7+
##       Platform = Raspberry Pi 3 Model A Plus Rev 1.0
##         Uptime = 1 day(s). 0:31:41
##      CPU Usage = 46.77 % avg over 4 cpu(s)  (4 core(s) x 1 socket(s))
##       CPU Load = 1m: 2.88, 5m: 5.32, 15m: 10.64
##         Memory = Free: 0.03GB (7%), Used: 0.43GB (93%), Total: 0.46GB
##           Swap = Free: 0.00GB (0%), Used: 0.09GB (100%), Total: 0.09GB
##           Root = Free: 11.16GB (80%), Used: 2.74GB (20%), Total: 14.53GB
##        Updates = 56 apt updates available.
##       Sessions = 1 sessions
##      Processes = 124 running processes of 32768 maximum processes
###############################################################################

              Welcome to            __  _____    ____  _
            ____  ____  ___  ____  / / / /   |  / __ )(_)___ _____
           / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __  / / __ `/ __ \
          / /_/ / /_/ /  __/ / / / __  / ___ |/ /_/ / / /_/ / / / /
          \____/ .___/\___/_/ /_/_/ /_/_/  |_/_____/_/\__,_/_/ /_/
              /_/
                  openHAB 2.4.0-1 (Release Build)


Looking for a place to get started? Check out 'sudo openhabian-config' and the
documentation at https://www.openhab.org/docs/installation/openhabian.html
The openHAB dashboard can be reached at http://openHABianPi:8080
To interact with openHAB on the command line, execute: 'openhab-cli --help'

[06:36:40] openhabian@openHABianPi:~$

Latest errors …

2020-01-10 06:18:06.613 [hingStatusInfoChangedEvent] - 'modbus:data:iwr3a:iwr3a_slave6:iwr3a_s6_000' changed from OFFLINE (COMMUNICATION_ERROR) to ONLINE

2020-01-10 06:18:06.638 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.700000762939453 to 25.799999237060547

2020-01-10 06:18:06.800 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave1' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

2020-01-10 06:18:06.865 [hingStatusInfoChangedEvent] - 'modbus:poller:iwr3a:iwr3a_slave5' changed from OFFLINE (COMMUNICATION_ERROR): Error with read: org.openhab.io.transport.modbus.internal.ModbusSlaveIOExceptionImpl: Modbus IO Error with cause=ModbusIOException, EOF=false, message='I/O exception: SocketTimeoutException Read timed out', cause2=null to ONLINE

==> /var/log/openhab2/openhab.log <==

2020-01-10 06:23:33.395 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@2aa7b8 (unit id 1 & transaction 50038). Address: /127.0.0.1:8502

2020-01-10 06:24:21.904 [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@2aa7b8 (unit id 1 & transaction 50038). Address: /127.0.0.1:8502

2020-01-10 06:27:17.125 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@8fa77d[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1100,length=8,maxTries=3]). 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 93ae972c-31a6-4177-9237-a6126e7d3b0d]

2020-01-10 06:27:53.988 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/1 error: I/O exception: SocketTimeoutException Read timed out. Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@c548cb (unit id 1 & transaction 50039). Address: /127.0.0.1:8502

2020-01-10 06:27:54.006 [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@c548cb (unit id 1 & transaction 50039). Address: /127.0.0.1:8502

2020-01-10 06:27:54.012 [WARN ] [rt.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 failed when executing request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@922003[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=1031,length=15,maxTries=3]). 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 b96830d1-4dc0-4f12-b8aa-5ec3c6b1bc12]

==> /var/log/openhab2/events.log <==

2020-01-10 06:27:54.973 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.799999237060547 to 25.700000762939453

2020-01-10 06:31:56.345 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.700000762939453 to 25.600000381469727

2020-01-10 06:32:36.570 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.600000381469727 to 25.700000762939453

2020-01-10 06:34:07.143 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.700000762939453 to 25.799999237060547

2020-01-10 06:36:38.811 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.799999237060547 to 25.700000762939453

2020-01-10 06:37:19.561 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.700000762939453 to 25.600000381469727

2020-01-10 06:37:29.628 [vent.ItemStateChangedEvent] - Tint1aa changed from 25.600000381469727 to 25.700000762939453

Anything jump out?

Did a htop as follows … and note the swap memory is maxed out …

I see you´re using influx as well as grafana… Are you using grafana image rendering? If so, how?
I had a very bad experience with OH crashing (well, it was actually Java crashing) causing OH to restart when I was running OH 2.4 with influx and grafana rendering images uisng phantomJS…
I ended up moving Grafana to another server (Windows).

Perhaps try temporary stop Grafana, just to see if it makes any changes.

Yea … I was wondering whether Grafana/Influx might be an issue and that was going to be one of my next steps … to take it out of the equation.

But I do like the pretty graphs. :slightly_smiling_face:

You can still have your graphs… Just move Grafana to another server…
I just noticed you´re using an Rpi3A (I didnt even know there was an A model). I´m using a Rpi3B+ on my main system. Thats the same Rpi which had the major issue running OH 2.4 with Grafana rendering images using phantomJS.
So if you´re using a Rpi3A, I would say for sure you are pushing it way above its limits… But give it a try by stopping grafana first (no need to stop Influxdb… It hardly uses any resources).

There may be another option regarding image rendering of Grafana… They dumped the phnatomJS and made something new. There should be a tutorial here somwhere… I have not tried myself, cause I choose to move Grafana insted, already having a windows server running doing nothing :smiley:

Now theres a bunch of reasons why your system crashes. RPi is getting a bit lightweight.

What does mbmain.py do?

What does mbmain.py do?

MBLogic software … we have adapted it a bit.

OK … one of our setups is as follows …

  1. rpi3A running OH2.4 … c/w Grafana/Influx … ip xxx.25 Modbus 2.x TCP Master wifi to …
  2. rpi0 running our modbus software server slave ip xxx.120 (no OH2) …

This setup has been running with no errors for 40+hrs …

htop as follows …

Note swap mem ~50% …

Me thinks the problem lies with running oh2.4 and our modbus system together on one rpi3A using the 127.0.0.1 ip.

Perhaps its just too much for the rpi3A setup …

Will remove Grafana as Kim advises and see how that goes :thinking:

This won’t make it better, possibly worse. Properly set up Modbus error recovery adds delay to any remaining working parts of your modbus system, extending transaction queues.
If want to think about this properly, please see guide -

But in truth with one Modbus slave as you have shown us, I would leave retry settings at default
Messing with this will not fix your problem.


Get rid of modbus.cfg. It’s neither use nor ornament. Make very sure that you do not have v1 binding installed alongside v2.

Okay, so you have one slave, a half dozen pollers at fairly high poll rates, and many tens of Item? I am guessing all those data Things are linked to Items?
This is asking a lot of an RPi3. It’s not too much at all - but there are big performance traps set for you. (Especially if you are running your slave on the same RPi). There is not enough performance to allow you to be careless here.

Modbus binding defaults are suited for unfamiliar users to get a simple project going, you’ve moved beyond that and should take steps to tune your system… All those Items updating every half second is stressing out the rest of your system - are some/all persisted?
I recommend to read and implement here -


Reviewing the performance guide, I feel should add a additional section about TCP behaviour. Again, the defaults get you started but need proper consideration in a limited resource environment - which can include the target Modbus slaves, not just the openHAB host.
This is probably the cause of your logged TCP errors, but has likely nothing at all to do with system issues.

I’ll add this when I’ve thought about it, but you’ve got plenty to get on with and I think that is more relevant.

And yes, v2 binding has different potential stress points and different settings to deal with them … because it’s different.

OK … many thanks for this … loads of homework to catch up on.

Will start afresh and let you all know how it goes. :crossed_fingers:

laters