search

stefanbode / Sonoff-Tasmota

Provide ESP8266 based itead Sonoff with Web, MQTT and OTA firmware using Arduino IDE, enhanced with I2C options
GNU General Public License v3.0
127 stars 41 forks source link

CORE Libary & MQTT Libary #123

Closed helmar74 closed 5 years ago

helmar74 commented 5 years ago

Hi everbody,

as I'm having the Problem that the MQTT message isn't received always (see

99 ) and I'm not quite sure how to figure it out a few questions to you:

  1. I'm currently compiled the Tasmota fork with Core Libary version 2.4.2. Is it better for battery powered (Deepsleep) Devices to use 2.3.0 or another version?

  2. With Parameter #define MQTT_LIBRARY_TYPE in my_user_config.h it is possible to choose a other MQTT Library. Currently using PUBSUBCLIENT, should I try another one?

  3. The parameter #define MQTT_TELE_RETAIN 0 is currently default (0) would it help to set to 1 in order to retain Tele messages on Broker?

  4. Any other idea what I can do to receive every MQTT Message afte Deepsleep Wakeup?

Thank you very much for your assistance

stefanbode commented 5 years ago

Without a log file from a failed try it is very difficult to guess what causes the issue. Maybe the string is empty maybe the wlan does not connect. For further investigation I would suggest to make a trigger on the LWT topic. In this case you should see if the device starts and is able to connect with mqtt. If you even there have no connection, then this issue is the wlan and or the mqtt server. To inspect this it’s getting complicated. You have to setup a logserver for the syslog. If the wlan connects you will see here some error messages. If you also see no wake up in the syslog server, then the wlan might have a problem. In this case you need to be live connected to the console via serial connector. Then check what messages causes the wlan fail.

helmar74 commented 5 years ago

Here is the Syslog from the tasmota device which is sending the topic Also included a non working example (section "Topic wasn't received by Broker:"):

Topic was received by Broker:

<13>1 2019-02-04T07:48:59.689139+01:00 sonoffAkku-5572 ESP-WIF - - - ESP-WIF: verbunden <13>1 2019-02-04T07:48:59.689139+01:00 sonoffAkku-5572 ESP-HTP - - - ESP-HTP: Web-Server aktiv bei sonoffAkku-5572 mit IP-Adresse 192.168.1.155 <13>1 2019-02-04T07:48:59.908887+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: (UTC) Mon Feb 04 06:48:59 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019 <13>1 2019-02-04T07:49:00.923299+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: Verbindungsversuch... <13>1 2019-02-04T07:49:00.938849+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: verbunden <13>1 2019-02-04T07:49:00.943833+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Online (beibehalten) <13>1 2019-02-04T07:49:00.945754+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: cmnd/sonoffAkku/POWER = <13>1 2019-02-04T07:49:00.964337+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkku/# <13>1 2019-02-04T07:49:00.965765+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuGruppe/# <13>1 2019-02-04T07:49:00.971777+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuTest234_fb/# <13>1 2019-02-04T07:49:00.973048+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO1 = {"Module":"Generic","Version":"6.4.1.9 stb-1.1(sonoff)","FallbackTopic":"cmnd/sonoffAkkuTest234_fb/","GroupTopic":"sonoffAkkuGruppe"} <13>1 2019-02-04T07:49:00.974154+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO2 = {"WebServerMode":"Admin","Hostname":"sonoffAkku-5572","IPAddress":"192.168.1.155"} <13>1 2019-02-04T07:49:00.975158+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO3 = {"RestartReason":"Deep-Sleep Wake"} <13>1 2019-02-04T07:49:01.164964+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: Anzahl Startvorgänge 241 <13>1 2019-02-04T07:49:01.468404+01:00 sonoffAkku-5572 ESP-CFG - - - ESP-CFG: in Flash gespeichert am F8, zählen 252, Bytes 3824 <13>1 2019-02-04T07:49:09.185744+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/STATE = {"Time":"2019-02-04T07:49:09","Uptime":"0T00:00:18","SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"Wifi":{"AP":1,"SSId":"hs.net_EG","BSSId":"9C:C7:A6:F7:72:A2","Channel":6,"RSSI":76},"DeepSleep":60,"Heap":21024} <13>1 2019-02-04T07:49:09.227541+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/SENSOR = {"Time":"2019-02-04T07:49:09","ANALOG":{"A0":0}} <13>1 2019-02-04T07:49:09.231623+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Offline <13>1 2019-02-04T07:49:09.231623+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/UPTIME_S = {"Time":"2019-02-04T07:49:09", "Uptime_s":15} *************Topic wasn't received by Broker:************* <13>1 2019-02-04T07:50:12.356765+01:00 sonoffAkku-5572 ESP-WIF - - - ESP-WIF: verbunden <13>1 2019-02-04T07:50:12.391555+01:00 sonoffAkku-5572 ESP-HTP - - - ESP-HTP: Web-Server aktiv bei sonoffAkku-5572 mit IP-Adresse 192.168.1.155 <13>1 2019-02-04T07:50:13.084799+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: (UTC) Mon Feb 04 06:50:12 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019 <13>1 2019-02-04T07:50:13.548110+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: Verbindungsversuch... <13>1 2019-02-04T07:50:13.563556+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: verbunden <13>1 2019-02-04T07:50:13.569555+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Online (beibehalten) <13>1 2019-02-04T07:50:13.570638+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: cmnd/sonoffAkku/POWER = <13>1 2019-02-04T07:50:13.572184+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkku/# <13>1 2019-02-04T07:50:13.578722+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuGruppe/# <13>1 2019-02-04T07:50:13.590034+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuTest234_fb/# <13>1 2019-02-04T07:50:13.595574+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO1 = {"Module":"Generic","Version":"6.4.1.9 stb-1.1(sonoff)","FallbackTopic":"cmnd/sonoffAkkuTest234_fb/","GroupTopic":"sonoffAkkuGruppe"} <13>1 2019-02-04T07:50:13.596680+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO2 = {"WebServerMode":"Admin","Hostname":"sonoffAkku-5572","IPAddress":"192.168.1.155"} <13>1 2019-02-04T07:50:13.597620+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO3 = {"RestartReason":"Deep-Sleep Wake"} <13>1 2019-02-04T07:50:17.840979+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: Anzahl Startvorgänge 242 <13>1 2019-02-04T07:50:18.140539+01:00 sonoffAkku-5572 ESP-CFG - - - ESP-CFG: in Flash gespeichert am F7, zählen 253, Bytes 3824 <13>1 2019-02-04T07:50:21.852370+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/STATE = {"Time":"2019-02-04T07:50:21","Uptime":"0T00:00:14","SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"Wifi":{"AP":1,"SSId":"hs.net_EG","BSSId":"9C:C7:A6:F7:72:A2","Channel":6,"RSSI":80},"DeepSleep":60,"Heap":19424} <13>1 2019-02-04T07:50:21.894999+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/SENSOR = {"Time":"2019-02-04T07:50:21","ANALOG":{"A0":0}} <13>1 2019-02-04T07:50:21.900028+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Offline <13>1 2019-02-04T07:50:21.900028+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/UPTIME_S = {"Time":"2019-02-04T07:50:21", "Uptime_s":13} *************Topic was received by Broker:************* <13>1 2019-02-04T07:51:25.492988+01:00 sonoffAkku-5572 ESP-WIF - - - ESP-WIF: verbunden <13>1 2019-02-04T07:51:25.492988+01:00 sonoffAkku-5572 ESP-HTP - - - ESP-HTP: Web-Server aktiv bei sonoffAkku-5572 mit IP-Adresse 192.168.1.155 <13>1 2019-02-04T07:51:26.273223+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: (UTC) Mon Feb 04 06:51:25 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019 <13>1 2019-02-04T07:51:26.735201+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: Verbindungsversuch... <13>1 2019-02-04T07:51:26.758347+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: verbunden <13>1 2019-02-04T07:51:26.767705+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Online (beibehalten) <13>1 2019-02-04T07:51:26.780191+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: cmnd/sonoffAkku/POWER = <13>1 2019-02-04T07:51:26.821447+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkku/# <13>1 2019-02-04T07:51:26.822770+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuGruppe/# <13>1 2019-02-04T07:51:26.823723+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuTest234_fb/# <13>1 2019-02-04T07:51:26.825091+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO1 = {"Module":"Generic","Version":"6.4.1.9 stb-1.1(sonoff)","FallbackTopic":"cmnd/sonoffAkkuTest234_fb/","GroupTopic":"sonoffAkkuGruppe"} <13>1 2019-02-04T07:51:26.827644+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO2 = {"WebServerMode":"Admin","Hostname":"sonoffAkku-5572","IPAddress":"192.168.1.155"} <13>1 2019-02-04T07:51:26.829021+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO3 = {"RestartReason":"Deep-Sleep Wake"} <13>1 2019-02-04T07:51:31.028550+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: Anzahl Startvorgänge 243 <13>1 2019-02-04T07:51:31.334161+01:00 sonoffAkku-5572 ESP-CFG - - - ESP-CFG: in Flash gespeichert am F6, zählen 254, Bytes 3824 <13>1 2019-02-04T07:51:35.043988+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/STATE = {"Time":"2019-02-04T07:51:34","Uptime":"0T00:00:14","SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"Wifi":{"AP":1,"SSId":"hs.net_EG","BSSId":"9C:C7:A6:F7:72:A2","Channel":6,"RSSI":74},"DeepSleep":60,"Heap":21024} <13>1 2019-02-04T07:51:35.087331+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/SENSOR = {"Time":"2019-02-04T07:51:34","ANALOG":{"A0":0}} <13>1 2019-02-04T07:51:35.091485+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Offline <13>1 2019-02-04T07:51:35.093514+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/UPTIME_S = {"Time":"2019-02-04T07:51:34", "Uptime_s":13}

