DeepSleep mode: no I2C data send to MQTT

[guiumb]

BUG DESCRIPTION

Using DeepSleep Mode on MiniD1 borad with I2C sensor no MQTT sensor data was send at wake-up.

I have a Mini D1 board with I2C temperature sensor connected. When I setup my device in order to use deepsleep function (in this test I have used 30 secs) the board goes to sleep, but at wake-up no MQTT sensor data was send.

The system send only INFO1-2-3 MQTT messages but no sensor data was sent.

When I disconnect GPIO16 and I stop deepsleep function I found the GPIO configuration resetted with default values (No config about I2C sensor).

I have already tryed to flash device from scratch and setup device with dynamic sleep set to 0 but the results is the same.

• Read the Contributing Guide and Policy and the Code of Conduct
• Searched the problem in issues
• Searched the problem in the wiki
• Searched the problem in the forum
• Searched the problem in the chat
• Device used (e.g., Sonoff Basic): Mini D1 Board
• Tasmota binary firmware version number used: 7.0.0.4 with core 2.6
  • Self-compiled
    • IDE / Compiler used: Arduino IDE 1.8.10
• Flashing tools used: _____
• Provide the output of command: Backlog Template; Module; GPIO:

22:35:38 CMD: Backlog Template; Module; GPIO
22:35:38 SRC: WebConsole from 10.0.0.6
22:35:38 CMD: Group 0, Index 1, Command "BACKLOG", Data "Template; Module; GPIO"
22:35:38 SRC: Backlog
22:35:38 CMD: Group 0, Index 1, Command "TEMPLATE", Data ""
22:35:38 MQT: stat/bme_temp_out/RESULT = {"NAME":"Generic","GPIO":[255,255,255,255,255,255,255,255,255,255,255,255,255],"FLAG":15,"BASE":18}
22:35:39 SRC: Backlog
22:35:39 CMD: Group 0, Index 1, Command "MODULE", Data ""
22:35:39 MQT: stat/bme_temp_out/RESULT = {"Module":{"18":"Generic"}}
22:35:39 SRC: Backlog
22:35:39 CMD: Group 0, Index 1, Command "GPIO", Data ""

• Provide the output of this command: Status 0:

22:37:44 CMD: Status 0
22:37:44 CMD: Group 0, Index 1, Command "STATUS", Data "0"
22:37:44 MQT: stat/bme_temp_out/STATUS = {"Status":{"Module":18,"FriendlyName":["Temperatura Esterna"],"Topic":"bme_temp_out","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":0,"LedMask":"FFFF","SaveData":1,"SaveState":1,"SwitchTopic":"0","SwitchMode":[0,0,0,0,0,0,0,0],"ButtonRetain":0,"SwitchRetain":0,"SensorRetain":0,"PowerRetain":0}}
22:37:44 MQT: stat/bme_temp_out/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"tasmotas","OtaUrl":"http://thehackbox.org/tasmota/release/tasmota.bin","RestartReason":"External System","Uptime":"0T00:02:50","StartupUTC":"2019-11-09T21:34:54","Sleep":0,"CfgHolder":4617,"BootCount":35,"SaveCount":68,"SaveAddress":"3F8000"}}
22:37:44 MQT: stat/bme_temp_out/STATUS2 = {"StatusFWR":{"Version":"7.0.0.4(tasmota)","BuildDateTime":"2019-11-09T21:37:03","Boot":31,"Core":"STAGE","SDK":"2.2.2-dev(5ab15d1)"}}
22:37:44 MQT: stat/bme_temp_out/STATUS3 = {"StatusLOG":{"SerialLog":0,"WebLog":4,"MqttLog":0,"SysLog":0,"LogHost":"","LogPort":514,"SSId":["guilan",""],"TelePeriod":10,"Resolution":"A98180C0","SetOption":["00008009","2805C8000100060000005A00000000000000","00000400","00000000"]}}
22:37:44 MQT: stat/bme_temp_out/STATUS4 = {"StatusMEM":{"ProgramSize":479,"Free":3596,"Heap":23,"ProgramFlashSize":4096,"FlashSize":4096,"FlashChipId":"164068","FlashMode":2,"Features":["00000809","8FC0A196","00000000","00000405","000000C0","00000800"],"Drivers":"1,2,3,4,8,9,10,12,29","Sensors":"1,2,3,9"}}
22:37:44 MQT: stat/bme_temp_out/STATUS5 = {"StatusNET":{"Hostname":"sensore24","IPAddress":"10.0.0.174","Gateway":"10.0.0.1","Subnetmask":"255.255.255.0","DNSServer":"10.0.0.1","Mac":"84:F3:EB:ED:63:A3","Webserver":2,"WifiConfig":4}}
22:37:44 MQT: stat/bme_temp_out/STATUS6 = {"StatusMQT":{"MqttHost":"10.0.0.150","MqttPort":1883,"MqttClientMask":"bme_temp_out","MqttClient":"bme_temp_out","MqttUser":"umbyha","MqttCount":1,"MAX_PACKET_SIZE":1000,"KEEPALIVE":30}}
22:37:44 MQT: stat/bme_temp_out/STATUS7 = {"StatusTIM":{"UTC":"Sat Nov 09 21:37:44 2019","Local":"Sat Nov 09 22:37:44 2019","StartDST":"Sun Mar 31 02:00:00 2019","EndDST":"Sun Oct 27 03:00:00 2019","Timezone":99,"Sunrise":"07:14","Sunset":"17:00"}}
22:37:44 MQT: stat/bme_temp_out/STATUS10 = {"StatusSNS":{"Time":"2019-11-09T22:37:44","ANALOG":{"A0":6},"BMP280":{"Temperature":25.30,"Pressure":960.07},"PressureUnit":"hPa","TempUnit":"C"}}
22:37:44 MQT: stat/bme_temp_out/STATUS11 = {"StatusSTS":{"Time":"2019-11-09T22:37:44","Uptime":"0T00:02:50","UptimeSec":170,"Heap":23,"SleepMode":"Normal","Sleep":0,"LoadAvg":999,"MqttCount":1,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"E0:46:9A:77:A6:DE","Channel":4,"RSSI":54,"LinkCount":1,"Downtime":"0T00:00:10"}}}

