[SOLVED] Mqtt (owntracks) not working since latest upgrade


I upgraded OH2 yesterday (SNAPSHOT on openhabian offline).

Unfortunately iphone positioning using mosquitto (owntracks) does not work anymore.

I have mqtt1, action-mqtt and owntracks (mqttitude1) installed, but I don’t see any mqtt related log entries anymore (before the upgrade everything was running fine).

First I thought it’s related to a missing tcp binding, which I did not place in addons.cfg before, but that didn’t help.

Something else I might have missed?

Even after setting the log in karaf to debug:
log:set DEBUG org.openhab.binding.mqttitude1
log:set DEBUG org.openhab.binding.mqttitude to be on the safe side :wink:

I don’t see any mqtt message except the update after reboot from the persistence.

So obviously mqttitude does not even start!?

I recognized that there is a mqttitude.cfg I did not need before, but this is obviously not the issue as I am working with Regions defined on my cell phone’s owntracks app…

For a short test I just copied org.openhab.binding.mqttitude-1.9.0-SNAPSHOT.jar

Which led to:

2016-12-26 14:00:10.395 [WARN ] [org.apache.felix.fileinstall        ] - A bundle with the same symbolic name (org.openhab.binding.mqttitude) and version ( is already installed.  Updating this bundle instead.
2016-12-26 14:00:10.465 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent STOPPING - org.openhab.binding.mqttitude
2016-12-26 14:00:10.491 [DEBUG] [org.openhab.binding.mqttitude       ] - ServiceEvent UNREGISTERING - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService}={event.topics=openhab/command/*, service.pid=org.openhab.mqttitude, component.name=org.openhab.binding.mqttitude, component.id=188, service.id=319, service.bundleid=238, service.scope=bundle} - org.openhab.binding.mqttitude
2016-12-26 14:00:10.513 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Deactivating Mqttitude binding
2016-12-26 14:00:10.516 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Unregistering Mqttitude consumer for owntracks/mosquitto/NCOs_iPhone/event (on mosquitto)
2016-12-26 14:00:10.519 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Unregistering Mqttitude consumer for owntracks/mosquitto/Donas_iPhone/event (on mosquitto)
2016-12-26 14:00:10.544 [DEBUG] [org.openhab.binding.mqttitude       ] - ServiceEvent UNREGISTERING - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.mqttitude.MqttitudeBindingProvider}={component.name=org.openhab.binding.mqttitude.genericbindingprovider, component.id=189, service.id=318, service.bundleid=238, service.scope=bundle} - org.openhab.binding.mqttitude
2016-12-26 14:00:10.602 [DEBUG] [qttitude.internal.MqttitudeActivator] - Mqttitude binding has been stopped.
2016-12-26 14:00:10.609 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent STOPPED - org.openhab.binding.mqttitude
2016-12-26 14:00:11.034 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent UNRESOLVED - org.openhab.binding.mqttitude
2016-12-26 14:00:11.092 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent UPDATED - org.openhab.binding.mqttitude
2016-12-26 14:00:11.450 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent RESOLVED - org.openhab.binding.mqttitude
2016-12-26 14:00:11.459 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent STARTING - org.openhab.binding.mqttitude
2016-12-26 14:00:11.467 [DEBUG] [qttitude.internal.MqttitudeActivator] - Mqttitude binding has been started.

So it was obviously already in place… :frowning:

After removing the jar from “addons” I can see mqttitude in the openhab.log:
2016-12-26 14:25:30.871 [DEBUG] [org.openhab.binding.mqttitude ] - BundleEvent INSTALLED - org.openhab.binding.mqttitude

And the services are registered on my mosquitto:

2016-12-26 14:25:47.820 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Registering Mqttitude consumer for owntracks/mosquitto/DNAs_iPhone/event (on mosquitto)
2016-12-26 14:25:47.837 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Registering Mqttitude consumer for owntracks/mosquitto/NCOs_iPhone/event (on mosquitto)

I did the same with binding.mqtt.jar and io.transport.mqtt and got entries in the openhab.log after that.
I am confused…

It really looks like it should work - I don’t know why it’s NOT … :frowning:

2016-12-26 21:53:30.795 [DEBUG] [.binding.mqtt.internal.MqttActivator] - MQTT binding has been started.

2016-12-26 21:53:30.870 [DEBUG] [binding.mqtt.internal.MqttItemConfig] - Loaded MQTT config for item 'mqttPositionNCORaw' : 1 subscribers, 0 publishers

2016-12-27 08:06:23.435 [DEBUG] [ng.mqtt.internal.MqttEventBusBinding] - MQTT: Activating event bus binding.

2016-12-27 08:06:29.181 [DEBUG] [qttitude.internal.MqttitudeActivator] - Mqttitude binding has been started.

2016-12-27 08:06:31.165 [DEBUG] [.mqttitude.internal.MqttitudeBinding] - Registering Mqttitude consumer for owntracks/mosquitto/NCOs_iPhone/event (on mosquitto)
2016-12-27 08:06:31.205 [DEBUG] [org.openhab.binding.mqttitude       ] - BundleEvent STARTED - org.openhab.binding.mqttitude

I just checked one thing and uninstalled the mqtt.action.binding.
Owntracks started working right away!!!
So there must be a bug.

If someone needs more debug logs to localize this, just let me know.