Here're the received topics of the Mosquitto Broker:

tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:47:53","ANALOG":{"A0":0}} tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:49:09","ANALOG":{"A0":0}} THE TOPIC at 7:50:21 IS MISSING (see Syslog above) tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:51:34","ANALOG":{"A0":0}} tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:52:48","ANALOG":{"A0":0}}

Here's the log of the mosquitto Broker:

549268427: New connection from 192.168.1.155 on port 1883. 1549268427: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). 1549268449: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. 1549268449: Socket error on client sonoffAkkuTest234, disconnecting. 1549268503: New connection from 192.168.1.155 on port 1883. 1549268503: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). 1549268526: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. 1549268526: Socket error on client sonoffAkkuTest234, disconnecting. 1549268576: New connection from 192.168.1.155 on port 1883. 1549268576: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). 1549268598: Client sonoffAkkuTest234 has exceeded timeout, disconnecting.

As the logging "arrives" at the syslog server, it shouldn't be a WLAN issue:

<13>1 2019-02-04T07:50:12.356765+01:00 sonoffAkku-5572 ESP-WIF - - - ESP-WIF: verbunden

The logging also shows, that the MQTT Broker is connected:

<13>1 2019-02-04T07:50:13.563556+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: verbunden ... and that the message is sent to Broker: <13>1 2019-02-04T07:50:21.894999+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/SENSOR = {"Time":"2019-02-04T07:50:21","ANALOG":{"A0":0}}

But when looking in parallel on the Broker with

mosquitto_sub -h localhost -v -t "tele/sonoffAkku/SENSOR" the topic isn't shown: tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:49:09","ANALOG":{"A0":0}} THE TOPIC at 7:50:21 IS MISSING (see Syslog above) tele/sonoffAkku/SENSOR {"Time":"2019-02-04T07:51:34","ANALOG":{"A0":0}}

The mosquitto.log also doesn't show any error.... It's really strange..

I setup a new Raspberry Pi 3 with mosquitto in default configuration, the error is the same...

helmar74 commented 5 years ago

I was able to reproduce the error: image

What you can see in the syslog file is, that the Devices wakes up, sends different MQTT messages like INFO1, INFO2, INFO3. But no MQTT message which contains the sensor data (tele/sonoffAkku/SENSOR) This topic isn't sent to broker on every wakeup: It is sent at 16:44:55 and then at 16:49:02. On the wakeups between e.g 16:46:25 the topic isn't sent.

Feb 4 16:44:47 homesrv7 mosquitto[30399]: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending CONNACK to sonoffAkkuTest234 (0, 0) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r1, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkku/# (QoS 0) Feb 4 16:44:47 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkku/# Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuGruppe/# (QoS 0) Feb 4 16:44:47 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuGruppe/# Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuTest234_fb/# (QoS 0) Feb 4 16:44:47 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuTest234_fb/# Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:44:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:44:48 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:44:48 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/30403-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes))

