eBUS Binding 3.x [3.4.0;3.9.9)

Thank you. The DateTume Debug bug should be fixed.

I try to add the son to use my VRt392 but i got this warning message:

20:46:53.540 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file ‘file:///etc/openhab2/ebus-configuration/vaillant/vaillant_template.json’ …
20:46:53.942 [WARN ] [.ebus.cfg.std.EBusConfigurationReader] - Property ‘name’ is not allowed for type ‘template-block’, ignore property !
20:46:53.950 [WARN ] [.ebus.cfg.std.EBusConfigurationReader] - Property ‘name’ is not allowed for type ‘template-block’, ignore property !
20:46:53.958 [WARN ] [.ebus.cfg.std.EBusConfigurationReader] - Property ‘name’ is not allowed for type ‘template-block’, ignore property !
20:46:53.966 [WARN ] [.ebus.cfg.std.EBusConfigurationReader] - Property ‘name’ is not allowed for type ‘template-block’, ignore property !
20:46:53.977 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file ‘file:///etc/openhab2/ebus-configuration/vaillant/39200.15.392_configuration.json’ …
20:46:54.342 [WARN ] [.internal.things.EBusTypeProviderImpl] - eBUS command clearerrorhistory only contains a setter channel!

i don’t get any data from my VRT392 to openhab :frowning: Can you please help me?

With the 2.5.1-2 version i get the following after a restart :-

2020-01-03 20:31:22.162 [INFO ] [.csdev.ebus.core.EBusEbusdController] - Try to reconnect to ebusd daemon ...
2020-01-03 20:31:22.177 [WARN ] [.csdev.ebus.core.EBusEbusdController] - Retry to connect to ebusd daemon in 5 seconds ...
2020-01-03 20:31:22.343 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.state_gas_valve" every 60 sec. (initial delay 6 sec.)
2020-01-03 20:31:22.344 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.temp_d_flow" every 60 sec. (initial delay 4 sec.)
2020-01-03 20:31:22.344 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.temp_d_flow_ext" every 60 sec. (initial delay 13 sec.)
2020-01-03 20:31:22.344 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.temp_return" every 60 sec. (initial delay 16 sec.)
2020-01-03 20:31:22.345 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.temp_outlet" every 60 sec. (initial delay 5 sec.)
2020-01-03 20:31:22.343 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.state_thermostat_24V" every 60 sec. (initial delay 2 sec.)
2020-01-03 20:31:22.350 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.mode_summer_winter_switch" every 60 sec. (initial delay 26 sec.)
2020-01-03 20:31:22.351 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.modulation_pump" every 60 sec. (initial delay 8 sec.)
2020-01-03 20:31:22.345 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.temp_flow" every 60 sec. (initial delay 10 sec.)
2020-01-03 20:31:22.345 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.state_diverter_valve" every 60 sec. (initial delay 1 sec.)
2020-01-03 20:31:22.351 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.state_pump" every 60 sec. (initial delay 1 sec.)
2020-01-03 20:31:22.352 [INFO ] [ng.ebus.internal.handler.EBusHandler] - Register polling for "boiler.state_pump_ext" every 60 sec. (initial delay 2 sec.)
2020-01-03 20:31:27.280 [INFO ] [.csdev.ebus.core.EBusEbusdController] - ebusd direct mode enabled!
2020-01-03 20:31:27.562 [INFO ] [ervice.device.EBusDeviceTableService] - DATA TABLE UPDATE EBusDevice [masterAddress=0xFF, slaveAddress=0x04, lastActivity=1578083487558, manufacturer=null(null), deviceId=, softwareVersion=null, hardwareVersion=null]
2020-01-03 20:31:27.565 [INFO ] [ervice.device.EBusDeviceTableService] - DATA TABLE UPDATE EBusDevice [masterAddress=0x03, slaveAddress=0x08, lastActivity=1578083487563, manufacturer=null(null), deviceId=, softwareVersion=null, hardwareVersion=null]
2020-01-03 20:31:28.455 [INFO ] [ervice.device.EBusDeviceTableService] - DATA TABLE UPDATE EBusDevice [masterAddress=0x31, slaveAddress=0x36, lastActivity=1578083488453, manufacturer=null(null), deviceId=, softwareVersion=null, hardwareVersion=null]
2020-01-03 20:31:31.413 [WARN ] [.csdev.ebus.core.EBusEbusdController] - ignore:  -s FF 08B509030DBB00
2020-01-03 20:31:31.414 [WARN ] [.csdev.ebus.core.EBusEbusdController] - ignore:  -s FF 36070400
2020-01-03 20:31:31.415 [WARN ] [.csdev.ebus.core.EBusEbusdController] - ignore:  -s FF 08B509030D7300

Then a bit later

2020-01-03 20:33:10.558 [WARN ] [.csdev.ebus.core.EBusEbusdController] - Stop sender thread!
2020-01-03 20:33:10.560 [WARN ] [e.csdev.ebus.core.EBusControllerBase] - ThreadPool not ready!

I seem to get one updated set of values at the beginning and then nothing more.

You use my old ebusd configuration files from github ? These files are just a test and a good start point to build own configurations. At the moment I can only support build-in commands.

yes, i use your json files from github.

oh, i thought this files are converted from this csv files.

Can you please check the json file for vaillant_template and 39200.15.392_configuration.

the viallant_template should have a problem:

{
“name”: “slot1-3”,
“template”: [
{“name”:“slot1”,“type”:“template”,“id”:“vaillant.slot1”},
{“name”:“slot2”,“type”:“template”,“id”:“vaillant.slot2”},
{“name”:“slot3”,“type”:“template”,“id”:“vaillant.slot3”}
]
},
{
“name”: “timer”,
“template”: [
{“name”:“slot1-3”,“type”:“template-block”,“id”:“vaillant.slot1-3”},
{“name”:“daysel”,“type”:“template”,“id”:“vaillant.templ.daysel”}
]
},

Here is the log file:

14:42:47.089 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file ‘file:///etc/openhab2/configurations/vaillant/vaillant_template.json’ …
14:42:47.455 [ERROR] [sdev.ebus.command.EBusCommandRegistry] - error!
de.csdev.ebus.cfg.EBusConfigurationReaderException: Unable to find a template for id vaillant.slot1!
at de.csdev.ebus.cfg.std.EBusConfigurationReader.parseValueConfiguration(EBusConfigurationReader.java:380) ~[bundleFile:?]
at de.csdev.ebus.cfg.std.EBusConfigurationReader.parseTemplateConfiguration(EBusConfigurationReader.java:160) ~[bundleFile:?]
at de.csdev.ebus.cfg.std.EBusConfigurationReader.loadConfigurationCollection(EBusConfigurationReader.java:134) ~[bundleFile:?]
at de.csdev.ebus.cfg.std.EBusConfigurationReader.loadConfigurationCollection(EBusConfigurationReader.java:114) ~[bundleFile:?]
at de.csdev.ebus.command.EBusCommandRegistry.loadCommandCollection(EBusCommandRegistry.java:99) [bundleFile:?]
at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.loadConfigurationByUrl(EBusTypeProviderImpl.java:331) [bundleFile:?]
at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.updateConfiguration(EBusTypeProviderImpl.java:487) [bundleFile:?]
at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.activate(EBusTypeProviderImpl.java:108) [bundleFile:?]
at sun.reflect.GeneratedMethodAccessor152.invoke(Unknown Source) ~[?:?]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_232]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_232]
at org.apache.felix.scr.impl.inject.methods.BaseMethod.invokeMethod(BaseMethod.java:228) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.BaseMethod.access$500(BaseMethod.java:41) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.BaseMethod$Resolved.invoke(BaseMethod.java:664) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.BaseMethod.invoke(BaseMethod.java:510) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.ActivateMethod.invoke(ActivateMethod.java:317) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.ActivateMethod.invoke(ActivateMethod.java:307) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.createImplementationObject(SingleComponentManager.java:340) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.createComponent(SingleComponentManager.java:114) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:982) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:955) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:900) [bundleFile:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:212) [org.eclipse.osgi-3.12.100.jar:?]
at java.security.AccessController.doPrivileged(Native Method) [?:1.8.0_232]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:210) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:111) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:45) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:508) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:461) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:624) [org.eclipse.osgi-3.12.100.jar:?]
at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:86) [bundleFile:?]
at org.apache.felix.scr.impl.inject.BindParameters.getServiceObject(BindParameters.java:47) [bundleFile:?]
at org.apache.felix.scr.impl.inject.field.FieldHandler$ReferenceMethodImpl.getServiceObject(FieldHandler.java:519) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2308) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.prebind(DependencyManager.java:954) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager.prebind(DependencyManager.java:1568) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.collectDependencies(AbstractComponentManager.java:1029) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:935) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:900) [bundleFile:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:212) [org.eclipse.osgi-3.12.100.jar:?]
at java.security.AccessController.doPrivileged(Native Method) [?:1.8.0_232]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:210) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:111) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:45) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:508) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:461) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:624) [org.eclipse.osgi-3.12.100.jar:?]
at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:86) [bundleFile:?]
at org.apache.felix.scr.impl.inject.BindParameters.getServiceObject(BindParameters.java:47) [bundleFile:?]
at org.apache.felix.scr.impl.inject.methods.BindMethod.getServiceObject(BindMethod.java:664) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2308) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager.doInvokeBindMethod(DependencyManager.java:1805) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager.invokeBindMethod(DependencyManager.java:1788) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.invokeBindMethod(SingleComponentManager.java:435) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager$MultipleDynamicCustomizer.addedService(DependencyManager.java:325) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager$MultipleDynamicCustomizer.addedService(DependencyManager.java:294) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1216) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1137) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:944) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:880) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1168) [bundleFile:?]
at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:125) [bundleFile:?]
at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:109) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:920) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:862) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:801) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:127) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:225) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:469) [org.eclipse.osgi-3.12.100.jar:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:906) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:892) [bundleFile:?]
at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:128) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:959) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:732) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.addedService(DependencyManager.java:825) [bundleFile:?]
at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.addedService(DependencyManager.java:767) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1216) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1137) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:944) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:880) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1168) [bundleFile:?]
at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:125) [bundleFile:?]
at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:109) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:920) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:862) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:801) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:127) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:225) [org.eclipse.osgi-3.12.100.jar:?]
at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:469) [org.eclipse.osgi-3.12.100.jar:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:906) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:892) [bundleFile:?]
at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:128) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:959) [bundleFile:?]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:732) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.reconfigure(SingleComponentManager.java:734) [bundleFile:?]
at org.apache.felix.scr.impl.manager.SingleComponentManager.reconfigure(SingleComponentManager.java:673) [bundleFile:?]
at org.apache.felix.scr.impl.manager.ConfigurableComponentHolder.configurationUpdated(ConfigurableComponentHolder.java:435) [bundleFile:?]
at org.apache.felix.scr.impl.manager.RegionConfigurationSupport.configurationEvent(RegionConfigurationSupport.java:316) [bundleFile:?]
at org.apache.felix.scr.impl.manager.RegionConfigurationSupport$2.configurationEvent(RegionConfigurationSupport.java:118) [bundleFile:?]
at org.apache.felix.cm.impl.ConfigurationManager$FireConfigurationEvent.sendEvent(ConfigurationManager.java:1709) [bundleFile:?]
at org.apache.felix.cm.impl.ConfigurationManager$FireConfigurationEvent.run(ConfigurationManager.java:1651) [bundleFile:?]
at org.apache.felix.cm.impl.UpdateThread.run0(UpdateThread.java:138) [bundleFile:?]
at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:105) [bundleFile:?]
at java.lang.Thread.run(Thread.java:748) [?:1.8.0_232]