• Provide the output of the Console log output when you experience your issue; if applicable:
  (Please use weblog 4 for more debug information)

00:00:00 CFG: Loaded from flash at 3F9, Count 51
00:00:00 QPC: Flag 0E
00:00:00 SRC: Restart
00:00:00 Project tasmota Temperatura Esterna Version 7.0.0.4(tasmota)-STAGE
00:00:00 I2C: BMP280 found at 0x76
00:00:00 WIF: Checking connection...
00:00:00 WIF: Attempting connection...
00:00:00 WIF: Connecting to AP1 guilan in mode 11N as sensore24...
00:00:01 WIF: Checking connection...
00:00:01 WIF: Attempting connection...
00:00:02 WIF: Checking connection...
00:00:02 WIF: Attempting connection...
00:00:04 WIF: Checking connection...
00:00:04 WIF: Attempting connection...
00:00:05 WIF: Checking connection...
00:00:05 WIF: Attempting connection...
00:00:06 WIF: Checking connection...
00:00:06 WIF: Attempting connection...
00:00:06 QPC: Reset
00:00:07 WIF: Checking connection...
00:00:07 WIF: Attempting connection...
00:00:07 RSL: tele/bme_temp_out/STATE = {"Time":"1970-01-01T00:00:07","Uptime":"0T00:00:09","UptimeSec":9,"Heap":31,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":20,"MqttCount":0,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"FF:FF:FF:FF:FF:FF","Channel":14,"RSSI":100,"LinkCount":0,"Downtime":"0T00:00:00"}}
00:00:07 RSL: tele/bme_temp_out/SENSOR = {"Time":"1970-01-01T00:00:07","ANALOG":{"A0":6},"BMP280":{"Temperature":25.18,"Pressure":960.04},"PressureUnit":"hPa","TempUnit":"C"}
00:00:08 WIF: Checking connection...
00:00:08 WIF: Connected
00:00:08 HTP: Web server active on sensore24 with IP address 10.0.0.174
00:00:08 APP: Boot Count 26
00:00:08 CFG: Saved to flash at 3F8, Count 52, Bytes 4096
22:14:03 MQT: Attempting connection...
22:14:03 MQT: Connected
22:14:03 MQT: tele/bme_temp_out/LWT = Online (retained)
22:14:03 MQT: cmnd/bme_temp_out/POWER = 
22:14:03 MQT: Subscribe to cmnd/bme_temp_out/#
22:14:03 MQT: Subscribe to cmnd/tasmotas/#
22:14:03 MQT: Subscribe to cmnd/bme_temp_out_fb/#
22:14:03 MQT: tele/bme_temp_out/INFO1 = {"Module":"Generic","Version":"7.0.0.4(tasmota)","FallbackTopic":"cmnd/bme_temp_out_fb/","GroupTopic":"cmnd/tasmotas/"}
22:14:03 MQT: tele/bme_temp_out/INFO2 = {"WebServerMode":"Admin","Hostname":"sensore24","IPAddress":"10.0.0.174"}
22:14:03 MQT: tele/bme_temp_out/INFO3 = {"RestartReason":"Software/System restart"}
22:14:03 HTP: Main Menu
22:14:04 NTP: Drift 0, (UTC) Sat Nov 09 21:14:04 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019
22:14:11 MQT: tele/bme_temp_out/STATE = {"Time":"2019-11-09T22:14:11","Uptime":"0T00:00:19","UptimeSec":19,"Heap":27,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":32,"MqttCount":1,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"E0:46:9A:77:A6:DE","Channel":4,"RSSI":50,"LinkCount":1,"Downtime":"0T00:00:09"}}
22:14:11 MQT: tele/bme_temp_out/SENSOR = {"Time":"2019-11-09T22:14:11","ANALOG":{"A0":6},"BMP280":{"Temperature":25.18,"Pressure":960.03},"PressureUnit":"hPa","TempUnit":"C"}
22:14:21 MQT: tele/bme_temp_out/STATE = {"Time":"2019-11-09T22:14:21","Uptime":"0T00:00:29","UptimeSec":29,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":49,"MqttCount":1,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"E0:46:9A:77:A6:DE","Channel":4,"RSSI":48,"LinkCount":1,"Downtime":"0T00:00:09"}}
22:14:21 MQT: tele/bme_temp_out/SENSOR = {"Time":"2019-11-09T22:14:21","ANALOG":{"A0":6},"BMP280":{"Temperature":25.15,"Pressure":960.07},"PressureUnit":"hPa","TempUnit":"C"}
22:14:23 WIF: Checking connection...
22:14:23 WIF: Connected
22:14:31 MQT: tele/bme_temp_out/STATE = {"Time":"2019-11-09T22:14:31","Uptime":"0T00:00:39","UptimeSec":39,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":42,"MqttCount":1,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"E0:46:9A:77:A6:DE","Channel":4,"RSSI":48,"LinkCount":1,"Downtime":"0T00:00:09"}}
22:14:31 MQT: tele/bme_temp_out/SENSOR = {"Time":"2019-11-09T22:14:31","ANALOG":{"A0":6},"BMP280":{"Temperature":25.15,"Pressure":960.04},"PressureUnit":"hPa","TempUnit":"C"}
22:14:41 MQT: tele/bme_temp_out/STATE = {"Time":"2019-11-09T22:14:41","Uptime":"0T00:00:49","UptimeSec":49,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":28,"MqttCount":1,"Wifi":{"AP":1,"SSId":"guilan","BSSId":"E0:46:9A:77:A6:DE","Channel":4,"RSSI":52,"LinkCount":1,"Downtime":"0T00:00:09"}}
22:14:41 MQT: tele/bme_temp_out/SENSOR = {"Time":"2019-11-09T22:14:41","ANALOG":{"A0":6},"BMP280":{"Temperature":25.15,"Pressure":960.06},"PressureUnit":"hPa","TempUnit":"C"}
22:14:44 WIF: Checking connection...
22:14:44 WIF: Connected
........
22:17:27 CMD: deepsleeptime 30
22:17:27 SRC: WebConsole from 10.0.0.6
22:17:27 CMD: Group 0, Index 1, Command "DEEPSLEEPTIME", Data "30"
22:17:27 MQT: stat/bme_temp_out/RESULT = {"DeepSleepTime":30}
........
00:00:00 CFG: Loaded from flash at 3F5, Count 55
00:00:00 APP: Some settings have been reset (5)
00:00:00 SRC: Restart
00:00:00 Project tasmota Temperatura Esterna Version 7.0.0.4(tasmota)-STAGE
00:00:00 CFG: Saved to flash at 3F4, Count 56, Bytes 4096
00:00:00 WIF: Checking connection...
00:00:00 WIF: Attempting connection...
00:00:00 WIF: Connecting to AP1 guilan in mode 11N as sensore24...
00:00:01 WIF: Checking connection...
00:00:01 WIF: Attempting connection...
00:00:02 WIF: Checking connection...
00:00:02 WIF: Attempting connection...
00:00:03 WIF: Checking connection...
00:00:03 WIF: Attempting connection...
00:00:04 WIF: Checking connection...
00:00:04 WIF: Connected
00:00:04 HTP: Web server active on sensore24 with IP address 10.0.0.174
22:20:08 NTP: Drift 0, (UTC) Sat Nov 09 21:20:08 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019
22:20:09 MQT: Attempting connection...
22:20:10 MQT: Connected
22:20:10 MQT: tele/bme_temp_out/LWT = Online (retained)
22:20:10 MQT: cmnd/bme_temp_out/POWER = 
22:20:10 MQT: Subscribe to cmnd/bme_temp_out/#
22:20:10 MQT: Subscribe to cmnd/tasmotas/#
22:20:10 MQT: Subscribe to cmnd/bme_temp_out_fb/#
22:20:10 MQT: tele/bme_temp_out/INFO1 = {"Module":"Generic","Version":"7.0.0.4(tasmota)","FallbackTopic":"cmnd/bme_temp_out_fb/","GroupTopic":"cmnd/tasmotas/"}
22:20:10 MQT: tele/bme_temp_out/INFO2 = {"WebServerMode":"Admin","Hostname":"sensore24","IPAddress":"10.0.0.174"}
22:20:10 MQT: tele/bme_temp_out/INFO3 = {"RestartReason":"Deep-Sleep Wake"}
........
00:00:00 CFG: Loaded from flash at 3FB, Count 57
00:00:00 QPC: Flag 0E
00:00:00 SRC: Restart
00:00:00 Project tasmota Temperatura Esterna Version 7.0.0.4(tasmota)-STAGE
00:00:00 WIF: Checking connection...
00:00:00 WIF: Attempting connection...
00:00:00 WIF: Connecting to AP1 guilan in mode 11N as sensore24...
00:00:01 WIF: Checking connection...
00:00:01 WIF: Attempting connection...
00:00:02 WIF: Checking connection...
00:00:02 WIF: Attempting connection...
00:00:03 WIF: Checking connection...
00:00:03 WIF: Attempting connection...
00:00:04 WIF: Checking connection...
00:00:04 WIF: Connected
00:00:04 HTP: Web server active on sensore24 with IP address 10.0.0.174
22:20:36 NTP: Drift 0, (UTC) Sat Nov 09 21:20:36 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019
22:20:36 MQT: Attempting connection...
22:20:37 MQT: Connected
22:20:37 MQT: tele/bme_temp_out/LWT = Online (retained)
22:20:37 MQT: cmnd/bme_temp_out/POWER = 
22:20:37 MQT: Subscribe to cmnd/bme_temp_out/#
22:20:37 MQT: Subscribe to cmnd/tasmotas/#
22:20:37 MQT: Subscribe to cmnd/bme_temp_out_fb/#
22:20:37 MQT: tele/bme_temp_out/INFO1 = {"Module":"Generic","Version":"7.0.0.4(tasmota)","FallbackTopic":"cmnd/bme_temp_out_fb/","GroupTopic":"cmnd/tasmotas/"}
22:20:37 MQT: tele/bme_temp_out/INFO2 = {"WebServerMode":"Admin","Hostname":"sensore24","IPAddress":"10.0.0.174"}
22:20:37 MQT: tele/bme_temp_out/INFO3 = {"RestartReason":"Deep-Sleep Wake"}
22:20:37 QPC: Reset
22:20:39 APP: Boot Count 28
22:20:40 CFG: Saved to flash at 3FA, Count 58, Bytes 4096
........
00:00:00 CFG: Loaded from flash at 3FB, Count 57
00:00:00 QPC: Flag 0E
00:00:00 SRC: Restart
00:00:00 Project tasmota Temperatura Esterna Version 7.0.0.4(tasmota)-STAGE
00:00:00 WIF: Checking connection...
00:00:00 WIF: Attempting connection...
00:00:00 WIF: Connecting to AP1 guilan in mode 11N as sensore24...
00:00:01 WIF: Checking connection...
00:00:01 WIF: Attempting connection...
00:00:02 WIF: Checking connection...
00:00:02 WIF: Attempting connection...
00:00:03 WIF: Checking connection...
00:00:03 WIF: Attempting connection...
00:00:04 WIF: Checking connection...
00:00:04 WIF: Connected
00:00:04 HTP: Web server active on sensore24 with IP address 10.0.0.174
22:20:36 NTP: Drift 0, (UTC) Sat Nov 09 21:20:36 2019, (DST) Sun Mar 31 02:00:00 2019, (STD) Sun Oct 27 03:00:00 2019
22:20:36 MQT: Attempting connection...
22:20:37 MQT: Connected
22:20:37 MQT: tele/bme_temp_out/LWT = Online (retained)
22:20:37 MQT: cmnd/bme_temp_out/POWER = 
22:20:37 MQT: Subscribe to cmnd/bme_temp_out/#
22:20:37 MQT: Subscribe to cmnd/tasmotas/#
22:20:37 MQT: Subscribe to cmnd/bme_temp_out_fb/#
22:20:37 MQT: tele/bme_temp_out/INFO1 = {"Module":"Generic","Version":"7.0.0.4(tasmota)","FallbackTopic":"cmnd/bme_temp_out_fb/","GroupTopic":"cmnd/tasmotas/"}
22:20:37 MQT: tele/bme_temp_out/INFO2 = {"WebServerMode":"Admin","Hostname":"sensore24","IPAddress":"10.0.0.174"}
22:20:37 MQT: tele/bme_temp_out/INFO3 = {"RestartReason":"Deep-Sleep Wake"}
22:20:37 QPC: Reset
22:20:39 APP: Boot Count 28
22:20:40 CFG: Saved to flash at 3FA, Count 58, Bytes 4096
........
00:00:00 CFG: Loaded from flash at 3FA, Count 58
00:00:00 QPC: Flag 0E
00:00:00 SRC: Restart
00:00:00 Project tasmota Temperatura Esterna Version 7.0.0.4(tasmota)-STAGE
00:00:00 WIF: Checking connection...
00:00:00 WIF: Attempting connection...
00:00:00 WIF: Connecting to AP1 guilan in mode 11N as sensore24...
00:00:01 WIF: Checking connection...
00:00:01 WIF: Attempting connection...
00:00:02 WIF: Checking connection...
00:00:02 WIF: Attempting connection...
00:00:03 WIF: Checking connection...
00:00:03 WIF: Attempting connection...
00:00:04 WIF: Checking connection...
00:00:04 WIF: Connected
00:00:04 HTP: Web server active on sensore24 with IP address 10.0.0.174
00:00:05 MQT: Attempting connection...
00:00:06 MQT: Connected
00:00:06 MQT: tele/bme_temp_out/LWT = Online (retained)
00:00:06 MQT: cmnd/bme_temp_out/POWER = 
00:00:06 MQT: Subscribe to cmnd/bme_temp_out/#
00:00:06 MQT: Subscribe to cmnd/tasmotas/#
00:00:06 MQT: Subscribe to cmnd/bme_temp_out_fb/#
00:00:06 MQT: tele/bme_temp_out/INFO1 = {"Module":"Generic","Version":"7.0.0.4(tasmota)","FallbackTopic":"cmnd/bme_temp_out_fb/","GroupTopic":"cmnd/tasmotas/"}
00:00:06 MQT: tele/bme_temp_out/INFO2 = {"WebServerMode":"Admin","Hostname":"sensore24","IPAddress":"10.0.0.174"}
00:00:06 MQT: tele/bme_temp_out/INFO3 = {"RestartReason":"Deep-Sleep Wake"}
00:00:06 QPC: Reset
00:00:08 APP: Boot Count 29
00:00:09 CFG: Saved to flash at 3F9, Count 59, Bytes 4096

There are 3 screenshot:
after_reboot.jpg shows GPIO config after deepsleep funtion was disable.

sensor.jpg shows "normal function" status device

config.jpg shows GPIO configuration at setup

[arendst]

Verified. Investigating

[arendst]

Ah! Nice one too. As the deepsleep wake-up time is sometimes shorter than the fast boot cycle time detection it will reset some or all settings resulting in your and mine issue.

As a workaround change the fast boot cycle detection to off using command SetOption36 0.

[guiumb]

Great !
Let me know if I can provide you traces for help in fixing the issue.

Thanks in advance

[arendst]

Try latest commit. It woks for me.

[guiumb]

I have falshed from scratch and I have set up SetOption36 to 0 as you suggested.
Immidiatly when I wrote in console DeepSleepTime 30 device shutdown. After 1 min wakeup and sent just onlu INFOS1-2-3 and shutdown again. After that no wakeup action was completed: device remain inactive (no ping and no web response) but blue led on board flash every 30 secs.
I noticed the fixed label: should I download new source code compile it?

[arendst]

As said. Try latest commit. And yes that means download and recompile.

[guiumb]

I am sorry for misunderstanding !
I will do immediatly I will keep you updated.