Installed the MQTT Binding, but no mqtt.cfg file

Lane_Smith · June 20, 2017, 6:30am

Howdy!

I’m having a challenge, and after several hours of scouring the forum, I’ve decided I should probably speak up.

Brand new install on Ubuntu 16.04.2 LTS
Mosquitto v1.4.8 installed, and operational, validated using MQTT.fx. I am able to publish messages without issue.
In OH2, added the MQTT Binding add-on (binding-mqtt1 - 1.9.0), which on the screen says installed.
Navigated to /etc/openhab2/services, and mqtt.cfg does not exist. The only files in the folder are addons.cfg, readme.txt and runtime.cfg

So, tried another … Chromecast Binding (binding-chromecast - 2.0.0). Nothing for that one either, although not certain if there is a .cfg file for that.

Little lost - thoughts?

Dim · June 20, 2017, 6:44am

that’s not a good sign… the mqtt.cfg (and mqtt-eventbus.cfg) should have been auto-created (in /etc/openhab2/services/) when you installed the MQTT binding

Try to re-install the MQTT binding and check the log (/var/log/openhab2) for any errors.
Which release of OH2 are you running? (check /var/lib/openhab2/etc/version.properties)

For the Chromecast: It’s a 2.x binding, so normally you will not need a *.cfg file… On the other hand… the docs entry talks about a strange services.cfg file… (without mentioning where it should be placed). We may need to clarify the docs entry.

Lane_Smith · June 20, 2017, 6:52am

No logs at all in /var/log/openhab2:

Downloaded the stable release today via apt-get"

openHAB Distribution Version Information

build-no : Release Build
online-repo : https://dl.bintray.com/openhab/mvn/online-repo/2.0

Repository Version

openhab-distro : 2.0.0
smarthome : 0.9.0.b4
openhab-core : 2.0.0
openhab1-addons : 1.9.0
openhab2-addons : 2.0.0
karaf : 4.0.8

Dim · June 20, 2017, 6:53am

output of java -version ?
seems like OH2 process can’t write to the filesystem (due to some reason that we need to identify)
Dir permissions look good (owned by openhab:openhab)

Lane_Smith · June 20, 2017, 6:54am

Should I go with the Oracle proper version of Java, instead of OpenJDK?

Dim · June 20, 2017, 6:55am

Yeah, try Oracle to see if things improve
or even better: Zulu

Lane_Smith · June 20, 2017, 7:03am

Installed Oracle Java 8:

java -version
java version "1.8.0_131"
Java™ SE Runtime Environment (build 1.8.0_131-b11)
Java HotSpot™ 64-Bit Server VM (build 25.131-b11, mixed mode)

Rebooted server (just to make sure)
Reinstalled the MQTT Binding in OH
Checked /etc/openhab2/services/ … no mqtt.cfg

Lane_Smith · June 20, 2017, 7:05am

All sub-directories are set to 755, with openhab set as the user/owner, so writing shouldn’t be an issue.

Dim · June 20, 2017, 7:11am

strange…
I don’t have any suggestions at the moment…
Also, since your java -version is showing Oracle, then your system is configured to use it as default (you can check with update-alternatives --config java)

Maybe try to start OH2 in debug mode: /usr/share/openhab2/start_debug.sh to see if something useful is posted?

Lane_Smith · June 20, 2017, 7:20am

Started debug mode … there is now an events.log and openhab.log. Viewing events.log shows an empty file, however openhab.log has a few entries.

Lane_Smith · June 20, 2017, 7:24am

    2017-06-20 01:14:34.278 [WARN ] [tty.util.component.AbstractLifeCycle] - FAILED 0.0.0.0:8443@49129fad{SSL-http/1.1}{0.0.0.0:8443}: java.net.BindException: Address already in use

java.net.BindException: Address already in use
at sun.nio.ch.Net.bind0(Native Method)[:1.8.0_131]
at sun.nio.ch.Net.bind(Net.java:433)[:1.8.0_131]
at sun.nio.ch.Net.bind(Net.java:425)[:1.8.0_131]
at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223)[:1.8.0_131]
at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74)[:1.8.0_131]
at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:321)
at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80)
at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:236)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.eclipse.jetty.server.Server.doStart(Server.java:366)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68)
at org.ops4j.pax.web.service.jetty.internal.JettyServerImpl$1.start(JettyServerImpl.java:287)
at org.ops4j.pax.web.service.internal.HttpServiceStarted.registerServlet(HttpServiceStarted.java:222)
at org.ops4j.pax.web.service.internal.HttpServiceStarted.registerServlet(HttpServiceStarted.java:196)
at org.ops4j.pax.web.service.internal.HttpServiceStarted.registerServlet(HttpServiceStarted.java:180)
at org.ops4j.pax.web.service.internal.HttpServiceProxy.registerServlet(HttpServiceProxy.java:64)
at org.openhab.ui.dashboard.internal.DashboardService.activate(DashboardService.java:55)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)[:1.8.0_131]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)[:1.8.0_131]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)[:1.8.0_131]
at java.lang.reflect.Method.invoke(Method.java:498)[:1.8.0_131]
at org.apache.felix.scr.impl.inject.BaseMethod.invokeMethod(BaseMethod.java:224)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.inject.BaseMethod.access$500(BaseMethod.java:39)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.inject.BaseMethod$Resolved.invoke(BaseMethod.java:617)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.inject.BaseMethod.invoke(BaseMethod.java:501)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.inject.ActivateMethod.invoke(ActivateMethod.java:302)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.inject.ActivateMethod.invoke(ActivateMethod.java:294)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.SingleComponentManager.createImplementationObject(SingleComponentManager.java:297)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.SingleComponentManager.createComponent(SingleComponentManager.java:108)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getService(SingleComponentManager.java:906)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.SingleComponentManager.getServiceInternal(SingleComponentManager.java:879)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.AbstractComponentManager.activateInternal(AbstractComponentManager.java:748)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:1012)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.DependencyManager$SingleStaticCustomizer.addedService(DependencyManager.java:968)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1215)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.customizerAdded(ServiceTracker.java:1136)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.trackAdding(ServiceTracker.java:945)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.ServiceTracker$AbstractTracked.track(ServiceTracker.java:881)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.manager.ServiceTracker$Tracked.serviceChanged(ServiceTracker.java:1167)[32:org.apache.felix.scr:2.0.6]
at org.apache.felix.scr.impl.BundleComponentActivator$ListenerInfo.serviceChanged(BundleComponentActivator.java:127)[32:org.apache.felix.scr:2.0.6]
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:]

Dim · June 20, 2017, 7:25am

/etc/default/openhab2

# openHAB 2 service configuration
# 
# Additional options for the JAVA_OPTS environment variable.
# These will be appended to the execution of the openHAB Java runtime in front of all other options.
# 
# A couple of independent examples:
#   EXTRA_JAVA_OPTS="-Dgnu.io.rxtx.SerialPorts=/dev/ttyAMA0"
#   EXTRA_JAVA_OPTS="-Dgnu.io.rxtx.SerialPorts=/dev/ttyUSB0:/dev/ttyS0:/dev/ttyS2:/dev/ttyACM0:/dev/ttyAMA0"
#   EXTRA_JAVA_OPTS="-Djna.library.path=/lib/arm-linux-gnueabihf/ -Duser.timezone=Europe/Berlin -Dgnu.io.rxtx.SerialPorts=/dev/ttyS0"
EXTRA_JAVA_OPTS=""

# The ports openHAB will bind its HTTP/HTTPS web server to.
OPENHAB_HTTP_PORT=8081
OPENHAB_HTTPS_PORT=8444

Lane_Smith · June 20, 2017, 7:31am

No comments, just the EXTRA_JAVA_OPTS="" line.

The server is defaulted to port 8080, but I could add the line to change that.

Dim · June 20, 2017, 7:31am

maybe tomcat with ssl is installed?
check what process is using tcp 8443

lsof -i:8443
netstat -nlp|grep 8443

Dim · June 20, 2017, 7:32am

yeah, add the 2 lines to change the default ports that OH2 will listen to

OPENHAB_HTTP_PORT=8081
OPENHAB_HTTPS_PORT=8444

Ps: The extra comments were added in the file during a recent snapshot (I think S#947)

Dim · June 20, 2017, 7:40am

On the other hand… that’s only a warning… (that OH2 couldn’t bring up its https interface)
You were able to run PaperUI on 8080 (so that was free) and install the MQTT binding…
so… something else is wrong…
anyway… fix the 8443 port issue and continue debugging

Lane_Smith · June 20, 2017, 7:51am

Ok … tomcat8 is now installed and the service is running … attempted to install MQTT Binding via PaperUI, still no go.

Here’s something, though … so I appended the two additional lines for ports 8081 and 8444, saved the file, restarted services, and both ports 8080 and 8443 are still active, and 8081/8444 don’t do a thing.

Lane_Smith · June 20, 2017, 7:52am

BTW … I appreciate you taking the time with me this early in the morning.

Dim · June 20, 2017, 7:55am

you don’t need tomcat. I asked if it was installed to see if that process was taking up tcp 8443 (that would cause the warning {0.0.0.0:8443}: java.net.BindException: Address already in use)
If you stop OH2 service: do you see any other process using tcp 8443?

ok… strange…
I have to check if there is any difference for Ubuntu (I use Debian)…
it should have worked

Any other useful info in the openhab.log when you start the service using start_debug.sh?

I am not sure if start_debug.sh will use /etc/defaults/openhab2
Maybe try: systemctl start openhab2 to make sure that it will use the file

Lane_Smith · June 20, 2017, 8:01am

openhab services stopped … validated web interface no longer active on both 8080 and 8443, and reports the same with lsof and netstat.

Started the service, only ports 8080 & 8443 are accessible.

FYI … I am maxed out on the number of entries I’m allowed to post, and the forum is blocking me from adding new replies for 22 hours.

cool … thx!

I’ll purge everything (not losing anything at the moment) and install the current snapshot to see if that helps, then will report.

[…]
Well … that didn’t work … after uninstalling and installing the snapshot, the service is now failing to start.

    root@TigieMontana:/etc/default# systemctl status openhab2.service
● openhab2.service - openHAB 2 - empowering the smart home
   Loaded: loaded (/usr/lib/systemd/system/openhab2.service; enabled; vendor preset: enabled)
   Active: inactive (dead) (Result: exit-code) since Tue 2017-06-20 02:31:44 MDT; 2s ago
     Docs: http://docs.openhab.org
           https://community.openhab.org
  Process: 13096 ExecStop=/usr/share/openhab2/runtime/bin/stop (code=exited, status=3)
  Process: 12887 ExecStart=/usr/share/openhab2/start.sh server (code=exited, status=255)
 Main PID: 12887 (code=exited, status=255)

Jun 20 02:31:44 TigieMontana systemd[1]: openhab2.service: Unit entered failed state.
Jun 20 02:31:44 TigieMontana systemd[1]: openhab2.service: Failed with result 'exit-code'.
Jun 20 02:31:44 TigieMontana systemd[1]: openhab2.service: Service hold-off time over, scheduling
Jun 20 02:31:44 TigieMontana systemd[1]: Stopped openHAB 2 - empowering the smart home.
Jun 20 02:31:44 TigieMontana systemd[1]: openhab2.service: Start request repeated too quickly.
Jun 20 02:31:44 TigieMontana systemd[1]: Failed to start openHAB 2 - empowering the smart home.

I think we have success!

                      __  _____    ____

____ ____ ___ ____ / / / / | / __ )
/ __ / __ / _ / __ / // / /| | / __ |
/ // / // / __/ / / / __ / ___ |/ // /
_/ ./_// /// /// |/___/
/_/ 2.1.0-SNAPSHOT
Build #958

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:38:12.926 [INFO ] [.dashboard.internal.DashboardService] 02:38:12.928 [INFO ] [.dashboard.internal.DashboardService] 02:39:06.997 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.536 [INFO ] [ui.habmin.internal.servlet.HABminApp] 02:39:09.567 [INFO ] [arthome.ui.paper.internal.PaperUIApp] 02:39:09.589 [INFO ] [assic.internal.servlet.WebAppServlet] 02:39:09.664 [INFO ] [basic.internal.servlet.WebAppServlet] 02:39:09.684 [INFO ] [panel.internal.HABPanelDashboardTile] 02:39:09.705 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.706 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.706 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.707 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.708 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.708 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.709 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.709 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.710 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.710 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.711 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.711 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.711 [INFO ] [smarthome.event.ExtensionEvent 02:39:09.712 [INFO ] [smarthome.event.ExtensionEvent 02:39:48.640 [INFO ] [smarthome.event.ExtensionEvent - Started dashboard at http://192.168 .1.29:8080
- Started dashboard at https://192.16 8.1.29:8443
] - Extension ‘package-expert’ has been installed.
- Started HABmin servlet at /habmin
- Started Paper UI at /paperui
- Started Classic UI at /classicui/app
- Started Basic UI at /basicui/app
- Started HABPanel at /habpanel
] - Extension ‘misc-restdocs’ has been installed.
] - Extension ‘transformation-javascript’ has been installed.
] - Extension ‘ui-habpanel’ has been installed.
] - Extension ‘transformation-regex’ has been installed.
] - Extension ‘transformation-jsonpath’ has been installed.
] - Extension ‘transformation-xpath’ has been installed.
] - Extension ‘ui-basic’ has been installed.
] - Extension ‘transformation-map’ has been installed.
] - Extension ‘ui-classic’ has been installed.
] - Extension ‘ui-habmin’ has been installed.
] - Extension ‘transformation-xslt’ has been installed.
] - Extension ‘transformation-exec’ has been installed.
] - Extension ‘ui-paper’ has been installed.
] - Extension ‘transformation-scale’ has been installed.
] - Extension ‘binding-mqtt1’ has been installed.

root@TigieMontana:/etc/openhab2/services# ls
addons.cfg  mqtt.cfg  mqtt-eventbus.cfg  readme.txt  runtime.cfg

Checking the file now …

Woohoo! I think we got it. Interesting how the stable release was challenging, but the the current snapshot was the ticket. Oh well … its operational, which is great.

Thank you very much for your help walking through this. I really appreciate your time.