Connecting to SolarEdge Inverter unstable

magpern commented 3 years ago

ModBus TCP proxy version: 0.2.0
Python version: The Docker one
Operating System: Derbian

Description

I have a SolarEdge inverter which only allows one connection at a time. modbus-proxy would be the bridge between the SolarEdge inverter and a battery storage system and HomeAssistant as monitoring platform.

The SolarEdge inverter seams to have a somewhat unstable Modbus TCP implementation. If the client does something wrong or not follow the undocumented connection routines, it will stop responding. You will loose connection and you will not regain it without total restart on modbus-proxy.

So in other words. Modbus-proxy does connect to SolarEdge, it proxys data for some time, a few hours up to a day. Then it stops. This is all you see in the trace file.

2021-01-24 20:35:27,049: INFO:modbus-proxy.Client(192.168.1.87:35476):new connection, 2021-01-24 20:35:27,049: ERROR:modbus-proxy.Client(192.168.1.87:35476):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:36:32,079: INFO:modbus-proxy.Client(192.168.1.87:35482):new connection, 2021-01-24 20:36:32,079: ERROR:modbus-proxy.Client(192.168.1.87:35482):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:37:37,097: INFO:modbus-proxy.Client(192.168.1.87:35488):new connection, 2021-01-24 20:37:37,098: ERROR:modbus-proxy.Client(192.168.1.87:35488):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:38:42,117: INFO:modbus-proxy.Client(192.168.1.87:35494):new connection, 2021-01-24 20:38:42,118: ERROR:modbus-proxy.Client(192.168.1.87:35494):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:39:47,138: INFO:modbus-proxy.Client(192.168.1.87:35500):new connection, 2021-01-24 20:39:47,139: ERROR:modbus-proxy.Client(192.168.1.87:35500):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:40:52,164: INFO:modbus-proxy.Client(192.168.1.87:35506):new connection, 2021-01-24 20:40:52,164: ERROR:modbus-proxy.Client(192.168.1.87:35506):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:41:57,191: INFO:modbus-proxy.Client(192.168.1.87:35512):new connection, 2021-01-24 20:41:57,191: ERROR:modbus-proxy.Client(192.168.1.87:35512):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:43:02,201: INFO:modbus-proxy.Client(192.168.1.87:35518):new connection, 2021-01-24 20:43:02,202: ERROR:modbus-proxy.Client(192.168.1.87:35518):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:44:07,207: INFO:modbus-proxy.Client(192.168.1.87:35524):new connection, 2021-01-24 20:44:07,207: ERROR:modbus-proxy.Client(192.168.1.87:35524):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:45:12,223: INFO:modbus-proxy.Client(192.168.1.87:35530):new connection, 2021-01-24 20:45:12,223: ERROR:modbus-proxy.Client(192.168.1.87:35530):ConnectionResetError(104, 'Connection reset by peer'),

After a reboot of the docker instance, it connects again

2021-01-24 20:45:12,223: ERROR:modbus-proxy.Client(192.168.1.87:35530):ConnectionResetError(104, 'Connection reset by peer'), 2021-01-24 20:45:52,755: INFO:modbus-proxy:Starting..., 2021-01-24 20:45:52,760: INFO:modbus-proxy:Ready!, 2021-01-24 20:46:17,242: INFO:modbus-proxy.Client(192.168.1.87:35536):new connection, 2021-01-24 20:46:17,242: INFO:modbus-proxy.modbus(192.168.1.8:1502):connecting to modbus at ('192.168.1.8', 1502)..., 2021-01-24 20:46:17,245: INFO:modbus-proxy.modbus(192.168.1.8:1502):connected!, 2021-01-24 20:46:17,507: INFO:modbus-proxy.Client(192.168.1.87:35538):new connection, 2021-01-24 20:46:17,507: INFO:modbus-proxy.Client(192.168.1.87:35536):ConnectionClosedError('Client disconnected'), 2021-01-24 20:47:22,511: INFO:modbus-proxy.Client(192.168.1.87:35544):new connection, 2021-01-24 20:47:22,672: INFO:modbus-proxy.Client(192.168.1.87:35544):ConnectionClosedError('Client disconnected'), 2021-01-24 20:48:27,676: INFO:modbus-proxy.Client(192.168.1.87:35550):new connection, 2021-01-24 20:48:27,837: INFO:modbus-proxy.Client(192.168.1.87:35550):ConnectionClosedError('Client disconnected'),

