i am wrong, but in this csv the sending command is B509 0E??? or is it a different one?
Mh, good point. I will check that with a small test case
Ah okay, you are wrong first two bytes are the command followed byte the LENGTH followed byte master data bytes. So it is correct.
Also my downloaded pre3 version runs as in my example. Do you have any other file in the addons directory?
ok, i thought it is similar to this post. They also have 10 08 B5 09 01 24 (B509) as well.
So it isnβt the PBSB The primary and secondary command bytes in hex ??
in the addon Folder, this is my only kar file.
Yes that is right. But it is more one of the prefix for Vaillant. So the difference in the commands ar the master data bytes.
But could you pleas analyze what is installed in your environment? With the header information I can see which version is really installed in your system.
Please check the three eBUS bundles
bundle:list
...
509 β Active β 80 β 1.0.3.SNAPSHOT β eBUS library configuration
510 β Active β 80 β 1.0.2.SNAPSHOT β eBUS core library
511 β Active β 80 β 2.5.1.202001061816 β openHAB Add-ons :: Bundles :: eBUS Binding
...
and than query all headers for the bundles.
bundle:headers 509 <!-- Bundle number from previous command -->
eBUS library configuration (509)
--------------------------------
Bnd-LastModified = 1578344853195
Build-Jdk = 1.8.0_181
Built-By = Christian
Created-By = Apache Maven Bundle Plugin
Manifest-Version = 1.0
Tool = Bnd-0.0.238
Bundle-Description = eBUS library configuration
Bundle-DocURL = http://www.cs-dev.de
Bundle-ManifestVersion = 2
Bundle-Name = eBUS library configuration
Bundle-SymbolicName = de.cs-dev.ebus.ebus-configuration
Bundle-Vendor = Christian Sowada
Bundle-Version = 1.0.3.SNAPSHOT
Export-Package =
de.csdev.ebus.configuration;uses:="org.slf4j,de.csdev.ebus.cfg.std"
Import-Package =
de.csdev.ebus.cfg.std,
de.csdev.ebus.configuration,
org.slf4j;version=1.7
Private-Package =
commands,
commands.vaillant
204 β Active β 80 β 3.15.0.OH2 β nrjavaserial 205 β Active β 80 β 1.0.3.SNAPSHOT β eBUS library configuration 206 β Active β 80 β 1.0.2.SNAPSHOT β eBUS core library ...... 208 β Active β 80 β 2.5.1.202001061816 β openHAB Add-ons :: Bundles :: eBUS Binding 209 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Configuration USB-Serial Discovery 210 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Configuration USB-Serial Discovery for Linux using sysfs scanning 211 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Configuration Serial 212 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Serial Transport 213 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Serial Transport for RXTX 214 β Active β 80 β 2.5.0 β openHAB Core :: Bundles :: Serial Transport for RFC2217```
eBUS library configuration (205) -------------------------------- Bnd-LastModified = 1578344853195 Build-Jdk = 1.8.0_181 Built-By = Christian Created-By = Apache Maven Bundle Plugin Manifest-Version = 1.0 Tool = Bnd-0.0.238 Bundle-Description = eBUS library configuration Bundle-DocURL = http://www.cs-dev.de Bundle-ManifestVersion = 2 Bundle-Name = eBUS library configuration Bundle-SymbolicName = de.cs-dev.ebus.ebus-configuration Bundle-Vendor = Christian Sowada Bundle-Version = 1.0.3.SNAPSHOT Export-Package = de.csdev.ebus.configuration;uses:="org.slf4j,de.csdev.ebus.cfg.std" Import-Package = de.csdev.ebus.cfg.std, de.csdev.ebus.configuration, org.slf4j;version=1.7 Private-Package = commands, commands.vaillant openhab> bundle:headers 206 eBUS core library (206) ----------------------- Bnd-LastModified = 1578344809261 Build-Jdk = 1.8.0_181 Built-By = Christian Created-By = Apache Maven Bundle Plugin Manifest-Version = 1.0 Tool = Bnd-0.0.238 Bundle-Description = eBUS core library - This library handles the communication with heating engineering via the BUS specification. This protocol is used by many heating manufacturers in Europe. Bundle-DocURL = http://www.cs-dev.de Bundle-License = http://www.eclipse.org/legal/epl-v10.html Bundle-ManifestVersion = 2 Bundle-Name = eBUS core library Bundle-SymbolicName = de.cs-dev.ebus.ebus-core Bundle-Vendor = Christian Sowada Bundle-Version = 1.0.2.SNAPSHOT Export-Package = de.csdev.ebus.command;uses:="de.csdev.ebus.cfg,org.slf4j,de.csdev.ebus.utils,de.csdev.ebus.core,de.csdev.ebus.command.datatypes.ext,de.csdev.ebus.command.datatypes,org.apache.commons.lang", de.csdev.ebus.cfg;uses:="de.csdev.ebus.command,de.csdev.ebus.command.datatypes", de.csdev.ebus.utils; uses:="de.csdev.ebus.command, org.slf4j, de.csdev.ebus.core, de.csdev.ebus.command.datatypes.std, de.csdev.ebus.service.parser, org.apache.commons.io, de.csdev.ebus.service.metrics, de.csdev.ebus.service.device, de.csdev.ebus.command.datatypes, org.apache.commons.lang", de.csdev.ebus.core;uses:="de.csdev.ebus.command,org.slf4j,de.csdev.ebus.utils,org.apache.commons.io,de.csdev.ebus.core.connection,org.apache.commons.lang", de.csdev.ebus.command.datatypes.std;uses:="org.slf4j,de.csdev.ebus.utils,de.csdev.ebus.command.datatypes,org.apache.commons.lang", de.csdev.ebus.service.parser;uses:="de.csdev.ebus.command,org.slf4j,de.csdev.ebus.utils,de.csdev.ebus.core,de.csdev.ebus.command.datatypes", de.csdev.ebus.cfg.std.dto;uses:="org.slf4j,de.csdev.ebus.utils,com.google.gson.annotations", de.csdev.ebus.core.connection;uses:="gnu.io,org.slf4j,de.csdev.ebus.utils,org.apache.commons.io,com.fazecast.jSerialComm", de.csdev.ebus.command.datatypes.ext;uses:="org.slf4j,de.csdev.ebus.utils,de.csdev.ebus.command.datatypes.std,de.csdev.ebus.command.datatypes,org.apache.commons.lang", de.csdev.ebus.service.metrics;uses:="de.csdev.ebus.command,de.csdev.ebus.core,de.csdev.ebus.service.parser", de.csdev.ebus.service.device; uses:="de.csdev.ebus.command, org.slf4j, de.csdev.ebus.utils, de.csdev.ebus.core, de.csdev.ebus.service.parser, de.csdev.ebus.command.datatypes, org.apache.commons.lang, com.google.gson, com.google.gson.reflect", de.csdev.ebus.client; uses:="de.csdev.ebus.command, de.csdev.ebus.service.metrics, org.slf4j, de.csdev.ebus.service.device, de.csdev.ebus.core, de.csdev.ebus.service.parser, de.csdev.ebus.command.datatypes", de.csdev.ebus.command.datatypes; uses:="org.slf4j, de.csdev.ebus.utils, org.apache.commons.lang.reflect, de.csdev.ebus.command.datatypes.std, org.apache.commons.lang, de.csdev.ebus.command.datatypes.ext", de.csdev.ebus.cfg.std; uses:="de.csdev.ebus.command, de.csdev.ebus.cfg, org.slf4j, de.csdev.ebus.utils, de.csdev.ebus.cfg.std.dto, de.csdev.ebus.command.datatypes.ext, de.csdev.ebus.command.datatypes, org.apache.commons.lang, com.google.gson, com.google.gson.annotations, com.google.gson.reflect" Import-Package = com.fazecast.jSerialComm;resolution:=optional;version=2.5, com.google.gson;version=2.5, com.google.gson.annotations;version=2.5, com.google.gson.reflect;version=2.5, de.csdev.ebus.cfg, de.csdev.ebus.cfg.std, de.csdev.ebus.cfg.std.dto, de.csdev.ebus.client, de.csdev.ebus.command, de.csdev.ebus.command.datatypes, de.csdev.ebus.command.datatypes.ext, de.csdev.ebus.command.datatypes.std, de.csdev.ebus.core, de.csdev.ebus.core.connection, de.csdev.ebus.service.device, de.csdev.ebus.service.metrics, de.csdev.ebus.service.parser, de.csdev.ebus.utils, gnu.io;resolution:=optional;version=3.12, org.apache.commons.io;version=2.2, org.apache.commons.lang;version=2.6, org.apache.commons.lang.reflect;version=2.6, org.slf4j;resolution:=optional;version=1.7 Private-Package = commands openhab> bundle:headers 208 openHAB Add-ons :: Bundles :: eBUS Binding (208) ------------------------------------------------ Automatic-Module-Name = org.openhab.binding.ebus Bnd-LastModified = 1578334605113 Build-Jdk = 1.8.0_181 Built-By = Christian Created-By = 1.8.0_181 (Oracle Corporation) Manifest-Version = 1.0 Service-Component = OSGI-INF/org.openhab.binding.ebus.internal.EBusHandlerFactory.xml,OSGI-INF/org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.xml,OSGI-INF/org.openhab.binding.ebus.internal.utils.EBusConsoleCommandExtension.xml Tool = Bnd-4.3.0.201909301554 Bundle-Description = This project contains the official add-ons of openHAB Bundle-Developers = openhab;email="info@openhab.org";name=openHAB;organization=openHAB;organizationUrl="https://www.openHAB.org" Bundle-DocURL = https://github.com/openhab/infrastructure/org.openhab.addons.reactor/org.openhab.addons.reactor.bundles/org.openhab.binding.ebus Bundle-License = "Eclipse Public License 2.0";link="https://www.eclipse.org/legal/epl-2.0/" Bundle-ManifestVersion = 2 Bundle-Name = openHAB Add-ons :: Bundles :: eBUS Binding Bundle-SCM = url="https://github.com/openhab/openhab2-addons/org.openhab.addons.reactor.bundles/org.openhab.binding.ebus",connection="scm:git:https://github.com/openhab/openhab2-addons.git/org.openhab.addons.reactor.bundles/org.openhab.binding.ebus",developer-connection="scm:git:https://github.com/openhab/openhab2-addons.git/org.openhab.addons.reactor.bundles/org.openhab.binding.ebus",tag=HEAD Bundle-SymbolicName = org.openhab.binding.ebus Bundle-Vendor = openHAB.org Bundle-Version = 2.5.1.202001061816 Provide-Capability = osgi.service;uses:=org.eclipse.smarthome.core.thing.binding;objectClass:List<String>=org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory, osgi.service; uses:="org.eclipse.smarthome.core.thing.binding, org.eclipse.smarthome.core.thing.type, org.eclipse.smarthome.core.thing.type, org.openhab.binding.ebus.internal.things"; objectClass:List<String>="org.eclipse.smarthome.core.thing.binding.ThingTypeProvider, org.eclipse.smarthome.core.thing.type.ChannelGroupTypeProvider, org.eclipse.smarthome.core.thing.type.ChannelTypeProvider, org.openhab.binding.ebus.internal.things.IEBusTypeProvider", osgi.service;uses:=org.eclipse.smarthome.io.console.extensions;objectClass:List<String>=org.eclipse.smarthome.io.console.extensions.ConsoleCommandExtension Require-Capability = osgi.service;effective:=active;filter:=(objectClass=org.eclipse.smarthome.core.thing.ThingRegistry), osgi.service;effective:=active;filter:=(objectClass=org.openhab.binding.ebus.internal.things.IEBusTypeProvider), osgi.service;effective:=active;filter:=(objectClass=org.osgi.service.cm.ConfigurationAdmin);resolution:=optional, osgi.extender;filter:=(&(osgi.extender=osgi.component)(version>=1.4.0)(!(version>=2.0.0))), osgi.ee;filter:=(&(osgi.ee=JavaSE)(version=1.8)) Import-Package = org.eclipse.smarthome.config.core, org.eclipse.smarthome.config.discovery, org.eclipse.smarthome.core.library.types, org.eclipse.smarthome.core.library.unit, org.eclipse.smarthome.core.thing, org.eclipse.smarthome.core.thing.binding, org.eclipse.smarthome.core.thing.type, org.eclipse.smarthome.core.types, org.eclipse.smarthome.io.console, org.eclipse.smarthome.io.console.extensions, de.csdev.ebus.client, de.csdev.ebus.command, de.csdev.ebus.command.datatypes, de.csdev.ebus.command.datatypes.ext, de.csdev.ebus.command.datatypes.std, de.csdev.ebus.configuration, de.csdev.ebus.core, de.csdev.ebus.core.connection, de.csdev.ebus.service.device, de.csdev.ebus.service.metrics, de.csdev.ebus.service.parser, de.csdev.ebus.utils, javax.measure;version="[1.0,2)", org.apache.commons.lang;version="[2.6,3)", org.osgi.framework;version="[1.8,2)", org.osgi.service.cm;version="[1.6,2)", org.osgi.service.component;version="[1.4,2)", org.slf4j;version="[1.7,2)" Private-Package = ESH-INF.binding, ESH-INF.config, ESH-INF.thing, org.openhab.binding.ebus.internal, org.openhab.binding.ebus.internal.handler, org.openhab.binding.ebus.internal.services, org.openhab.binding.ebus.internal.things, org.openhab.binding.ebus.internal.utils```
do I have a bug in my bundles?
No, you have the correct timestamp so far. But I have no clue so far. It is so simple in the source code, but your output is different. Iβll build a version with special log entries to identify the correct build. Really strange β¦
Thank you for you support/help. Sorry for the problem but i will be very very happy when it will work
Yes, and Iβll try to help. In two minutes Iβve a new version for you.
Man you are amazing
Here is the new version, it is just for you with some greetings in the logs. I hope it is now working as expected.
You should see two or more warnings in the log with some greetings. That should show that is my last compiled version.
You should see two or more log entries like.
2020-01-07 22:28:56.004 [WARN ] [iguration.EBusConfigurationReaderExt] - Hello again HANNES, your are using the right config version ;-)
...
2020-01-07 22:28:58.530 [WARN ] [dev.ebus.core.EBusLowLevelController] - Hello HANNES, your are using the right version ;-)
So, now it should look like
2020-01-07 22:30:28.766 [DEBUG] [dev.ebus.core.EBusLowLevelController] - Send: 00 35 50 22 03 A3 75 27 79 @ 0. attempt
2020-01-07 22:30:28.767 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
2020-01-07 22:30:28.768 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 00
2020-01-07 22:30:28.775 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
2020-01-07 22:30:28.776 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x35
2020-01-07 22:30:28.783 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x35
2020-01-07 22:30:28.784 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
2020-01-07 22:30:28.785 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x50
2020-01-07 22:30:28.792 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x50
2020-01-07 22:30:28.793 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
2020-01-07 22:30:28.794 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x22
2020-01-07 22:30:28.801 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x22
2020-01-07 22:30:28.802 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
2020-01-07 22:30:28.803 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x03
2020-01-07 22:30:28.810 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x03
2020-01-07 22:30:28.811 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Master data length: 3
2020-01-07 22:30:28.812 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
2020-01-07 22:30:28.813 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0xA3
2020-01-07 22:30:28.821 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0xA3
2020-01-07 22:30:28.822 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Data 2
2020-01-07 22:30:28.823 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x75
2020-01-07 22:30:28.831 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x75
2020-01-07 22:30:28.832 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Data 1
2020-01-07 22:30:28.833 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x27
2020-01-07 22:30:28.840 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x27
2020-01-07 22:30:28.841 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
2020-01-07 22:30:28.842 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send 0x79
2020-01-07 22:30:28.849 [TRACE] [dev.ebus.core.EBusLowLevelController] - Received 0x79
2020-01-07 22:30:28.850 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Master CRC correct
2020-01-07 22:30:28.850 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1
2020-01-07 22:30:28.851 [TRACE] [dev.ebus.core.EBusLowLevelController] - Waiting for slave answer ...
2020-01-07 22:30:28.854 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1
2020-01-07 22:30:28.858 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 2
2020-01-07 22:30:28.858 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2
2020-01-07 22:30:28.862 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Data 1
2020-01-07 22:30:28.866 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2
2020-01-07 22:30:28.870 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2
2020-01-07 22:30:28.871 [TRACE] [dev.ebus.core.EBusLowLevelController] - Slave answer received ...
2020-01-07 22:30:28.872 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send Master ACK to Slave ...
2020-01-07 22:30:28.880 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2
2020-01-07 22:30:28.880 [TRACE] [dev.ebus.core.EBusLowLevelController] - Send SYN to bus ...
2020-01-07 22:30:28.887 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN
2020-01-07 22:30:28.888 [TRACE] [ev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable ...
2020-01-07 22:30:28.890 [DEBUG] [dev.ebus.core.EBusLowLevelController] - Succesful send: 00 35 50 22 03 A3 75 27 79 00 02 01 00 B7 00
22:34:06.493 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 ... 22:34:06.523 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:34:06.571 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:34:06.578 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF FE 07 FE 00 B5 @ 0. attempt 22:34:06.586 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:34:06.594 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:34:06.607 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:34:06.615 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision detected! 0x00 22:34:06.623 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn't match, blocked for next SYN ... 22:34:06.631 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN ... 22:34:06.642 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN 22:34:07.212 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:34:07.221 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF FE 07 FE 00 B5 @ 1. attempt 22:34:07.228 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:34:07.233 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:34:07.244 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:34:07.250 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FE 22:34:07.260 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:34:07.268 [WARN ] [service.device.EBusDeviceTableService] - Scan broadcast failed! 22:34:07.278 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN 22:34:07.851 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:34:07.857 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ... 22:34:07.899 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:34:07.903 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ...```
this is my new log (my looks like a little bit different ) i miss the warning in my terminal
22:39:16.365 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.387 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1 22:39:16.413 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.420 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 ... 22:39:16.446 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.494 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.503 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt 22:39:16.507 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.514 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:16.525 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.531 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10 22:39:16.542 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:16.546 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.590 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:16.637 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.646 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt 22:39:16.649 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.653 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:16.669 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.673 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10 22:39:16.676 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:16.685 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.717 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:16.781 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.790 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt 22:39:16.794 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.801 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:16.813 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.818 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision with SYN detected! 22:39:16.824 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn't match, blocked for next SYN ... 22:39:16.830 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN ... 22:39:16.862 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:16.870 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 3. attempt 22:39:16.876 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:16.881 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:16.893 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.899 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10 22:39:16.910 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:16.925 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:16.958 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN 22:39:17.005 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:17.014 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 4. attempt 22:39:17.019 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:17.025 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:17.037 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN 22:39:17.628 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:17.634 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 5. attempt 22:39:17.638 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:17.644 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF 22:39:17.660 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:17.665 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10 22:39:17.672 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR 22:39:17.676 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5 22:39:17.687 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD 22:39:17.694 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09 22:39:17.704 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD 22:39:17.709 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04 22:39:17.719 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9 22:39:17.724 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1 22:39:17.729 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E 22:39:17.735 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8 22:39:17.740 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F 22:39:17.752 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7 22:39:17.757 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00 22:39:17.762 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6 22:39:17.767 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01 22:39:17.783 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5 22:39:17.787 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6 22:39:17.792 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4 22:39:17.795 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ... 22:39:17.803 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN 22:39:18.390 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:18.395 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ... 22:39:18.438 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:18.486 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received 22:39:18.492 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR 22:39:18.502 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR 22:39:18.511 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD 22:39:18.516 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD 22:39:18.522 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9 22:39:18.526 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1 22:39:18.531 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8 22:39:18.536 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7 22:39:18.540 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6 22:39:18.545 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5 22:39:18.550 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4 22:39:18.554 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3 22:39:18.559 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2 22:39:18.565 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1 22:39:18.570 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1 22:39:18.575 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct 22:39:18.579 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1 22:39:18.589 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer ... 22:39:18.593 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1 22:39:18.599 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 1 22:39:18.603 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2 22:39:18.608 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2 22:39:18.612 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2 22:39:18.617 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2 22:39:18.624 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN 22:39:18.628 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable ... 22:39:18.634 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN 22:39:18.639 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN 22:39:18.674 [TRACE] [ebus.service.parser.EBusParserService] - No command method matches the telegram 10 08 B5 10 09 00 00 00 78 FF FF 05 FF 00 A0 00 01 01 9A 00 ..```
Yes, now it looks like Iβve expected before my last update !?!?!? It is cached somehow.
But okay, it is still not working. For today I give up β¦
thank you good night
You should try to remove the binding, shutdown openhab, restart without binding and shutdown again. Then add the kar file again. Maybe then you could see the lastest changes. But it is really confusing β¦
Now i got the right Warning message
>18:49:59.163 [WARN ] [figuration.EBusConfigurationReaderExt] - Hello again HANNES, your are using the right config version ;-)
>
> 18:50:01.585 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file 'file:///etc/openhab2/ebusnew/vaillant_template.json' ...
>
> 18:50:01.817 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file 'file:///etc/openhab2/ebusnew/39200.15.392_configuration.json' ...
>
> 18:50:01.970 [WARN ] [.internal.things.EBusTypeProviderImpl] - eBUS command clearerrorhistory only contains a setter channel!
Now the last changes are activated.
>
> 18:59:07.076 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.107 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.146 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1
>
> 18:59:07.155 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.180 [INFO ] [smarthome.event.ItemStateChangedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit changed from NULL to 1
>
> 18:59:07.203 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.213 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 ...
>
> 18:59:07.251 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.299 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
>
> 18:59:07.306 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt
>
> 18:59:07.309 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.316 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
>
> 18:59:07.330 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.336 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
>
> 18:59:07.347 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xAA
>
> 18:59:07.353 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.358 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.378 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.427 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.436 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt
>
> 18:59:07.443 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.451 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
>
> 18:59:07.475 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.482 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
>
> 18:59:07.491 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xAA
>
> 18:59:07.497 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.507 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.523 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.571 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.581 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt
>
> 18:59:07.586 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.592 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
>
> 18:59:07.603 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.609 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
>
> 18:59:07.619 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xAA
>
> 18:59:07.625 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.635 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.667 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.715 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.722 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 3. attempt
>
> 18:59:07.726 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.733 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
>
> 18:59:07.746 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.758 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
>
> 18:59:07.763 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xAA
>
> 18:59:07.769 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.779 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.811 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN
>
> 18:59:07.842 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.849 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 4. attempt
>
> 18:59:07.855 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:07.863 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF
>
> 18:59:07.875 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
>
> 18:59:07.879 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
>
> 18:59:07.888 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x00
>
> 18:59:07.895 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
>
> 18:59:07.902 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0xB5
>
> 18:59:07.908 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x88
>
> 18:59:07.913 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
>
> 18:59:07.921 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x09
>
> 18:59:07.929 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xB5
>
> 18:59:07.936 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
>
> 18:59:07.941 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x04
>
> 18:59:07.949 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x09
>
> 18:59:07.956 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9
>
> 18:59:07.962 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
>
> 18:59:07.967 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x0E
>
> 18:59:07.976 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x04
>
> 18:59:07.980 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
>
> 18:59:07.987 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x2F
>
> 18:59:07.995 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x0E
>
> 18:59:08.002 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
>
> 18:59:08.007 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x00
>
> 18:59:08.015 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x2F
>
> 18:59:08.021 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
>
> 18:59:08.027 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x01
>
> 18:59:08.032 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x00
>
> 18:59:08.039 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
>
> 18:59:08.046 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0xF6
>
> 18:59:08.052 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x01
>
> 18:59:08.059 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
>
> 18:59:08.065 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ...
>
> 18:59:08.070 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
>
> 18:59:08.653 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
>
> 18:59:08.660 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ...
Could you please set the bridge address to 00, it looks like another device uses FF already.
so i changed in PaperUI/Configuration/Things/Edit/eBUS Bridge -> eBUS Master Address=00.
Here is my new log:
18:34:55.026 [INFO ] [smarthome.event.ItemCommandEvent ] - Item 'VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit' received command 1
18:34:55.062 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
18:34:55.110 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
18:34:55.111 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1
18:34:55.153 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 ...
18:34:55.158 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
18:34:55.166 [INFO ] [smarthome.event.ItemStateChangedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit changed from 2 to 1
18:34:55.189 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received
18:34:55.194 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: 00 10 B5 09 04 0E 2F 00 01 22 @ 0. attempt
18:34:55.199 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
18:34:55.204 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00
18:34:55.221 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
18:34:55.226 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x10
18:34:55.234 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x00
18:34:55.238 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
18:34:55.242 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0xB5
18:34:55.249 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xC4
18:34:55.253 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
18:34:55.259 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x09
18:34:55.265 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0xB5
18:34:55.269 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
18:34:55.276 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x04
18:34:55.290 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x09
18:34:55.298 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9
18:34:55.307 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
18:34:55.314 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x0E
18:34:55.322 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x04
18:34:55.331 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
18:34:55.338 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x2F
18:34:55.346 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x0E
18:34:55.355 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
18:34:55.364 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x00
18:34:55.372 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x2F
18:34:55.379 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
18:34:55.388 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x01
18:34:55.396 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x00
18:34:55.402 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
18:34:55.411 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0x22
18:34:55.419 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Received 0x01
18:34:55.427 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
18:34:55.434 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ...
18:34:55.441 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
18:34:56.017 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN
18:34:56.025 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter ...
18:34:56.032 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR
18:34:56.051 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR
18:34:56.057 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD
18:34:56.067 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD
18:34:56.075 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 1
18:34:56.081 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1
18:34:56.090 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1
18:34:56.097 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct
18:34:56.102 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1
18:34:56.110 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer ...
18:34:56.115 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1
18:34:56.124 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 9
18:34:56.129 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2
18:34:56.136 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8
18:34:56.142 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7
18:34:56.149 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6
18:34:56.154 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5
18:34:56.161 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4
18:34:56.166 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3
18:34:56.173 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2
18:34:56.180 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1
18:34:56.187 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2
18:34:56.193 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2
18:34:56.200 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2
18:34:56.206 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN
18:34:56.214 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable ...
18:34:56.219 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN
18:34:56.227 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN```