Need help to setup MQTT Binding on OH2

rajeev2301 · June 1, 2018, 3:50pm

Good Morning Vincent !

No, it did not work. Here is the OH log…

2018-06-01 08:46:44.929 [WARN ] [g.dispatch.internal.ConfigDispatcher] - The file /etc/openhab2/services/mqtt.cfg subsequently defines the exclusive PID 'org.$
2018-06-01 08:46:44.942 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2018-06-01 08:46:44.945 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'broker'
2018-06-01 08:46:44.980 [ERROR] [penhab.io.transport.mqtt.MqttService] - Error starting broker connection
org.eclipse.paho.client.mqttv3.MqttException: Unable to connect to server
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:79) [225:org.openhab.io.transport.mqtt:1.12.0.RC2]
        at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:650) [225:org.openhab.io.transport.mqtt:1.12.0.RC2]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.net.ConnectException: Connection refused
        at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:?]
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
        at java.net.Socket.connect(Socket.java:589) ~[?:?]
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:70) ~[?:?]
        ... 2 more

vzorglub · June 1, 2018, 3:51pm

reboot the pi

Dim · June 1, 2018, 3:56pm

it seems that mosquitto is not running (it doesn’t seem to be an auth issue)
it is strange but if you don’t put anything (port, listener, etc) in the config file for networking… it works (it uses the defaults)
(I saw this recently on my rPi3 after I upgraded mosquitto to v1.5)

try the following for mosquitto.conf:

persistence false
log_dest file /var/log/mosquitto/mosquitto.log
log_type information
connection_message true
log_timestamp true
allow_anonymous false
password_file /etc/mosquitto/pwfile

github.com/eclipse/mosquitto

Stopped working on Pi when running via systemctl

opened 12:56PM - 20 May 18 UTC

TotallyInformation

I've just updated my Pi which is running Rasbian Jesse. I guess I got v1.5 at th…at point. However, I now always get the following error when booting the Pi: ``` [Sun May 20 10:56:19 2018]: mosquitto version 1.5 starting [Sun May 20 10:56:19 2018]: Config loaded from /etc/mosquitto/mosquitto.conf. [Sun May 20 10:56:19 2018]: Opening ipv4 listen socket on port 1883. [Sun May 20 10:56:19 2018]: Opening ipv6 listen socket on port 1883. [Sun May 20 10:56:19 2018]: Opening websockets listen socket on port 9001. [Sun May 20 10:56:19 2018]: Opening ipv4 listen socket on port 1883. [Sun May 20 10:56:19 2018]: Error: Address already in use ``` As you can see, it appears to be trying to open twice. The result is that no connections are possible. I removed Mosquitto and reinstalled after manually deleting `/etc/mosquitto` and weirdly, I still get the same problem even though I now have no config file at all. When I run it manually from the command line, everything works as expected. Importantly, running without a config file means that I don't get websockets on port 9001 which is what I expect so I can't work out what is causing that configuration to still be active when running via systemctl. Confused!! Can anyone help? Thanks.

rajeev2301 · June 1, 2018, 4:03pm

@Vincent

I did reboot pi. Here is OH log

2018-06-01 08:57:55.755 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-06-01 08:57:56.953 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'MyHome.things'
2018-06-01 08:57:58.897 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-06-01 08:57:59.058 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-06-01 08:57:59.136 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-06-01 08:57:59.544 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2018-06-01 08:57:59.551 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'broker'
2018-06-01 08:57:59.826 [ERROR] [penhab.io.transport.mqtt.MqttService] - Error starting broker connection
org.eclipse.paho.client.mqttv3.MqttException: Unable to connect to server
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:79) [225:org.openhab.io.transport.mqtt:1.12.0.RC2]
        at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:650) [225:org.openhab.io.transport.mqtt:1.12.0.RC2]
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.net.ConnectException: Connection refused
        at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:?]
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
        at java.net.Socket.connect(Socket.java:589) ~[?:?]
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:70) ~[?:?]

Dim · June 1, 2018, 4:03pm

fix your mosquitto

vzorglub · June 1, 2018, 4:09pm

uninstall and purge mosquitto

sudo apt-get purge mosquitto
sudo reboot

Then follow:

STEP BY STEP
Don’t skip one thinking that you know better. You don’t

rajeev2301 · June 1, 2018, 4:11pm

Sure !

Let me uninstall and purge mosquitto.

Dim · June 1, 2018, 4:12pm

if you don’t fix your mosquitto.conf… you will spend all week here
comment out all networking options from the file and check if mosquitto is running
then, OH2 will connect (your mqtt.cfg is finally correct)

rajeev2301 · June 1, 2018, 4:16pm

sure !

Just another input for your guys while I am trying to install Mosquitto again… I had MQTT.fx runnning on another PC and till yesterday it was connecting properly with MQTT broker… In fact I was able to publish and subscribe messages…

Dim · June 1, 2018, 4:19pm

now… MQTT.fx cannot connect to the broker… correct?

rajeev2301 · June 1, 2018, 4:20pm

Yes

vzorglub · June 1, 2018, 4:23pm

mosquitto ist kaput!! the mosquitto is dead, we don’t really know what you have been trying to do to get it back up and running so I think it’s better that you start from scracth and remove it and re-install it

