Modbus 2.x? from Marketplace... help needed!

[peteroakes (Peter Oakes (The Breadboardca))]

Hi there, sorry if this is a bit of a noob question
I have a problem getting the new modbus modules to work on my OH2 install (RPI3)

I believe I have the correct modules installed

I can not find any documentation on how the new modules are to be configured, and have guessed the best I can. this is my first time with any version of OH so I may be missing something. I do however have plenty of experience with using Node-Red with Modbus and have it polling the same device without any issues. (I do realize it is an Alpha Feature for OH2 but folks are saying is basically works, I have also spend a long time reading through other posts to try to find answers before posting this request but to no avail, I like where this is going as a product and really would like to be able to start a few how to’s for it on my channel.

Here is my log excerpt to show the error

2017-10-01 17:58:07.325 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://192.168.1.146:8080
2017-10-01 17:58:07.341 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://192.168.1.146:8443
2017-10-01 17:58:07.524 [WARN ] [thome.core.internal.I18nProviderImpl] - Region (Canada) is invalid. Skip it.
java.util.IllformedLocaleException: Ill-formed region: Canada [at index 0]
_ at java.util.Locale$Builder.setRegion(Locale.java:2495)[:1.8.0_121]_
_ at org.eclipse.smarthome.core.internal.I18nProviderImpl.modified(I18nProviderImpl.java:115)_
_ at org.eclipse.smarthome.core.internal.I18nProviderImpl.activate(I18nProviderImpl.java:66)_
_ at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)[:1.8.0_121]_
_ at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)[:1.8.0_121]_
_ at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)[:1.8.0_121]_
_ at java.lang.reflect.Method.invoke(Method.java:498)[:1.8.0_121]_
_ at org.apache.felix.scr.impl.inject.BaseMethod.invokeMethod(BaseMethod.java:224)_
_ at org.apache.felix.scr.impl.inject.BaseMethod.access$500(BaseMethod.java:39)_
_ at org.apache.felix.scr.impl.inject.BaseMethod$Resolved.invoke(BaseMethod.java:617)_
_ at org.apache.felix.scr.impl.inject.BaseMethod.invoke(BaseMethod.java:501)_
_ at org.apache.felix.scr.impl.inject.ActivateMethod.invoke(ActivateMethod.java:302)_
_ at org.apache.felix.scr.impl.inject.ActivateMethod.invoke(ActivateMethod.java:294)_
_ at org.apache.felix.scr.impl.manager.SingleComponentManager.createImplementationObject(SingleComponentManager.java:297)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.SingleComponentManager.createComponent(SingleComponentManager.java:108)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:906)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:879)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:823)[31:org.apache.felix.scr:2.0.6]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse$1.run(ServiceFactoryUse.java:212)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at java.security.AccessController.doPrivileged(Native Method)[:1.8.0_121]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.factoryGetService(ServiceFactoryUse.java:210)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceFactoryUse.getService(ServiceFactoryUse.java:111)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceConsumer$2.getService(ServiceConsumer.java:45)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.getService(ServiceRegistrationImpl.java:496)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.getService(ServiceRegistry.java:461)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.BundleContextImpl.getService(BundleContextImpl.java:619)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at com.eclipsesource.jaxrs.publisher.internal.ResourceTracker.addingService(ResourceTracker.java:39)[9:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]_
_ at org.osgi.util.tracker.ServiceTracker$Tracked.customizerAdding(ServiceTracker.java:941)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.ServiceTracker$Tracked.customizerAdding(ServiceTracker.java:870)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.AbstractTracked.trackAdding(AbstractTracked.java:256)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:229)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:901)[org.osgi.core-6.0.0.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.FilteredServiceListener.serviceChanged(FilteredServiceListener.java:109)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:914)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEventPrivileged(ServiceRegistry.java:862)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.publishServiceEvent(ServiceRegistry.java:801)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistrationImpl.register(ServiceRegistrationImpl.java:127)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.serviceregistry.ServiceRegistry.registerService(ServiceRegistry.java:225)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.BundleContextImpl.registerService(BundleContextImpl.java:464)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:886)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager$3.register(AbstractComponentManager.java:873)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.RegistrationManager.changeRegistration(RegistrationManager.java:132)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager.registerService(AbstractComponentManager.java:940)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:740)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager.enableInternal(AbstractComponentManager.java:674)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.AbstractComponentManager.enable(AbstractComponentManager.java:429)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.manager.ConfigurableComponentHolder.enableComponents(ConfigurableComponentHolder.java:657)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.BundleComponentActivator.initialEnable(BundleComponentActivator.java:341)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.Activator.loadComponents(Activator.java:403)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.Activator.access$200(Activator.java:54)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.scr.impl.Activator$ScrExtension.start(Activator.java:278)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.utils.extender.AbstractExtender.createExtension(AbstractExtender.java:259)[31:org.apache.felix.scr:2.0.6]_
_ at org.apache.felix.utils.extender.AbstractExtender.modifiedBundle(AbstractExtender.java:232)[31:org.apache.felix.scr:2.0.6]_
_ at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:482)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.BundleTracker$Tracked.customizerModified(BundleTracker.java:415)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.AbstractTracked.track(AbstractTracked.java:232)[org.osgi.core-6.0.0.jar:]_
_ at org.osgi.util.tracker.BundleTracker$Tracked.bundleChanged(BundleTracker.java:444)[org.osgi.core-6.0.0.jar:]_
_ at org.eclipse.osgi.internal.framework.BundleContextImpl.dispatchEvent(BundleContextImpl.java:902)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.ListenerQueue.dispatchEventSynchronous(ListenerQueue.java:148)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEventPrivileged(EquinoxEventPublisher.java:165)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:75)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.EquinoxEventPublisher.publishBundleEvent(EquinoxEventPublisher.java:67)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.internal.framework.EquinoxContainerAdaptor.publishModuleEvent(EquinoxContainerAdaptor.java:102)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.Module.publishEvent(Module.java:466)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.Module.start(Module.java:457)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1582)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.incStartLevel(ModuleContainer.java:1562)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.doContainerStartLevel(ModuleContainer.java:1533)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1476)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.container.ModuleContainer$ContainerStartLevel.dispatchEvent(ModuleContainer.java:1)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.EventManager.dispatchEvent(EventManager.java:230)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
_ at org.eclipse.osgi.framework.eventmgr.EventManager$EventThread.run(EventManager.java:340)[org.eclipse.osgi-3.10.101.v20150820-1432.jar:]_
2017-10-01 17:58:10.996 [INFO ] [e.io.rest.internal.filter.CorsFilter] - enabled CORS for REST API.
2017-10-01 17:58:16.250 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model ‘default.items’ has errors, therefore ignoring it: [1,1]: missing EOF at '#'