Interesting observations

The company Victron has an operating system called Venus OS. They have a feature that connects to SolarEdge inverters and retrieves the data. It has never really worked, until they found a problem in their code and fixed it (2021 jan 21 beta release). After this fix, Venus OS and SolarEdge never drops the connection, before this fix, it dropped constantly (max an hour)

It is discussed here https://github.com/victronenergy/venus/issues/760 maybe this could help. The fix is here https://github.com/victronenergy/dbus-fronius/commit/6ecc1d01840d327ed27607d03ce1be070c3f6836 although their code is C++, but the problem seems to be the same.

tiagocoutinho commented 3 years ago

Thanks for the detailed report and for your patience.

It looks like the bug in Venus OS was related to Qt. They were calling the Qt disconnect() which as disconnecting the socket object from all qt signals instead of calling disconnectFromHost() which actually closed the socket... so my guess is it is not related.

I made some changes in master trying to take into account the following:

Some Modbus slaves decide to close the TCP connection in some circumstances (ex: connection is idle for some time, connection is active for a maximum time, excessive request frequency, etc)
Some clients close their end of the socket after each req/rep

Actions taken:

when attempting to communicate with slave make a second attempt in case the socket was closed by the slave
close the connection to the client if any error occurs
handle clients which close sockets after each request
make sure each message on both ends are full Modbus TCP messages (sometimes TCP packets can be broken into smaller pieces so we need to make sure we read a complete modbus message before sending it to the other side)
add a modbus timeout
better logging

I kindly ask that you test the latest master with an added parameter --log-level=DEBUG. If the problem persists could you send back the log? Thanks in advance.

magpern commented 3 years ago

I must say. You level of engagement and support... it is... as if you were me !!!

I have installed the latest version and is running it in a docker. At first glance, it looks like it is working, we'll see if it survives the night. It has not in the past.

I had to alter the dockerfile to include the debug option

CMD modbus-proxy -b "${MODBUS_BIND}" --modbus="${MODBUS_ADDRESS}" --log-level=${LOG_LEVEL}

and in docker-compose.yml i do this:


version: '3'
services:
  tcp_modbus_proxy:
    container_name: tcp_modbus_proxy
    image: mapern/tcp_modbus_proxy
    restart: unless-stopped
    build: modbus-proxy/.
    environment:
      - MODBUS_ADDRESS=tcp://192.168.1.8:1502
      - MODBUS_BIND=tcp://0:1502
      - LOG_LEVEL=DEBUG
    ports:
      - 502:1502