Could set the logging level to debug or trace?

log:set DEBUG org.openhab.binding.ebus

or

log:set TRACE org.openhab.binding.ebus

I’m not sure yet, but I think it should be template instead of template-block. Could you please send me a raw ebus telegram for this command? I’m not able to replay such issues without it. Maybe I find some time in the evening to check this. Together with the ebusd issue from @jpharvey

thank you.
if i know how to get the raw bus telegram i will send you.
perhaps you can shortly explain me how i can do it

I had intended to turn on TRACE but set it to INFO by mistake before. ebus parts of the log are at https://pastebin.com/jkAdKKtM

Mh, I can’t reproduce you issue. Could you please set the logging for the ebus core lib. There must be something that stops the thread. You haven’t done anything in the meantime?

log:set TRACE de.csdev.ebus

after i activate “enabled advanced logging” i got this LOG information:

19:29:47.938 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file ‘file:///etc/openhab2/configurations/vaillant/vaillant_template.json’ …

19:29:48.018 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cntstarts to registry …

19:29:48.033 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cntminutes to registry …

19:29:48.051 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cnthours to registry …

19:29:48.069 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cntfuel to registry …

19:29:48.090 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.unit to registry …

19:29:48.108 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.fueltype to registry …

19:29:48.126 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.countertype to registry …

19:29:48.145 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.opdatacyclic to registry …

19:29:48.166 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.opdatatransfer to registry …

19:29:48.187 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.opdataheat to registry …

19:29:48.207 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.opdataaction to registry …

19:29:48.226 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.desiredtemp to registry …

19:29:48.245 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.outsidetemp to registry …

19:29:48.264 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.percent to registry …

19:29:48.282 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.percent0 to registry …

19:29:48.300 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.percent1 to registry …

19:29:48.325 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.percent2 to registry …

19:29:48.345 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.percents to registry …

19:29:48.365 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temp to registry …

19:29:48.384 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temp0 to registry …

19:29:48.403 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temp1 to registry …

19:29:48.423 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temp2 to registry …

19:29:48.439 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.press to registry …

19:29:48.454 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.btime to registry …

19:29:48.470 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.bdate to registry …

19:29:48.487 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.id to registry …

19:29:48.507 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.version to registry …

19:29:48.524 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.skip to registry …

19:29:48.546 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temps to registry …

19:29:48.564 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.temps2 to registry …

19:29:48.595 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.tempv to registry …

19:29:48.618 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.press to registry …

19:29:48.636 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.pressv to registry …

19:29:48.653 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.date to registry …