@Dim, what do you think?

rajeev2301 · June 1, 2018, 4:25pm

yes , that what i am doing now… I have purged mosquitto… and following the steps you mentioned in previous thread…

Dim · June 1, 2018, 4:29pm

There is an open issue with Eclipse with regards to Mosquitto v1.5 on Debian Jessie (most likely this is what Rajeev) is running

If you specify any networking options in the config file: It launches the default plus whatever you defined in your mosquitto.conf… causing a conflict and you get:

Error: Address already in use

Some people say that this can be fixed if you upgrade Jessie to Stretch which will update libwebsockets.so
There is no root-cause identified yet and no definite solution…

more info on:

rajeev2301 · June 1, 2018, 4:30pm

quick question:

I am using below source to download mosquitto. Do you have any other recommendation?

wget https://repo.mosquitto.org/debian/mosquitto-repo.gpg.key
sudo apt-key add mosquitto-repo.gpg.key
sudo wget http://repo.mosquitto.org/debian/mosquitto-stretch.list 
sudo -i
sudo apt-get install mosquitto
sudo apt-get install mosquitto-clients
sudo apt-get update

vzorglub · June 1, 2018, 4:33pm

Use the raspberry pi source
You don’t need:

wget https://repo.mosquitto.org/debian/mosquitto-repo.gpg.key
sudo apt-key add mosquitto-repo.gpg.key
sudo wget http://repo.mosquitto.org/debian/mosquitto-stretch.list

If you did that before you need to remove the repo from the repo list.
Sorry I got ahead of myself

The raspberry pi repository has mosquitto in it
Use that one as it is GUARANTEED to work with your pi

Who told you to install another repository for your rasperry pi is a twit.

Just do:

sudo apt-get update
sudo apt-get upgrade
sudo apt-get install mosquitto

Dim · June 1, 2018, 4:50pm

btw: @rajeev2301 is your Raspbian based on Debian Jessie (8) or Stretch (9)?

output of

lsb_release -a

rajeev2301 · June 1, 2018, 5:37pm

pi@raspberrypi:~ $ lsb_release -a

|Distributor ID:|Raspbian|
|---|---|
|Description:|Raspbian GNU/Linux 9.4 (stretch)|
|Release:|9.4|
|Codename:|stretch|

vzorglub · June 1, 2018, 5:43pm

sudo apt-get purge mosquitto
sudo reboot
...
sudo apt-get update
sudo apt-get upgrade
sudo apt-get install mosquitto

rajeev2301 · June 1, 2018, 6:56pm

I have reinstalled mosquitto

here is the log OH file and mosquitto log file.

2018-06-01 11:44:32.943 [INFO ] [er.internal.HomeBuilderDashboardTile] - Started Home Builder at /homebuilder
2018-06-01 11:44:34.546 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at http://192.XX.X.XX:8080
2018-06-01 11:44:34.550 [INFO ] [.dashboard.internal.DashboardService] - Started Dashboard at https://192.XX.X.XX:8443
2018-06-01 11:45:09.155 [WARN ] [el.core.internal.ModelRepositoryImpl] - Configuration model 'Myhome.items' has errors, therefore i$

2018-06-01 11:45:13.705 [INFO ] [thome.model.lsp.internal.ModelServer] - Started Language Server Protocol (LSP) service on port 5007
2018-06-01 11:45:14.899 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'MyHome.things'
2018-06-01 11:45:17.121 [INFO ] [basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
2018-06-01 11:45:17.311 [INFO ] [arthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
2018-06-01 11:45:17.516 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2018-06-01 11:45:17.523 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'broker'
2018-06-01 11:45:17.683 [INFO ] [panel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
2018-06-01 11:45:17.758 [ERROR] [penhab.io.transport.mqtt.MqttService] - Error starting broker connection
org.eclipse.paho.client.mqttv3.MqttException: Unable to connect to server
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:79) [205:org.openhab.io.transport.m$
        at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:650) [205:org.openhab.io.transport.mq$
        at java.lang.Thread.run(Thread.java:745) [?:?]
Caused by: java.net.ConnectException: Connection refused
        at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
        at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:?]
        at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:?]
        at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:?]
        at java.net.Socket.connect(Socket.java:589) ~[?:?]
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:70) ~[?:?]

Mosquitto log file

1527876354: Opening ipv6 listen socket on port 1883.
1527876424: New connection from 127.0.0.1 on port 1883.
1527876424: New client connected from 127.0.0.1 as paho51879022739 (c1, k60).
1527876760: New connection from ::1 on port 1883.
1527876760: New client connected from ::1 as mosqpub|1865-raspberryp (c1, k60, $
1527876760: Client mosqpub|1865-raspberryp disconnected.
1527876777: New connection from ::1 on port 1883.
1527876777: New client connected from ::1 as mosqsub|1876-raspberryp (c1, k60, $
1527876781: New connection from ::1 on port 1883.
1527876781: New client connected from ::1 as mosqpub|1877-raspberryp (c1, k60, $
1527876781: Client mosqpub|1877-raspberryp disconnected.
1527876809: Socket error on client mosqsub|1876-raspberryp, disconnecting.
1527876809: mosquitto version 1.5 terminating