`

Is the trace file stored somewhere? As it is now, the trace file is just accessible through docker/portainer.
Can it be saved to disk? If yes, a data folder in the docker would be needed, because it is not possible to open a terminal in this docker image. (ENTRYPOINT missing?)
I don't know how many rows of trace are accessible through docker/portainer, but last time it stopped working I saw it too late, and the trace was not accessible.

So far the trace looks like this:

`192.168.1.87 VICTRON/Venus OS client`
`192.168.1.8 SolarEdge Modbus Server`
`172.19.0.1 HomeAssistant client`

2021-01-25T22:56:25.480109000Z 2021-01-25 22:56:25,479:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02P\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:25.480499000Z 2021-01-25 22:56:25,480:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02P\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:25.504602000Z 2021-01-25 22:56:25,504:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b"\xea\xc8\x00\x00\x00\x06~\x03\x9c\x85\x00'",
01-25T22:56:25.537698000Z 2021-01-25 22:56:25,537:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02P\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f[\x0f`\x08\xd2\x08\xcf\x08\xef\xff\xff\x00\x00\x00\x00\x13\x83\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:25.538106000Z 2021-01-25 22:56:25,537:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02P\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f[\x0f`\x08\xd2\x08\xcf\x08\xef\xff\xff\x00\x00\x00\x00\x13\x83\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:25.538439000Z 2021-01-25 22:56:25,538:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b"\xea\xc8\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:25.556086000Z 2021-01-25 22:56:25,555:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\xea\xc8\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f[\x0f`\x08\xd2\x08\xcf\x08\xef\xff\xff\x00\x00\x00\x00\x13\x83\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:25.556571000Z 2021-01-25 22:56:25,555:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\xea\xc8\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f[\x0f`\x08\xd2\x08\xcf\x08\xef\xff\xff\x00\x00\x00\x00\x13\x83\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:26.541116000Z 2021-01-25 22:56:26,540:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02Q\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:26.542591000Z 2021-01-25 22:56:26,541:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02Q\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:26.558549000Z 2021-01-25 22:56:26,557:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02Q\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f^\x0f[\x08\xd2\x08\xd2\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:26.559109000Z 2021-01-25 22:56:26,558:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02Q\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f^\x0f[\x08\xd2\x08\xd2\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:27.562250000Z 2021-01-25 22:56:27,561:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02R\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:27.564012000Z 2021-01-25 22:56:27,562:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02R\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:27.599992000Z 2021-01-25 22:56:27,599:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02R\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f_\x0f\\\x08\xd1\x08\xd1\x08\xec\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:27.600478000Z 2021-01-25 22:56:27,599:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02R\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f_\x0f\\\x08\xd1\x08\xd1\x08\xec\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:28.490898000Z 2021-01-25 22:56:28,490:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b':\x1d\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:28.491497000Z 2021-01-25 22:56:28,490:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b':\x1d\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:28.511398000Z 2021-01-25 22:56:28,510:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b':\x1d\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00<\x00\x10\x00\x1a\x00\x11\xff\xffY\x05Y\x05W\xb2Xf\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfc \xfe\xc4\xfe\t\xffS\x00\x00\x04\xef\x01z\x02A\x01R\x00\x00\xfc\xf1\xff/\xfe\xe5\xfe\xdd\x00\x00\x1c\xe8 \xcf!\xfc\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:28.511993000Z 2021-01-25 22:56:28,511:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b':\x1d\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00<\x00\x10\x00\x1a\x00\x11\xff\xffY\x05Y\x05W\xb2Xf\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfc \xfe\xc4\xfe\t\xffS\x00\x00\x04\xef\x01z\x02A\x01R\x00\x00\xfc\xf1\xff/\xfe\xe5\xfe\xdd\x00\x00\x1c\xe8 \xcf!\xfc\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:28.559438000Z 2021-01-25 22:56:28,558:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b"\x15\x9d\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:28.560094000Z 2021-01-25 22:56:28,559:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b"\x15\x9d\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:28.581313000Z 2021-01-25 22:56:28,580:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x15\x9d\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f[\x0f_\x08\xd2\x08\xcf\x08\xee\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:28.583115000Z 2021-01-25 22:56:28,581:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\x15\x9d\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f[\x0f_\x08\xd2\x08\xcf\x08\xee\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:28.603104000Z 2021-01-25 22:56:28,602:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02S\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:28.603413000Z 2021-01-25 22:56:28,603:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02S\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:28.640644000Z 2021-01-25 22:56:28,640:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02S\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f[\x0f_\x08\xd2\x08\xcf\x08\xee\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:28.641111000Z 2021-01-25 22:56:28,640:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02S\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f[\x0f_\x08\xd2\x08\xcf\x08\xee\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:29.644550000Z 2021-01-25 22:56:29,643:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02T\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:29.645979000Z 2021-01-25 22:56:29,644:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02T\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:29.661740000Z 2021-01-25 22:56:29,661:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02T\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fN\x0fc\x0fd\x08\xd5\x08\xd5\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:29.662265000Z 2021-01-25 22:56:29,661:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02T\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fN\x0fc\x0fd\x08\xd5\x08\xd5\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:30.665653000Z 2021-01-25 22:56:30,665:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02U\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:30.667231000Z 2021-01-25 22:56:30,665:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02U\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:30.683967000Z 2021-01-25 22:56:30,683:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02U\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f^\x0fZ\x08\xd1\x08\xcf\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:30.684817000Z 2021-01-25 22:56:30,683:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02U\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f^\x0fZ\x08\xd1\x08\xcf\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:31.528278000Z 2021-01-25 22:56:31,527:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b'\xa9\x8c\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:31.528906000Z 2021-01-25 22:56:31,528:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\xa9\x8c\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:31.564870000Z 2021-01-25 22:56:31,564:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\xa9\x8c\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00<\x00\x10\x00\x1a\x00\x11\xff\xffY\x05Y\x05W\xbcXz\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfc#\xfe\xc3\xfe\r\xffS\x00\x00\x04\xed\x01{\x02=\x01R\x00\x00\xfc\xf1\xff/\xfe\xe5\xfe\xdd\x00\x00\x1c\xc6 k!\xfc\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:31.565425000Z 2021-01-25 22:56:31,564:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\xa9\x8c\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00<\x00\x10\x00\x1a\x00\x11\xff\xffY\x05Y\x05W\xbcXz\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfc#\xfe\xc3\xfe\r\xffS\x00\x00\x04\xed\x01{\x02=\x01R\x00\x00\xfc\xf1\xff/\xfe\xe5\xfe\xdd\x00\x00\x1c\xc6 k!\xfc\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:31.585036000Z 2021-01-25 22:56:31,584:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b"c\x0e\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:31.585362000Z 2021-01-25 22:56:31,585:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b"c\x0e\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:31.628319000Z 2021-01-25 22:56:31,627:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'c\x0e\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fP\x0ff\x0f_\x08\xd8\x08\xd6\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:31.628831000Z 2021-01-25 22:56:31,628:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'c\x0e\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fP\x0ff\x0f_\x08\xd8\x08\xd6\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:31.687378000Z 2021-01-25 22:56:31,686:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02V\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:31.688612000Z 2021-01-25 22:56:31,687:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02V\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:31.702793000Z 2021-01-25 22:56:31,702:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02V\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fP\x0ff\x0f_\x08\xd8\x08\xd6\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:31.703149000Z 2021-01-25 22:56:31,702:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02V\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fP\x0ff\x0f_\x08\xd8\x08\xd6\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:32.706553000Z 2021-01-25 22:56:32,705:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02W\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:32.708106000Z 2021-01-25 22:56:32,706:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02W\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:32.724619000Z 2021-01-25 22:56:32,724:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02W\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0fb\x0fZ\x08\xd1\x08\xd2\x08\xec\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:32.725198000Z 2021-01-25 22:56:32,724:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02W\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0fb\x0fZ\x08\xd1\x08\xd2\x08\xec\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:33.728426000Z 2021-01-25 22:56:33,727:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02X\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:33.730061000Z 2021-01-25 22:56:33,728:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02X\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:33.745235000Z 2021-01-25 22:56:33,744:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02X\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f`\x0f^\x08\xd1\x08\xd1\x08\xf0\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:33.745902000Z 2021-01-25 22:56:33,745:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02X\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f`\x0f^\x08\xd1\x08\xd1\x08\xf0\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:34.579643000Z 2021-01-25 22:56:34,579:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b'\xd2\xca\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:34.580131000Z 2021-01-25 22:56:34,579:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\xd2\xca\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:34.618688000Z 2021-01-25 22:56:34,618:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\xd2\xca\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00B\x00\x10\x00 \x00\x11\xff\xffY\x05Y\x05W\xd0X\x84\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfb\x91\xfe\xc3\xfd|\xffR\x00\x00\x05c\x01{\x02\xbf\x01S\x00\x00\xfc\xf0\xff/\xfe\xe4\xfe\xdd\x00\x00\x1dm k#\xf0\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:34.619288000Z 2021-01-25 22:56:34,618:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\xd2\xca\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00B\x00\x10\x00 \x00\x11\xff\xffY\x05Y\x05W\xd0X\x84\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfb\x91\xfe\xc3\xfd|\xffR\x00\x00\x05c\x01{\x02\xbf\x01S\x00\x00\xfc\xf0\xff/\xfe\xe4\xfe\xdd\x00\x00\x1dm k#\xf0\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:34.631376000Z 2021-01-25 22:56:34,630:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b"9\xab\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:34.631891000Z 2021-01-25 22:56:34,631:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b"9\xab\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:34.645209000Z 2021-01-25 22:56:34,644:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'9\xab\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fJ\x0f`\x0f_\x08\xd3\x08\xd0\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:34.645493000Z 2021-01-25 22:56:34,645:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'9\xab\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fJ\x0f`\x0f_\x08\xd3\x08\xd0\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:34.748849000Z 2021-01-25 22:56:34,748:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02Y\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:34.749985000Z 2021-01-25 22:56:34,748:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02Y\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:34.766445000Z 2021-01-25 22:56:34,765:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02Y\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fJ\x0f`\x0f_\x08\xd3\x08\xd0\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:34.767007000Z 2021-01-25 22:56:34,766:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02Y\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fJ\x0f`\x0f_\x08\xd3\x08\xd0\x08\xf1\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:35.770322000Z 2021-01-25 22:56:35,769:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02Z\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:35.771816000Z 2021-01-25 22:56:35,770:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02Z\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:35.797060000Z 2021-01-25 22:56:35,796:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02Z\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f`\x0f[\x08\xd2\x08\xd0\x08\xed\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:35.797594000Z 2021-01-25 22:56:35,797:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02Z\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f`\x0f[\x08\xd2\x08\xd0\x08\xed\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:36.800536000Z 2021-01-25 22:56:36,800:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02[\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:36.801244000Z 2021-01-25 22:56:36,800:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02[\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:36.818258000Z 2021-01-25 22:56:36,817:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02[\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fL\x0fc\x0f^\x08\xd4\x08\xd3\x08\xed\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:36.818682000Z 2021-01-25 22:56:36,818:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02[\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fL\x0fc\x0f^\x08\xd4\x08\xd3\x08\xed\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:37.635021000Z 2021-01-25 22:56:37,634:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b'\xa5\xc4\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:37.635662000Z 2021-01-25 22:56:37,635:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\xa5\xc4\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:37.651172000Z 2021-01-25 22:56:37,650:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\xa5\xc4\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00B\x00\x10\x00 \x00\x11\xff\xffY\x05Y\x05W\xd0X\x84\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfa\xa0\xfe\xc4\xfc\x8b\xffQ\x00\x00\x06/\x01z\x03\xa1\x01S\x00\x00\xfc\xf0\xff/\xfe\xe4\xfe\xdd\x00\x00\x1dm k#\xf0\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:37.651810000Z 2021-01-25 22:56:37,651:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\xa5\xc4\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00B\x00\x10\x00 \x00\x11\xff\xffY\x05Y\x05W\xd0X\x84\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xfa\xa0\xfe\xc4\xfc\x8b\xffQ\x00\x00\x06/\x01z\x03\xa1\x01S\x00\x00\xfc\xf0\xff/\xfe\xe4\xfe\xdd\x00\x00\x1dm k#\xf0\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:37.662626000Z 2021-01-25 22:56:37,662:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b"mP\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:37.662845000Z 2021-01-25 22:56:37,662:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b"mP\x00\x00\x00\x06~\x03\x9c\x85\x00'",
2021-01-25T22:56:37.708487000Z 2021-01-25 22:56:37,708:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'mP\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0fa\x0f^\x08\xd2\x08\xd2\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:37.708882000Z 2021-01-25 22:56:37,708:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'mP\x00\x00\x00Q~\x03N\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0fa\x0f^\x08\xd2\x08\xd2\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02',
2021-01-25T22:56:37.821744000Z 2021-01-25 22:56:37,821:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02\\\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:37.822884000Z 2021-01-25 22:56:37,821:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02\\\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:37.869887000Z 2021-01-25 22:56:37,869:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02\\\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f^\x0f[\x08\xd3\x08\xcf\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:37.870512000Z 2021-01-25 22:56:37,869:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02\\\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fH\x0f^\x0f[\x08\xd3\x08\xcf\x08\xeb\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:38.874504000Z 2021-01-25 22:56:38,873:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02]\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:38.875978000Z 2021-01-25 22:56:38,874:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02]\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:38.891390000Z 2021-01-25 22:56:38,890:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02]\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f_\x0f^\x08\xd2\x08\xd2\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:38.891946000Z 2021-01-25 22:56:38,891:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02]\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fG\x0f_\x0f^\x08\xd2\x08\xd2\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:39.894978000Z 2021-01-25 22:56:39,894:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):received b'\x02^\x00\x00\x00\x06~\x03\x9c\x85\x004',
2021-01-25T22:56:39.896443000Z 2021-01-25 22:56:39,894:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x02^\x00\x00\x00\x06~\x03\x9c\x85\x004',
01-25T22:56:39.921751000Z 2021-01-25 22:56:39,921:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\x02^\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f`\x0f_\x08\xd2\x08\xd1\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:39.922332000Z 2021-01-25 22:56:39,921:  DEBUG:modbus-proxy.Client(192.168.1.87:46806):sending b'\x02^\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fI\x0f`\x0f_\x08\xd2\x08\xd1\x08\xef\xff\xff\x00\x00\x00\x00\x13\x82\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00',
2021-01-25T22:56:40.667954000Z 2021-01-25 22:56:40,667:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):received b'\xbf\xba\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:40.668560000Z 2021-01-25 22:56:40,667:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\xbf\xba\x00\x00\x00\x06~\x03\x9c\xfc\x00k',
2021-01-25T22:56:40.703669000Z 2021-01-25 22:56:40,703:  DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'\xbf\xba\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00K\x00\x10\x00)\x00\x11\xff\xffX\xfcX\xfcW\xbcXo\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xf8`\xfe\xc3\xfc\x8b\xfd\x12\x00\x00\x086\x01{\x03\xa0\x03$\x00\x00\xfc\xf4\xff/\xfe\xe7\xfe\xde\x00\x00\x1d\xd1 k%\x1b\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',
2021-01-25T22:56:40.707568000Z 2021-01-25 22:56:40,703:  DEBUG:modbus-proxy.Client(172.19.0.1:46402):sending b'\xbf\xba\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00K\x00\x10\x00)\x00\x11\xff\xffX\xfcX\xfcW\xbcXo\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x83\xff\xfe\xf8`\xfe\xc3\xfc\x8b\xfd\x12\x00\x00\x086\x01{\x03\xa0\x03$\x00\x00\xfc\xf4\xff/\xfe\xe7\xfe\xde\x00\x00\x1d\xd1 k%\x1b\x13\xeb\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf4*\x00k-:\x006\xa8l\x00$\x11\xe8\x00\x13\x04f\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',