2017-10-01 17:58:21.574 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.sitemap’
2017-10-01 17:58:21.635 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'xxdefault.sitemap’
2017-10-01 17:58:21.678 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'lab.sitemap’
2017-10-01 17:58:24.620 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2017-10-01 17:58:24.732 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2017-10-01 17:58:24.816 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2017-10-01 17:58:25.530 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
2017-10-01 17:58:25.892 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app
2017-10-01 17:58:26.211 [INFO ] [ding.piface.internal.PifaceActivator] - Piface binding has been started.
2017-10-01 17:58:26.536 [WARN ] [sql.internal.MysqlPersistenceService] - The SQL database URL is missing - please configure the sql:url parameter in openhab.cfg
2017-10-01 17:58:26.756 [INFO ] [rt.modbus.internal.ModbusManagerImpl] - Modbus manager activated
2017-10-01 17:58:26.776 [INFO ] [modbus.internal.ModbusHandlerFactory] - Setting manager: org.openhab.io.transport.modbus.internal.ModbusManagerImpl@d770b5
2017-10-01 17:58:27.227 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1dd1379 (unit id 1 & transaction 1). Address: /192.168.1.157:502
2017-10-01 17:58:27.307 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 2/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1dd1379 (unit id 1 & transaction 2). Address: /192.168.1.157:502
2017-10-01 17:58:27.391 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 3/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1dd1379 (unit id 1 & transaction 3). Address: /192.168.1.157:502
2017-10-01 17:58:27.394 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 3, throwing last error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@1dd1379 (unit id 1 & transaction 3). Address: /192.168.1.157:502
2017-10-01 17:58:27.426 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Error when executing read request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@efce2b[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=16,length=2]): net.wimpi.modbus.ModbusIOException Executing transaction failed (tried 3 times)
2017-10-01 17:58:27.496 [ERROR] [odbus.handler.ModbusDataThingHandler] - Thing modbus:data:1eb052bc ‘AX8’ had error on read: net.wimpi.modbus.ModbusIOException Executing transaction failed (tried 3 times). Stack trace follows for unexpected errors.
net.wimpi.modbus.ModbusIOException: Executing transaction failed (tried 3 times)
_ at net.wimpi.modbus.io.ModbusTCPTransaction.execute(ModbusTCPTransaction.java:220)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]_
_ at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOneTimePoll(ModbusManagerImpl.java:493)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]_
_ at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$19(ModbusManagerImpl.java:545)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]_
_ at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]_
_ at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)[:1.8.0_121]_
_ at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]_
_ at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)[:1.8.0_121]_
_ at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]_
_ at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]_
_ at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]_

