Sonoff NSpanel weather stops refreshing

[navennn]

PROBLEM DESCRIPTION

The device stops requesting weathers updates after several days of uptime.
After reboot, I can clearly see that it sends DNS query for wttr.in towards my DNS server, receives an answer and then contacts 5.9.243.187 on port 80 as I would expect. It does that every 1 hour.
This stops after a few days and temperature is no longer being updated. While DNS queries for an NTP server can be seen from the device, nothing is coming for wttr.in anymore.
Device is reachable all the time, nothing else seems to be broken (I'm only using relays and the display). I am forcing the display to be always on.
I first installed 12.3.0 then upgraded 12.4.0 and now I'm on 12.5.0 for like a month. It has been an issue since I started using the device. I did not use any other software before this. I installed tasmota immediately after buying the device.

Uptime is 19T04:38:34 (I assume this means 19 days 4 hours 38 minutes?)
Last successful log I can see happened on 2023-06-17 01:41:42.161 CEST
From weblog output I'd expect an update to happen at around 14:42:00 (this time, every traffic log was around 41-42 minutes after every hour)

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): Sonoff NSPanel
• Tasmota binary firmware version number used: 12.5.0(tasmota32)
  • Pre-compiled
  • Self-compiled
• Flashing tools used: serial flashing (I followed this: https://templates.blakadder.com/sonoff_NSPanel.html)
• Provide the output of command: Backlog Template; Module; GPIO 255:

  Configuration output here:
14:05:56.085 CMD: Backlog Template; Module; GPIO 255
14:05:56.125 RSL: RESULT = {"NAME":"NSPanel","GPIO":[0,0,0,0,3872,0,0,0,0,0,32,0,0,0,0,225,0,480,224,1,0,0,0,33,0,0,0,0,0,0,0,0,0,0,4736,0],"FLAG":0,"BASE":1}
14:05:56.324 RSL: RESULT = {"Module":{"0":"NSPanel"}}
14:05:56.532 RSL: RESULT = {"GPIO0":{"0":"None"},"GPIO1":{"0":"None"},"GPIO2":{"0":"None"},"GPIO3":{"0":"None"},"GPIO4":{"3872":"Output Lo"},"GPIO5":{"0":"None"},"GPIO6":{"0":"None"},"GPIO7":{"0":"None"},"GPIO8":{"0":"None"},"GPIO9":{"0":"None"},"GPIO10":{"0":"None"},"GPIO11":{"0":"None"},"GPIO12":{"0":"None"},"GPIO13":{"0":"None"},"GPIO14":{"32":"Button1"},"GPIO15":{"0":"None"},"GPIO16":{"0":"None"},"GPIO17":{"0":"None"},"GPIO18":{"0":"None"},"GPIO19":{"225":"Relay2"},"GPIO20":{"0":"None"},"GPIO21":{"480":"Buzzer"},"GPIO22":{"224":"Relay1"},"GPIO23":{"0":"None"},"GPIO24":{"0":"None"},"GPIO25":{"0":"None"},"GPIO26":{"0":"None"},"GPIO27":{"33":"Button2"},"GPIO32":{"0":"None"},"GPIO33":{"0":"None"},"GPIO34":{"0":"None"},"GPIO35":{"0":"None"},"GPIO36":{"0":"None"},"GPIO37":{"0":"None"},"GPIO38":{"4736":"ADC Temp1"},"GPIO39":{"0":"None"}}
14:06:00.674 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":6,"week":0}

• 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:
14:20:50.533 CMD: STATUS 0
14:20:50.534 SRC: WebConsole from 192.168.34.20
14:20:50.537 CMD: Grp 0, Cmd 'STATUS', Idx 1, Len 1, Pld 0, Data '0'
14:20:50.541 RSL: STATUS = {"Status":{"Module":0,"DeviceName":"panel1.mydomain.com","FriendlyName":["panel1.mydomain.com",""],"Topic":"tasmota_30BCBC","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}}
14:20:50.562 RSL: STATUS1 = {"StatusPRM":{"Baudrate":115200,"SerialConfig":"8N1","GroupTopic":"tasmotas","OtaUrl":"https://ota.tasmota.com/tasmota32/release/tasmota32.bin","RestartReason":"Vbat power on reset","Uptime":"19T04:46:33","StartupUTC":"2023-05-30T07:34:17","Sleep":0,"CfgHolder":4617,"BootCount":28,"BCResetTime":"2022-12-30T10:56:19","SaveCount":485}}
14:20:50.579 RSL: STATUS2 = {"StatusFWR":{"Version":"12.5.0(tasmota32)","BuildDateTime":"2023-04-17T08:07:16","Core":"2_0_7","SDK":"4.4.4.20230310","CpuFrequency":160,"Hardware":"ESP32-D0WD-V3 rev.3","CR":"434/699"}}
14:20:50.593 RSL: STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":3,"MqttLog":0,"SysLog":0,"LogHost":"graylog.mydomain.com","LogPort":514,"SSId":["Krig",""],"TelePeriod":300,"Resolution":"558180C0","SetOption":["00008001","2805C80001000600003C5A0A192800000000","00000080","00006000","00004000","00000000"]}}
14:20:50.621 RSL: STATUS4 = {"StatusMEM":{"ProgramSize":1525,"Free":1354,"Heap":123,"StackLowMark":3,"PsrMax":0,"PsrFree":0,"ProgramFlashSize":4096,"FlashSize":4096,"FlashChipId":"16405E","FlashFrequency":40,"FlashMode":"DIO","Features":["00000809","9F9AD7CF","0015A001","B7F7BFCF","05DA9BC0","E0360DC7","480840D2","20200000","D4BC482D","000A80B1"],"Drivers":"1,2,3,4,5,7,8,9,10,11,12,14,16,17,20,21,24,26,27,29,34,35,38,50,52,59,60,62,63,66,67,82,86,87,88","Sensors":"1,2,3,5,6,7,8,9,10,11,12,13,14,15,17,18,19,20,21,22,26,31,34,37,39,40,42,43,45,51,52,55,56,58,59,64,66,67,74,85,92,95,98,103,105,127","I2CDriver":"7,8,9,10,11,12,13,14,15,17,18,20,24,29,31,36,41,42,44,46,48,58,62,65,69,76,77"}}
14:20:50.642 RSL: STATUS5 = {"StatusNET":{"Hostname":"tasmota-30BCBC-7356","IPAddress":"192.168.41.44","Gateway":"192.168.41.1","Subnetmask":"255.255.255.0","DNSServer1":"192.168.39.13","DNSServer2":"192.168.39.15","Mac":"0C:DC:7E:30:BC:BC","IP6Global":"","IP6Local":"fe80::edc:7eff:fe30:bcbc","Ethernet":{"Hostname":"","IPAddress":"0.0.0.0","Gateway":"0.0.0.0","Subnetmask":"0.0.0.0","DNSServer1":"192.168.39.13","DNSServer2":"192.168.39.15","Mac":"00:00:00:00:00:00","IP6Global":"","IP6Local":""},"Webserver":2,"HTTP_API":1,"WifiConfig":4,"WifiPower":17.0}}
14:20:50.664 RSL: STATUS7 = {"StatusTIM":{"UTC":"2023-06-18T12:20:50","Local":"2023-06-18T14:20:50","StartDST":"2023-03-26T02:00:00","EndDST":"2023-10-29T03:00:00","Timezone":99,"Sunrise":"05:46","Sunset":"21:56"}}
14:20:50.682 RSL: STATUS10 = {"StatusSNS":{"Time":"2023-06-18T14:20:50","ANALOG":{"Temperature1":26.1},"TempUnit":"C"}}
14:20:50.699 RSL: STATUS11 = {"StatusSTS":{"Time":"2023-06-18T14:20:50","Uptime":"19T04:46:33","UptimeSec":1658793,"Heap":116,"SleepMode":"Dynamic","Sleep":0,"LoadAvg":1001,"MqttCount":0,"Berry":{"HeapUsed":22,"Objects":458},"POWER1":"OFF","POWER2":"OFF","Wifi":{"AP":1,"SSId":"Krig","BSSId":"76:4D:28:4C:0A:16","Channel":1,"Mode":"11n","RSSI":100,"Signal":-34,"LinkCount":1,"Downtime":"0T00:00:04"}}}

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

  Console output here:


14:37:59.984 RSL: RESULT = {"WebLog":4}
14:38:00.781 CFG: Saved, Count 487, Bytes 4096
14:38:00.794 BRY: GC from 33459 to 22785 bytes, objects freed 271/457 (in 3 ms) - slots from 811/822 to 375/595
14:38:00.801 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":38,"week":0}
14:38:00.803 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":38,"week":0}
14:38:00.831 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:38:09.063 BRY: GC from 33046 to 22745 bytes, objects freed 209/456 (in 3 ms) - slots from 703/717 to 371/595
14:38:16.787 WIF: Checking connection...
14:38:36.759 WIF: Checking connection...
14:38:39.661 NTP: Sync time...
14:38:39.670 WIF: DNS resolved 'ntp.mydomain.com' (192.168.39.6) in 7 ms
14:38:39.687 RTC: UTC 2023-06-18T12:38:40, DST 2023-03-26T02:00:00, STD 2023-10-29T03:00:00
14:38:40.000 RTC: Synced by NTP
14:38:50.461 BRY: GC from 32992 to 22828 bytes, objects freed 306/456 (in 2 ms) - slots from 888/897 to 373/595
14:38:56.759 WIF: Checking connection...
14:39:00.670 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":39,"week":0}
14:39:00.672 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":39,"week":0}
14:39:00.698 BRY: GC from 33139 to 23352 bytes, objects freed 221/464 (in 3 ms) - slots from 739/762 to 381/625
14:39:00.705 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:39:16.761 WIF: Checking connection...
14:39:36.760 WIF: Checking connection...
14:39:41.060 BRY: GC from 33648 to 22764 bytes, objects freed 298/455 (in 3 ms) - slots from 863/882 to 371/625
14:39:56.760 WIF: Checking connection...
14:40:00.670 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":40,"week":0}
14:40:00.672 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":40,"week":0}
14:40:00.689 BRY: GC from 33008 to 23352 bytes, objects freed 237/464 (in 3 ms) - slots from 772/792 to 381/625
14:40:00.705 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:40:16.760 WIF: Checking connection...
14:40:27.067 BRY: GC from 33599 to 22798 bytes, objects freed 276/457 (in 2 ms) - slots from 822/853 to 374/641
14:40:36.759 WIF: Checking connection...
14:40:56.760 WIF: Checking connection...
14:41:00.670 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":41,"week":0}
14:41:00.672 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":41,"week":0}
14:41:00.682 BRY: GC from 33059 to 23376 bytes, objects freed 259/465 (in 2 ms) - slots from 816/852 to 382/625
14:41:00.705 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:41:16.759 WIF: Checking connection...
14:41:17.666 BRY: GC from 33626 to 22732 bytes, objects freed 257/455 (in 3 ms) - slots from 781/808 to 370/595
14:41:36.770 WIF: Checking connection...
14:41:56.760 WIF: Checking connection...
14:42:00.670 BRY: GC from 32977 to 22731 bytes, objects freed 306/454 (in 2 ms) - slots from 882/897 to 371/595
14:42:00.674 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":42,"week":0}
14:42:00.676 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":42,"week":0}
14:42:00.703 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:42:08.266 BRY: GC from 32973 to 22745 bytes, objects freed 207/456 (in 3 ms) - slots from 699/717 to 371/626
14:42:16.763 WIF: Checking connection...
14:42:36.760 WIF: Checking connection...
14:42:42.664 BRY: GC from 30404 to 22635 bytes, objects freed 232/453 (in 2 ms) - slots from 756/791 to 368/564
14:42:42.668 RSL: STATE = {"Time":"2023-06-18T14:42:42","Uptime":"19T05:08:25","UptimeSec":1660105,"Heap":120,"SleepMode":"Dynamic","Sleep":0,"LoadAvg":999,"MqttCount":0,"Berry":{"HeapUsed":22,"Objects":453},"POWER1":"OFF","POWER2":"OFF","Wifi":{"AP":1,"SSId":"Krig","BSSId":"76:4D:28:4C:0A:16","Channel":1,"Mode":"11n","RSSI":100,"Signal":-34,"LinkCount":1,"Downtime":"0T00:00:04"}}
14:42:42.681 NSP: Wi-Fi icon set with {"wifiState":"connected","rssiLevel":4}
14:42:42.682 NSP: Sent = {"wifiState":"connected","rssiLevel":4}
14:42:42.703 NSP: NSPanel payload sent = bytes('55AA8527007B22776966695374617465223A22636F6E6E6563746564222C2272...')
14:42:42.716 RSL: SENSOR = {"Time":"2023-06-18T14:42:42","ANALOG":{"Temperature1":26.3},"TempUnit":"C"}
14:42:42.725 NSP: Indoor temperature set with {"temperature":26.3,"tempUnit":"C"}
14:42:42.727 NSP: Sent = {"temperature":26.3,"tempUnit":"C"}
14:42:42.736 BRY: GC from 32886 to 23185 bytes, objects freed 189/462 (in 3 ms) - slots from 693/732 to 377/641
14:42:42.752 NSP: NSPanel payload sent = bytes('55AA8323007B2274656D7065726174757265223A32362E332C2274656D70556E...')
14:42:56.759 WIF: Checking connection...
14:43:00.673 BRY: GC from 33466 to 23193 bytes, objects freed 250/464 (in 3 ms) - slots from 787/807 to 379/595
14:43:00.676 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":43,"week":0}
14:43:00.678 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":43,"week":0}
14:43:00.705 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:43:12.662 BRY: GC from 33441 to 22727 bytes, objects freed 228/455 (in 4 ms) - slots from 727/732 to 370/580
14:43:16.760 WIF: Checking connection...
14:43:36.760 WIF: Checking connection...
14:43:56.760 WIF: Checking connection...
14:43:58.662 BRY: GC from 33013 to 22950 bytes, objects freed 305/460 (in 3 ms) - slots from 885/897 to 379/626
14:44:00.670 NSP: Time and date synced with {"year":2023,"mon":6,"day":18,"hour":14,"min":44,"week":0}
14:44:00.671 NSP: Sent = {"year":2023,"mon":6,"day":18,"hour":14,"min":44,"week":0}
14:44:00.699 NSP: NSPanel payload sent = bytes('55AA823A007B2279656172223A323032332C226D6F6E223A362C22646179223A...')
14:44:03.263 BRY: GC from 33194 to 22807 bytes, objects freed 211/457 (in 3 ms) - slots from 708/732 to 373/626
14:44:16.759 WIF: Checking connection...
14:44:36.761 WIF: Checking connection...
14:44:49.259 BRY: GC from 33054 to 22860 bytes, objects freed 307/457 (in 3 ms) - slots from 886/897 to 374/595

TO REPRODUCE

I don't know what triggers it, it seems to happen every 2 weeks

EXPECTED BEHAVIOUR

Weather being refreshed all the time

SCREENSHOTS

If applicable, add screenshots to help explain your problem.

ADDITIONAL CONTEXT

Network access is firewalled. No traffic that is not necessary is not allowed. I am not seeing any denied traffic in firewall's traffic logs originating from the device.

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

[RobertoCravallo]

I have the same problem, the weather only updates, after the device is rebooted. Not sure when this started happening. I am using TASMOTA 13.

[RobertoCravallo]

Strange, aber getting a new FRITZBOX (6690) the panel does update. My old box (6591) is being used as mesh repeater. Looks like the panel is now updated regularly.

[ip6li]

Root cause is unreliable wttr.in. Tasmota logs a timeout for running nspanel.be. Somtimes wttr.in delivers senseless data like "j2r" instead a well formed json file. My solution consists on two parts now:

1. Temperature is read from my local weather station now, this depends an LAN only, this works even if ISP is down.
2. Data from wttr.in are read from an external Linux server (Raspi) by a simple curl script which verifies delivered data for well formed JSON format. If no valid data are available, last valid JSON file is provided.

nspanel reads all data from local web server, no more problems now.

[Noschvie]

@ip6li why not using Berry to verify the delivered data?

[ip6li]

In case of timeout of remote site berry seems to be stop further processing of nspanel.be. If remote site respond in time, it is a of course a valid option to verify JSON file by nspanel.be script.

[Noschvie]

Maybe this helps to handle the timeout.