magpern commented 3 years ago

Update. It has been running for 10 hours now and is still proxying data!

tiagocoutinho commented 3 years ago

Great! That's excellent news. I suspect the most important fix was the one that makes sure that the full modbus message is received before sending it.

Concerning logging to a file, IMHO, the most flexible way would be to support a command line argument that is a python logging configuration file (as in logging.config). I guess that depending on the contents of the logging configuration, a matching docker volume would have to be mounted. I'll have a look at it.

magpern commented 3 years ago

It's been running for 24 hours now, and till running. This has never been done before. I have seen some errors in the trace file, but it recuperates from the errors and continues. I, personally, consider this bug fixed. Gold star to you for your work and extremely fast action. modbis-proxy will be a key component in my production environment for my battery for my house. I see you have implemented logging to file, I will test that too. But as far as this issue, I will close it!

2021-01-26 21:30:51,840: DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b'ML\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fn\x0f\x88\x0f\x87\x08\xe6\x08\xe8\t\x07\xff\xff\x00\x00\x00\x00\x13\x84\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00', 2021-01-26 21:30:51,841: DEBUG:modbus-proxy.Client(192.168.1.87:54480):sending b'ML\x00\x00\x00k~\x03h\x00g\x002\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x0fn\x0f\x88\x0f\x87\x08\xe6\x08\xe8\t\x07\xff\xff\x00\x00\x00\x00\x13\x84\xff\xfe\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x96\x81\xbe\x00\x00\xff\xff\x80\x00\x00\x00\xff\xff\x00\x00\x00\x00\x80\x00\x00\x00\x80\x00\x80\x00\xff\xfe\x00\x02\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00', 2021-01-26 21:30:52,380: INFO:modbus-proxy.Client(172.19.0.1:53630):new client connection, 2021-01-26 21:30:52,381: DEBUG:modbus-proxy.Client(172.19.0.1:53630):received b'\x00\x7f\x00\x00\x00\x06+\x03\x9c@\x00\x02', 2021-01-26 21:30:52,381: DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b'\x00\x7f\x00\x00\x00\x06+\x03\x9c@\x00\x02', 2021-01-26 21:30:52,561: DEBUG:modbus-proxy.Client(172.19.0.1:53614):received b'\x85\x1c\x00\x00\x00\x06~\x03\x9c\xfc\x00k', 2021-01-26 21:30:52,844: DEBUG:modbus-proxy.Client(192.168.1.87:54480):received b'MM\x00\x00\x00\x06~\x03\x9c\x85\x004', 2021-01-26 21:31:02,383: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: TimeoutError(), 2021-01-26 21:31:02,383: INFO:modbus-proxy.ModBus(192.168.1.8:1502):closing connection..., 2021-01-26 21:31:02,384: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connection closed, 2021-01-26 21:31:02,384: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,389: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,389: INFO:modbus-proxy.Client(172.19.0.1:53630):closing connection..., 2021-01-26 21:31:02,390: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,391: INFO:modbus-proxy.Client(172.19.0.1:53630):connection closed, 2021-01-26 21:31:02,391: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,392: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,393: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,393: INFO:modbus-proxy.Client(172.19.0.1:53614):closing connection..., 2021-01-26 21:31:02,394: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,395: INFO:modbus-proxy.Client(172.19.0.1:53614):connection closed, 2021-01-26 21:31:02,395: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,395: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,397: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,397: INFO:modbus-proxy.Client(192.168.1.87:54480):closing connection..., 2021-01-26 21:31:02,397: INFO:modbus-proxy.Client(192.168.1.87:54480):connection closed, 2021-01-26 21:31:02,399: INFO:modbus-proxy.Client(172.19.0.1:53650):new client connection, 2021-01-26 21:31:02,399: DEBUG:modbus-proxy.Client(172.19.0.1:53650):received b"\xc7\xf3\x00\x00\x00\x06~\x03\x9c\x85\x00'", 2021-01-26 21:31:02,400: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,400: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,400: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:02,401: ERROR:modbus-proxy.ModBus(192.168.1.8:1502):write_read error: ConnectionRefusedError(111, "Connect call failed ('192.168.1.8', 1502)"), 2021-01-26 21:31:02,401: INFO:modbus-proxy.Client(172.19.0.1:53650):closing connection..., 2021-01-26 21:31:02,402: INFO:modbus-proxy.Client(172.19.0.1:53650):connection closed, 2021-01-26 21:31:05,403: INFO:modbus-proxy.Client(172.19.0.1:53660):new client connection, 2021-01-26 21:31:05,403: DEBUG:modbus-proxy.Client(172.19.0.1:53660):received b't\x9c\x00\x00\x00\x06~\x03\x9c\xfc\x00k', 2021-01-26 21:31:05,404: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connecting to modbus..., 2021-01-26 21:31:05,405: INFO:modbus-proxy.ModBus(192.168.1.8:1502):connected!, 2021-01-26 21:31:05,406: DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):sending b't\x9c\x00\x00\x00\x06~\x03\x9c\xfc\x00k', 2021-01-26 21:31:05,435: DEBUG:modbus-proxy.ModBus(192.168.1.8:1502):received b't\x9c\x00\x00\x00\xd9~\x03\xd6\x00\xcb\x00i\x00e\x00,\x00,\x00\x0c\xff\xffY\xe2Y\xe2X\x8dY`\x00\x00\x00\x00\x00\x00\x00\x00\xff\xfe\x13\x88\xff\xfe\xf8\xf3\xfc\x90\xfd\x0e\xffU\x00\x00\x07\x0e\x03\xd6\x03\x0b\x00\xf2\x00\x00\x00A\x01\xb4\xff9\xffT\x00\x00!v"\xc3&H\x1bW\xff\xfe\x001e\xc8\x00\x0e3\x96\x00\x11\xcf\xec\x00\x13\xf44\x00k\xdc\xbb\x006\xf0\x1a\x00$W \x00\x13\'\x0c\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x80\x00\x00\x00\x00\x00',

tiagocoutinho commented 3 years ago

great! thanks for your kind words. they help keeping the motivation i will have a quick look later on at the latest log you sent just to check if the ConnectionRefusedError is due to an error in the proxy.

best of luck with your interesting project

magpern commented 3 years ago

Here's just a follow up! I've been using this since january 2021. It has not failed one single time, and it is running 24/7. Super stable!

tiagocoutinho commented 3 years ago

I'm very glad to hear that it working well. Thanks a lot for your feedback @magpern!

tiagocoutinho / modbus-proxy

Connecting to SolarEdge Inverter unstable #2

Description

Interesting observations