openhab-addons: [mqtt] Updating values through a subscription does not work

Expected Behavior

  1. Send message to topic “presence/pavel/phone” with payload “online”
  2. The Switch Item Pavel_At_Home2 turns on

Current Behavior

  1. Send message to topic “presence/pavel/phone” with payload “online”
  2. Nothing (in log too)

Context

The problem appeared after updating 2.5.0M6 (that also has a problem with subscribe on reconnection, but works until reconnect to broker) to snapshot Build #1774

Thing refresh log:

....
....
2019-12-10 15:08:16.358 [INFO ] [el.core.internal.ModelRepositoryImpl] - Refreshing model 'mqtt.things'
2019-12-10 15:08:16.373 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Closing the MQTT broker connection 'mqtt.parabox.org'
2019-12-10 15:08:16.376 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Unsubscribing message consumer for topic 'presence/pavel/phone' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.385 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Unsubscribing message consumer for topic 'homeassistant/#' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.385 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Unsubscribing message consumer for topic 'presence/victoria/phone' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.385 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Unsubscribing message consumer for topic '+/+/$homie' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.391 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Successfully unsubscribed org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@25771597 from topic presence/pavel/phone
2019-12-10 15:08:16.392 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Successfully unsubscribed org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@25771597 from topic homeassistant/#
2019-12-10 15:08:16.394 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Successfully unsubscribed org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@25771597 from topic presence/victoria/phone
2019-12-10 15:08:16.394 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Successfully unsubscribed org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@25771597 from topic +/+/$homie
2019-12-10 15:08:16.441 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Tried to unsubscribe org.openhab.binding.mqtt.discovery.TopicSubscribe@69484ab from topic homeassistant/#, but subscriber list is empty
2019-12-10 15:08:16.442 [TRACE] [.transport.mqtt.MqttBrokerConnection] - Tried to unsubscribe org.openhab.binding.mqtt.discovery.TopicSubscribe@1390caa0 from topic +/+/$homie, but subscriber list is empty
2019-12-10 15:08:16.467 [INFO ] [.transport.mqtt.MqttBrokerConnection] - Starting MQTT broker connection to 'mqtt.parabox.org' with clientid c274032d-f16a-48ba-9884-9bf6d3837630
2019-12-10 15:08:16.471 [TRACE] [g.mqtt.handler.AbstractBrokerHandler] - Subscribed org.openhab.binding.mqtt.homeassistant.internal.discovery.HomeAssistantDiscovery@21a27de8 to discovery topic homeassistant/# on broker mqtt:broker:mqtt_broker
2019-12-10 15:08:16.472 [TRACE] [g.mqtt.handler.AbstractBrokerHandler] - Subscribed org.openhab.binding.mqtt.homie.internal.discovery.Homie300Discovery@54a8134f to discovery topic +/+/$homie on broker mqtt:broker:mqtt_broker
2019-12-10 15:08:16.654 [TRACE] [.transport.mqtt.MqttBrokerConnection] - subscribeRaw message consumer for topic 'homeassistant/#' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.655 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Trying to subscribe org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@f2d4188 to topic homeassistant/#
2019-12-10 15:08:16.662 [TRACE] [.transport.mqtt.MqttBrokerConnection] - subscribeRaw message consumer for topic '+/+/$homie' from broker 'mqtt.parabox.org'
2019-12-10 15:08:16.662 [TRACE] [ternal.client.MqttAsyncClientWrapper] - org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@f2d4188 already subscribed to topic +/+/$homie
2019-12-10 15:08:16.669 [TRACE] [ternal.client.MqttAsyncClientWrapper] - Successfully subscribed org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@f2d4188 to topic homeassistant/#
2019-12-10 15:08:16.672 [TRACE] [ternal.client.MqttAsyncClientWrapper] - org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@f2d4188 already subscribed to topic presence/victoria/phone
2019-12-10 15:08:16.673 [DEBUG] [ab.binding.mqtt.generic.ChannelState] - Subscribed channel mqtt:topic:mqtt_broker:presence:presence_victoria to topic: presence/victoria/phone
2019-12-10 15:08:16.674 [TRACE] [ternal.client.MqttAsyncClientWrapper] - org.eclipse.smarthome.io.transport.mqtt.internal.client.Mqtt3AsyncClientWrapper@f2d4188 already subscribed to topic presence/pavel/phone
2019-12-10 15:08:16.674 [DEBUG] [ab.binding.mqtt.generic.ChannelState] - Subscribed channel mqtt:topic:mqtt_broker:presence:presence_pavel to topic: presence/pavel/phone
2019-12-10 15:09:41.178 [TRACE] [.MqttChannelStateDescriptionProvider] - Providing state description for channel mqtt:topic:mqtt_broker:presence:presence_pavel
2019-12-10 15:09:41.179 [TRACE] [.MqttChannelStateDescriptionProvider] - Providing state description for channel mqtt:topic:mqtt_broker:presence:presence_pavel
2019-12-10 15:09:41.179 [TRACE] [.MqttChannelStateDescriptionProvider] - Providing state description for channel mqtt:topic:mqtt_broker:presence:presence_pavel
....
....

Things configuration:

Bridge mqtt:broker:mqtt_broker "MQTT Broker Connection" [ publickeypin=false, lastwill_qos=0, retain=false, secure=true, certificatepin=false, keep_alive_time=60000, password="*****", qos=0, port=8883, host="*****", reconnect_time=60000, username="*****" ] {
  Thing topic presence "MQTT Presence" {
    Channels:
      Type switch : presence_pavel "Pavel presence" [ stateTopic="presence/pavel/phone", on="online", off="offline"]
  }
}

Item configuration:

Switch Pavel_At_Home2 "At home" <man_3> (Group_At_Home) {channel="mqtt:topic:mqtt_broker:presence:presence_pavel"}

Your Environment

2.5.0-snapshot Build #1774 (Docker installation) x86_64, Linux Debian

About this issue

  • Original URL
  • State: closed
  • Created 5 years ago
  • Comments: 17 (11 by maintainers)

Most upvoted comments

This is a core-issue, can someone move it over there?

I have an idea what‘s happening and I‘ll try to fix that this evening.

Since it works for all if you: I‘ll prepare a PR. Thanks for your assistance.

Edit: After cleaning the cache and restarting, now it is working for me as well! (on RC1)

As mentioned, it worked for me on build 1774 so I’ll switch to RC1 and test.

Edit: not working for me on RC1 either.

Edit#2: Restarted openHAB a couple of times and it’s working again.