Stable connection only with --loglevel=debug

[alpxp]

Description

I have a weird situation where ebusd works stable only when I add --loglevel=debug to the arguments. Without the debug flag it takes very long to discover the device and most of the communications end up with ERR: read timeout. Must be some hidden configuration differences between debug and production modes?

Actual behavior

with
EBUSD_OPTS="--scanconfig -d ens:/dev/ttyACM0 --scanconfig=08 -c /etc/ebusd/"
the device discovery may take very long and most (but not all) of reads/writes fail, i.e.

$ ebusctl read -f -c recov TempOutsideAir
end up with
ERR: read timeout
see Log-no-debug below.

When I add --loglevel=debug to the EBUSD_OPTS, the communication is stable.
i.e. with
-d ens:/dev/ttyACM0 --loglevel=debug --scanconfig=08 -c /etc/ebusd/
most of reads are successfully.
see Log-debug below.

I tried playing with latency and receivetimeout parameters for production runs but saw no improvements. i.e. to be concrete --latency=10 --receivetimeout=50 does not help.

Expected behavior

The communication quality is not expected to depend on log level. Also a stable communication with with the adapter is expected.

ebusd version

23.2

ebusd arguments

EBUSD_OPTS="--scanconfig -d ens:/dev/ttyACM0 --scanconfig=08 -c /etc/ebusd/"

Operating system

Debian 11 (Bullseye) / Ubuntu 20-21 / Raspbian 11 / Raspberry Pi OS 11 (including lite)

CPU architecture

x64

Dockerized

None

Hardware interface

adapter 5 via USB

Related integration

No response

Logs

Log-no-debug:

alpxp@raspberrypi4:~ $ sudo ebusd -f -d ens:/dev/ttyACM0 --scanconfig=08 -c /etc/ebusd/
2023-09-01 11:41:31.053 [main notice] ebusd 23.2.23.2 started with single scan on device: /dev/ttyACM0, enhanced high speed
2023-09-01 11:41:31.058 [bus notice] device status: resetting
2023-09-01 11:41:31.058 [bus notice] bus started with own address 31/36
2023-09-01 11:41:31.069 [bus notice] signal acquired
2023-09-01 11:41:31.258 [bus notice] device status: reset, supports info
2023-09-01 11:41:31.369 [bus notice] device status: extra info: firmware 1.1[380f].1[380f], jumpers 0x03
2023-09-01 11:41:41.059 [main notice] starting initial scan for 08
2023-09-01 11:41:41.182 [main error] initial scan failed: ERR: read timeout
2023-09-01 11:41:50.650 [main notice] starting initial scan for 08
2023-09-01 11:41:50.786 [bus notice] new master 03, master count 2
2023-09-01 11:41:50.786 [bus notice] scan 08: ;Vaillant;recoV;0229;0203
2023-09-01 11:41:50.786 [update notice] store 08 ident: done
2023-09-01 11:41:50.786 [update notice] sent scan-read scan.08  QQ=31: Vaillant;recoV;0229;0203
2023-09-01 11:41:50.786 [bus notice] scan 08: ;Vaillant;recoV;0229;0203
2023-09-01 11:41:50.797 [main notice] read common config file vaillant/broadcast.csv
2023-09-01 11:41:50.797 [main notice] read common config file vaillant/scan.csv
2023-09-01 11:41:50.798 [main notice] read common config file vaillant/general.csv
2023-09-01 11:41:50.801 [main notice] read scan config file vaillant/08.recov.csv for ID "recov", SW0229, HW0203
2023-09-01 11:41:50.801 [main notice] found messages: 42 (0 conditional on 0 conditions, 0 poll, 8 update)
2023-09-01 11:41:50.911 [main notice] initial scan result: 08;Vaillant;recoV;0229;0203
2023-09-01 11:42:00.142 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:00.648 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:00.743 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:00.743 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:01.678 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:01.773 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:02.283 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:02.283 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:25.767 [update notice] sent read recov TempOutsideAir QQ=31: 19.56;ok
2023-09-01 11:42:26.707 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:26.802 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:27.312 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:27.312 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:31.269 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:31.364 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:31.875 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:31.875 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:32.642 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:33.148 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:33.247 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:33.247 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:34.010 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:34.147 [update notice] sent read recov TempOutsideAir QQ=31: 19.56;ok
2023-09-01 11:42:35.177 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:35.683 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:35.735 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:35.736 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:36.503 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:37.030 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:37.536 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:37.536 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:38.313 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:38.403 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:38.494 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:38.494 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:39.442 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:39.533 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:39.624 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:39.624 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:40.572 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:40.663 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:40.753 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:40.753 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:41.530 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:41.620 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:41.711 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:41.711 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:42.445 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:42.535 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:42.626 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:42.626 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:43.402 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:43.493 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:43.584 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:43.584 [bus error] send message part 0: ERR: read timeout
2023-09-01 11:42:44.274 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:44.365 [bus error] send to 08: ERR: read timeout, retry
2023-09-01 11:42:44.460 [bus error] send to 08: ERR: read timeout
2023-09-01 11:42:44.460 [bus error] send message part 0: ERR: read timeout