19:29:48.670 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.daysel to registry …

19:29:48.688 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.days to registry …

19:29:48.716 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.time to registry …

19:29:48.736 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.time2 to registry …

19:29:48.763 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.minutes to registry …

19:29:48.782 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.minutes0 to registry …

19:29:48.804 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.minutes2 to registry …

19:29:48.825 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hoursum to registry …

19:29:48.845 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hoursum2 to registry …

19:29:48.862 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hours to registry …

19:29:48.885 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hours1 to registry …

19:29:48.910 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hours12 to registry …

19:29:48.931 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hour to registry …

19:29:48.953 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.seconds to registry …

19:29:48.972 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.seconds0 to registry …

19:29:48.990 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.seconds2 to registry …

19:29:49.006 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.seconds4 to registry …

19:29:49.024 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.calibration to registry …

19:29:49.039 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.calibration0 to registry …

19:29:49.055 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.calibration1 to registry …

19:29:49.072 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.calibrations to registry …

19:29:49.087 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.calibrationv to registry …

19:29:49.104 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.integral to registry …

19:29:49.121 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hcmode to registry …

19:29:49.137 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hcmode2 to registry …

19:29:49.154 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.mcmode to registry …

19:29:49.172 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.mctype to registry …

19:29:49.198 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.mctype7 to registry …

19:29:49.216 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hwcmode to registry …

19:29:49.234 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hwcmode2 to registry …

19:29:49.252 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.mixer to registry …

19:29:49.270 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.daynight to registry …

19:29:49.284 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.status to registry …

19:29:49.290 [INFO ] [smarthome.event.ItemStateChangedEvent] - LivingColors1Alert changed from SELECT to NONE

19:29:49.301 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.onoff to registry …

19:29:49.326 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.yesno to registry …

19:29:49.417 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.errorok to registry …

19:29:49.440 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.sensor to registry …

19:29:49.455 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.tempmirror to registry …

19:29:49.475 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.pressm to registry …

19:29:49.499 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.pressm2 to registry …

19:29:49.525 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.uin100 to registry …

19:29:49.545 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.curve to registry …

19:29:49.562 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.phase1 to registry …

19:29:49.582 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.phase2 to registry …

19:29:49.602 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.phase3 to registry …

19:29:49.619 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.code to registry …

19:29:49.636 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.error to registry …

19:29:49.651 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.from to registry …

19:29:49.668 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.to to registry …

19:29:49.688 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.backtype to registry …

19:29:49.706 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.backmode to registry …

19:29:49.723 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.power to registry …

19:29:49.741 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.energy to registry …

19:29:49.760 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.energyr to registry …

19:29:49.776 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.energy4 to registry …

19:29:49.795 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cntstarts to registry …

19:29:49.813 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.cntstarts2 to registry …

19:29:49.829 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.pumppow to registry …

19:29:49.849 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.pumpstate to registry …

19:29:49.867 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.dcfstate to registry …

19:29:49.889 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.phaseok to registry …

19:29:49.904 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.switchvalve to registry …

19:29:49.921 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.switchvalve2 to registry …

19:29:49.939 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hfrom to registry …

19:29:49.955 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hto to registry …

19:29:49.973 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hpstatus to registry …

19:29:49.988 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.frequency to registry …

19:29:50.005 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.zonesel to registry …

19:29:50.021 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.sfmode to registry …

19:29:50.036 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.opmode to registry …

19:29:50.054 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.zmapping to registry …

19:29:50.069 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.hcname to registry …

19:29:50.084 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.zname to registry …

19:29:50.099 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.shortname to registry …

19:29:50.116 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.phone to registry …

19:29:50.131 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.shortphone to registry …

19:29:50.151 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.mamode to registry …

19:29:50.168 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.offmode to registry …

19:29:50.188 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.templ.rcmode to registry …

19:29:50.203 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.templ to registry …

19:29:50.220 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.tempsensor.temp to registry …

19:29:50.233 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.tempsensor.sensor to registry …

19:29:50.249 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.tempsensor to registry …

19:29:50.262 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.tempmirrorsensor.temp to registry …

19:29:50.276 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.tempmirrorsensor.tempmirror to registry …

19:29:50.290 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.tempmirrorsensor.sensor to registry …

19:29:50.305 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.tempmirrorsensor to registry …

19:29:50.320 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.presssensor.press to registry …

19:29:50.334 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.presssensor.sensor to registry …

19:29:50.348 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.presssensor to registry …

19:29:50.364 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.phase.phase1 to registry …

19:29:50.380 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.phase.phase2 to registry …

19:29:50.393 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.phase.phase3 to registry …

19:29:50.406 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.phase to registry …

19:29:50.428 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errors.error1 to registry …

19:29:50.442 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errors.error2 to registry …

19:29:50.457 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errors.error3 to registry …

19:29:50.471 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errors.error4 to registry …