And the Event log for the same period
2017-10-01 17:58:22.149 [ItemChannelLinkAddedEvent ] - Link ‘AX8_Temp-modbus:readwrite:d8648559:number’ has been added.
2017-10-01 17:58:22.170 [ItemChannelLinkAddedEvent ] - Link ‘Tests-modbus:read:2851bf8c:number’ has been added.
2017-10-01 17:58:22.177 [ItemChannelLinkAddedEvent ] - Link ‘AX8_InternalTemp-modbus:data:1eb052bc:number’ has been added.
2017-10-01 17:58:22.277 [ThingAddedEvent ] - Thing ‘modbus:data:1eb052bc’ has been added.
2017-10-01 17:58:22.290 [ThingAddedEvent ] - Thing ‘modbus:tcp:ce9bb45a’ has been added.
2017-10-01 17:58:22.292 [ThingAddedEvent ] - Thing ‘modbus:poller:2889750c’ has been added.
2017-10-01 17:58:26.814 [hingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
2017-10-01 17:58:26.895 [hingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from UNINITIALIZED to INITIALIZING
2017-10-01 17:58:26.902 [hingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from INITIALIZING to UNKNOWN
2017-10-01 17:58:26.973 [hingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from UNKNOWN to ONLINE
2017-10-01 17:58:27.025 [hingStatusInfoChangedEvent] - ‘modbus:poller:2889750c’ changed from UNINITIALIZED to INITIALIZING
2017-10-01 17:58:27.071 [hingStatusInfoChangedEvent] - ‘modbus:poller:2889750c’ changed from INITIALIZING to ONLINE
2017-10-01 17:58:27.144 [hingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
2017-10-01 17:58:27.232 [hingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from INITIALIZING to ONLINE
2017-10-01 17:58:27.509 [hingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: net.wimpi.modbus.ModbusIOException: Executing transaction failed (tried 3 times)

The IP is correct, same for socket etc. I am trying in this case to read a Holding register index 16 but any will do for now.
Here are the Things defined (a few minutes after a reboot)

Here is each definition
TCP

Poller:

Data:

Does the new modules require any manual input to the file default.items ?
Number AX8_Temp “AX8 Temperature [%d]” { modbus=“AX8:16”}

are entries required in modbus.config ?

I can get Modbus/TCP connection, , the poller shows online, but the data even when I can get it online will still not retrieve data. I know the device is ok as I have it running every few seconds through node red with the same parameters.

I am planning (Or was) to video a how to for Openhab for my YouTube channel :- thebreadboard where I post many industrial and home control related videos, I was hoping to be able to start with openHAB so your help getting this to work would be appreciated

Regards
Peter Oakes

[namraccr]

For this error:

[WARN ] [thome.core.internal.I18nProviderImpl] - Region (Canada) is invalid.

The javadoc says:

The typical region value is a two-letter ISO 3166 code or a three-digit UN M.49 area code

So whereever you configured the “Canada” value, you may be able to just swap in the correct value, “CA”.

[namraccr]

And of course you should probably configure for debug logging to obtain additional insight about the read failures you’re seeing.

[peteroakes (Peter Oakes (The Breadboardca))]

Thanks, done. It was the issue with the modbus I was concerned about, I doubt or hope the region code will not affect that.

[peteroakes (Peter Oakes (The Breadboardca))]

OK, ill go figure out how to do that… but in the meantime, are there any guides regarding the new modbus modules ? or any insight on what may be wrong, is this a currently working module ?, (Everything including OH2 was downloaded in the last few days so should be the latest versions.

Thanks

[peteroakes (Peter Oakes (The Breadboardca))]

ok, nothing more available but here it is

[01:59:27] openhabian@openHABianPi:~$
Using username “openhabian”.
openhabian@openHABianPi’s password:

The programs included with the Debian GNU/Linux system are free software;
the exact distribution terms for each program are described in the
individual files in /usr/share/doc/*/copyright.

Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
Last login: Mon Oct 2 01:59:00 2017 from peter-pc.oakesclanhome

###############################################################################
################# openHABianPi #################################### 7.05.170509
###############################################################################

Ip = 192.168.1.146

Release = Raspbian GNU/Linux 8 (jessie)

Kernel = Linux 4.9.35-v7+

Platform = Raspberry Pi 3 Model B Rev 1.2

Uptime = 0 day(s). 0:0:21

CPU Usage = 67.41 % avg over 4 cpu(s) (4 core(s) x 1 socket(s))

CPU Load = 1m: 1.13, 5m: 0.26, 15m: 0.09

Memory = Free: 0.66GB (70%), Used: 0.28GB (30%), Total: 0.94GB

Swap = Free: 0.09GB (100%), Used: 0.00GB (0%), Total: 0.09GB

Root = Free: 26.01GB (92%), Used: 2.06GB (8%), Total: 29.29GB

Updates = 0 apt-get updates available.

Sessions = 1 sessions

Processes = 134 running processes of 32768 maximum processes

###############################################################################

          Welcome to            __  _____    ____  _
        ____  ____  ___  ____  / / / /   |  / __ )(_)___ _____
       / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __  / / __ `/ __ \
      / /_/ / /_/ /  __/ / / / __  / ___ |/ /_/ / / /_/ / / / /
      \____/ .___/\___/_/ /_/_/ /_/_/  |_/_____/_/\__,_/_/ /_/
          /_/
              openHAB 2.1.0-1 (Release Build)

Looking for a place to get started? Check out ‘sudo openhabian-config’ and the
documentation at http://docs.openhab.org/installation/openhabian.html
The openHAB dashboard can be reached at http://openHABianPi:8080

[01:59:55] openhabian@openHABianPi:~$ ssh -p 8101 openhab@localhost
Password authentication
Password:

                      __  _____    ____

____ ____ ___ ____ / / / / | / __ )
/ __ / __ / _ / __ / // / /| | / __ |
/ // / // / __/ / / / __ / ___ |/ // /
_/ ./_// /// /// |/_____/
/_/ 2.1.0
- release build -

Hit ‘’ for a list of available commands
and ‘[cmd] --help’ for help on a specific command.
Hit ‘’ or type ‘system:shutdown’ or ‘logout’ to shutdown openHAB.

openhab> log:tail
02:00:01.459 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at http://192.168.1.146:8080
02:00:01.468 [INFO ] [.dashboard.internal.DashboardService] - Started dashboard at https://192.168.1.146:8443
02:00:01.618 [INFO ] [thome.core.internal.I18nProviderImpl] - Locale set to en_CA, Location set to 43.897095,-78.865791
02:00:05.217 [INFO ] [e.io.rest.internal.filter.CorsFilter] - enabled CORS for REST API.
02:00:10.677 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'default.items’
02:00:15.753 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'demo.sitemap’
02:00:15.861 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'lab.sitemap’
02:00:18.021 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link ‘AX8_Temp-modbus:readwrite:d8648559:number’ has been added.
02:00:18.026 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link ‘Tests-modbus:read:2851bf8c:number’ has been added.
02:00:18.030 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link ‘AX8_InternalTemp-modbus:data:1eb052bc:number’ has been added.
02:00:18.258 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing ‘modbus:data:1eb052bc’ has been added.
02:00:18.268 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing ‘modbus:tcp:ce9bb45a’ has been added.
02:00:18.275 [INFO ] [smarthome.event.ThingAddedEvent ] - Thing ‘modbus:poller:2889750c’ has been added.
02:00:20.687 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
02:00:20.782 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
02:00:20.909 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
02:00:21.578 [INFO ] [ui.habmin.internal.servlet.HABminApp] - Started HABmin servlet at /habmin
02:00:21.963 [INFO ] [assic.internal.servlet.WebAppServlet] - Started Classic UI at /classicui/app
02:00:22.373 [INFO ] [ding.piface.internal.PifaceActivator] - Piface binding has been started.
02:00:23.544 [WARN ] [sql.internal.MysqlPersistenceService] - The SQL database URL is missing - please configure the sql:url parameter in openhab.cfg
02:00:23.563 [DEBUG] [org.openhab.binding.modbus ] - BundleEvent STARTING - org.openhab.binding.modbus
02:00:23.569 [DEBUG] [org.openhab.binding.modbus ] - BundleEvent STARTED - org.openhab.binding.modbus
02:00:23.726 [INFO ] [rt.modbus.internal.ModbusManagerImpl] - Modbus manager activated
02:00:23.740 [INFO ] [modbus.internal.ModbusHandlerFactory] - Setting manager: org.openhab.io.transport.modbus.internal.ModbusManagerImpl@12fff61
02:00:23.744 [DEBUG] [org.openhab.binding.modbus ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.core.thing.binding.ThingHandlerFactory}={component.name=org.openhab.binding.modbus.internal.ModbusHandlerFactory, component.id=222, service.id=353, service.bundleid=220, service.scope=bundle} - org.openhab.binding.modbus
02:00:23.756 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus tcp
02:00:23.762 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from UNINITIALIZED to UNINITIALIZED (BRIDGE_UNINITIALIZED)
02:00:23.810 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from UNINITIALIZED to INITIALIZING
02:00:23.815 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from INITIALIZING to UNKNOWN
02:00:23.912 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:tcp:ce9bb45a’ changed from UNKNOWN to ONLINE
02:00:23.914 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler Modbus poller
02:00:23.944 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:poller:2889750c’ changed from UNINITIALIZED to INITIALIZING
02:00:23.989 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:poller:2889750c’ changed from INITIALIZING to ONLINE
02:00:23.989 [DEBUG] [modbus.internal.ModbusHandlerFactory] - createHandler data
02:00:24.052 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from UNINITIALIZED (BRIDGE_UNINITIALIZED) to INITIALIZING
02:00:24.083 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from INITIALIZING to ONLINE
02:00:24.097 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 1/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@9906c1 (unit id 1 & transaction 1). Address: /192.168.1.157:502
02:00:24.196 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 2/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@9906c1 (unit id 1 & transaction 2). Address: /192.168.1.157:502
02:00:24.280 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute try 3/3 error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@9906c1 (unit id 1 & transaction 3). Address: /192.168.1.157:502
02:00:24.282 [ERROR] [wimpi.modbus.io.ModbusTCPTransaction] - execute reached max tries 3, throwing last error: I/O exception - failed to read… Request: net.wimpi.modbus.msg.ReadMultipleRegistersRequest@9906c1 (unit id 1 & transaction 3). Address: /192.168.1.157:502
02:00:24.314 [ERROR] [rt.modbus.internal.ModbusManagerImpl] - Error when executing read request (ModbusPollerThingHandlerImpl.ModbusPollerReadRequest@fbc38a[slaveId=1,functionCode=READ_MULTIPLE_REGISTERS,start=16,length=2]): net.wimpi.modbus.ModbusIOException Executing transaction failed (tried 3 times)
02:00:24.380 [ERROR] [odbus.handler.ModbusDataThingHandler] - Thing modbus:data:1eb052bc ‘AX8’ had error on read: net.wimpi.modbus.ModbusIOException Executing transaction failed (tried 3 times). Stack trace follows for unexpected errors.
net.wimpi.modbus.ModbusIOException: Executing transaction failed (tried 3 times)
at net.wimpi.modbus.io.ModbusTCPTransaction.execute(ModbusTCPTransaction.java:220)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]
at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.executeOneTimePoll(ModbusManagerImpl.java:493)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]
at org.openhab.io.transport.modbus.internal.ModbusManagerImpl.lambda$19(ModbusManagerImpl.java:545)[222:org.openhab.io.transport.modbus:2.2.0.201709291832]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)[:1.8.0_121]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)[:1.8.0_121]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
02:00:24.399 [INFO ] [me.event.ThingStatusInfoChangedEvent] - ‘modbus:data:1eb052bc’ changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Error with read: net.wimpi.modbus.ModbusIOException: Executing transaction failed (tried 3 times)

[rossko57]

Note that Modbus v2 is still at alpha stage (early) of development.
Hence no proper docs yet.
See Modbus openHAB2 binding available for alpha testing

As a new OpenHAB user, you face a difficult choice of using this new version, or the older better documented version 1.
Having said that, it does look like some fairly basic error if you cannot read at all.

[peteroakes (Peter Oakes (The Breadboardca))]

@rossko57

Having said that, it does look like some fairly basic error if you cannot read at all.

So trying to be very polite… I can read, I have read and if the actual problem is being shown in the above posts, I am not seeing it (Sorry but I am not). It would be more helpful to provide help rather than suggest I can not read at all (This is how I am seeing your response to me, I hope I am wrong.). If the “Basic” error is not in my above posts and you happen to know where the answer may be then a simple link to it would be a nice and friendly way to respond. I am not an idiot, there is a huge amount of information to read and absorb and as a new user to OpenHAB I expect to be treated a little better. If I am interpreting your response incorrectly then I apologize but your response is not helpful and seems rather condescending.

Regards
Peter

[rlkoshak (Rich Koshak)]

@peteroakes

So trying to be very polite… I can read,…

I’m pretty certain @rossko57 meant that if the binding cannot read at all, not that you are not reading the available information.

I think he means that there must be some sort of basic configuration error that is preventing the binding from doing much of anything at all, including reading from the bus. So I would focus on your basic configs for the binding. rossko is one of the better mobus helpers on the forum so if he doesn’t provide more info it probably means it isn’t clear to him what the problem is either.

As a new openHAB user, I think you would be wise to heed his advice and use the 1.x version of the binding. There is enough to learn and struggle through to get OH up and running without trying to figure out how to use an Alpha binding that lacks proper documentation. I’ve been using OH for years now and I’m not sure I would tackle running a binding that is this immature unless I had a really solid grasp of the underlying technology and OH internals.

Generally, with very few exceptions, everyone on this forum is very helpful and friendly so if you ever feel you have received an unhelpful and unfriendly response it is highly likely either you misunderstood or the poster is having a really bad day (we all get grumpy sometimes). The bulk of users and posters on this forum are European so language barriers can often be a problem as well.

I know nothing of this binding so I can’t help much beyond smoothing down some ruffled feathers.

Good luck!

[peteroakes (Peter Oakes (The Breadboardca))]

OK, Then misunderstanding on my side, sorry about that @rossko57, I was getting very frustrated with the whole process. I will heed the advice and revert back to the OH1 version of the modbus driver. I did try this on OH2 prior to the alpha version and also did not have any luck, I am starting to think it may be worth reverting back to a clean install and start fresh, incase I messed up other things that could be interfering with the bus.

Thanks for the prompt feedback and clarification

Regards
Peter

[ssalonen]

Hello I’m the guy working on the new binding version.

I would definitely agree with @rlkoshak and @rossko57: go with the stable binding for now. The alpha binding is, well, alpha and at this stage for users who are willing to experiment the new binding and are experienced with openHAB and troubleshooting bindings in general. At this stage errors are expected for sure.

Actually you are using a feature (data thing) that I have not yet documented on the thread since it’s still unstable. The only document for the alpha exists in the form of examples on that thread.

Best,
Sami

[rossko57]

No offence taken, I should and could have been clearer.

The massive flexibity of OpenHAB leads to that first step being huge, I remember that initial frustration. I’m still on OH1 (not least because I’m using Modbus heavily in a commercial environment) and not looking forward to going OH2.

There’s a lot of fiddly things to get just right for the first real, personal, result. We all come in with fairly grand ambitions, beyond ‘turn on light when dark’, else we wouldn’t be looking at an automation system. But persist !! Tinker, fail, step away and come back … worth it in the end.

Practical advice, yep don’t be afraid to trash and start over at this stage.

Forum search I expect you have already found - but don’t miss the opportunity to read apparently unrelated threads. I have obtained ideas and insights from doing that.

[peteroakes (Peter Oakes (The Breadboardca))]

Thought I would provide an update
I got OH2 up and running with modbus using the earlier binding modules along with manual config of .items and .things

when the new bindings are ready, i’ll be happy to try them out.

Now i’m trying to get the wemo bindings to work with some esp8266 based wemo emulator code, no joy so far, there seems to be reports of binding issues with the current version. I raised a separate question to that though as not appropriate here.

Thanks for the help.