Log-debug:

2023-09-01 11:13:06.494 [main notice] ebusd 23.2.23.2 started with single scan on device: /dev/ttyACM0, enhanced high speed
2023-09-01 11:13:06.494 [main info] loading configuration files from /etc/ebusd/
2023-09-01 11:13:06.494 [main debug] reading directory /etc/ebusd/
2023-09-01 11:13:06.494 [main debug] file type of /etc/ebusd/memory.csv is file
2023-09-01 11:13:06.494 [main debug] file type of /etc/ebusd/mqtt-integration.cfg is file
2023-09-01 11:13:06.495 [main debug] file type of /etc/ebusd/broadcast.csv is file
2023-09-01 11:13:06.495 [main debug] file type of /etc/ebusd/mqtt-hassio.cfg is file
2023-09-01 11:13:06.495 [main debug] file type of /etc/ebusd/vaillant is dir
2023-09-01 11:13:06.495 [main debug] file type of /etc/ebusd/_templates.csv is file
2023-09-01 11:13:06.495 [main info] reading templates /
2023-09-01 11:13:06.497 [main info] read templates in /
2023-09-01 11:13:06.497 [main info] reading file memory.csv
2023-09-01 11:13:06.498 [main info] successfully read file memory.csv
2023-09-01 11:13:06.498 [main info] reading file broadcast.csv
2023-09-01 11:13:06.499 [main info] successfully read file broadcast.csv
2023-09-01 11:13:06.499 [main info] read config files, got 11 messages
2023-09-01 11:13:06.501 [bus notice] device status: resetting
2023-09-01 11:13:06.502 [main info] registering data handlers
2023-09-01 11:13:06.502 [main info] registered data handlers
2023-09-01 11:13:06.502 [bus notice] bus started with own address 31/36
2023-09-01 11:13:06.504 [bus debug] ERR: SYN received during no signal, switching to ready
2023-09-01 11:13:06.504 [bus notice] signal acquired
2023-09-01 11:13:07.254 [bus notice] device status: reset, supports info
2023-09-01 11:13:07.361 [bus notice] device status: extra info: firmware 1.1[380f].1[380f], jumpers 0x03
2023-09-01 11:13:16.503 [main debug] performing regular tasks
2023-09-01 11:13:16.503 [main notice] starting initial scan for 08
2023-09-01 11:13:16.503 [bus info] scan 08 cmd: 3108070400
2023-09-01 11:13:16.534 [bus debug] start request 31
2023-09-01 11:13:16.534 [bus debug] arbitration start with 31
2023-09-01 11:13:16.581 [bus debug] arbitration won
2023-09-01 11:13:16.581 [bus debug] arbitration delay 18 micros
2023-09-01 11:13:16.581 [bus info] arbitration delay 18 - 18 micros
2023-09-01 11:13:16.581 [bus debug] switching from ready to send command
2023-09-01 11:13:16.585 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.585 [bus info] send/receive symbol latency 4 - 4 ms
2023-09-01 11:13:16.590 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.594 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.598 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.598 [bus debug] switching from send command to send command CRC
2023-09-01 11:13:16.602 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.602 [bus debug] switching from send command CRC to receive command ACK
2023-09-01 11:13:16.607 [bus debug] switching from receive command ACK to receive response
2023-09-01 11:13:16.660 [bus debug] switching from receive response to receive response CRC
2023-09-01 11:13:16.664 [bus debug] switching from receive response CRC to send response ACK
2023-09-01 11:13:16.668 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.668 [bus notice] new master 03, master count 2
2023-09-01 11:13:16.668 [update info] sent MS cmd: 3108070400 / 0ab57265636f5602290203
2023-09-01 11:13:16.669 [bus notice] scan 08: ;Vaillant;recoV;0229;0203
2023-09-01 11:13:16.669 [update notice] store 08 ident: done
2023-09-01 11:13:16.669 [update notice] sent scan-read scan.08  QQ=31: Vaillant;recoV;0229;0203
2023-09-01 11:13:16.669 [bus debug] notify request: done
2023-09-01 11:13:16.669 [bus notice] scan 08: ;Vaillant;recoV;0229;0203
2023-09-01 11:13:16.669 [bus debug] switching from send response ACK to send SYN
2023-09-01 11:13:16.669 [main debug] reading directory /etc/ebusd/vaillant/
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/1c.rcc.4.csv is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/54.mc2.mc.6.csv is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/timerhwc.inc is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/05.vd4.csv is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/bai.0010002465.inc is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/3c.rcc.5.csv is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/timerhc.inc is file
2023-09-01 11:13:16.670 [main debug] file type of /etc/ebusd/vaillant/25.ehp.hwc.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/iotestact.inc is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/iotestbsol.inc is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/15.700.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/15.392.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/7c.v81.6.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/75.rcc.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/08.bai.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/iotest620.inc is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/15.ui.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/15.140.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/quick.inc is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/84.zeo.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/15.160.csv is file
2023-09-01 11:13:16.671 [main debug] file type of /etc/ebusd/vaillant/05.vl9.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/08.hmu.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/bai.0010021961.inc is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/26.vr630.hc.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/0a.pmw.hwc.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/hcmode.inc is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/15.f35.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/15.f37.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/15.b7v.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/bai.0010005400.inc is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/05.vd6.csv is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/roomtempoffset.inc is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/bai.0010006341.inc is file
2023-09-01 11:13:16.672 [main debug] file type of /etc/ebusd/vaillant/yield8485.inc is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/05.vl8.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/50.vr630.mc.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/15.400.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/23.ehp.cc.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/15.f47.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/broadcast.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/15.f43.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/e0.omu.csv is file
2023-09-01 11:13:16.673 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.673 [bus debug] switching from send SYN to ready
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/75.v81.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/05.vd2.csv is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/service.inc is file
2023-09-01 11:13:16.673 [main debug] file type of /etc/ebusd/vaillant/timer.inc is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/7c.rcc.6.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/yield3d43.inc is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/53.mc2.mc.5.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/50.zeo.mc.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/mcmode.inc is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/bai.0010003886.inc is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/15.350.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/23.solsy.cc.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/ec.solsy.sc.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/35.rcc.1.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/26.vr_71.csv is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/iotestbmc.inc is file
2023-09-01 11:13:16.674 [main debug] file type of /etc/ebusd/vaillant/26.solsy.hc.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/15.e7f.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/fc.rcc.7.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/50.v61.mc.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/25.solsy.hwc.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/timercool.inc is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/yield4445.inc is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/bai.0010015600.inc is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/15.720.csv is file
2023-09-01 11:13:16.675 [main debug] file type of /etc/ebusd/vaillant/bai.0010003857.inc is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/52.vr_70.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/iotesthp.inc is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/23.zeo.cc.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/ec.sol.sc.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/15.360.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/52.mc2.mc.4.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/tempsetpoints.inc is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/bai.0010004150.inc is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/08.recov.csv_bkp is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/51.vr630.mc.3.csv is file
2023-09-01 11:13:16.676 [main debug] file type of /etc/ebusd/vaillant/25.vr630.hwc.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/15.hep.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/64.v65.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/scan.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/bai.308523.inc is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/timertariff.inc is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/50.ehp.mc.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/35.v81.1.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/a1.sol.cc.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/50.solsy.mc.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/15.470.csv is file
2023-09-01 11:13:16.677 [main debug] file type of /etc/ebusd/vaillant/iotest630.inc is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/3c.v81.5.csv is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/bai.0010007508.inc is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/15.e7f_2.csv is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/bai.0010002315.inc is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/f5.v81.3.csv is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/ec.zeo.sc.csv is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/55.mc2.mc.7.csv is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/bai.0010010674.inc is file
2023-09-01 11:13:16.678 [main debug] file type of /etc/ebusd/vaillant/bai.0010008045.inc is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/15.sdr_p.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/25.zeo.hwc.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/06.pms.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/15.heb.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/15.36p.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/05.vd3.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/yield3f40.inc is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/15.basv.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/15.e7c.csv is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/timercc.inc is file
2023-09-01 11:13:16.679 [main debug] file type of /etc/ebusd/vaillant/errors.inc is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/08.recov.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/f5.rcc.3.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/08.ehp.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/hwcmode.inc is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/23.vr630.cc.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/_templates.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/06.vms.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/1c.v81.4.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/15.uih.csv is file
2023-09-01 11:13:16.680 [main debug] file type of /etc/ebusd/vaillant/ed.pms.sc.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/15.370.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/bai.0010004121.inc is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/bai.0010006101.inc is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/general.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/15.430.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/a0.sol.hwc.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/bai.0020066007.inc is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/fc.v81.7.csv is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/yield8485r.inc is file
2023-09-01 11:13:16.681 [main debug] file type of /etc/ebusd/vaillant/e1.omu.1.csv is file
2023-09-01 11:13:16.682 [main debug] found 4 matching scan config files from vaillant with prefix 08: done
2023-09-01 11:13:16.682 [main info] reading templates vaillant
2023-09-01 11:13:16.687 [main info] read templates in vaillant
2023-09-01 11:13:16.687 [main debug] reading directory /etc/ebusd/vaillant/
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/1c.rcc.4.csv is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/54.mc2.mc.6.csv is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/timerhwc.inc is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/05.vd4.csv is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/bai.0010002465.inc is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/3c.rcc.5.csv is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/timerhc.inc is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/25.ehp.hwc.csv is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/iotestact.inc is file
2023-09-01 11:13:16.688 [main debug] file type of /etc/ebusd/vaillant/iotestbsol.inc is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/15.700.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/15.392.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/7c.v81.6.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/75.rcc.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/08.bai.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/iotest620.inc is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/15.ui.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/15.140.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/quick.inc is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/84.zeo.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/15.160.csv is file
2023-09-01 11:13:16.689 [main debug] file type of /etc/ebusd/vaillant/05.vl9.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/08.hmu.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/bai.0010021961.inc is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/26.vr630.hc.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/0a.pmw.hwc.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/hcmode.inc is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/15.f35.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/15.f37.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/15.b7v.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/bai.0010005400.inc is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/05.vd6.csv is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/roomtempoffset.inc is file
2023-09-01 11:13:16.690 [main debug] file type of /etc/ebusd/vaillant/bai.0010006341.inc is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/yield8485.inc is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/05.vl8.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/50.vr630.mc.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/15.400.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/23.ehp.cc.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/15.f47.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/broadcast.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/15.f43.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/e0.omu.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/75.v81.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/05.vd2.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/service.inc is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/timer.inc is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/7c.rcc.6.csv is file
2023-09-01 11:13:16.691 [main debug] file type of /etc/ebusd/vaillant/yield3d43.inc is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/53.mc2.mc.5.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/50.zeo.mc.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/mcmode.inc is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/bai.0010003886.inc is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/15.350.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/23.solsy.cc.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/ec.solsy.sc.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/35.rcc.1.csv is file
2023-09-01 11:13:16.692 [main debug] file type of /etc/ebusd/vaillant/26.vr_71.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/iotestbmc.inc is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/26.solsy.hc.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/15.e7f.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/fc.rcc.7.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/50.v61.mc.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/25.solsy.hwc.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/timercool.inc is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/yield4445.inc is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/bai.0010015600.inc is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/15.720.csv is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/bai.0010003857.inc is file
2023-09-01 11:13:16.693 [main debug] file type of /etc/ebusd/vaillant/52.vr_70.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/iotesthp.inc is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/23.zeo.cc.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/ec.sol.sc.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/15.360.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/52.mc2.mc.4.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/tempsetpoints.inc is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/bai.0010004150.inc is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/08.recov.csv_bkp is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/51.vr630.mc.3.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/25.vr630.hwc.csv is file
2023-09-01 11:13:16.694 [main debug] file type of /etc/ebusd/vaillant/15.hep.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/64.v65.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/scan.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/bai.308523.inc is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/timertariff.inc is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/50.ehp.mc.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/35.v81.1.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/a1.sol.cc.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/50.solsy.mc.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/15.470.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/iotest630.inc is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/3c.v81.5.csv is file
2023-09-01 11:13:16.695 [main debug] file type of /etc/ebusd/vaillant/bai.0010007508.inc is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/15.e7f_2.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/bai.0010002315.inc is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/f5.v81.3.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/ec.zeo.sc.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/55.mc2.mc.7.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/bai.0010010674.inc is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/bai.0010008045.inc is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/15.sdr_p.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/25.zeo.hwc.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/06.pms.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/15.heb.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/15.36p.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/05.vd3.csv is file
2023-09-01 11:13:16.696 [main debug] file type of /etc/ebusd/vaillant/yield3f40.inc is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/15.basv.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/15.e7c.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/timercc.inc is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/errors.inc is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/08.recov.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/f5.rcc.3.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/08.ehp.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/hwcmode.inc is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/23.vr630.cc.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/_templates.csv is file
2023-09-01 11:13:16.697 [main debug] file type of /etc/ebusd/vaillant/06.vms.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/1c.v81.4.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/15.uih.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/ed.pms.sc.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/15.370.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/bai.0010004121.inc is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/bai.0010006101.inc is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/general.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/15.430.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/a0.sol.hwc.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/bai.0020066007.inc is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/fc.v81.7.csv is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/yield8485r.inc is file
2023-09-01 11:13:16.698 [main debug] file type of /etc/ebusd/vaillant/e1.omu.1.csv is file
2023-09-01 11:13:16.700 [main notice] read common config file vaillant/broadcast.csv
2023-09-01 11:13:16.701 [main notice] read common config file vaillant/scan.csv
2023-09-01 11:13:16.701 [main notice] read common config file vaillant/general.csv
2023-09-01 11:13:16.705 [main notice] read scan config file vaillant/08.recov.csv for ID "recov", SW0229, HW0203
2023-09-01 11:13:16.705 [main notice] found messages: 42 (0 conditional on 0 conditions, 0 poll, 8 update)
2023-09-01 11:13:16.705 [bus info] scan 08 cmd: 3108b5090124
2023-09-01 11:13:16.716 [bus debug] start request 31
2023-09-01 11:13:16.716 [bus debug] arbitration start with 31
2023-09-01 11:13:16.763 [bus debug] arbitration won
2023-09-01 11:13:16.763 [bus debug] arbitration delay 63 micros
2023-09-01 11:13:16.763 [bus info] arbitration delay 18 - 63 micros
2023-09-01 11:13:16.763 [bus debug] switching from ready to send command
2023-09-01 11:13:16.768 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.772 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.776 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:16.806 [bus debug] notify request: ERR: read timeout
2023-09-01 11:13:16.806 [bus debug] ERR: read timeout during send command, switching to skip
2023-09-01 11:13:16.806 [main notice] initial scan result: 08;Vaillant;recoV;0229;0203
2023-09-01 11:13:26.807 [main debug] performing regular tasks
2023-09-01 11:13:33.790 [network info] [00001] client connection opened 127.0.0.1
2023-09-01 11:13:33.790 [network debug] [00001] wait for result
2023-09-01 11:13:33.790 [main debug] performing regular tasks
2023-09-01 11:13:33.791 [main debug] >>> read -f -c recov TempOutsideAir
2023-09-01 11:13:33.791 [bus info] send message: 3108b50903290c0e
2023-09-01 11:13:33.822 [bus debug] start request 31
2023-09-01 11:13:33.822 [bus debug] arbitration start with 31
2023-09-01 11:13:33.869 [bus debug] arbitration won
2023-09-01 11:13:33.869 [bus debug] arbitration delay 17 micros
2023-09-01 11:13:33.869 [bus info] arbitration delay 17 - 63 micros
2023-09-01 11:13:33.869 [bus debug] switching from ready to send command
2023-09-01 11:13:33.873 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.877 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.882 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.886 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.890 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.894 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.899 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.899 [bus debug] switching from send command to send command CRC
2023-09-01 11:13:33.903 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.903 [bus debug] switching from send command CRC to receive command ACK
2023-09-01 11:13:33.908 [bus debug] switching from receive command ACK to receive response
2023-09-01 11:13:33.937 [bus debug] switching from receive response to receive response CRC
2023-09-01 11:13:33.941 [bus debug] switching from receive response CRC to send response ACK
2023-09-01 11:13:33.946 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.946 [update info] sent MS cmd: 3108b50903290c0e / 050c0e310100
2023-09-01 11:13:33.946 [update notice] sent read recov TempOutsideAir QQ=31: 19.06;ok
2023-09-01 11:13:33.946 [bus debug] notify request: done
2023-09-01 11:13:33.946 [bus debug] read res: 050c0e310100
2023-09-01 11:13:33.946 [bus debug] switching from send response ACK to send SYN
2023-09-01 11:13:33.946 [main info] read recov TempOutsideAir: 19.06;ok
2023-09-01 11:13:33.947 [main debug] <<< 19.06;ok
2023-09-01 11:13:33.948 [network info] [00001] connection closed
2023-09-01 11:13:33.951 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:33.951 [bus debug] switching from send SYN to ready
2023-09-01 11:13:34.791 [network debug] dead connection removed - 0
2023-09-01 11:13:35.091 [network debug] [00002] wait for result
2023-09-01 11:13:35.091 [main debug] >>> read -f -c recov TempOutsideAir
2023-09-01 11:13:35.091 [bus info] send message: 3108b50903290c0e
2023-09-01 11:13:35.091 [network info] [00002] client connection opened 127.0.0.1
2023-09-01 11:13:35.108 [bus debug] start request 31
2023-09-01 11:13:35.109 [bus debug] arbitration start with 31
2023-09-01 11:13:35.156 [bus debug] arbitration won
2023-09-01 11:13:35.156 [bus debug] arbitration delay 18 micros
2023-09-01 11:13:35.156 [bus debug] switching from ready to send command
2023-09-01 11:13:35.160 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.164 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.168 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.173 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.177 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.181 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.186 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.186 [bus debug] switching from send command to send command CRC
2023-09-01 11:13:35.190 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.190 [bus debug] switching from send command CRC to receive command ACK
2023-09-01 11:13:35.196 [bus debug] switching from receive command ACK to receive response
2023-09-01 11:13:35.225 [bus debug] switching from receive response to receive response CRC
2023-09-01 11:13:35.229 [bus debug] switching from receive response CRC to send response ACK
2023-09-01 11:13:35.233 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.233 [update info] sent MS cmd: 3108b50903290c0e / 050c0e310100
2023-09-01 11:13:35.233 [update notice] sent read recov TempOutsideAir QQ=31: 19.06;ok
2023-09-01 11:13:35.233 [bus debug] notify request: done
2023-09-01 11:13:35.233 [bus debug] read res: 050c0e310100
2023-09-01 11:13:35.234 [bus debug] switching from send response ACK to send SYN
2023-09-01 11:13:35.234 [main info] read recov TempOutsideAir: 19.06;ok
2023-09-01 11:13:35.234 [main debug] <<< 19.06;ok
2023-09-01 11:13:35.235 [network info] [00002] connection closed
2023-09-01 11:13:35.238 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:35.238 [bus debug] switching from send SYN to ready
2023-09-01 11:13:36.093 [network debug] dead connection removed - 0
2023-09-01 11:13:36.194 [network debug] [00003] wait for result
2023-09-01 11:13:36.194 [network info] [00003] client connection opened 127.0.0.1
2023-09-01 11:13:36.194 [main debug] >>> read -f -c recov TempOutsideAir
2023-09-01 11:13:36.195 [bus info] send message: 3108b50903290c0e
2023-09-01 11:13:36.224 [bus debug] start request 31
2023-09-01 11:13:36.224 [bus debug] arbitration start with 31
2023-09-01 11:13:36.271 [bus debug] arbitration won
2023-09-01 11:13:36.271 [bus debug] arbitration delay 17 micros
2023-09-01 11:13:36.271 [bus debug] switching from ready to send command
2023-09-01 11:13:36.275 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.279 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.284 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.288 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.292 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.296 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.301 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.301 [bus debug] switching from send command to send command CRC
2023-09-01 11:13:36.305 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.305 [bus debug] switching from send command CRC to receive command ACK
2023-09-01 11:13:36.310 [bus debug] switching from receive command ACK to receive response
2023-09-01 11:13:36.341 [bus debug] switching from receive response to receive response CRC
2023-09-01 11:13:36.345 [bus debug] switching from receive response CRC to send response ACK
2023-09-01 11:13:36.349 [bus debug] send/receive symbol latency 4 ms
2023-09-01 11:13:36.349 [update info] sent MS cmd: 3108b50903290c0e / 050c0e310100
2023-09-01 11:13:36.350 [update notice] sent read recov TempOutsideAir QQ=31: 19.06;ok
2023-09-01 11:13:36.350 [bus debug] notify request: done
2023-09-01 11:13:36.350 [bus debug] read res: 050c0e310100
2023-09-01 11:13:36.350 [bus debug] switching from send response ACK to send SYN
2023-09-01 11:13:36.350 [main info] read recov TempOutsideAir: 19.06;ok
2023-09-01 11:13:36.350 [main debug] <<< 19.06;ok

