Frequent MQTT reconnects

[Andrei-Iosifescu123]

PROBLEM DESCRIPTION

Tasmotized ESP8266-01 disconnects from MQTT every 1-4 days for about 10 seconds, and then successfully reconnects. The Wi-Fi connection is not affected, only MQTT.

REQUESTED INFORMATION

Make sure your have performed every step and checked the applicable boxes before submitting your issue. Thank you!

• Read the Contributing Guide and Policy and the Code of Conduct
• Searched the problem in issues
• Searched the problem in discussions
• Searched the problem in the docs
• Searched the problem in the chat
• Device used (e.g., Sonoff Basic): ESP8266-01
• Tasmota binary firmware version number used: 13.2.0
  • Pre-compiled
  • Self-compiled
• Flashing tools used: Web Flasher
• Provide the output of command: Backlog Template; Module; GPIO 255:

  Configuration output here:
19:18:58.118 MQT: stat/tasmota_E3844F/RESULT = {"NAME":"Generic","GPIO":[1,1,1,1,1,1,1,1,1,1,1,1,1,1],"FLAG":0,"BASE":18}
19:18:58.335 MQT: stat/tasmota_E3844F/RESULT = {"Module":{"18":"Generic"}}
19:18:58.591 MQT: stat/tasmota_E3844F/RESULT = {"GPIO0":{"256":"Relay_i1"},"GPIO1":{"0":"None"},"GPIO2":{"1216":"AM2301"},"GPIO3":{"32":"Button1"},"GPIO4":{"0":"None"},"GPIO5":{"0":"None"},"GPIO9":{"0":"None"},"GPIO10":{"0":"None"},"GPIO12":{"0":"None"},"GPIO13":{"0":"None"},"GPIO14":{"0":"None"},"GPIO15":{"0":"None"},"GPIO16":{"0":"None"},"GPIO17":{"0":"None"}}

• If using rules, provide the output of this command: Backlog Rule1; Rule2; Rule3:

  Rules output here:

• Provide the output of this command: Status 0:

  STATUS 0 output here:
19:17:11.439 MQT: stat/tasmota_E3844F/STATUS = {"Status":{"Module":18,"DeviceName":"Dormitor Sus","FriendlyName":["Tasmota"],"Topic":"tasmota_E3844F","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"LedMask":"FFFF","SaveData":1,"SaveState":1,"SwitchTopic":"0","SwitchMode":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"ButtonRetain":0,"SwitchRetain":0,"SensorRetain":0,"PowerRetain":0,"InfoRetain":0,"StateRetain":0,"StatusRetain":0}}
19:17:11.476 MQT: stat/tasmota_E3844F/STATUS1 = {"StatusPRM":{"Baudrate":115200,"SerialConfig":"8N1","GroupTopic":"tasmotas","OtaUrl":"http://ota.tasmota.com/tasmota/release/tasmota.bin.gz","RestartReason":"Software/System restart","Uptime":"1T04:17:36","StartupUTC":"2023-11-08T13:59:35","Sleep":50,"CfgHolder":4617,"BootCount":65,"BCResetTime":"2023-04-09T16:28:36","SaveCount":422,"SaveAddress":"F5000"}}
19:17:11.515 MQT: stat/tasmota_E3844F/STATUS2 = {"StatusFWR":{"Version":"13.2.0(tasmota)","BuildDateTime":"2023-10-19T09:02:07","Boot":31,"Core":"2_7_4_9","SDK":"2.2.2-dev(38a443e)","CpuFrequency":80,"Hardware":"ESP8266EX","CR":"370/699"}}
19:17:11.534 MQT: stat/tasmota_E3844F/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":2,"MqttLog":0,"SysLog":0,"LogHost":"192.168.0.132","LogPort":514,"SSId":["Andrei","Andrei"],"TelePeriod":60,"Resolution":"558180C0","SetOption":["00008009","2805C80001000600003C5A0A192800000000","00008040","00006000","00004000","00000000"]}}
19:17:11.568 MQT: stat/tasmota_E3844F/STATUS4 = {"StatusMEM":{"ProgramSize":635,"Free":368,"Heap":23,"ProgramFlashSize":1024,"FlashSize":1024,"FlashChipId":"1440C8","FlashFrequency":40,"FlashMode":"DOUT","Features":["00000809","8F9AC787","04368001","000000CF","010013C0","C000F981","00004004","00001000","54000020","00000080"],"Drivers":"1,2,3,4,5,6,7,8,9,10,12,16,18,19,20,21,22,24,26,27,29,30,35,37,45,62,68","Sensors":"1,2,3,4,5,6","I2CDriver":"7"}}
19:17:11.608 MQT: stat/tasmota_E3844F/STATUS5 = {"StatusNET":{"Hostname":"tasmota-E3844F-1103","IPAddress":"192.168.0.168","Gateway":"192.168.0.1","Subnetmask":"255.255.255.0","DNSServer1":"192.168.0.1","DNSServer2":"0.0.0.0","Mac":"50:02:91:E3:84:4F","Webserver":2,"HTTP_API":1,"WifiConfig":4,"WifiPower":17.0}}
19:17:11.633 MQT: stat/tasmota_E3844F/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.0.132","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_E3844F","MqttUser":"admin","MqttCount":6,"MAX_PACKET_SIZE":1200,"KEEPALIVE":30,"SOCKET_TIMEOUT":4}}
19:17:11.656 MQT: stat/tasmota_E3844F/STATUS7 = {"StatusTIM":{"UTC":"2023-11-09T18:17:11","Local":"2023-11-09T19:17:11","StartDST":"2023-03-26T03:00:00","EndDST":"2023-10-29T03:00:00","Timezone":99,"Sunrise":"07:49","Sunset":"17:17"}}
19:17:11.677 MQT: stat/tasmota_E3844F/STATUS10 = {"StatusSNS":{"Time":"2023-11-09T19:17:11","AM2301":{"Temperature":14.7,"Humidity":50.8,"DewPoint":4.6},"TempUnit":"C"}}
19:17:11.691 MQT: stat/tasmota_E3844F/STATUS11 = {"StatusSTS":{"Time":"2023-11-09T19:17:11","Uptime":"1T04:17:36","UptimeSec":101856,"Heap":23,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":6,"POWER":"OFF","Wifi":{"AP":1,"SSId":"Andrei","BSSId":"52:D4:F7:4A:0D:0A","Channel":1,"Mode":"11n","RSSI":72,"Signal":-64,"LinkCount":2,"Downtime":"0T00:00:36"}}}

• Set weblog to 4 and then, when you experience your issue, provide the output of the Console log:

  Console output here:
Nov  9 16:43:05 tasmota-E3844F-1103 ESP-RSL: STATE = {"Time":"2023-11-09T15:43:04","Uptime":"1T00:43:29","UptimeSec":89009,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":5,"POWER":"OFF","Wifi":{"AP":1,"SSId":"Andrei","BSSId":"52:D4:F7:4A:0D:0A","Channel":1,"Mode":"11n","RSSI":68,"Signal":-66,"LinkCount":2,"Downtime":"0T00:00:36"}}
Nov  9 16:43:05 tasmota-E3844F-1103 ESP-RSL: SENSOR = {"Time":"2023-11-09T15:43:04","AM2301":{"Temperature":14.7,"Humidity":49.3,"DewPoint":4.2},"TempUnit":"C"}
Nov  9 16:43:05 tasmota-E3844F-1103 ESP-DHT: Pin2 cycles (80/80)  67 34 65 34 71 34 71 34 71 34 71 34 71 34 71 101 71 98 71 99 ..
Nov  9 16:43:05 tasmota-E3844F-1103 ESP-DHT: Pin2 read 01E900937D
Nov  9 16:43:06 tasmota-E3844F-1103 ESP-WIF: Checking connection...
Nov  9 16:43:09 tasmota-E3844F-1103 ESP-DHT: Pin2 cycles (80/80)  67 34 65 34 71 34 71 34 71 34 71 34 71 35 71 100 71 99 71 99 ..
Nov  9 16:43:09 tasmota-E3844F-1103 ESP-DHT: Pin2 read 01EC009380
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-DHT: Pin2 cycles (80/80)  67 34 65 34 71 34 71 34 71 35 71 34 71 34 71 100 71 99 71 99 ..
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-DHT: Pin2 read 01E900937D
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: Attempting connection...
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-WIF: DNS resolved '192.168.0.132' (192.168.0.132) in 0 ms
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: Connected
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: tele/tasmota_E3844F/LWT = Online (retained)
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: cmnd/tasmota_E3844F/POWER = 
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: Subscribe to cmnd/tasmota_E3844F/#
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: Subscribe to cmnd/tasmotas/#
Nov  9 16:43:13 tasmota-E3844F-1103 ESP-MQT: Subscribe to cmnd/DVES_E3844F_fb/#
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: tasmota/discovery/500291E3844F/config = {"ip":"192.168.0.168","dn":"Dormitor Sus","fn":["Tasmota",null,null,null,null,null,null,null],"hn":"tasmota-E3844F-1103","mac":"500291E3844F","md":"Generic","ty":0,"if":0,"ofln":"Offline","onln":"Online","state":["OFF","ON","TOGGLE","HOLD"],"sw":"13.2.0","t":"tasmota_E3844F","ft":"%prefix%/%topic%/","tp":["cmnd","stat","tele"],"rl":[1,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"swc":[-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1,-1],"swn":[null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null,null],"btn":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"so":{"4":0,"11":0,"13":0,"17":0,"20":0,"30":0,"68":0,"73":0,"82":0,"114":0,"117":0},"lk":0,"lt_st":0,"bat":0,"dslp":0,"sho":[],"sht":[],"ver":1} (retained)
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: tasmota/discovery/500291E3844F/sensors = {"sn":{"Time":"2023-11-09T15:43:13","AM2301":{"Temperature":14.7,"Humidity":48.9,"DewPoint":4.0},"TempUnit":"C"},"ver":1} (retained)
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 4, Pld -99, Data 'None'
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/STATUS = {"Status":{"Module":18,"DeviceName":"Dormitor Sus","FriendlyName":["Tasmota"],"Topic":"tasmota_E3844F","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"LedMask":"FFFF","SaveData":1,"SaveState":1,"SwitchTopic":"0","SwitchMode":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"ButtonRetain":0,"SwitchRetain":0,"SensorRetain":0,"PowerRetain":0,"InfoRetain":0,"StateRetain":0,"StatusRetain":0}}
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 1, Pld 1, Data '1'
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/STATUS1 = {"StatusPRM":{"Baudrate":115200,"SerialConfig":"8N1","GroupTopic":"tasmotas","OtaUrl":"http://ota.tasmota.com/tasmota/release/tasmota.bin.gz","RestartReason":"Software/System restart","Uptime":"1T00:43:38","StartupUTC":"2023-11-08T13:59:35","Sleep":50,"CfgHolder":4617,"BootCount":65,"BCResetTime":"2023-04-09T16:28:36","SaveCount":420,"SaveAddress":"F7000"}}
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 2, Pld 11, Data '11'
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/STATUS11 = {"StatusSTS":{"Time":"2023-11-09T15:43:13","Uptime":"1T00:43:38","UptimeSec":89018,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":27,"MqttCount":6,"POWER":"OFF","Wifi":{"AP":1,"SSId":"Andrei","BSSId":"52:D4:F7:4A:0D:0A","Channel":1,"Mode":"11n","RSSI":72,"Signal":-64,"LinkCount":2,"Downtime":"0T00:00:36"}}}
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 1, Pld 2, Data '2'
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/STATUS2 = {"StatusFWR":{"Version":"13.2.0(tasmota)","BuildDateTime":"2023-10-19T09:02:07","Boot":31,"Core":"2_7_4_9","SDK":"2.2.2-dev(38a443e)","CpuFrequency":80,"Hardware":"ESP8266EX","CR":"370/699"}}
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATE', Idx 1, Len 0, Pld -99, Data ''
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/RESULT = {"Time":"2023-11-09T15:43:14","Uptime":"1T00:43:39","UptimeSec":89019,"Heap":25,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":31,"MqttCount":6,"POWER":"OFF","Wifi":{"AP":1,"SSId":"Andrei","BSSId":"52:D4:F7:4A:0D:0A","Channel":1,"Mode":"11n","RSSI":72,"Signal":-64,"LinkCount":2,"Downtime":"0T00:00:36"}}
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-SRC: MQTT
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 2, Pld 10, Data '10'
Nov  9 16:43:14 tasmota-E3844F-1103 ESP-MQT: stat/tasmota_E3844F/STATUS10 = {"StatusSNS":{"Time":"2023-11-09T15:43:14","AM2301":{"Temperature":14.7,"Humidity":48.9,"DewPoint":4.0},"TempUnit":"C"}}

TO REPRODUCE

Steps to reproduce the behavior:
Power on the ESP and wait for some time

EXPECTED BEHAVIOUR

A clear and concise description of what you expected to happen.
The MQTT stays connected all the time without dropping

SCREENSHOTS

If applicable, add screenshots to help explain your problem.

ADDITIONAL CONTEXT

Add any other context about the problem here.
Wi-Fi connection is stable, with around 70% signal strength. I am using Mosquitto broker on Raspberry Pi 4. SetOption57 is disabled.
Raspberry Pi log before the disconnect:

Nov  9 16:42:59 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-DISCONNECTED bssid=52:d4:f7:4a:0d:0a reason=7 locally_generated=1
Nov  9 16:42:59 raspberrypi systemd-networkd[685]: wlan0: Lost carrier
Nov  9 16:42:59 raspberrypi systemd-networkd[685]: wlan0: DHCP lease lost
Nov  9 16:42:59 raspberrypi avahi-daemon[694]: Withdrawing address record for 192.168.0.132 on wlan0.
Nov  9 16:42:59 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Nov  9 16:42:59 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB
Nov  9 16:42:59 raspberrypi systemd-timesyncd[644]: No network connectivity, watching for changes.
Nov  9 16:42:59 raspberrypi avahi-daemon[694]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132.
Nov  9 16:42:59 raspberrypi avahi-daemon[694]: Interface wlan0.IPv4 no longer relevant for mDNS.
Nov  9 16:42:59 raspberrypi dbus-daemon[695]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.0' (uid=100 pid=685 comm="/lib/systemd/systemd-networkd " label="unconfined")
Nov  9 16:42:59 raspberrypi systemd[1]: Starting Hostname Service...
Nov  9 16:42:59 raspberrypi dbus-daemon[695]: [system] Successfully activated service 'org.freedesktop.hostname1'
Nov  9 16:42:59 raspberrypi systemd[1]: Started Hostname Service.
Nov  9 16:43:01 raspberrypi wpa_supplicant[578]: wlan0: Trying to associate with SSID 'Andrei'
Nov  9 16:43:04 raspberrypi systemd-networkd[685]: wlan0: Gained carrier
Nov  9 16:43:04 raspberrypi wpa_supplicant[578]: wlan0: Associated with 52:d4:f7:4a:0d:0a
Nov  9 16:43:04 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-CONNECTED - Connection to 52:d4:f7:4a:0d:0a completed [id=0 id_str=]
Nov  9 16:43:04 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Nov  9 16:43:04 raspberrypi systemd-timesyncd[644]: Network configuration changed, trying to establish connection.
Nov  9 16:43:04 raspberrypi wpa_supplicant[578]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RO
Nov  9 16:43:04 raspberrypi systemd-networkd[685]: wlan0: Lost carrier
Nov  9 16:43:04 raspberrypi systemd-networkd[685]: wlan0: Gained carrier
Nov  9 16:43:04 raspberrypi systemd-timesyncd[644]: Network configuration changed, trying to establish connection.
Nov  9 16:43:04 raspberrypi systemd-networkd[685]: wlan0: DHCPv4 address 192.168.0.132/24 via 192.168.0.1
Nov  9 16:43:04 raspberrypi avahi-daemon[694]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.132.
Nov  9 16:43:04 raspberrypi avahi-daemon[694]: New relevant interface wlan0.IPv4 for mDNS.
Nov  9 16:43:04 raspberrypi avahi-daemon[694]: Registering new address record for 192.168.0.132 on wlan0.IPv4.
Nov  9 16:43:04 raspberrypi systemd-timesyncd[644]: Network configuration changed, trying to establish connection.
Nov  9 16:43:04 raspberrypi systemd-timesyncd[644]: Initial synchronization to time server 185.125.190.57:123 (ntp.ubuntu.com).

(Please, remember to close the issue when the problem has been addressed)

[Andrei-Iosifescu123]

I found the problem: My mqtt broker (Raspberry Pi) was disconnecting from wifi. After disabling "Disassociate on low ACK" from my router, everything works great again.