Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Tasmota H801 immediately switch on again #71672

Closed
msth opened this issue May 11, 2022 · 11 comments
Closed

Tasmota H801 immediately switch on again #71672

msth opened this issue May 11, 2022 · 11 comments
Assignees

Comments

@msth
Copy link

msth commented May 11, 2022

The problem

once the Tasmota H801 has been found by the integration, the RGB stripe can no longer be switched off. After a click on switch off, it will be switched on again immediately. there is no automation that can cause this.

What version of Home Assistant Core has the issue?

2022.5.3

What was the last working version of Home Assistant Core?

2022.5.3

What type of installation are you running?

Home Assistant Supervised

Integration causing the issue

tasmota

Link to integration documentation on our website

No response

Diagnostics information

image

image

Example YAML snippet

No response

Anything in the logs that might be useful for us?

00:23:24.434 MQT: stat/tasmota-33C31C/RESULT = {"POWER":"ON"}
00:23:24.438 MQT: stat/tasmota-33C31C/POWER = ON
00:23:24.522 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:24","Uptime":"0T00:23:22","UptimeSec":1402,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":95,"MqttCount":1,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"298,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:24.527 MQT: stat/tasmota-33C31C/RESULT = {"POWER":"ON"}
00:23:24.530 MQT: stat/tasmota-33C31C/POWER = ON
00:23:24.543 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:24","Uptime":"0T00:23:22","UptimeSec":1402,"Heap":23,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":97,"MqttCount":1,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"298,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:24.549 MQT: stat/tasmota-33C31C/RESULT = {"Scheme":4}
00:23:25.979 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:25","Uptime":"0T00:23:24","UptimeSec":1404,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":99,"MqttCount":1,"POWER":"OFF","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"311,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:25.985 MQT: stat/tasmota-33C31C/RESULT = {"POWER":"OFF"}
00:23:25.990 MQT: stat/tasmota-33C31C/POWER = OFF
00:23:26.130 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:26","Uptime":"0T00:23:24","UptimeSec":1404,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":89,"MqttCount":1,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"311,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:26.134 MQT: stat/tasmota-33C31C/RESULT = {"POWER":"ON"}
00:23:26.137 MQT: stat/tasmota-33C31C/POWER = ON
00:23:26.272 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:26","Uptime":"0T00:23:24","UptimeSec":1404,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":91,"MqttCount":1,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"314,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:26.277 MQT: stat/tasmota-33C31C/RESULT = {"POWER":"ON"}
00:23:26.282 MQT: stat/tasmota-33C31C/POWER = ON
00:23:26.295 MQT: tele/tasmota-33C31C/STATE = {"Time":"1970-01-01T00:23:26","Uptime":"0T00:23:24","UptimeSec":1404,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":93,"MqttCount":1,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"314,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Hoffmann","BSSId":"2C:91:AB:F8:9A:BE","Channel":6,"Mode":"11n","RSSI":44,"Signal":-78,"LinkCount":1,"Downtime":"0T00:00:05"}}
00:23:26.301 MQT: stat/tasmota-33C31C/RESULT = {"Scheme":4}

Additional information

No response

@probot-home-assistant
Copy link

Hey there @emontnemery, mind taking a look at this issue as it has been labeled with an integration (tasmota) you are listed as a code owner for? Thanks!
(message by CodeOwnersMention)


tasmota documentation
tasmota source
(message by IssueLinks)

@emontnemery
Copy link
Contributor

emontnemery commented May 11, 2022

Is the tasmota device configured with setoption19 0 or setoption19 1?

After a click on switch off

Which switch off do you click, do you mean Home Assistant frontend?
image

Please enable debug logs for Tasmota and MQTT and share a log where the problem is reproduced.

Log settings:

logger:                       
 default: info               
 logs:                       
   homeassistant.core: debug
   homeassistant.components.mqtt: debug   
   homeassistant.components.tasmota: debug
   hatasmota: debug                       

@msth
Copy link
Author

msth commented May 11, 2022

thanks for the reply.

The Option 19 is OFF

05:32:07.956 CMD: SetOption19
05:32:07.962 MQT: stat/tasmota-33C31C/RESULT = {"SetOption19":"OFF"}

here are the required log files

2022-05-11 14:15:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_off, service_data=entity_id=light.rgb_samy>
2022-05-11 14:15:23 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 OFF', mid: 505
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:38","Uptime":"0T05:41:37","UptimeSec":20497,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":99,"MqttCount":2,"POWER":"OFF","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"221,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":82,"Signal":-59,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:14:00.307589+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:24.865325+02:00>>
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:14:00.308326+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:24.866176+02:00>>
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(106.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:13:20.444412+02:00>, new_state=<state light.rgb_samy=off; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:24.867373+02:00>>
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"OFF"}'
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.rgb_samy, effect=>
2022-05-11 14:15:24 DEBUG (MainThread) [hatasmota.light] Unknown effect 
2022-05-11 14:15:24 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 ON', mid: 506
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:40","Uptime":"0T05:41:39","UptimeSec":20499,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":156,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"221,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":82,"Signal":-59,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:24.865325+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.277557+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:24.866176+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.278368+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=off; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:24.867373+02:00>, new_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(221.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:25.279579+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"ON"}'
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.rgb_samy, effect=Random>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 ON;NoDelay;Scheme 4', mid: 507
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:40","Uptime":"0T05:41:39","UptimeSec":20499,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":148,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"220,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":86,"Signal":-57,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.277557+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.393095+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.278368+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.394037+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(221.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:25.279579+02:00>, new_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(220.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:25.279579+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"ON"}'
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:40","Uptime":"0T05:41:39","UptimeSec":20499,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":149,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"220,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":88,"Signal":-56,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.393095+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.418177+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.394037+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.420187+02:00>>
2022-05-11 14:15:25 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"Scheme":4}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on zigbee2mqtt/Router_03: b'{"last_seen":"2022-05-11T14:15:28+02:00","led":false,"linkquality":66}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=binary_sensor.router_03_led, old_state=<state binary_sensor.router_03_led=off; last_seen=2022-05-11T14:14:28+02:00, led=False, linkquality=51, friendly_name=Router_03_led @ 2022-05-11T14:13:28.977661+02:00>, new_state=<state binary_sensor.router_03_led=off; last_seen=2022-05-11T14:15:28+02:00, led=False, linkquality=66, friendly_name=Router_03_led @ 2022-05-11T14:13:28.977661+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_off, service_data=entity_id=light.rgb_samy>
2022-05-11 14:15:29 ERROR (MainThread) [homeassistant.components.onvif] Fetch snapshot image failed from HIKVISION DS-2CD2385FWD-I, falling back to FFmpeg; Unknown error: 
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 OFF', mid: 508
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.memory_free, old_state=<state sensor.memory_free=11746.5; state_class=measurement, unit_of_measurement=MiB, icon=mdi:memory, friendly_name=Memory free @ 2022-05-11T14:15:12.972902+02:00>, new_state=<state sensor.memory_free=11741.6; state_class=measurement, unit_of_measurement=MiB, icon=mdi:memory, friendly_name=Memory free @ 2022-05-11T14:15:29.161189+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.load_15m, old_state=<state sensor.load_15m=1.82; state_class=measurement, icon=mdi:cpu-64-bit, friendly_name=Load (15m) @ 2022-05-11T14:15:12.973262+02:00>, new_state=<state sensor.load_15m=1.83; state_class=measurement, icon=mdi:cpu-64-bit, friendly_name=Load (15m) @ 2022-05-11T14:15:29.161571+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.processor_temperature, old_state=<state sensor.processor_temperature=40.0; state_class=measurement, unit_of_measurement=°C, device_class=temperature, friendly_name=Processor temperature @ 2022-05-11T14:15:12.973508+02:00>, new_state=<state sensor.processor_temperature=41.0; state_class=measurement, 

2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:44","Uptime":"0T05:41:43","UptimeSec":20503,"Heap":26,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":99,"MqttCount":2,"POWER":"OFF","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"186,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":84,"Signal":-58,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:25.418177+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.199195+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:25.420187+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.199483+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(220.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:25.279579+02:00>, new_state=<state light.rgb_samy=off; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:29.199925+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"OFF"}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.rgb_samy, effect=>
2022-05-11 14:15:29 DEBUG (MainThread) [hatasmota.light] Unknown effect 
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 ON', mid: 509
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:44","Uptime":"0T05:41:43","UptimeSec":20503,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":93,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"186,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":84,"Signal":-58,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.199195+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.300137+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.199483+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.300952+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=off; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:29.199925+02:00>, new_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(186.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:29.302245+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"ON"}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=light, service=turn_on, service_data=entity_id=light.rgb_samy, effect=Random>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Transmitting message on cmnd/tasmota-33C31C/Backlog: 'NoDelay;Power1 ON;NoDelay;Scheme 4', mid: 510
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:44","Uptime":"0T05:41:43","UptimeSec":20503,"Heap":26,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":95,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"184,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":86,"Signal":-57,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.300137+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.434347+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.300952+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.435208+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=light.rgb_samy, old_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(186.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:29.302245+02:00>, new_state=<state light.rgb_samy=on; min_mireds=153, max_mireds=500, effect_list=['None', 'Wake up', 'Cycle up', 'Cycle down', 'Random'], supported_color_modes=[<ColorMode.COLOR_TEMP: 'color_temp'>, <ColorMode.HS: 'hs'>], color_mode=hs, brightness=0, hs_color=(184.0, 0.0), rgb_color=(255, 255, 255), xy_color=(0.323, 0.329), effect=Random, friendly_name=RGB_Samy, supported_features=36 @ 2022-05-11T14:15:29.302245+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"POWER":"ON"}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on tele/tasmota-33C31C/STATE: b'{"Time":"1970-01-01T05:41:44","Uptime":"0T05:41:43","UptimeSec":20503,"Heap":23,"SleepMode":"Dynamic","Sleep":10,"LoadAvg":96,"MqttCount":2,"POWER":"ON","Dimmer":0,"Color":"0,0,0,0,0","HSBColor":"184,0,0","White":0,"CT":153,"Channel":[0,0,0,0,0],"Scheme":4,"Fade":"OFF","Speed":5,"LedTable":"OFF","Wifi":{"AP":1,"SSId":"Mueller","BSSId":"2C:91:AB:F8:BD:D9","Channel":6,"Mode":"11n","RSSI":90,"Signal":-55,"LinkCount":2,"Downtime":"0T00:00:07"}}'
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_wifi_connect_count, old_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.434347+02:00>, new_state=<state sensor.rgb_samy_wifi_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy WiFi Connect Count @ 2022-05-11T14:15:29.477827+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.rgb_samy_mqtt_connect_count, old_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.435208+02:00>, new_state=<state sensor.rgb_samy_mqtt_connect_count=2; icon=mdi:counter, friendly_name=RGB_Samy MQTT Connect Count @ 2022-05-11T14:15:29.478604+02:00>>
2022-05-11 14:15:29 DEBUG (MainThread) [homeassistant.components.mqtt] Received message on stat/tasmota-33C31C/RESULT: b'{"Scheme":4}'


@msth
Copy link
Author

msth commented May 11, 2022

the switch turns on again in both cases. it doesn't matter if you click the homeassistant switch or the one from the tasmota frontend.

@emontnemery
Copy link
Contributor

It's not turned on by the Tasmota integration, there's something in Home Assistant actively turning it back on, first with no effect and then with effect set to "Random".

It's very likely an automation doing this, you could disable all automations just to be sure.

Maybe there's a hint in the logbook, it will look like this:
image

Please also check the device view, it lists automations, scenes and scripts referencing the device:
image

@msth
Copy link
Author

msth commented May 11, 2022

Hi, i checked this already.
image

image

it must have something to do with the effects

image

@emontnemery
Copy link
Contributor

Again, something actively is asking the light to turn back on as soon as it changes to off state.
That something seems to be playing with the effects too.

The list of automations referencing a device is not always complete. Just to be sure, can you try turning off all automations?
Try also searching the homeassistant storage folder for turn_on, toggle and effect.

You could also try enabling full debug log, it may reveal something:

logger:                       
 default: debug               

@msth
Copy link
Author

msth commented May 12, 2022

many thanks for the analysis. I deactivated all automations and activated debug as default.

this of course logged a lot of logs. I had to filter it a bit. I sorted out all entries that have nothing to do with RGB_Samy.

https://0bin.net/paste/Flp7VKXb#8SaFzRCxdM47i4cG2BlY8W0126zdKPBfO6bY0corGRo

@emontnemery
Copy link
Contributor

The log shows that the request to again turn the light after it was turned off is coming in over http, most likely from the web UI.

Do you have any custom components or custom cards etc. installed?

Try also searching the homeassistant storage folder for turn_on, toggle and effect.

Did you try this?

@msth
Copy link
Author

msth commented May 12, 2022

Thanks a lot. I would never have found that

You were right. I use a HACS addon light entity card.

Had forgotten that this is not a native application.

Greed there is already an open ticket.

ljmerza/light-entity-card#90

@ljmerza
Copy link
Contributor

ljmerza commented May 12, 2022

light-entity-card uses websocket to call services on toggle. Verified this still works for Hue, Kasa, and Lutron. I dont have a Tasmota device to test.

@github-actions github-actions bot locked and limited conversation to collaborators Jun 11, 2022
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Projects
None yet
Development

No branches or pull requests

4 participants