Feb 4 16:45:09 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:45:09 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting. Feb 4 16:45:09 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:45:10 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/4171-homesrv7 Feb 4 16:45:10 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/4171-homesrv7 Feb 4 16:45:28 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/3967-homesrv7 Feb 4 16:45:28 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/3967-homesrv7 Feb 4 16:45:47 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:45:47 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:46:09 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/4171-homesrv7 Feb 4 16:46:09 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/4171-homesrv7

Feb 4 16:46:25 homesrv7 mosquitto[30399]: New connection from 192.168.1.155 on port 1883. Feb 4 16:46:25 homesrv7 mosquitto[30399]: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending CONNACK to sonoffAkkuTest234 (0, 0) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r1, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkku/# (QoS 0) Feb 4 16:46:25 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkku/# Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuGruppe/# (QoS 0) Feb 4 16:46:25 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuGruppe/# Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuTest234_fb/# (QoS 0) Feb 4 16:46:25 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuTest234_fb/# Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:46:25 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:46:29 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/3967-homesrv7 Feb 4 16:46:29 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/3967-homesrv7 Feb 4 16:46:33 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:46:33 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:46:33 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:46:47 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:46:47 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting. Feb 4 16:46:47 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:46:47 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:46:47 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:47:10 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/4171-homesrv7 Feb 4 16:47:10 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/4171-homesrv7 Feb 4 16:47:29 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/3967-homesrv7 Feb 4 16:47:29 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/3967-homesrv7 Feb 4 16:47:41 homesrv7 mosquitto[30399]: New connection from 192.168.1.155 on port 1883. Feb 4 16:47:41 homesrv7 mosquitto[30399]: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending CONNACK to sonoffAkkuTest234 (0, 0) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r1, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkku/# (QoS 0) Feb 4 16:47:41 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkku/# Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuGruppe/# (QoS 0) Feb 4 16:47:41 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuGruppe/# Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuTest234_fb/# (QoS 0) Feb 4 16:47:41 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuTest234_fb/# Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:47:41 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:47:48 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:47:48 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:47:49 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:47:49 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:47:49 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:48:03 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:48:03 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting. Feb 4 16:48:04 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:48:10 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/4171-homesrv7 Feb 4 16:48:10 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/4171-homesrv7 Feb 4 16:48:29 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/3967-homesrv7 Feb 4 16:48:29 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/3967-homesrv7 Feb 4 16:48:48 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:48:48 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:48:53 homesrv7 mosquitto[30399]: New connection from 192.168.1.155 on port 1883. Feb 4 16:48:53 homesrv7 mosquitto[30399]: New client connected from 192.168.1.155 as sonoffAkkuTest234 (c1, k10, u'DVES_USER'). Feb 4 16:48:53 homesrv7 mosquitto[30399]: Sending CONNACK to sonoffAkkuTest234 (0, 0) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r1, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (6 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'cmnd/sonoffAkku/POWER', ... (0 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkku/# (QoS 0) Feb 4 16:48:54 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkku/# Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuGruppe/# (QoS 0) Feb 4 16:48:54 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuGruppe/# Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received SUBSCRIBE from sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: #011cmnd/sonoffAkkuTest234_fb/# (QoS 0) Feb 4 16:48:54 homesrv7 mosquitto[30399]: sonoffAkkuTest234 0 cmnd/sonoffAkkuTest234_fb/# Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending SUBACK to sonoffAkkuTest234 Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO1', ... (133 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO2', ... (82 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:48:54 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/INFO3', ... (35 bytes)) Feb 4 16:49:01 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:49:01 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:49:01 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/30403-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes)) Feb 4 16:49:02 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes)) Feb 4 16:49:09 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/4171-homesrv7 Feb 4 16:49:09 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/4171-homesrv7 Feb 4 16:49:16 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:49:16 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting. Feb 4 16:49:17 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:49:29 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/3967-homesrv7 Feb 4 16:49:29 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/3967-homesrv7 Feb 4 16:49:48 homesrv7 mosquitto[30399]: Received PINGREQ from mosqsub/30403-homesrv7 Feb 4 16:49:48 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7