[john30]

I can only imagine that the timing is special for this recov device. you could check that now with the current firmware as stated here:
john30/ebusd-esp32#14

[alpxp]

Here is the output of the ebus -v

error: unable to load from NVS
init_ebus: already switched to enhanced eBUS mode on TCP port. updating verbosity.
init_ebus: clock frq 80000000 div 33 1/3, src clk 4
init_ebus: detected: 2412 Hz on 18 edges with 1989 +/1989 - edge width, 969 H/1019 L pulse width, i.e. 404 us H/425 us L period

I don't know how to judge. Does this look ok?

Update:
After starting ebusd I get:

init_ebus: clock frq 80000000 div 33 1/3, src clk 4
init_ebus: detected: 2434 Hz on 1023 edges with 1988 +/1988 - edge width, 969 H/1002 L pulse width, i.e. 404 us H/418 us L period
init_ebus: master 8 0x31 delay=196 us [195-201]
init_ebus: master 14 0x73 delay=4556 us [4556-4556]

with 2434 Hz, which is that 1.4% off of 2400? If I understood your explanation correctly this could be the problem? Is there a way to fix it?

[s0129]

This is strange, I have also been having problems and just tried adding --loglevel=debug and it has also sprung to life!

I am also using an adapter 5 via USB with a Vaillant boiler initial scan result: 08;Vaillant;BAI00;0202;8001.

I adjusted the baud rate using the new feature (+24) and it didn't seem to make a difference.

init_ebus: clock frq 80000000 div 33 1/3, src clk 4
init_ebus: detected: 2424 Hz on 19 edges with 1979 +/1979 - edge width, 1001 H/977 L pulse width, i.e. 417 us H/407 us L period
init_ebus: master 8 0x31 delay=198 us [197-203]
init_ebus: master 11 0x03 delay=147 us [147-197]

[john30]

I've found an issue hidden in the ESP32-C3 UART that reports a falsy non-TX-readiness. this seems to be an issue especially if ebusd handles the traffic too quickly as it seems to be the case on your host without the debug logging.

could you check with the new firmware 20230917 just released?

[alpxp]

I confirm that with the new firmware the issue is resolved. Quick test showed identically good behaviour with and without debug log.
Thank you