–>
2) TRACE logs with two pollers (holding register and 4 coils) and .things file:
17:02:44.289 [DEBUG] [ort.modbus.internal.ModbusManagerImpl] - Executing scheduled (5000ms) poll task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366]
17:02:44.374 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366] (oneOff=false)! Waiting for connection [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.417 [DEBUG] [.wimpi.modbus.net.TCPMasterConnection] - connect()
17:02:44.444 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]] -> true
17:02:44.472 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - borrowing connection (got Optional[TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]]]) for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] took 55 ms
17:02:44.504 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366] (oneOff=false)! Connection received in 87 ms [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.555 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.474 [DEBUG] [ort.modbus.internal.ModbusManagerImpl] - Executing scheduled (5000ms) poll task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb]
17:02:44.571 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.614 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb] (oneOff=false)! Waiting for connection [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:44.640 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38059: net.wimpi.modbus.msg.ReadCoilsRequest@6ba520df
17:02:44.750 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38059
17:02:44.766 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38059): 94 ab 00 00 00 04 02 01 01 00 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.796 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.816 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.797 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:44.842 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.878 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:44.860 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:44.909 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38060: net.wimpi.modbus.msg.ReadCoilsRequest@16a6f875
17:02:44.926 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:44.960 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:45.003 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38060
17:02:45.018 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38060): 94 ac 00 00 00 04 02 01 01 00 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.045 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:45.049 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 3 out of 3 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.060 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:45.078 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.092 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:45.116 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.130 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:45.148 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.195 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38061: net.wimpi.modbus.msg.ReadCoilsRequest@71b01d6b
17:02:45.248 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38061
17:02:45.265 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38061): 94 ad 00 00 00 04 02 01 01 00 [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.295 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:45.297 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]] -> true
17:02:45.312 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:45.352 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Passivating connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]...
17:02:45.369 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:45.408 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - (passivate) Connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]] (endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]) age 964ms is over the reconnectAfterMillis=0ms limit or has been connection time (1510326164444) is after the "disconnectBeforeConnectedMillis"=1510326144369 -> disconnecting.
17:02:45.425 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:45.501 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - ...Passivated connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48646]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:45.534 [DEBUG] [.wimpi.modbus.net.TCPMasterConnection] - connect()
17:02:45.537 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - returned connection for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:45.562 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Connection was returned to the pool, ending operation [operation ID 8deacf57-16af-401e-b1b9-aace50278baf]
17:02:45.569 [ERROR] [ling.ModbusSlaveConnectionFactoryImpl] - connect try 1/3 error: Yhteys torjuttu (Connection refused). Connection TCPMasterConnection@2b4765df[socket=Socket[unconnected]]. Endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:45.718 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Waited 100ms (interConnectDelayMillis 0ms, passivateBorrowMinMillis 100ms) before connecting disconnected connection TCPMasterConnection@2b4765df[socket=Socket[unconnected]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502], to allow delay between connections re-connects
17:02:45.816 [DEBUG] [.wimpi.modbus.net.TCPMasterConnection] - connect()
17:02:45.852 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]] -> true
17:02:45.881 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - borrowing connection (got Optional[TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]]]) for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] took 1189 ms
17:02:45.910 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb] (oneOff=false)! Connection received in 1218 ms [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:45.957 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:45.973 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.000 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38062: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@2c0fc0e4
17:02:46.025 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38062
17:02:46.039 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38062): 94 ae 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.073 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.089 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.109 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.125 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.150 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38063: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@769e7f43
17:02:46.331 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38063
17:02:46.344 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38063): 94 af 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.375 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 3 out of 3 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.392 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.413 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.428 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.454 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38064: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@40198db1
17:02:46.528 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38064
17:02:46.543 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38064): 94 b0 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:46.605 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]] -> true
17:02:46.665 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Passivating connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]...
17:02:46.729 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - (passivate) Connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]] (endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]) age 877ms is over the reconnectAfterMillis=0ms limit or has been connection time (1510326165852) is after the "disconnectBeforeConnectedMillis"=1510326144369 -> disconnecting.
17:02:46.806 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - ...Passivated connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48648]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:46.862 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - returned connection for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:46.901 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Connection was returned to the pool, ending operation [operation ID fb334e58-700a-4c58-a7f8-1aba5049fc91]
17:02:49.289 [DEBUG] [ort.modbus.internal.ModbusManagerImpl] - Executing scheduled (5000ms) poll task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366]
17:02:49.378 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366] (oneOff=false)! Waiting for connection [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.446 [DEBUG] [.wimpi.modbus.net.TCPMasterConnection] - connect()
17:02:49.468 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]] -> true
17:02:49.474 [DEBUG] [ort.modbus.internal.ModbusManagerImpl] - Executing scheduled (5000ms) poll task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb]
17:02:49.548 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb] (oneOff=false)! Waiting for connection [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:49.502 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - borrowing connection (got Optional[TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]]]) for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] took 56 ms
17:02:49.648 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@21e3ee75[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@2f04de46[slaveId=2,functionCode=READ_COILS,start=3,length=4,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@52b96366] (oneOff=false)! Connection received in 202 ms [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.691 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.705 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.727 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38065: net.wimpi.modbus.msg.ReadCoilsRequest@3d8ac49f
17:02:49.770 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38065
17:02:49.784 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38065): 94 b1 00 00 00 04 02 01 01 00 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.808 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.809 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:49.825 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.839 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:49.860 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.873 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:49.887 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:49.900 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:49.923 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38066: net.wimpi.modbus.msg.ReadCoilsRequest@1b9b1c6e
17:02:49.975 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38066
17:02:49.991 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38066): 94 b2 00 00 00 04 02 01 01 00 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.020 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 3 out of 3 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.021 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:50.040 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.083 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.057 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:50.098 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_COILS): 00 00 00 00 00 06 02 01 00 03 00 04 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.111 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:50.134 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38067: net.wimpi.modbus.msg.ReadCoilsRequest@336ea08e
17:02:50.147 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:50.175 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38067
17:02:50.188 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=1, transaction ID=38067): 94 b3 00 00 00 04 02 01 01 00 [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.215 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]] -> true
17:02:50.250 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Passivating connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]...
17:02:50.287 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - (passivate) Connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]] (endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]) age 819ms is over the reconnectAfterMillis=0ms limit or has been connection time (1510326169468) is after the "disconnectBeforeConnectedMillis"=1510326144369 -> disconnecting.
17:02:50.215 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #0
17:02:50.349 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #1
17:02:50.360 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #2
17:02:50.361 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - ...Passivated connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48649]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:50.372 [TRACE] [net.wimpi.modbus.util.BitVector ] - Get bit #3
17:02:50.401 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - returned connection for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:50.401 [DEBUG] [.wimpi.modbus.net.TCPMasterConnection] - connect()
17:02:50.434 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Connection was returned to the pool, ending operation [operation ID 514b24f6-dd89-4482-8afb-b5d0db37ee71]
17:02:50.461 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]] -> true
17:02:50.504 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - borrowing connection (got Optional[TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]]]) for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] took 893 ms
17:02:50.540 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Executing task PollTaskImpl@4c3d0299[request=ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@466a0020[slaveId=2,functionCode=READ_MULTIPLE_REGISTERS,start=0,length=14,maxTries=3],endpoint=ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502],callback=java.lang.ref.WeakReference@2adddfcb] (oneOff=false)! Connection received in 929 ms [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.592 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 1 out of 3 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.608 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.635 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38068: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@7439d04c
17:02:50.667 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38068
17:02:50.679 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38068): 94 b4 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.709 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 2 out of 3 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.724 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.744 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.868 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:50.892 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38069: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@2b1267b6
17:02:50.966 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38069
17:02:50.978 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38069): 94 b5 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.015 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Try 3 out of 3 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.046 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Ensuring that enough time passes before retrying again. Sleeping [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.083 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Sleep ended [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.097 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Going execute transaction with request request (FC=READ_MULTIPLE_REGISTERS): 00 00 00 00 00 06 02 03 00 00 00 0e [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.123 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Sending request with transaction ID 38070: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@4cd01679
17:02:51.201 [TRACE] [.wimpi.modbus.io.ModbusTCPTransaction] - Received response with transaction ID 38070
17:02:51.214 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Response for read request (FC=3, transaction ID=38070): 94 b6 00 00 00 1f 02 03 1c 00 ed 00 00 00 cb 01 19 00 de 00 00 00 db 00 00 00 d4 00 00 00 e4 00 00 00 f3 01 16 [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
17:02:51.254 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Validating endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502] connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]] -> true
17:02:51.289 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - Passivating connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]...
17:02:51.349 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - (passivate) Connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]] (endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]) age 888ms is over the reconnectAfterMillis=0ms limit or has been connection time (1510326170461) is after the "disconnectBeforeConnectedMillis"=1510326144369 -> disconnecting.
17:02:51.463 [TRACE] [ling.ModbusSlaveConnectionFactoryImpl] - ...Passivated connection TCPMasterConnection@2b4765df[socket=Socket[addr=/192.168.1.100,port=502,localport=48650]] for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:51.494 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - returned connection for endpoint ModbusTCPSlaveEndpoint@61b5c2f3[address=192.168.1.100,port=502]
17:02:51.513 [TRACE] [ort.modbus.internal.ModbusManagerImpl] - Connection was returned to the pool, ending operation [operation ID 2d24b42d-dc4a-47c9-b7b9-ba55dc7491f0]
Bridge modbus:tcp:endpointTCP [ host="192.168.1.100", port=502, id=2, connectMaxTries=3, timeBetweenTransactionsMillis=100 ] {
Bridge poller greenlineCoil [ start=3, length=4, refresh=5000, type="coil" ] {
Thing data d04 [ readStart="3", writeStart="3", readValueType="bit", writeType="coil" ]
Thing data d05 [ readStart="4", writeStart="4", readValueType="bit", writeType="coil" ]
Thing data d06 [ readStart="5", writeStart="5", readValueType="bit", writeType="coil" ]
Thing data d07 [ readStart="6", writeStart="6", readValueType="bit", writeType="coil" ]
}
Bridge poller holding [ start=0, length=14, refresh=5000, type="holding" ] {
Thing data mh4 [ readStart="0", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data ph [ readStart="2", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data phrh [ readStart="3", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data mh1 [ readStart="4", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data mh2 [ readStart="6", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data mh3 [ readStart="8", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data oh [ readStart="10", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data tekn [ readStart="12", readValueType="uint16", readTransform="JS(divide.js)" ]
Thing data teknrh [ readStart="13", readValueType="uint16", readTransform="JS(divide.js)" ]