Ebusd reads are blocking the ebus. #765
Comments
|
please check with the latest source code if it still persists |
|
I still have the same issue, I did the following I pulled the src like this Built using Run with pi@raspberrypi:~/ebusd $ ebusd --mqttport=1883 -s -f --device=/dev/ttyUSB0 --port=8887 022-12-22 12:28:07.028 [update notice] sent read bai Statenumber QQ=31: 30 |
|
please start ebusd with --lograwdate=bytes, then do the same failing query again, and then stop ebusd and post that part of the log file (or send it to me to ebusd(at)ebusd.eu ) |
|
The log is attached. |
|
the log shows that the 08 unit just doesn't answer to the requests within the allowed time anymore. it usually answers with the ACK symbol within less than 10ms and when it stops it doesn't answer within 35ms. so maybe you'd have to give it a bit more time by increasing the allowed time using e.g. "--receivetimeout=50" |
|
ah, just saw that you have a PL2303 based interface. this might also be relevant if it buffers some characters in the driver or the hardware |
|
The receive time out fixed the problem. |
Description
Hi John,
I have been using ebusd for many years with very few problems, its been very reliable , thanks for the project !
I do some simple reporting on the temperature of my Vaillant ecotec, reported in a node red dashboard via mqtt.
A couple of week ago the reporting stopped working,so I restarted in the foreground
and discovered the config was not being found, now I believe this was related to Issue #754 -
Config files not always loaded.
I decided to upgrade as had not done so for 2 years 🙁
I now have a very different problem.
Ebus starts as normal, finds the config and reads the bus messages without any problem.
It will continue to report the state of the V65 for days without any problem.
If I send a single read it will reply , if i send 3-4 multiple sequential reads it will cause the
Following error :
[bus error] send to 08: ERR: read timeout, retry
If I stop the polling the ebus will recover.
If I continue polling the bus blocks and the v65 can not talk to the boiler.
Before upgrading I used to poll every 1 second with no error.
Now even if I poll every 10 seconds the bus blocks after a few hours.
How can I debug what is going on?
pi@raspberrypi:~ $ ebusd --mqttport=1883 -s -f --device=/dev/ttyUSB0 --port=8887
2022-12-19 10:14:48.323 [main notice] ebusd 22.4.v22.4 started with auto scan on device /dev/ttyUSB0
2022-12-19 10:14:49.096 [bus notice] bus started with own address 31/36
2022-12-19 10:14:49.098 [mqtt notice] connection established
2022-12-19 10:14:49.118 [bus notice] signal acquired
2022-12-19 10:14:52.175 [bus notice] new master 03, master count 2
2022-12-19 10:14:52.223 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:14:57.625 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:14:59.235 [bus notice] scan 08: ;Vaillant;BAI00;0603;1303
2022-12-19 10:14:59.235 [update notice] store 08 ident: done
2022-12-19 10:14:59.235 [update notice] sent scan-read scan.08 QQ=31: Vaillant;BAI00;0603;1303
2022-12-19 10:14:59.235 [bus notice] scan 08: ;Vaillant;BAI00;0603;1303
2022-12-19 10:15:00.062 [main notice] read common config file vaillant/broadcast.csv
2022-12-19 10:15:00.251 [main notice] read common config file vaillant/scan.csv
2022-12-19 10:15:00.458 [main notice] read common config file vaillant/general.csv
2022-12-19 10:15:00.664 [main notice] read scan config file vaillant/08.bai.csv for ID "bai00", SW0603, HW1303
2022-12-19 10:15:00.817 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:00.957 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:01.097 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:01.237 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:01.964 [main notice] found messages: 224 (3 conditional on 28 conditions, 0 poll, 10 update)
2022-12-19 10:15:02.114 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.254 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.394 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.534 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.534 [bus notice] scan 08: ;21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:03.001 [bus notice] max. symbols per second: 112
2022-12-19 10:15:03.011 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:15:04.707 [bus notice] scan 64: ;Vaillant;V6500;0007;8301
2022-12-19 10:15:04.707 [update notice] store 64 ident: done
2022-12-19 10:15:04.707 [update notice] sent scan-read scan.64 QQ=31: Vaillant;V6500;0007;8301
2022-12-19 10:15:04.707 [bus notice] scan 64: ;Vaillant;V6500;0007;8301
2022-12-19 10:15:05.307 [main notice] read scan config file vaillant/64.v65.csv for ID "v6500", SW0007, HW8301
2022-12-19 10:15:05.308 [main notice] found messages: 226 (3 conditional on 28 conditions, 0 poll, 11 update)
2022-12-19 10:15:08.443 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:13.844 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:19.239 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:24.638 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^[v2022-12-19 10:15:29.998 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:35.405 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:40.805 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:46.207 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:51.607 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:57.022 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:16:02.381 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C^[c2022-12-19 10:16:07.782 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C2022-12-19 10:16:10.952 [main notice] SIGINT received
2022-12-19 10:16:13.581 [
2022-12-19 10:21:39.229 [update notice] sent read bai RemainingBoilerblocktime QQ=31: 0
2022-12-19 10:21:39.229 [mqtt notice] read bai RemainingBoilerblocktime: 1
2022-12-19 10:21:39.341 [update notice] sent read bai Statenumber QQ=31: 30
2022-12-19 10:21:39.341 [mqtt notice] read bai Statenumber: 1
2022-12-19 10:21:41.809 [update notice] sent read bai ReturnTemp QQ=31: 25.62;65125;ok
2022-12-19 10:21:41.809 [mqtt notice] read bai ReturnTemp: 1
2022-12-19 10:21:41.932 [update notice] sent read bai FlowTemp QQ=31: 27.56;ok
2022-12-19 10:21:41.932 [mqtt notice] read bai FlowTemp: 1
2022-12-19 10:21:42.049 [update notice] sent read bai HcHours QQ=31: 8385
2022-12-19 10:21:42.049 [mqtt notice] read bai HcHours: 1
2022-12-19 10:21:42.168 [update notice] sent read bai HwcHours QQ=31: 1768
2022-12-19 10:21:42.169 [mqtt notice] read bai HwcHours: 1
2022-12-19 10:21:42.288 [update notice] sent read bai ModulationTempDesired QQ=31: 75.4
2022-12-19 10:21:42.289 [mqtt notice] read bai ModulationTempDesired: 1
2022-12-19 10:21:42.457 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:43.046 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:43.637 [bus error] send to 08: ERR: read timeout
2022-12-19 10:21:43.637 [bus error] send message part 0: ERR: read timeout
2022-12-19 10:21:43.637 [mqtt error] read bai RemainingBoilerblocktime: ERR: read timeout
2022-12-19 10:21:44.227 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:44.816 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:45.407 [bus error] send to 08: ERR: read timeout
2022-12-19 10:21:45.407 [bus error] send message part 0: ERR: read timeout
2022-12-19 10:21:45.407 [mqtt error] read bai Statenumber: ERR: read timeout
2022-12-19 10:21:45.958 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:21:47.815 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:21:53.216 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C2022-12-19 10:21:56.273 [main notice] SIGINT received
2022-12-19 10:21:58.621 [main notice] ebusd stopped
pi@raspberrypi:~ $
Environment
Home made interface using USB pl2303
Raspberry Pi 4 - Buster
NodeRed 2.1.3
Mosquitto Mqtt
Ebusd version 22.4v22.4
Installed from :
sudo wget -O /etc/apt/sources.list.d/ebusd.list https://raw.githubusercontent.com/john30/ebusd-debian/master/ebusd-default-buster.list
Run with :
ebusd --mqttport=1883 -s -f --device=/dev/ttyUSB0
Actual behavior
Ebus starts as normal, finds the config and reads the bus messages without any problem.
It will continue to report the state of the V65 for days without any problem.
If I send a single read it will reply , if i send 3-4 multiple sequential reads it will cause the
Following error :
[bus error] send to 08: ERR: read timeout, retry
Expected behavior
Before upgrading I used to poll every 1 second with no error.
Now even if I poll every 10 seconds the bus blocks after a few hours.
ebusd version
current source from git
ebusd arguments
ebusd --mqttport=1883 -s -f --device=/dev/ttyUSB0
Operating system
Debian 10 (Buster) / Ubuntu 18-19 / Raspbian 10 / Raspberry Pi OS 10 (including lite)
CPU architecture
arm64
Dockerized
None
Hardware interface
other
Related integration
MQTT generic
Logs
pi@raspberrypi:~ $ ebusd --mqttport=1883 -s -f --device=/dev/ttyUSB0 --port=8887
2022-12-19 10:14:48.323 [main notice] ebusd 22.4.v22.4 started with auto scan on device /dev/ttyUSB0
2022-12-19 10:14:49.096 [bus notice] bus started with own address 31/36
2022-12-19 10:14:49.098 [mqtt notice] connection established
2022-12-19 10:14:49.118 [bus notice] signal acquired
2022-12-19 10:14:52.175 [bus notice] new master 03, master count 2
2022-12-19 10:14:52.223 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:14:57.625 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:14:59.235 [bus notice] scan 08: ;Vaillant;BAI00;0603;1303
2022-12-19 10:14:59.235 [update notice] store 08 ident: done
2022-12-19 10:14:59.235 [update notice] sent scan-read scan.08 QQ=31: Vaillant;BAI00;0603;1303
2022-12-19 10:14:59.235 [bus notice] scan 08: ;Vaillant;BAI00;0603;1303
2022-12-19 10:15:00.062 [main notice] read common config file vaillant/broadcast.csv
2022-12-19 10:15:00.251 [main notice] read common config file vaillant/scan.csv
2022-12-19 10:15:00.458 [main notice] read common config file vaillant/general.csv
2022-12-19 10:15:00.664 [main notice] read scan config file vaillant/08.bai.csv for ID "bai00", SW0603, HW1303
2022-12-19 10:15:00.817 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:00.957 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:01.097 [update notice] sent scan-read scan.08 id QQ=31:
2022-12-19 10:15:01.237 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:01.964 [main notice] found messages: 224 (3 conditional on 28 conditions, 0 poll, 10 update)
2022-12-19 10:15:02.114 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.254 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.394 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.534 [update notice] sent scan-read scan.08 id QQ=31: 21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:02.534 [bus notice] scan 08: ;21;15;46;0010015673;1300;606111;N4
2022-12-19 10:15:03.001 [bus notice] max. symbols per second: 112
2022-12-19 10:15:03.011 [update notice] received unknown MS cmd: 0364b5120202fe / 06fe000080ffff
2022-12-19 10:15:04.707 [bus notice] scan 64: ;Vaillant;V6500;0007;8301
2022-12-19 10:15:04.707 [update notice] store 64 ident: done
2022-12-19 10:15:04.707 [update notice] sent scan-read scan.64 QQ=31: Vaillant;V6500;0007;8301
2022-12-19 10:15:04.707 [bus notice] scan 64: ;Vaillant;V6500;0007;8301
2022-12-19 10:15:05.307 [main notice] read scan config file vaillant/64.v65.csv for ID "v6500", SW0007, HW8301
2022-12-19 10:15:05.308 [main notice] found messages: 226 (3 conditional on 28 conditions, 0 poll, 11 update)
2022-12-19 10:15:08.443 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:13.844 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:19.239 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:24.638 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^[v2022-12-19 10:15:29.998 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:35.405 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:40.805 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:46.207 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:51.607 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:15:57.022 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:16:02.381 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C^[c2022-12-19 10:16:07.782 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C2022-12-19 10:16:10.952 [main notice] SIGINT received
2022-12-19 10:16:13.581 [
2022-12-19 10:21:39.229 [update notice] sent read bai RemainingBoilerblocktime QQ=31: 0
2022-12-19 10:21:39.229 [mqtt notice] read bai RemainingBoilerblocktime: 1
2022-12-19 10:21:39.341 [update notice] sent read bai Statenumber QQ=31: 30
2022-12-19 10:21:39.341 [mqtt notice] read bai Statenumber: 1
2022-12-19 10:21:41.809 [update notice] sent read bai ReturnTemp QQ=31: 25.62;65125;ok
2022-12-19 10:21:41.809 [mqtt notice] read bai ReturnTemp: 1
2022-12-19 10:21:41.932 [update notice] sent read bai FlowTemp QQ=31: 27.56;ok
2022-12-19 10:21:41.932 [mqtt notice] read bai FlowTemp: 1
2022-12-19 10:21:42.049 [update notice] sent read bai HcHours QQ=31: 8385
2022-12-19 10:21:42.049 [mqtt notice] read bai HcHours: 1
2022-12-19 10:21:42.168 [update notice] sent read bai HwcHours QQ=31: 1768
2022-12-19 10:21:42.169 [mqtt notice] read bai HwcHours: 1
2022-12-19 10:21:42.288 [update notice] sent read bai ModulationTempDesired QQ=31: 75.4
2022-12-19 10:21:42.289 [mqtt notice] read bai ModulationTempDesired: 1
2022-12-19 10:21:42.457 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:43.046 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:43.637 [bus error] send to 08: ERR: read timeout
2022-12-19 10:21:43.637 [bus error] send message part 0: ERR: read timeout
2022-12-19 10:21:43.637 [mqtt error] read bai RemainingBoilerblocktime: ERR: read timeout
2022-12-19 10:21:44.227 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:44.816 [bus error] send to 08: ERR: read timeout, retry
2022-12-19 10:21:45.407 [bus error] send to 08: ERR: read timeout
2022-12-19 10:21:45.407 [bus error] send message part 0: ERR: read timeout
2022-12-19 10:21:45.407 [mqtt error] read bai Statenumber: ERR: read timeout
2022-12-19 10:21:45.958 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:21:47.815 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
2022-12-19 10:21:53.216 [update notice] received update-read v65 State QQ=03: standby;standby;no;-
^C2022-12-19 10:21:56.273 [main notice] SIGINT received
2022-12-19 10:21:58.621 [main notice] ebusd stopped
pi@raspberrypi:~ $
The text was updated successfully, but these errors were encountered: