Tasmota: syslog stops working after reboot

Created on 8 Mar 2018  路  36Comments  路  Source: arendst/Tasmota

I'm using one of my Debian Machines as remote syslog Server. If have setup all of my tasmota devices to syslog to this remote Machine. Remote syslog works fine till I reboot one of my tasmota's. After this, the the rebootet device stops logging to the remote machine until I go into the Logging options and simply press "save". After saving, it starts logging to remote again.

Im using Sonoff S20, status 0:

16:53:03 MQT: stat/tasmota3/STATUS = {"Status":{"Module":8,"FriendlyName":"deckenfluter","Topic":"tasmota3","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}}
16:53:03 MQT: stat/tasmota3/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://domus1:80/api/arduino/sonoff.ino.bin","Uptime":"0 01:54:07","Sleep":50,"BootCount":57,"SaveCount":357,"SaveAddress":"F6000"}}
16:53:03 MQT: stat/tasmota3/STATUS2 = {"StatusFWR":{"Version":"5.12.0","BuildDateTime":"2018.02.09 14:55:34","Boot":6,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}}
16:53:03 MQT: stat/tasmota3/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":2,"SysLog":4,"LogHost":"192.168.1.20","LogPort":514,"SSId1":"XXXX","SSId2":"XXXX","TelePeriod":300,"SetOption":"55800009"}}
16:53:03 MQT: stat/tasmota3/STATUS4 = {"StatusMEM":{"ProgramSize":486,"Free":516,"Heap":21,"ProgramFlashSize":1024,"FlashSize":1024,"FlashMode":3}}
16:53:03 MQT: stat/tasmota3/STATUS5 = {"StatusNET":{"Hostname":"tasmota3","IPAddress":"192.168.1.60","Gateway":"192.168.1.2","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.18","Mac":"68:C6:3A:A2:EA:05","Webserver":2,"WifiConfig":2}}
16:53:03 MQT: stat/tasmota3/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.21","MqttPort":1883,"MqttClientMask":"tasmota3","MqttClient":"tasmota3","MqttUser":"DVES_USER","MAX_PACKET_SIZE":1000,"KEEPALIVE":15}}
16:53:03 MQT: stat/tasmota3/STATUS7 = {"StatusTIM":{"UTC":"Thu Mar 08 15:53:03 2018","Local":"Thu Mar 08 16:53:03 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":1}}
16:53:03 MQT: stat/tasmota3/STATUS10 = {"StatusSNS":{"Time":"2018.03.08 16:53:03"}}
16:53:03 MQT: stat/tasmota3/STATUS11 = {"StatusSTS":{"Time":"2018.03.08 16:53:03","Uptime":"0 01:54:07","Vcc":3.234,"POWER":"OFF","Wifi":{"AP":1,"SSId":"XXXX","RSSI":78,"APMac":"XXXX"}}}

awaiting feedback fixed troubleshooting
Source
picture cyablo

Most helpful comment

@cyablo @reloxx13 @bollitec

Found the issue!!!! I could reproduce it!

_When you have emulation enabled, syslog goes off._ _(See lines 1007, 1599 and 2516 in sonoff.ino)_

So, as it is an intended behaviour in the code I will ask you to test if Alexa works when you have syslog working (doing the save in the webmenu). I don't have an Alexa to test it.

If Alexa works when you have syslog working, we can just change the lines 1007, 1599 and 2516 in _sonoff.ino_ from:
syslog_level = (Settings.flag2.emulation) ? 0 : Settings.syslog_level;
to
syslog_level = Settings.syslog_level;

If Alexa don't work, that means that both features can not be used at the same time.

picture ascillato on 12 Sep 2018
🎉2 👍1

All 36 comments

Make sure these boxes are checked before submitting your issue - Thank you!

  • [x] What hardware you are using
  • [x] Provide the output of command status 0
curzon01 picture curzon01 on 8 Mar 2018

Added Info.

cyablo picture cyablo on 8 Mar 2018

Status output looks fine. Pls try 5.12.0d which has some changes regarding syslog DNS.

What's the value of syslog after a restart?

arendst picture arendst on 8 Mar 2018

Hello there,

found this issue with nearly the same situation:

Workflow:
a)
Set via MQTT SysLogInfo: mosquitto_pub -t 'cmnd/sonoffs/Backlog' -m 'loghost raspi; syslog 4; logport 514'
b)
Response: stat/sonoff1/RESULT {"Backlog":"Appended"}
stat/sonoff1/RESULT {"LogHost":"raspi"}
stat/sonoff1/RESULT {"SysLog":"4 (Active 0)"}
stat/sonoff1/RESULT {"LogPort":514}
c)
no input to SysLog Server => seems the Active 0
d)
got to web and click only save (as mentioned from @cyablo)...
mosquitto_pub -t 'cmnd/sonoffs/Backlog' -m 'syslog'
stat/sonoff1/RESULT {"SysLog":"4 (Active 4)"}
e)
reboot and control
mosquitto_pub -t 'cmnd/sonoffs/Backlog' -m 'syslog'
stat/sonoff1/RESULT {"SysLog":"4 (Active 0)"}

How can the Syslog activated without use the web save button?

bollitec picture bollitec on 3 May 2018
👍1

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.

stale[bot] picture stale[bot] on 17 Jun 2018

this behaviour still exists.

without save active 0, after save its active 4

6.0.[email protected], sylog host is a local ip and port 514

reloxx13 picture reloxx13 on 1 Jul 2018

Same issue here with Tasmota 5.13.1 and on all hardware (Sonoff basics and general (wemos D1)
<
09:51:33 CMD: status 0
09:51:33 MQT: stat/wemos82/STATUS = {"Status":{"Module":18,"FriendlyName":["television","blavbla"],"Topic":"wemos82","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}}
09:51:33 MQT: stat/wemos82/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://sonoff.maddox.co.uk/tasmota/sonoff.ino.bin","RestartReason":"Software/System restart","Uptime":"0T00:10:54","StartupUTC":"2018-08-11T07:40:39","Sleep":0,"BootCount":10,"SaveCount":22,"SaveAddress":"3FA000"}}
09:51:33 MQT: stat/wemos82/STATUS2 = {"StatusFWR":{"Version":"5.13.1","BuildDateTime":"2018-08-11T09:40:13","Boot":31,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}}
09:51:33 MQT: stat/wemos82/STATUS3 = {"StatusLOG":{"SerialLog":0,"WebLog":2,"SysLog":2,"LogHost":"192.168.178.59","LogPort":514,"SSId":["mmwireless",""],"TelePeriod":300,"SetOption":["00008009","55C18000"]}}
09:51:33 MQT: stat/wemos82/STATUS4 = {"StatusMEM":{"ProgramSize":496,"Free":524,"Heap":10,"ProgramFlashSize":4096,"FlashSize":4096,"FlashMode":2}}
09:51:33 MQT: stat/wemos82/STATUS5 = {"StatusNET":{"Hostname":"wemos82","IPAddress":"192.168.178.82","Gateway":"192.168.178.1","Subnetmask":"255.255.255.0","DNSServer":"192.168.178.1","Mac":"2C:3A:E8:0B:4D:60","Webserver":2,"WifiConfig":3}}
09:51:33 MQT: stat/wemos82/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.178.59","MqttPort":8883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_0B4D60","MqttUser":"mqtt","MqttType":3,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}}
09:51:33 MQT: stat/wemos82/STATUS7 = {"StatusTIM":{"UTC":"Sat Aug 11 07:51:33 2018","Local":"Sat Aug 11 09:51:33 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":99}}
09:51:33 MQT: stat/wemos82/STATUS10 = {"StatusSNS":{"Time":"2018-08-11T09:51:33"}}
09:51:33 MQT: stat/wemos82/STATUS11 = {"StatusSTS":{"Time":"2018-08-11T09:51:33","Uptime":"0T00:10:54","Vcc":2.779,"POWER1":"OFF","POWER2":"OFF","Wifi":{"AP":1,"SSId":"mmwireless","RSSI":36,"APMac":"38:10:D5:F1:C7:0C"}}}
>

10:00:36 CMD: syslog
10:00:36 MQT: stat/wemos82/RESULT = {"SysLog":"2 (Active 2)"}

---RESTART---

10:01:25 CMD: syslog
10:01:25 MQT: stat/wemos82/RESULT = {"SysLog":"2 (Active 0)"}

mrohner picture mrohner on 11 Aug 2018

v6.1.1 is the up-to-date version, try this - I did not expect it with that.

curzon01 picture curzon01 on 11 Aug 2018

Well, I just updated all devices to Tasmota 5.13.1. I will not install new releases just for that bug. You probably know the with new releases you get new bugs. So I will not change my running system.

mrohner picture mrohner on 13 Aug 2018

@cyablo

Hi,

Have you managed to solve your issue?

ascillato2 picture ascillato2 on 4 Sep 2018

@ascillato2 I'm not 100% sure since I migrated my main VM from jessie to stretch and did not changed the syslog destination IP in tasmota since then. But a quick test shows the excact same behavior. No logging after reboot till you hit the save button again, running Tasmota 6.2.0.

cyablo picture cyablo on 4 Sep 2018

Can't still reproduce the issue,a lso not with workflow from @bollitec - it may be related to other cases but the infos given here are to less - what's the steps from first flash a device to reproduce the issue

curzon01 picture curzon01 on 4 Sep 2018

Pretty simple for me:

1) Enable UDP+TCP Input on Port 514 on a remote syslog server.
2) Go to logging settings of one of your tasmota devices and enter IP-Adress of the remote syslog server and save.
3) See log entrys getting written to your remote logfile.
4) Reboot tasmota device.
5) See no more log entrys getting written on remote syslog.
6) Go to logging settings of your tasmota device and press save.
7) See log entrys come flying in again.

cyablo picture cyablo on 4 Sep 2018

Cannot confirm.

Pls provide latest status 0 information.

arendst picture arendst on 4 Sep 2018

Status now:

12:34:56 MQT: stat/tasmota7/STATUS = {"Status":{"Module":8,"FriendlyName":["Lichterkette"],"Topic":"tasmota7","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}}
12:34:56 MQT: stat/tasmota7/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://:80/sonweb/data/firmwares/sonoff.bin","RestartReason":"Software/System restart","Uptime":"0T04:19:37","StartupUTC":"2018-09-04T06:15:19","Sleep":50,"BootCount":55,"SaveCount":597,"SaveAddress":"F4000"}}
12:34:56 MQT: stat/tasmota7/STATUS2 = {"StatusFWR":{"Version":"6.2.0","BuildDateTime":"2018.08.31 15:19:15","Boot":31,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}}
12:34:56 MQT: stat/tasmota7/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":2,"SysLog":2,"LogHost":"192.168.1.26","LogPort":514,"SSId":["xxx","xxx"],"TelePeriod":300,"SetOption":["00000009","55A18000","00000001"]}}
12:34:56 MQT: stat/tasmota7/STATUS4 = {"StatusMEM":{"ProgramSize":473,"Free":528,"Heap":17,"ProgramFlashSize":1024,"FlashSize":1024,"FlashMode":3,"Features":["00000407","0FDAE794","24000000","23B617CE","00000000"]}}
12:34:56 MQT: stat/tasmota7/STATUS5 = {"StatusNET":{"Hostname":"tasmota7","IPAddress":"192.168.1.43","Gateway":"192.168.1.2","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.18","Mac":"EC:FA:BC:12:F7:E5","Webserver":2,"WifiConfig":5}}
12:34:56 MQT: stat/tasmota7/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.26","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_12F7E5","MqttUser":"DVES_USER","MqttType":1,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}}
12:34:56 MQT: stat/tasmota7/STATUS7 = {"StatusTIM":{"UTC":"Tue Sep 04 10:34:56 2018","Local":"Tue Sep 04 12:34:56 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":99,"Sunrise":"07:11","Sunset":"20:27"}}
12:34:56 MQT: stat/tasmota7/STATUS10 = {"StatusSNS":{"Time":"2018-09-04T12:34:56"}}
12:34:56 MQT: stat/tasmota7/STATUS11 = {"StatusSTS":{"Time":"2018-09-04T12:34:56","Uptime":"0T04:19:37","Vcc":3.196,"POWER":"OFF","Wifi":{"AP":1,"SSId":"xxx","RSSI":82,"APMac":"xxx"}}}

Status after reboot:

12:38:54 MQT: stat/tasmota7/STATUS = {"Status":{"Module":8,"FriendlyName":["Lichterkette"],"Topic":"tasmota7","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}}
12:38:54 MQT: stat/tasmota7/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://:80/sonweb/data/firmwares/sonoff.bin","RestartReason":"Exception","Uptime":"0T00:00:17","StartupUTC":"2018-09-04T10:38:37","Sleep":50,"BootCount":57,"SaveCount":599,"SaveAddress":"FA000"}}
12:38:54 MQT: stat/tasmota7/STATUS2 = {"StatusFWR":{"Version":"6.2.0","BuildDateTime":"2018.08.31 15:19:15","Boot":31,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}}
12:38:54 MQT: stat/tasmota7/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":2,"SysLog":2,"LogHost":"192.168.1.26","LogPort":514,"SSId":["xxx","xxx"],"TelePeriod":300,"SetOption":["00000009","55A18000","00000001"]}}
12:38:54 MQT: stat/tasmota7/STATUS4 = {"StatusMEM":{"ProgramSize":473,"Free":528,"Heap":17,"ProgramFlashSize":1024,"FlashSize":1024,"FlashMode":3,"Features":["00000407","0FDAE794","24000000","23B617CE","00000000"]}}
12:38:54 MQT: stat/tasmota7/STATUS5 = {"StatusNET":{"Hostname":"tasmota7","IPAddress":"192.168.1.43","Gateway":"192.168.1.2","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.18","Mac":"EC:FA:BC:12:F7:E5","Webserver":2,"WifiConfig":5}}
12:38:54 MQT: stat/tasmota7/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.26","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_12F7E5","MqttUser":"DVES_USER","MqttType":1,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}}
12:38:54 MQT: stat/tasmota7/STATUS7 = {"StatusTIM":{"UTC":"Tue Sep 04 10:38:54 2018","Local":"Tue Sep 04 12:38:54 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":99,"Sunrise":"07:11","Sunset":"20:27"}}
12:38:54 MQT: stat/tasmota7/STATUS10 = {"StatusSNS":{"Time":"2018-09-04T12:38:54"}}
12:38:54 MQT: stat/tasmota7/STATUS11 = {"StatusSTS":{"Time":"2018-09-04T12:38:54","Uptime":"0T00:00:17","Vcc":3.196,"POWER":"OFF","Wifi":{"AP":1,"SSId":"xxx","RSSI":88,"APMac":"xxx"}}}

And again, no logs until save button under logging is pressed once.

cyablo picture cyablo on 4 Sep 2018

Set sleep to 0 and try again pls.

You might want to play with the code below in file support.ino lines 2244:

  if (syslog_host_hash != GetHash(Settings.syslog_host, strlen(Settings.syslog_host))) {
    syslog_host_hash = GetHash(Settings.syslog_host, strlen(Settings.syslog_host));
    WiFi.hostByName(Settings.syslog_host, syslog_host_addr);  // If sleep enabled this might result in exception so try to do it once using hash
  }
  if (PortUdp.beginPacket(syslog_host_addr, Settings.syslog_port)) {
arendst picture arendst on 4 Sep 2018

Set sleep to 0 -> reboot = no logs :(

cyablo picture cyablo on 4 Sep 2018

Hi

I have tested syslog with last version v6.2.1.2 and if I set syslog 4 and make a software reboot or a powercycle it remains at 4.

Could be that you have a retain flag in your mqtt server that is sending syslog 0?

To check this, please enable weblog 4, make a reboot and check what are the commands coming from mqtt at startup from the Tasmota console.

ascillato2 picture ascillato2 on 7 Sep 2018

Hi,

Have you managed to solve your issue?

ascillato picture ascillato on 9 Sep 2018

nope, still not fixed on 6.2.0.[email protected] ...

re-produce:
go on web gui
set syslog 4 more debug
hit save
check syslog => incoming logs
console enter syslog, result => 

19:05:30 CMD: syslog 4
19:05:30 RSL: RESULT = {"SysLog":"4 (Active 4)"}

restart device by command restart 1

check syslog => no new entries
go on web gui
check syslog config => still on syslog 4 more debug
enter syslog in console, result =>

19:07:55 CMD: syslog
19:07:55 RSL: RESULT = {"SysLog":"4 (Active 0)"}

go back to logging config page on web gui
hit save
check syslog => new logs incoming
check syslog command, result =>

19:09:48 CMD: syslog
19:09:48 RSL: RESULT = {"SysLog":"4 (Active 4)"}

entering syslog 4 as comand also does not re-activate it, no mqtt/ha in use.

status 0:

{
  "Status": {
    "Module": 1,
    "FriendlyName": [
      "sOnOff-4-4MB"
    ],
    "Topic": "sonoff",
    "ButtonTopic": "0",
    "Power": 0,
    "PowerOnState": 0,
    "LedState": 1,
    "SaveData": 1,
    "SaveState": 1,
    "ButtonRetain": 0,
    "PowerRetain": 0
  },
  "StatusPRM": {
    "Baudrate": 115200,
    "GroupTopic": "sonoffs",
    "OtaUrl": "http://192.168.XXX.XXX:XXXXXX/data/firmwares/sonoff.bin",
    "RestartReason": "Software/System restart",
    "Uptime": "0T00:00:23",
    "StartupUTC": "2018-09-09T17:13:35",
    "Sleep": 150,
    "BootCount": 114,
    "SaveCount": 176,
    "SaveAddress": "3F5000"
  },
  "StatusFWR": {
    "Version": "6.2.0.2",
    "BuildDateTime": "2018.09.05 00:41:35",
    "Boot": 31,
    "Core": "2_3_0",
    "SDK": "1.5.3(aec24ac9)"
  },
  "StatusLOG": {
    "SerialLog": 0,
    "WebLog": 2,
    "SysLog": 4,
    "LogHost": "192.168.XXX.XXX",
    "LogPort": 514,
    "SSId": [
      "XXX",
      "XXX"
    ],
    "TelePeriod": 300,
    "SetOption": [
      "00008001",
      "56A18000",
      "00000001"
    ]
  },
  "StatusMEM": {
    "ProgramSize": 437,
    "Free": 2632,
    "Heap": 21,
    "ProgramFlashSize": 4096,
    "FlashSize": 4096,
    "FlashMode": 3,
    "Features": [
      "00000407",
      "0F080B94",
      "94000001",
      "00000496",
      "00000000"
    ]
  },
  "StatusNET": {
    "Hostname": "sOnOff-4-4MB",
    "IPAddress": "192.168.XX.XX",
    "Gateway": "192.168.XX.XX",
    "Subnetmask": "255.255.XX.0",
    "DNSServer": "192.168.XX.XX",
    "Mac": "60:01:94:74:XX:XX",
    "Webserver": 2,
    "WifiConfig": 5
  },
  "StatusTIM": {
    "UTC": "Sun Sep 09 17:13:58 2018",
    "Local": "Sun Sep 09 19:13:58 2018",
    "StartDST": "Sun Mar 25 02:00:00 2018",
    "EndDST": "Sun Oct 28 03:00:00 2018",
    "Timezone": 99,
    "Sunrise": "07:18",
    "Sunset": "20:16"
  },
  "StatusSNS": {
    "Time": "2018-09-09T19:13:58",
    "BME280": {
      "Temperature": 21.8,
      "Humidity": 55.7,
      "Pressure": 1014.9
    },
    "TempUnit": "C"
  },
  "StatusSTS": {
    "Time": "2018-09-09T19:13:58",
    "Uptime": "0T00:00:23",
    "Vcc": 3.723,
    "POWER": "OFF",
    "Wifi": {
      "AP": 1,
      "SSId": "XXX",
      "BSSId": "44:4E:6D:XX:XX:XX",
      "Channel": 1,
      "RSSI": 60
    }
  }
}
reloxx13 picture reloxx13 on 9 Sep 2018

In your console, when Tasmota starts, it shows something about syslog like a retained message?

ascillato picture ascillato on 9 Sep 2018

no ha/mqtt in use, updated comment, pls wait a sec ill add status 0 on above comment

reloxx13 picture reloxx13 on 9 Sep 2018

log right after restart 1

00:00:00 Projekt sonoff sOnOff-4-4MB (Topic sonoff, Fallback DVES_746DCF, GroupTopic sonoffs) Version 6.2.0.2-2_3_0
00:00:00 WIF: verbinden mit AP1 XXX in Modus 11N wie sOnOff-4-4MB...
00:00:06 WIF: verbunden
00:00:06 DNS: initialisiert
00:00:06 HTP: Web-Server aktiv bei sOnOff-4-4MB.local mit IP-Adresse 192.168.178.XX
00:00:06 UPP: Multicast (wieder-)verbunden
00:00:06 RSL: INFO1 = {"Module":"Sonoff Basic","Version":"6.2.0.2","FallbackTopic":"DVES_746DCF","GroupTopic":"sonoffs"}
00:00:06 RSL: INFO2 = {"WebServerMode":"Admin","Hostname":"sOnOff-4-4MB","IPAddress":"192.168.178.XX"}
00:00:06 RSL: INFO3 = {"RestartReason":"Software/System restart"}
00:00:06 RSL: RESULT = {"POWER":"OFF"}
00:00:06 RSL: POWER = OFF
00:00:09 WIF: verbunden
00:00:09 DNS: initialisiert
00:00:09 HTP: Web-Server aktiv bei sOnOff-4-4MB.local mit IP-Adresse 192.168.178.XX
00:00:09 UPP: Multicast (wieder-)verbunden
19:17:02 RSL: STATE = {"Time":"2018-09-09T19:17:02","Uptime":"0T00:00:17","Vcc":3.726,"POWER":"OFF","Wifi":{"AP":1,"SSId":"XXX","BSSId":"44:4E:6D:47:XX:XX","Channel":1,"RSSI":62}}
19:17:02 RSL: SENSOR = {"Time":"2018-09-09T19:17:02","BME280":{"Temperature":21.9,"Humidity":55.6,"Pressure":1014.9},"TempUnit":"C"}
reloxx13 picture reloxx13 on 9 Sep 2018

retain would also not set this active 0/4, it would set syslog 0/4.

the syslog config itself stays, just this active behind does change.

reloxx13 picture reloxx13 on 9 Sep 2018

Weird. Let's investigate it.

ascillato picture ascillato on 9 Sep 2018

Hi,

I have just configured a Syslog Server in a RPI following this tutorial (link also in the wiki)

I could not reproduce the issue (the syslog works fine after a reboot of Tasmota last dev version).

The output of the console (with weblog 4):

00:00:00 CFG: Cargado desde flash en 3F9, Conteo 73
00:00:00 APP: Conteo Reinicios 31
00:00:00 SRC: Restart
00:00:00 Proyecto sonoff Living (Topic living, Fallback DVES_591719, GroupTopic sonoffs) Versi贸n 6.2.1.3-2_4_2
00:00:00 CFG: Grabado a la flash en 3F8, Conteo 74, Bytes 3584
00:00:00 WIF: Probando conexi贸n...
00:00:00 WIF: Intentando conectar...
00:00:00 WIF: Connectando a AP1 NetWireless en modo 11N como living-5913...
00:00:01 WIF: Probando conexi贸n...
00:00:01 WIF: Intentando conectar...
00:00:02 WIF: Probando conexi贸n...
00:00:02 WIF: Intentando conectar...
00:00:03 WIF: Probando conexi贸n...
00:00:03 WIF: Intentando conectar...
00:00:04 WIF: Probando conexi贸n...
00:00:04 WIF: Conectado
00:00:04 HTP: Servidor web activo en living-5913 con direcci贸n IP 192.168.1.33
00:00:05 MQT: Intentando conectar...
00:00:07 APP: (UTC) Mon Sep 10 21:28:11 2018, (DST) Sun Mar 25 02:00:00 2018, (STD) Sun Oct 28 03:00:00 2018
18:28:14 MQT: Conectado
18:28:14 MQT: tele/living/LWT = Online (Grabado)
18:28:14 MQT: cmnd/living/POWER = 
18:28:14 MQT: Suscribir a cmnd/living/#
18:28:14 MQT: Suscribir a cmnd/sonoffs/#
18:28:14 MQT: Suscribir a cmnd/DVES_591719/#
18:28:14 MQT: tele/living/INFO1 = {"Module":"Generic","Version":"6.2.1.3","FallbackTopic":"DVES_591719","GroupTopic":"sonoffs"}
18:28:14 MQT: tele/living/INFO2 = {"WebServerMode":"Admin","Hostname":"living-5913","IPAddress":"192.168.1.33"}
18:28:14 MQT: tele/living/INFO3 = {"RestartReason":"External System"}
18:28:14 MQT: stat/living/RESULT = {"POWER1":"OFF"}
18:28:14 MQT: stat/living/POWER1 = OFF
18:28:20 CMD: syslog
18:28:20 SRC: WebConsole from 192.168.1.114
18:28:20 RSL: Topic Recibido /syslog, Tama帽o de Datos 0, Datos 
18:28:20 RSL: Grupo 0, 脥ndice 1, Comando SYSLOG, Datos 
18:28:20 MQT: stat/living/RESULT = {"SysLog":"4 (Active 4)"}

And all that output was also in the file/var/log/user.log in my RPI

@reloxx13

So, I don't know what is the difference between my setup and yours.

What else we can test ?

ascillato picture ascillato on 10 Sep 2018

Status 0 Output:

18:39:50 MQT: stat/living/STATUS = {"Status":{"Module":18,"FriendlyName":["Living"],"Topic":"living","ButtonTopic":"living","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}}
18:39:50 MQT: stat/living/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://sonoff.maddox.co.uk/tasmota/sonoff.bin","RestartReason":"External System","Uptime":"0T00:00:18","StartupUTC":"2018-09-10T21:39:32","Sleep":0,"BootCount":33,"SaveCount":77,"SaveAddress":"3F5000"}}
18:39:50 MQT: stat/living/STATUS2 = {"StatusFWR":{"Version":"6.2.1.3","BuildDateTime":"2018-09-10T17:30:48","Boot":31,"Core":"2_4_2","SDK":"2.2.1(cfd48f3)"}}
18:39:50 MQT: stat/living/STATUS3 = {"StatusLOG":{"SerialLog":0,"WebLog":4,"SysLog":0,"LogHost":"192.168.1.104","LogPort":514,"SSId":["NetWireless","NetWireless"],"TelePeriod":300,"SetOption":["04008009","55818000","00000000"]}}
18:39:50 MQT: stat/living/STATUS4 = {"StatusMEM":{"ProgramSize":489,"Free":2580,"Heap":19,"ProgramFlashSize":4096,"FlashSize":4096,"FlashMode":3,"Features":["00002C0A","1F80A984","0C0003B0","23021796","000000C0"]}}
18:39:50 MQT: stat/living/STATUS5 = {"StatusNET":{"Hostname":"living-5913","IPAddress":"192.168.1.33","Gateway":"192.168.1.1","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.1","Mac":"60:01:94:59:17:19","Webserver":2,"WifiConfig":5}}
18:39:50 MQT: stat/living/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.2","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_591719","MqttUser":"homeassistant","MqttType":2,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}}
18:39:50 MQT: stat/living/STATUS7 = {"StatusTIM":{"UTC":"Mon Sep 10 21:39:50 2018","Local":"Mon Sep 10 18:39:50 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":-3,"Sunrise":"07:40","Sunset":"19:17"}}
18:39:50 MQT: stat/living/STATUS10 = {"StatusSNS":{"Time":"2018-09-10T18:39:50"}}
18:39:50 MQT: stat/living/STATUS11 = {"StatusSTS":{"Time":"2018-09-10T18:39:50","Uptime":"0T00:00:18","Vcc":2.997,"POWER1":"OFF","Wifi":{"AP":1,"SSId":"NetWireless","BSSId":"18:D6:C7:80:38:2C","Channel":1,"RSSI":64}}}
ascillato picture ascillato on 10 Sep 2018

I'm also unable to reproduce the issue with all the given steps above so
@reloxx13 as your project desc assumes you are using an extenend Sonoff with 4MB and own compilation - suggestion: can you try to reproduce the issue with one of the repository bin 6.1.1/6.2.1 on an original sonoff?

curzon01 picture curzon01 on 11 Sep 2018

Tried also with MQTT = 0 and also works fine:

00:00:00 CFG: Cargado desde flash en 3FB, Conteo 95
00:00:00 APP: Conteo Reinicios 46
00:00:00 SRC: Restart
00:00:00 Proyecto sonoff Living (Topic living, Fallback DVES_591719, GroupTopic sonoffs) Versi贸n 6.2.1.3-2_4_2
00:00:00 CFG: Grabado a la flash en 3FA, Conteo 96, Bytes 3584
00:00:00 WIF: Probando conexi贸n...
00:00:00 WIF: Intentando conectar...
00:00:00 WIF: Connectando a AP1 NetWireless en modo 11N como living-5913...
00:00:01 WIF: Probando conexi贸n...
00:00:01 WIF: Intentando conectar...
00:00:02 WIF: Probando conexi贸n...
00:00:02 WIF: Intentando conectar...
00:00:03 WIF: Probando conexi贸n...
00:00:03 WIF: Intentando conectar...
00:00:04 WIF: Probando conexi贸n...
00:00:04 WIF: Conectado
00:00:04 HTP: Servidor web activo en living-5913 con direcci贸n IP 192.168.1.33
00:00:04 RSL: INFO1 = {"Module":"Generic","Version":"6.2.1.3","FallbackTopic":"DVES_591719","GroupTopic":"sonoffs"}
00:00:04 RSL: INFO2 = {"WebServerMode":"Admin","Hostname":"living-5913","IPAddress":"192.168.1.33"}
00:00:04 RSL: INFO3 = {"RestartReason":"Software/System restart"}
00:00:04 RSL: RESULT = {"POWER1":"OFF"}
00:00:04 RSL: POWER1 = OFF
00:00:07 APP: (UTC) Tue Sep 11 17:55:44 2018, (DST) Sun Mar 25 02:00:00 2018, (STD) Sun Oct 28 03:00:00 2018
14:55:50 RSL: STATE = {"Time":"2018-09-11T14:55:50","Uptime":"0T00:00:13","Vcc":2.991,"POWER1":"OFF","Wifi":{"AP":1,"SSId":"NetWireless","BSSId":"18:D6:C7:80:38:2C","Channel":1,"RSSI":68}}
14:55:58 HTP: Men煤 Principal
14:55:59 HTP: Consola
14:56:01 WIF: Probando conexi贸n...
14:56:01 WIF: Conectado
14:56:02 CMD: syslog
14:56:02 SRC: WebConsole from 192.168.1.114
14:56:02 RSL: Topic Recibido /syslog, Tama帽o de Datos 0, Datos 
14:56:02 RSL: Grupo 0, 脥ndice 1, Comando SYSLOG, Datos 
14:56:02 RSL: RESULT = {"SysLog":"4 (Active 4)"}
ascillato2 picture ascillato2 on 11 Sep 2018

@cyablo

Please, can you test this issue again but with last firmware?
If stills happening, please share your new status 0, console output (with weblog set to 4) and also any further details of your setup as actual syslog server software, etc. Thanks

ascillato picture ascillato on 12 Sep 2018

@reloxx13

How is your setup? Which syslog server are you using?

ascillato picture ascillato on 12 Sep 2018

Im using standard rsyslogd and mosquitto on debian stretch, no special setup. No custom build tasmota...

Same with 6.2.1, after rbeoot of the tasmota device, syslog stops working until I hit "save" once under logging options.

status 0 before reboot:

22:34:46 CMD: status 0 22:34:46 SRC: WebConsole from 192.168.1.19 22:34:46 RSL: empfangenes topic /status, Datengr枚脽e 1, Daten 0 22:34:46 RSL: Gruppe 0, Index 1, Befehl STATUS, Daten 0 22:34:46 MQT: stat/tasmota11/STATUS = {"Status":{"Module":18,"FriendlyName":["neonroehre"],"Topic":"tasmota11","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}} 22:34:46 MQT: stat/tasmota11/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://192.168.1.26:80/sonweb/data/firmwares/sonoff.bin","RestartReason":"Software/System restart","Uptime":"0T00:03:14","StartupUTC":"2018-09-12T20:31:32","Sleep":50,"BootCount":96,"SaveCount":241,"SaveAddress":"FB000"}} 22:34:46 MQT: stat/tasmota11/STATUS2 = {"StatusFWR":{"Version":"6.2.1","BuildDateTime":"2018.09.05 10:44:57","Boot":31,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}} 22:34:46 MQT: stat/tasmota11/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":4,"SysLog":4,"LogHost":"192.168.1.26","LogPort":514,"SSId":["xxx","xxx"],"TelePeriod":300,"SetOption":["00000009","55A18000","00000001"]}} 22:34:46 MQT: stat/tasmota11/STATUS4 = {"StatusMEM":{"ProgramSize":473,"Free":528,"Heap":17,"ProgramFlashSize":1024,"FlashSize":1024,"FlashMode":3,"Features":["00000407","0FDAE794","24000000","23B617CE","00000000"]}} 22:34:46 MQT: stat/tasmota11/STATUS5 = {"StatusNET":{"Hostname":"tasmota11","IPAddress":"192.168.1.55","Gateway":"192.168.1.2","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.18","Mac":"60:01:94:51:AA:EA","Webserver":2,"WifiConfig":3}} 22:34:46 MQT: stat/tasmota11/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.26","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_51AAEA","MqttUser":"DVES_USER","MqttType":1,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}} 22:34:46 MQT: stat/tasmota11/STATUS7 = {"StatusTIM":{"UTC":"Wed Sep 12 20:34:46 2018","Local":"Wed Sep 12 22:34:46 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":99,"Sunrise":"07:22","Sunset":"20:10"}} 22:34:46 MQT: stat/tasmota11/STATUS10 = {"StatusSNS":{"Time":"2018-09-12T22:34:46"}} 22:34:46 MQT: stat/tasmota11/STATUS11 = {"StatusSTS":{"Time":"2018-09-12T22:34:46","Uptime":"0T00:03:14","Vcc":3.222,"POWER":"OFF","Wifi":{"AP":1,"SSId":"xxx","RSSI":100,"APMac":"xxx"}}}

after reboot:

22:37:52 CMD: status 0 22:37:52 SRC: WebConsole from 192.168.1.19 22:37:52 RSL: empfangenes topic /status, Datengr枚脽e 1, Daten 0 22:37:52 RSL: Gruppe 0, Index 1, Befehl STATUS, Daten 0 22:37:52 MQT: stat/tasmota11/STATUS = {"Status":{"Module":18,"FriendlyName":["neonroehre"],"Topic":"tasmota11","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"SaveData":1,"SaveState":1,"ButtonRetain":0,"PowerRetain":0}} 22:37:52 MQT: stat/tasmota11/STATUS1 = {"StatusPRM":{"Baudrate":115200,"GroupTopic":"sonoffs","OtaUrl":"http://192.168.1.26:80/sonweb/data/firmwares/sonoff.bin","RestartReason":"Software/System restart","Uptime":"0T00:00:46","StartupUTC":"2018-09-12T20:37:06","Sleep":50,"BootCount":97,"SaveCount":242,"SaveAddress":"FA000"}} 22:37:52 MQT: stat/tasmota11/STATUS2 = {"StatusFWR":{"Version":"6.2.1","BuildDateTime":"2018.09.05 10:44:57","Boot":31,"Core":"2_3_0","SDK":"1.5.3(aec24ac9)"}} 22:37:53 MQT: stat/tasmota11/STATUS3 = {"StatusLOG":{"SerialLog":2,"WebLog":4,"SysLog":4,"LogHost":"192.168.1.26","LogPort":514,"SSId":["xxx","xxx"],"TelePeriod":300,"SetOption":["00000009","55A18000","00000001"]}} 22:37:53 MQT: stat/tasmota11/STATUS4 = {"StatusMEM":{"ProgramSize":473,"Free":528,"Heap":18,"ProgramFlashSize":1024,"FlashSize":1024,"FlashMode":3,"Features":["00000407","0FDAE794","24000000","23B617CE","00000000"]}} 22:37:53 MQT: stat/tasmota11/STATUS5 = {"StatusNET":{"Hostname":"tasmota11","IPAddress":"192.168.1.55","Gateway":"192.168.1.2","Subnetmask":"255.255.255.0","DNSServer":"192.168.1.18","Mac":"60:01:94:51:AA:EA","Webserver":2,"WifiConfig":3}} 22:37:53 MQT: stat/tasmota11/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.1.26","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_51AAEA","MqttUser":"DVES_USER","MqttType":1,"MAX_PACKET_SIZE":1000,"KEEPALIVE":15}} 22:37:53 MQT: stat/tasmota11/STATUS7 = {"StatusTIM":{"UTC":"Wed Sep 12 20:37:53 2018","Local":"Wed Sep 12 22:37:53 2018","StartDST":"Sun Mar 25 02:00:00 2018","EndDST":"Sun Oct 28 03:00:00 2018","Timezone":99,"Sunrise":"07:22","Sunset":"20:10"}} 22:37:53 MQT: stat/tasmota11/STATUS10 = {"StatusSNS":{"Time":"2018-09-12T22:37:53"}} 22:37:53 MQT: stat/tasmota11/STATUS11 = {"StatusSTS":{"Time":"2018-09-12T22:37:53","Uptime":"0T00:00:47","Vcc":3.222,"POWER":"OFF","Wifi":{"AP":1,"SSId":"xxx","RSSI":100,"APMac":"xxx"}}}

cyablo picture cyablo on 12 Sep 2018

@cyablo @reloxx13 @bollitec

Found the issue!!!! I could reproduce it!

_When you have emulation enabled, syslog goes off._ _(See lines 1007, 1599 and 2516 in sonoff.ino)_

So, as it is an intended behaviour in the code I will ask you to test if Alexa works when you have syslog working (doing the save in the webmenu). I don't have an Alexa to test it.

If Alexa works when you have syslog working, we can just change the lines 1007, 1599 and 2516 in _sonoff.ino_ from:
syslog_level = (Settings.flag2.emulation) ? 0 : Settings.syslog_level;
to
syslog_level = Settings.syslog_level;

If Alexa don't work, that means that both features can not be used at the same time.

ascillato picture ascillato on 12 Sep 2018
🎉2 👍1

You mean wemo emulation? Yep, I'm using this feature for alexa. Alexa always works with wemo emulation, have not encountered a single failure of my voice commands to turn on/off a tasmota device, with and without working syslog.

Would be interesting to know the reason of this intended disabling of the remote syslog function.

cyablo picture cyablo on 12 Sep 2018

Thanks.

Proposed the pull request #3784 for Theo to review it. Tested and now syslog is enabled and working when you reboot Tasmota and you have enabled the emulation.

ascillato picture ascillato on 12 Sep 2018

Hi,

Closing as the issue has been solved. Please, ask to reopen if needed. Thanks.

ascillato2 picture ascillato2 on 13 Sep 2018
👍1
Was this page helpful?
0 / 5 - 0 ratings

Related issues

Vujagig picture Vujagig  路  3Comments
Ndrinta picture Ndrinta  路  3Comments
he-so picture he-so  路  3Comments
wirelesssolution picture wirelesssolution  路  3Comments
garret picture garret  路  3Comments