19:29:50.485 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errors.error5 to registry …

19:29:50.501 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.errors to registry …

19:29:50.515 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errorhistory.status to registry …

19:29:50.530 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errorhistory.time2 to registry …

19:29:50.544 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errorhistory.date to registry …

19:29:50.558 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.errorhistory.error to registry …

19:29:50.574 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.errorhistory to registry …

19:29:50.581 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot1.from to registry …

19:29:50.589 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot1.to to registry …

19:29:50.597 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.slot1 to registry …

19:29:50.604 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot2.from to registry …

19:29:50.612 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot2.to to registry …

19:29:50.621 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.slot2 to registry …

19:29:50.628 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot3.from to registry …

19:29:50.636 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template with global id vaillant.slot3.to to registry …

19:29:50.644 [TRACE] [.ebus.cfg.std.EBusConfigurationReader] - Add template block with global id vaillant.slot3 to registry …

19:29:50.652 [ERROR] [sdev.ebus.command.EBusCommandRegistry] - error!

de.csdev.ebus.cfg.EBusConfigurationReaderException: Unable to find a template for id vaillant.slot1!

at de.csdev.ebus.cfg.std.EBusConfigurationReader.parseValueConfiguration(EBusConfigurationReader.java:380) ~[bundleFile:?]

at de.csdev.ebus.cfg.std.EBusConfigurationReader.parseTemplateConfiguration(EBusConfigurationReader.java:160) ~[bundleFile:?]

at de.csdev.ebus.cfg.std.EBusConfigurationReader.loadConfigurationCollection(EBusConfigurationReader.java:134) ~[bundleFile:?]

at de.csdev.ebus.cfg.std.EBusConfigurationReader.loadConfigurationCollection(EBusConfigurationReader.java:114) ~[bundleFile:?]

at de.csdev.ebus.command.EBusCommandRegistry.loadCommandCollection(EBusCommandRegistry.java:99) [bundleFile:?]

at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.loadConfigurationByUrl(EBusTypeProviderImpl.java:331) [bundleFile:?]

at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.updateConfiguration(EBusTypeProviderImpl.java:487) [bundleFile:?]

at org.openhab.binding.ebus.internal.things.EBusTypeProviderImpl.activate(EBusTypeProviderImpl.java:108) [bundleFile:?]

at sun.reflect.GeneratedMethodAccessor152.invoke(Unknown Source) ~[?:?]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_232]

at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_232]

at org.apache.felix.scr.impl.inject.methods.BaseMethod.invokeMethod(BaseMethod.java:228) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.BaseMethod.access$500(BaseMethod.java:41) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.BaseMethod$Resolved.invoke(BaseMethod.java:664) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.BaseMethod.invoke(BaseMethod.java:510) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.ActivateMethod.invoke(ActivateMethod.java:317) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.ActivateMethod.invoke(ActivateMethod.java:307) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.createImplementationObject(SingleComponentManager.java:340) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.createComponent(SingleComponentManager.java:114) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:982) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:955) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:900) [bundleFile:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:212) [org.eclipse.osgi-3.12.100.jar:?]

at java.security.AccessController.doPrivileged(Native Method) [?:1.8.0_232]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:210) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:111) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:45) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:508) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:461) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:624) [org.eclipse.osgi-3.12.100.jar:?]

at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:86) [bundleFile:?]

at org.apache.felix.scr.impl.inject.BindParameters.getServiceObject(BindParameters.java:47) [bundleFile:?]

at org.apache.felix.scr.impl.inject.field.FieldHandler$ReferenceMethodImpl.getServiceObject(FieldHandler.java:519) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2308) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.prebind(DependencyManager.java:954) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager.prebind(DependencyManager.java:1568) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager.collectDependencies(AbstractComponentManager.java:1029) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:935) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:900) [bundleFile:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:212) [org.eclipse.osgi-3.12.100.jar:?]

at java.security.AccessController.doPrivileged(Native Method) [?:1.8.0_232]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:210) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:111) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:45) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:508) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:461) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:624) [org.eclipse.osgi-3.12.100.jar:?]

at org.apache.felix.scr.impl.manager.SingleRefPair.getServiceObject(SingleRefPair.java:86) [bundleFile:?]

at org.apache.felix.scr.impl.inject.BindParameters.getServiceObject(BindParameters.java:47) [bundleFile:?]

at org.apache.felix.scr.impl.inject.methods.BindMethod.getServiceObject(BindMethod.java:664) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager.getServiceObject(DependencyManager.java:2308) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager.doInvokeBindMethod(DependencyManager.java:1805) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager.invokeBindMethod(DependencyManager.java:1788) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.invokeBindMethod(SingleComponentManager.java:435) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager$MultipleDynamicCustomizer.addedService(DependencyManager.java:325) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager$MultipleDynamicCustomizer.addedService(DependencyManager.java:294) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1216) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1137) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:944) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:880) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1168) [bundleFile:?]

