MQTT connection lost

Setup, Configuration and Use Items & Sitemaps
, ,
41

is anything useful in the logs?
read again the examples that Thom posted, You will get it working :slight_smile: (it seems that your broker connection is fine)

2 Likes
42 
[19:34:12] openhabian@openHABianPi:~$ lsof -i :1883
COMMAND    PID       USER   FD   TYPE DEVICE SIZE/OFF NODE NAME
mosquitto 7105 openhabian    5u  IPv4  21570      0t0  TCP localhost:40962->localhost:1883 (ESTABLISHED)

ANYWAYā€¦ these are my settings now:
sonoff device TH16:

Client Id = OH2MQTT
Topic = sonoff1
Full Topic = tasmota/%topic%/%prefix%/

items (according to @ThomDietrich) :

Switch Sonoff_TH16A "Balkonmagic" <stars>                            {mqtt=">[broker:tasmota/sonoff1/cmnd/POWER:command:*:default], <[broker:tasmota/sonoff1/stat/POWER:state:default]"}
Number Sonoff_TH16A_RSSI "Balkonmagic RSSI [%d %%]"                  {mqtt="<[broker:tasmota/sonoff1/tele/STATE:state:JSONPATH($.Wifi.RSSI)]"}
Switch Sonoff_TH16A_Unreach "Balkonmagic unreachable" <siren>        {mqtt="<[broker:tasmota/sonoff1/tele/LWT:state:MAP(unreach.map)]"}

mqtt.cfg:

[19:59:02] openhabian@openHABianPi:~$ cat /var/lib/openhab2/config/org/openhab/mqtt.config
mqttson.clientId="openHAB2"
mqttson.url="tcp://127.0.0.1:1883"
service.pid="org.openhab.mqtt"
43

Soā€¦ does it work?

I feel like both of you should take a step back. For example in your posting the Client Id = OH2MQTT - Do you know what this means? Your setting doesnā€™t make much sense, you should just leave it with the default setting. Next your Topic setting. Why sonoff1? Give it a meaningful name, something you can work with (e.g. ā€œsonoff-coridorlightā€, not too long, no underscore).

Your config looks okay. You can again leave the client Id setting commented to have a random one generated. I take it your broker doesnā€™t provide authentication? If youā€™ve installed via openHABian setting up a password was offered.

After youā€™ve set up everything do a test. You could for example restart openHAB, then wait a few minutes and check the log for mqtt related messages. It should look similar to this one:

[21:22:34] openhabian@openHABianPiW66a:~$ grep "mqtt" /var/log/openhab2/*
/var/log/openhab2/openhab.log:2017-07-22 16:52:19.891 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
/var/log/openhab2/openhab.log:2017-07-22 16:52:19.893 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'broker'
/var/log/openhab2/openhab.log:2017-07-22 17:13:40.344 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
/var/log/openhab2/openhab.log:2017-07-22 17:15:24.084 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
/var/log/openhab2/openhab.log:2017-07-22 17:15:24.089 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'broker'
1 Like
44

sadly it doesnā€™t
Client id - itā€™s the id iā€™m connecting to the broker?
which part of the (mqtt.cfg?) setting doesnā€™t make sense?

why sonoff1? because for now i have two sonoff devices and this is the one iā€™m testing with (iā€™m not sure where the device will be installed later, so a meaningful name would be hard to find)

[quote=ā€œThomDietrich, post:43, topic:31993ā€]
Your config looks okay. You can again leave the client Id setting commented to have a random one generated. I take it your broker doesnā€™t provide authentication? If youā€™ve installed via openHABian setting up a password was offered.
[/quote]didnā€™t set auth.

reboot:

2017-07-22 23:28:41.980 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to broker was lost
        at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:146)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:65)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:107)[197:org.openhab.io.transport.mqtt:1.10.0]
2017-07-22 23:28:41.998 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to 'mqttson' was lost: Connection lost : ReasonCode 32109 : Cause : null
2017-07-22 23:28:41.999 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting connection helper to periodically try restore connection to broker 'mqttson'
2017-07-22 23:28:52.002 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'
2017-07-22 23:28:55.288 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 23:28:55.289 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'mqttson'
2017-07-22 23:30:04.375 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2017-07-22 23:30:04.387 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'
45

2 mqtt broker configsā€¦ did you clear /var/lib/openhab2/config/org/openhab/mqtt.config ?

46

Since when is this really needed? I personally never had to mess around in these filesā€¦

47
48

followed these steps (again), same result:

2017-07-23 09:11:55.159 [ERROR] [penhab.io.transport.mqtt.MqttService] - Error starting broker connection
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:79)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.ClientComms$ConnectBG.run(ClientComms.java:650)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.TCPNetworkModule.start(TCPNetworkModule.java:70)[197:org.openhab.io.transport.mqtt:1.10.0]
2017-07-23 09:12:13.307 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:12:13.308 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'mqttson'
2017-07-23 09:13:21.659 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2017-07-23 09:13:21.664 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'

ā€˜brokerā€™ is stopping, but it never startsā€¦?

[09:18:39] openhabian@openHABianPi:~$ grep "'broker'" /var/log/openhab2/openhab.log
2017-07-21 22:56:56.786 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-21 23:02:55.915 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 09:01:43.367 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 12:30:50.985 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 20:03:20.494 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 23:28:55.288 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:09:37.596 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:12:13.307 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
49

I have never seen this beforeā€¦

question: do you have the MQTT Action addon installed?

check something please within the openHAB console (post the output):

config:list "(service.pid=org.openhab.mqtt)"
config:list |grep -i broker

You can also increase the log levels to see if something more interesting comes up:

log:set TRACE org.openhab.binding.mqtt
log:set TRACE org.openhab.io.transport.mqtt
50

yes. just uninstalled it, but log doesnā€™t change.

[quote]check something please within the openHAB console (post the output):

config:list "(service.pid=org.openhab.mqtt)"
config:list |grep -i broker
```[/quote]

```php
openhab> config:list "(service.pid=org.openhab.mqtt)"
----------------------------------------------------------------
Pid:            org.openhab.mqtt
BundleLocation: mvn:org.openhab.io/org.openhab.io.transport.mqtt/1.10.0
Properties:
   mqttson.clientId = openHAB2
   mqttson.url = tcp://127.0.0.1:1883
   service.pid = org.openhab.mqtt
openhab> config:list |grep -i broker
openhab>

done

51

restart OH2 after uninstalling the action addon.
There is a logged bug about this (the action addon does not allow the mqtt binding to connect)
this doesnā€™t explain the 2nd connectionā€¦ and I donā€™t see anything wrong in your console outputā€¦ still a mystery where this 2nd connection is coming fromā€¦

52

by the way: you can always try theā€¦ nuclear optionā€¦ :stuck_out_tongue:

backup configs, purge (press the red button), re-install, restore configs :slight_smile:

1 Like
53

log was the same after reboot.
now reboot with TRACE log:

2017-07-23 09:35:43.476 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to broker was lost
        at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:146)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.wire.MqttInputStream.readMqttWireMessage(MqttInputStream.java:65)[197:org.openhab.io.transport.mqtt:1.10.0]
        at org.eclipse.paho.client.mqttv3.internal.CommsReceiver.run(CommsReceiver.java:107)[197:org.openhab.io.transport.mqtt:1.10.0]
2017-07-23 09:35:43.490 [ERROR] [t.mqtt.internal.MqttBrokerConnection] - MQTT connection to 'mqttson' was lost: Connection lost : ReasonCode 32109 : Cause : null
2017-07-23 09:35:43.492 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting connection helper to periodically try restore connection to broker 'mqttson'
2017-07-23 09:35:53.498 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'
2017-07-23 09:35:56.595 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent UNREGISTERING - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.mqtt.MqttBindingProvider}={component.name=org.openhab.binding mqtt.genericbindingprovider, component.id=178, service.id=314, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:35:56.597 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent UNREGISTERING - {org.osgi.service.event.EventHandler}={event.topics=openhab/*, component.name=org.openhab.binding.mqtt.MqttItemBinding, component.id=180, service.id=315, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:35:56.604 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent UNREGISTERING - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService}={event.topics=openhab/*, service.pid=org.openhab.mqtt-eventbus, component.name=org.openhab.binding.mqtt.eventbus, component.id=179, service.id=317, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:35:56.609 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STOPPING - org.openhab.binding.mqtt
2017-07-23 09:35:56.610 [DEBUG] [.binding.mqtt.internal.MqttActivator] - MQTT binding has been stopped.
2017-07-23 09:35:56.611 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STOPPED - org.openhab.binding.mqtt
2017-07-23 09:35:56.632 [DEBUG] [org.openhab.io.transport.mqtt       ] - ServiceEvent UNREGISTERING - {org.openhab.io.transport.mqtt.MqttService, org.osgi.service.cm.ManagedService}={service.pid=org.openhab.mqtt, component.name=MQTT Connection Service, component.id=192, service.id=313, service.bundleid=197, service.scope=bundle} - org.openhab.io.transport.mqtt
2017-07-23 09:35:56.634 [DEBUG] [penhab.io.transport.mqtt.MqttService] - Stopping MQTT Service...
2017-07-23 09:35:56.635 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:35:56.635 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Closing connection to broker 'broker'
2017-07-23 09:35:56.636 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'mqttson'
2017-07-23 09:35:56.636 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Closing connection to broker 'mqttson'
2017-07-23 09:35:56.637 [DEBUG] [penhab.io.transport.mqtt.MqttService] - MQTT Service stopped.
2017-07-23 09:35:56.639 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STOPPING - org.openhab.io.transport.mqtt
2017-07-23 09:35:56.641 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STOPPED - org.openhab.io.transport.mqtt



2017-07-23 09:37:04.318 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STARTING - org.openhab.binding.mqtt
2017-07-23 09:37:04.332 [DEBUG] [.binding.mqtt.internal.MqttActivator] - MQTT binding has been started.
2017-07-23 09:37:04.334 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STARTED - org.openhab.binding.mqtt
2017-07-23 09:37:05.318 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STARTING - org.openhab.io.transport.mqtt
2017-07-23 09:37:05.350 [DEBUG] [penhab.io.transport.mqtt.MqttService] - Starting MQTT Service...
2017-07-23 09:37:05.380 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler}={event.topics=openhab/*, component.name=org.openhab.binding.mqtt.MqttItemBinding, component.id=180, service.id=315, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:37:05.386 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.mqtt.MqttBindingProvider}={component.name=org.openhab.binding.mqtt.genericbindingprovider, component.id=178, service.id=314, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:37:05.412 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A' : 1 subscribers, 1 publishers
2017-07-23 09:37:05.458 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A_RSSI' : 1 subscribers, 0 publishers
2017-07-23 09:37:05.466 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A_Unreach' : 1 subscribers, 0 publishers
2017-07-23 09:37:05.486 [DEBUG] [ng.mqtt.internal.MqttEventBusBinding] - MQTT: Activating event bus binding.
2017-07-23 09:37:05.492 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService}={event.topics=openhab/*, service.pid=org.openhab.mqtt-eventbus, component.name=org.openhab.binding.mqtt.eventbus, component.id=179, service.id=317, service.bundleid=193, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 09:37:05.506 [DEBUG] [org.openhab.io.transport.mqtt       ] - ServiceEvent REGISTERED - {org.openhab.io.transport.mqtt.MqttService, org.osgi.service.cm.ManagedService}={service.pid=org.openhab.mqtt, component.name=MQTT Connection Service, component.id=192, service.id=313, service.bundleid=197, service.scope=bundle} - org.openhab.io.transport.mqtt
2017-07-23 09:37:05.510 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STARTED - org.openhab.io.transport.mqtt
2017-07-23 09:37:05.524 [TRACE] [ng.mqtt.internal.MqttEventBusBinding] - No mqtt-eventbus properties configured.
2017-07-23 09:37:05.530 [TRACE] [penhab.io.transport.mqtt.MqttService] - Processing property: mqttson.clientId = openHAB2
2017-07-23 09:37:05.531 [TRACE] [penhab.io.transport.mqtt.MqttService] - Processing property: mqttson.url = tcp://127.0.0.1:1883
2017-07-23 09:37:05.533 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2017-07-23 09:37:05.539 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - No url defined for MQTT broker connection 'broker'. Not starting.
2017-07-23 09:37:05.540 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'
2017-07-23 09:37:05.561 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Creating new client for 'tcp://127.0.0.1:1883' using id 'openHAB2' and file store '/var/lib/openhab2/tmp/mqttson'

nuking is not an option. cost me a day last time to get it working again :frowning:

1 Like
54

soā€¦ no messages regarding Starting MQTT broker connection for the 2nd ā€˜brokerā€™ config in the log? (only for the correct ā€˜mqttsonā€™)?
ā€¦butā€¦ logged messages about Stopping broker connection for the 2nd ā€˜brokerā€™ configā€¦

while nothing shows up as trying to start a 2nd connection (and no configs can be found)ā€¦ this 2nd connection is actually initiated from openHAB towards the broker that conflicts with the correct 1st one, not allowing the binding to establish a correct linkā€¦ so nothing MQTT related worksā€¦

I am about to give up :stuck_out_tongue:

Last try:
stop OH2
clear the contents of your /var/lib/openhab2/tmp/ & /var/lib/openhab2/cache/ dirs
start OH2

rm -ir /var/lib/openhab2/tmp/*
rm -ir /var/lib/openhab2/cache/*

Ps: Is there a /var/lib/openhab2/tmp/broker dir in there?

55

except this one:

2017-07-23 09:37:05.539 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - No url defined for MQTT broker connection 'broker'. Not starting.

no:

[09:53:44] root@openHABianPi:/var/lib/openhab2/tmp# ls -l
total 48
drwxrwxr-x+ 2 openhab openhabian 4096 Jun 30 20:34 bundles
drwxrwxr-x+ 2 openhab openhab    4096 Jul 14 13:17 fileinstall-2615193637295698452
drwxrwxr-x+ 2 openhab openhab    4096 Jul 14 13:17 fileinstall--4291590336138452965
drwxrwxr-x+ 2 openhab openhab    4096 Jul 13 13:44 fileinstall--610255515732200476
drwxrwxr-x+ 2 openhab openhabian 4096 Jun 30 22:45 fileinstall-9003287045152143424
-rw-rw-r--  1 openhab openhabian    3 Jul 23 09:36 karaf.pid
drwxrwxr-x+ 2 openhab openhabian 4096 Jul 23 09:37 libNRJavaSerial_HF_openhab_0
-rw-rw-r--  1 openhab openhabian    0 Jun 30 20:34 lock
drwxrwxr-x+ 2 openhab openhab    4096 Jul 20 22:47 mqtt1
drwxrwxr-x+ 2 openhab openhabian 4096 Jul 20 22:53 mqtt_service
drwxrwxr-x+ 2 openhab openhab    4096 Jul 23 09:53 mqttson
drwxrwxr-x+ 3 openhab openhabian 4096 Jun 30 20:36 mvn
-rw-rw-r--  1 openhab openhabian    5 Jul 23 09:36 port

omg - how many times do i have to confirm the remove command???

56

:slight_smile: I gave you the safe optionā€¦

there is always the dangerous option (not interactiveā€¦ but force remove)

rm -rf /var/lib/openhab2/tmp/*
rm -rf /var/lib/openhab2/cache/*
57

where in HELL is this config stored??? I canā€™t understand where this is coming fromā€¦

Is there any other kind of config file that holds such info (about a broker) in your /etc/openhab2/ subfolders?

cd /etc/openhab2/services/
grep broker *

check also the other subfolders

another trick would be to set the url configuration property manually for this broker guy:

config:property-set -p org.openhab.mqtt broker.url tcp://192.168.0.1:1883
or try
config:property-delete -p org.openhab.mqtt broker.url

(basically, set a fake url for this guy so he doesnā€™t cause errors)

but this doesnā€™t make sense since this config property didnā€™t show up when you did config:list

58

alright, removed everything in those to folders and rebooted:

2017-07-23 10:04:01.099 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent INSTALLED - org.openhab.binding.mqtt
2017-07-23 10:04:01.546 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent INSTALLED - org.openhab.io.transport.mqtt
2017-07-23 10:04:06.346 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent RESOLVED - org.openhab.io.transport.mqtt
2017-07-23 10:04:06.352 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent RESOLVED - org.openhab.binding.mqtt
2017-07-23 10:04:06.888 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent [unknown:512] - org.openhab.io.transport.mqtt
2017-07-23 10:04:07.273 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STARTING - org.openhab.io.transport.mqtt
2017-07-23 10:04:07.284 [DEBUG] [org.openhab.io.transport.mqtt       ] - BundleEvent STARTED - org.openhab.io.transport.mqtt
2017-07-23 10:04:07.295 [DEBUG] [penhab.io.transport.mqtt.MqttService] - Starting MQTT Service...
2017-07-23 10:04:07.307 [TRACE] [penhab.io.transport.mqtt.MqttService] - Processing property: mqttson.clientId = openHAB2
2017-07-23 10:04:07.309 [DEBUG] [org.openhab.io.transport.mqtt       ] - ServiceEvent REGISTERED - {org.openhab.io.transport.mqtt.MqttService, org.osgi.service.cm.ManagedService}={service.pid=org.openhab.mqtt, component.name=MQTT Connection Service, component.id=151, service.id=281, service.bundleid=199, service.scope=bundle} - org.openhab.io.transport.mqtt
2017-07-23 10:04:07.319 [TRACE] [penhab.io.transport.mqtt.MqttService] - Processing property: mqttson.url = tcp://127.0.0.1:1883
2017-07-23 10:04:07.320 [INFO ] [penhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
2017-07-23 10:04:07.322 [INFO ] [t.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mqttson'
2017-07-23 10:04:07.355 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Creating new client for 'tcp://127.0.0.1:1883' using id 'openHAB2' and file store '/var/lib/openhab2/tmp/mqttson'
2017-07-23 10:04:13.229 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STARTING - org.openhab.binding.mqtt
2017-07-23 10:04:13.233 [DEBUG] [.binding.mqtt.internal.MqttActivator] - MQTT binding has been started.
2017-07-23 10:04:17.337 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A' : 1 subscribers, 1 publishers
2017-07-23 10:04:17.358 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A_RSSI' : 1 subscribers, 0 publishers
2017-07-23 10:04:17.362 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'Sonoff_TH16A_Unreach' : 1 subscribers, 0 publishers
2017-07-23 10:04:17.364 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.mqtt.MqttBindingProvider}={component.name=org.openhab.binding.mqtt.genericbindingprovider, component.id=177, service.id=305, service.bundleid=194, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 10:04:17.392 [DEBUG] [ng.mqtt.internal.MqttEventBusBinding] - MQTT: Activating event bus binding.
2017-07-23 10:04:17.397 [TRACE] [ng.mqtt.internal.MqttEventBusBinding] - No mqtt-eventbus properties configured.
2017-07-23 10:04:17.400 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService}={event.topics=openhab/*, service.pid=org.openhab.mqtt-eventbus, component.name=org.openhab.binding.mqtt.eventbus, component.id=178, service.id=307, service.bundleid=194, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 10:04:17.412 [DEBUG] [org.openhab.binding.mqtt            ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler}={event.topics=openhab/*, component.name=org.openhab.binding.mqtt.MqttItemBinding, component.id=179, service.id=308, service.bundleid=194, service.scope=bundle} - org.openhab.binding.mqtt
2017-07-23 10:04:17.414 [DEBUG] [org.openhab.binding.mqtt            ] - BundleEvent STARTED - org.openhab.binding.mqtt
59

I have never seen a config about a binding being stored in these 2 folders (cache & tmp)ā€¦
this would be the first if the error goes away after clearing themā€¦

60

nothing with ā€˜brokerā€™ since reboot:

[10:15:58] openhabian@openHABianPi:/etc/openhab2$ grep "'broker'" /var/log/openhab2/openhab.log
2017-07-21 22:56:56.786 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-21 23:02:55.915 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 09:01:43.367 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 12:30:50.985 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 20:03:20.494 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-22 23:28:55.288 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:09:37.596 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:12:13.307 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:22:38.028 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:30:01.406 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:35:56.635 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:35:56.635 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Closing connection to broker 'broker'
2017-07-23 09:37:05.539 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - No url defined for MQTT broker connection 'broker'. Not starting.
2017-07-23 09:53:19.499 [INFO ] [penhab.io.transport.mqtt.MqttService] - Stopping broker connection 'broker'
2017-07-23 09:53:19.500 [DEBUG] [t.mqtt.internal.MqttBrokerConnection] - Closing connection to broker 'broker'
1 Like