helmar74 commented 5 years ago

Here's the log again, only the relevant lines: When all is ok, the topic tele/sonoffAkku/SENSOR is received from the Broker:

Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/30403-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes))

>Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/SENSOR', ... (48 bytes))

Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/LWT', ... (7 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes)) Feb 4 16:44:55 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/UPTIME_S', ... (45 bytes))

Feb 4 16:45:09 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:45:09 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting.

In Error condition the topic tele/sonoffAkku/SENSOR isn't received from the Broker:

Feb 4 16:47:48 homesrv7 mosquitto[30399]: Sending PINGRESP to mosqsub/30403-homesrv7 Feb 4 16:47:49 homesrv7 mosquitto[30399]: Received PUBLISH from sonoffAkkuTest234 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:47:49 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/3967-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:47:49 homesrv7 mosquitto[30399]: Sending PUBLISH to mosqsub/4171-homesrv7 (d0, q0, r0, m0, 'tele/sonoffAkku/STATE', ... (211 bytes)) Feb 4 16:48:03 homesrv7 mosquitto[30399]: Client sonoffAkkuTest234 has exceeded timeout, disconnecting. Feb 4 16:48:03 homesrv7 mosquitto[30399]: Socket error on client sonoffAkkuTest234, disconnecting.

BUT: The syslog, which is logged from the Device to a remote syslog server, says, that the topic was sent. In the same millisecond the LWT goes to OFFLINE:

<13>1 2019-02-04T16:47:39.826813+01:00 sonoffAkku-5572 ESP-WIF - - - ESP-WIF: verbunden <13>1 2019-02-04T16:47:39.826813+01:00 sonoffAkku-5572 ESP-HTP - - - ESP-HTP: Web-Server aktiv bei sonoffAkku-5572 mit IP-Adresse 192.168.1.155 <13>1 2019-02-04T16:47:40.125037+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: (UTC) Mon Feb 04 15:47:39 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019 <13>1 2019-02-04T16:47:41.073370+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: Verbindungsversuch... <13>1 2019-02-04T16:47:41.339402+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: verbunden <13>1 2019-02-04T16:47:41.347457+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Online (beibehalten) <13>1 2019-02-04T16:47:41.348125+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: cmnd/sonoffAkku/POWER = <13>1 2019-02-04T16:47:41.348125+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkku/# <13>1 2019-02-04T16:47:41.349767+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuGruppe/# <13>1 2019-02-04T16:47:41.350958+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: abonniere cmnd/sonoffAkkuTest234_fb/# <13>1 2019-02-04T16:47:41.352305+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/INFO1 = {"Module":"Generic","Version":"6.4.1.9 stb-1.1(sonoff)","FallbackTopic":"cmnd/sonoffAkkuTest234_fb/","GroupTopic":"sonoffAkkuGruppe"} <13>1 2019-02-04T16:47:41.379024+01:00 sonoffAkku-5572 ESP-APP - - - ESP-APP: Anzahl Startvorgänge 551 <13>1 2019-02-04T16:47:41.639910+01:00 sonoffAkku-5572 ESP-CFG - - - ESP-CFG: in Flash gespeichert am F8, zählen 564, Bytes 3824 <13>1 2019-02-04T16:47:49.391046+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/STATE = {"Time":"2019-02->04T16:47:49","Uptime":"0T00:00:18","SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"Wifi":>>{"AP":1,"SSId":"hs.net_EG","BSSId":"9C:C7:A6:F7:72:A2","Channel":6,"RSSI":80},"DeepSleep":60,"Heap":20352} ### ><13>1 2019-02-04T16:47:49.464459+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/SENSOR = {"Time":"2019-02-04T16:47:49","ANALOG":{"A0":0}} *****<13>1 2019-02-04T16:47:49.464459+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/LWT = Offline <13>1 2019-02-04T16:47:49.464490+01:00 sonoffAkku-5572 ESP-MQT - - - ESP-MQT: tele/sonoffAkku/UPTIME_S = {"Time":"2019-02-04T16:47:49", "Uptime_s":17}

Could it be, that the software reports to syslog, that the topic was sent to broker, but in the meantime (before really sending the mqtt message) the device went in deepsleep mode? If so, is it a software bug?