at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:125) [bundleFile:?]

at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:109) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:920) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:862) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:801) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:127) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:225) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:469) [org.eclipse.osgi-3.12.100.jar:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:906) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:892) [bundleFile:?]

at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:128) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:959) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:732) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.addedService(DependencyManager.java:825) [bundleFile:?]

at org.apache.felix.scr.impl.manager.DependencyManager$SingleDynamicCustomizer.addedService(DependencyManager.java:767) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1216) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1137) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:944) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:880) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1168) [bundleFile:?]

at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:125) [bundleFile:?]

at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:109) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:920) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:862) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:801) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:127) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:225) [org.eclipse.osgi-3.12.100.jar:?]

at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:469) [org.eclipse.osgi-3.12.100.jar:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:906) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:892) [bundleFile:?]

at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:128) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:959) [bundleFile:?]

at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:732) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.reconfigure(SingleComponentManager.java:734) [bundleFile:?]

at org.apache.felix.scr.impl.manager.SingleComponentManager.reconfigure(SingleComponentManager.java:673) [bundleFile:?]

at org.apache.felix.scr.impl.manager.ConfigurableComponentHolder.configurationUpdated(ConfigurableComponentHolder.java:435) [bundleFile:?]

at org.apache.felix.scr.impl.manager.RegionConfigurationSupport.configurationEvent(RegionConfigurationSupport.java:316) [bundleFile:?]

at org.apache.felix.scr.impl.manager.RegionConfigurationSupport$2.configurationEvent(RegionConfigurationSupport.java:118) [bundleFile:?]

at org.apache.felix.cm.impl.ConfigurationManager$FireConfigurationEvent.sendEvent(ConfigurationManager.java:1709) [bundleFile:?]

at org.apache.felix.cm.impl.ConfigurationManager$FireConfigurationEvent.run(ConfigurationManager.java:1651) [bundleFile:?]

at org.apache.felix.cm.impl.UpdateThread.run0(UpdateThread.java:138) [bundleFile:?]

at org.apache.felix.cm.impl.UpdateThread.run(UpdateThread.java:105) [bundleFile:?]

at java.lang.Thread.run(Thread.java:748) [?:1.8.0_232]

19:29:50.740 [INFO ] [.internal.things.EBusTypeProviderImpl] - Load custom configuration file ‘file:///etc/openhab2/configurations/vaillant/39200.15.392_configuration.json’ …

19:29:51.768 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - ‘ebus:bridge:fdcdc515’ changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING

19:29:51.810 [WARN ] [us.internal.handler.EBusBridgeHandler] - Enable advanced logging for eBUS commands!

19:32:56.732 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

19:32:56.741 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

19:32:56.751 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

19:32:56.762 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

19:32:56.775 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9

19:32:56.783 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

19:32:56.794 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8

19:32:56.804 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7

19:32:56.811 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6

19:32:56.815 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5

19:32:56.820 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4

19:32:56.828 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3

19:32:56.838 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2

19:32:56.849 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1

19:32:56.858 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1

19:32:56.868 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct

19:32:56.882 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1

19:32:56.892 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer …

19:32:56.903 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1

19:32:56.918 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 1

19:32:56.930 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2

19:32:56.941 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2

19:32:56.952 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2

19:32:56.965 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2

19:32:56.975 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN

19:32:56.987 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable …

19:32:56.997 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

19:32:57.010 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

19:32:57.015 [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 …

19:32:57.021 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

19:32:57.043 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

19:32:57.048 [TRACE] [us.internal.handler.EBusBridgeHandler] - Unknown telegram 10 08 B5 10 09 00 00 00 78 FF FF 05 FF 00 A0 00 01 01 9A 00

do you mean that log ?

i don’t know only to get the raw log file for only this command:
{“name”:“slot1-3”,“type”:“template-block”,“id”:“vaillant.slot1-3”},

Hallo @Trainer,

I’ve reworked the ebusd configuration bundle. Now it is a Maven bundle with a simple test case. I found some major bug in the files. Now it works with my test cases. Could you please try it with the new files. But the structure of the project has changed. You find the JSON files now here:

You should only use the vaillant_template.json and your 39200.15.392_configuration.json file and not all at once to test. You can also build your own index-configuration.json as configurationBundle.

New log with de.csdev logging set to trace is at https://pastebin.com/NMzt79UW

If you cant get anywhere then i dont mind having a go at debugging it but probably need to understand how you build the binding & the ebus interface classes.

Hello @csowada , @MWerk

i have tested your new vailant template file with V6100.50.v61.mc_configuration and it s look quit good.
All commands for VR61 seem to work. If I find any problems, I’ll let you know.

Oh wow. Now i can read the data from my VRT 392.
I also try to write some data like change the mode com AUTO to MANUAL and change the AUTO Temp, but it didn’t work :frowning:

12:18:27.212 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1QuickVetoTemp_ManualOverrideSetpointOfTheHeatingCircuit predicted to become 22 °C
12:18:27.231 [ERROR] [.ebus.internal.utils.EBusClientBridge] - Unable to find setter command with id Hc1QuickVetoTemp

i think this is because of missing template-block in vaillant-template

Hello @jpharvey,
now I’m able to reproduce your issue. Now I try to build a fix for that.

Hello @Trainer,

yes, there is no setter for the Hc1QuickVetoTemp. So the error is correct. Feel free to modify the file and send a pull request on GitHub. I can’t analyse and fix all Vaillant files, Sorry.

Please give more details, didn’t work is not helpful for me.
Which commands? Please give the ids and add a log snippet to show what is not working?

Here is the log try to change from AUTO to MANUAL Mode(command is called > Hc1OPMode):

{
“label”: “HC1_OPMode”,
“id”: “Hc1OPMode”,
“command”: “B5 09”,
“template”: [
{“name”:“Hc1OPMode”,“type”:“byte”,“label”:“operation mode of the heating circuit”}
],
“get”: {
“master”: [
{“type”:“static”,“default”:“0D 2F 00”}
],
“slave”: [
{“type”:“template-block”}
]
},
“set”: {
“command”: “B5 09”,
“master”: [
{“type”:“static”,“default”:“0E 2F 00”},
{“type”:“template-block”}
]
}
},

14:07:59.775 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit’ received command 1

14:07:59.785 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1

14:07:59.812 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 2 …

14:08:00.157 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:00.164 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:00.188 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:00.193 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:00.205 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:00.212 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

14:08:00.220 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

14:08:00.229 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

14:08:00.234 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 1

14:08:00.242 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

14:08:00.249 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1

14:08:00.256 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct

14:08:00.262 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1

14:08:00.267 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer …

14:08:00.272 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1

14:08:00.278 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 9

14:08:00.284 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2

14:08:00.290 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8

14:08:00.295 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7

14:08:00.301 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6

14:08:00.305 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5

14:08:00.312 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4

14:08:00.317 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3

14:08:00.322 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2

14:08:00.327 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1

14:08:00.332 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2

14:08:00.339 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2

14:08:00.345 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2

14:08:00.350 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN

14:08:00.355 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable …

14:08:00.360 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

14:08:00.368 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:00.374 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 15 B5 09 03 0D 3C 00 07 @ 0. attempt

14:08:00.380 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:00.385 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

14:08:00.396 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:00.403 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision with SYN detected!

14:08:00.408 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …

14:08:00.413 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …

14:08:00.419 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:00.430 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN

14:08:00.477 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:00.483 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 15 B5 09 03 0D 3C 00 07 @ 1. attempt

14:08:00.488 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:00.494 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

14:08:00.508 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:00.514 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 15

14:08:00.520 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5

14:08:00.525 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09

14:08:00.531 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 03

14:08:00.538 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0D

14:08:00.543 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 3C

14:08:00.549 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00

14:08:00.555 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 07

14:08:00.560 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN

14:08:00.569 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

14:08:01.084 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:01.089 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:01.132 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:01.137 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:01.163 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:01.211 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:01.218 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt

14:08:01.224 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:01.230 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

14:08:01.243 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:01.250 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10

14:08:01.256 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5

14:08:01.262 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09

14:08:01.268 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04

14:08:01.274 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E

14:08:01.282 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F

14:08:01.288 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00

14:08:01.296 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01

14:08:01.302 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6

14:08:01.310 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

14:08:01.317 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

14:08:01.323 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

14:08:01.329 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 9

14:08:01.335 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

14:08:01.341 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8

14:08:01.347 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7

14:08:01.353 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6

14:08:01.359 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5

14:08:01.364 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4

14:08:01.371 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:01.379 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

14:08:01.914 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:01.920 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

14:08:01.962 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.010 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.058 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.106 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.137 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.154 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:02.161 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

14:08:02.169 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

14:08:02.178 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

14:08:02.186 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 1

14:08:02.195 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

14:08:02.203 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1

14:08:02.210 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct

14:08:02.219 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1

14:08:02.225 [TRACE] [sdev.ebus.core.EBusLowLevelController] - waiting for slave answer …

14:08:02.231 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to ACK1

14:08:02.238 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Slave data Length: 10

14:08:02.243 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK1 to LENGTH2

14:08:02.246 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 9

14:08:02.251 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 8

14:08:02.255 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 7

14:08:02.260 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 6

14:08:02.264 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 5

14:08:02.271 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 4

14:08:02.276 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 3

14:08:02.282 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 2

14:08:02.287 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1

14:08:02.292 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH2 to DATA2

14:08:02.298 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA2 to CRC2

14:08:02.305 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC2 to ACK2

14:08:02.312 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from ACK2 to SYN

14:08:02.320 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable …

14:08:02.332 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

14:08:02.337 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:02.348 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.348 [TRACE] [ommand.datatypes.ext.EBusTypeDateTime] - The decoded date part of datetime is null!

14:08:02.378 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.426 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

14:08:02.441 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

14:08:02.448 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

14:08:02.456 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

14:08:02.463 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

14:08:02.468 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 2

14:08:02.473 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

14:08:02.478 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Data 1

14:08:02.484 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from LENGTH1 to DATA1

14:08:02.492 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master CRC correct

14:08:02.500 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from DATA1 to CRC1

14:08:02.505 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - broadcast end

14:08:02.510 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from CRC1 to SYN

14:08:02.516 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - fireTelegramAvailable …

14:08:02.522 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

14:08:02.529 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

14:08:02.544 [TRACE] [ebus.service.parser.EBusParserService] - No command method matches the telegram 10 FE B5 05 02 04 00 0B AA …

My first impression is, that you adapter is not able to send fast enough to the bus. What kind of adapter do you use? A Ethernet or Wifi adapter? Or do you use a serial adapter? Could you try to switch to the jSerialComm serial driver?

On Linux you could set a lower latency with following commands

# echo 1 > /sys/bus/usb-serial/devices/ttyUSB0/latency_timer

Check the values before

# cat /sys/bus/usb-serial/devices/ttyUSB0/latency_timer
16 <- default
# echo 1 > /sys/bus/usb-serial/devices/ttyUSB0/latency_timer
# cat /sys/bus/usb-serial/devices/ttyUSB0/latency_timer
1 <- better

Thank you, i use the eBUS Adapter 2.2 with a serial adapter. Now i change the latency_timer to the value 1 and now i got this log:

15:49:07.325 [INFO ] [smarthome.event.ItemCommandEvent ] - Item ‘VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit’ received command 1

15:49:07.388 [INFO ] [arthome.event.ItemStatePredictedEvent] - VaillantVRT392_39200_Hc1OPMode_OperationModeOfTheHeatingCircuit predicted to become 1

15:49:07.442 [DEBUG] [de.csdev.ebus.core.EBusQueue ] - Size of send queue is 1 …

15:49:07.567 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN

15:49:07.634 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:07.640 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

15:49:07.681 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

15:49:07.691 [TRACE] [de.csdev.ebus.core.EBusQueue ] - No access to eBUS because the lock counter …

15:49:07.725 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

15:49:07.770 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Auto-SYN byte received

15:49:07.782 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 0. attempt

15:49:07.790 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:07.800 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

15:49:07.813 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

15:49:07.827 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision detected! 0x00

15:49:07.837 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …

15:49:07.847 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …

15:49:07.856 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

15:49:08.411 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:08.422 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 1. attempt

15:49:08.430 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:08.439 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

15:49:08.454 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

15:49:08.461 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - eBUS collision detected! 0x00

15:49:08.467 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Priority class doesn’t match, blocked for next SYN …

15:49:08.476 [TRACE] [de.csdev.ebus.core.EBusQueue ] - Sender was blocked for this SYN …

15:49:08.485 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to UNKNOWN

15:49:09.052 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:09.060 [DEBUG] [sdev.ebus.core.EBusLowLevelController] - Send: FF 10 B5 09 04 0E 2F 00 01 F6 @ 2. attempt

15:49:09.067 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from UNKNOWN to SYN

15:49:09.076 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send FF

15:49:09.091 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

15:49:09.099 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 10

15:49:09.108 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send B5

15:49:09.115 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 09

15:49:09.125 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 04

15:49:09.133 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 0E

15:49:09.141 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 2F

15:49:09.148 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 00

15:49:09.153 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send 01

15:49:09.162 [TRACE] [sdev.ebus.core.EBusLowLevelController] - Send F6

15:49:09.169 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to UNKNOWN

15:49:09.176 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SYN to SRC_ADDR

15:49:09.183 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SRC_ADDR to TGT_ADDR

15:49:09.188 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from TGT_ADDR to PRIMARY_CMD

15:49:09.195 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from PRIMARY_CMD to SECONDARY_CMD

15:49:09.201 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Master data length: 14

15:49:09.208 [TRACE] [dev.ebus.core.EBusReceiveStateMachine] - Update state from SECONDARY_CMD to LENGTH1

What does "No access to eBUS because the lock counter …"mean?

It is just an eBUS rule to not flood the whole bus with telegrams. We have to wait several SYN symbols to access the bus. It is just a TRACE message.

But your log shows that it takes three attempts to send the telegram. But the slave part of the telegram is not in your log. But it looks good so far.