Core. 2024.1.5
Supervisor 2023.12.1
Operating System 11.4
Frontend 20240104.0
Zigbee2MQTT 1.35.1-1
Mosquitto broker 6.4.0
2 gang switch (FMS2C017) by KnockautX (Official support by Z2M)
Problem:
A view weeks ago and over x-mas/new year I did play around with some (I’ve forgotten the names) blueprints and configuration examples to use a rapid change of humidity to switch on a fan in the shower. Didn’t work well for me and I didn’t have the energy to go trough the problems. So I did delete all corresponding files and helpers and sensors and …
But since this year I face the problem, that this specific “Switch” does suddenly turn on the fan without interaction of my side. With some luck I was able to gather debug logs from journalctl as well as from Z2M from the time it happens and approx. 60 seconds before and after.
This is part of the Z2M log file.
debug 2024-01-30 14:12:33: Received Zigbee message from 'Schaltaktor Dusche', type 'attributeReport', cluster 'genBasic', data '{"appVersion":67}' from endpoint 1 with groupID 0
info 2024-01-30 14:12:33: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche', payload '{"last_seen":"2024-01-30T14:12:33+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"OFF"}'
debug 2024-01-30 14:12:48: Received Zigbee message from 'Dimmer Wohnzimmer-Gang', type 'attributeReport', cluster 'genBasic', data '{"65506":31,"65508":0,"appVersion":68}' from endpoint 1 with groupID 0
info 2024-01-30 14:12:48: MQTT publish: topic 'zigbee2mqtt/Dimmer Wohnzimmer-Gang', payload '{"brightness_l1":51,"brightness_l2":79,"countdown_l1":0,"countdown_l2":0,"last_seen":"2024-01-30T14:12:48+01:00","light_type_l1":"led","light_type_l2":"led","linkquality":102,"max_brightness_l1":255,"max_brightness_l2":175,"min_brightness_l1":1,"min_brightness_l2":0,"power_on_behavior":"off","state_l1":"OFF","state_l2":"OFF"}'
info 2024-01-30 14:12:48: MQTT publish: topic 'zigbee2mqtt/Dimmer Wohnzimmer-Gang/l1', payload '{"brightness":51,"countdown":0,"light_type":"led","max_brightness":255,"min_brightness":1,"state":"OFF"}'
info 2024-01-30 14:12:48: MQTT publish: topic 'zigbee2mqtt/Dimmer Wohnzimmer-Gang/l2', payload '{"brightness":79,"countdown":0,"light_type":"led","max_brightness":175,"min_brightness":0,"state":"OFF"}'
info 2024-01-30 14:12:48: MQTT publish: topic 'zigbee2mqtt/Dimmer Wohnzimmer-Gang/type_l1', payload '{"light":"led"}'
info 2024-01-30 14:12:48: MQTT publish: topic 'zigbee2mqtt/Dimmer Wohnzimmer-Gang/type_l2', payload '{"light":"led"}'
debug 2024-01-30 14:13:25: Received Zigbee message from 'Schaltaktor Badzimmer', type 'readResponse', cluster 'genBasic', data '{"zclVersion":3}' from endpoint 1 with groupID 0
info 2024-01-30 14:13:25: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Badzimmer', payload '{"last_seen":"2024-01-30T14:13:25+01:00","linkquality":98,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"OFF"}'
debug 2024-01-30 14:13:25: Successfully pinged 'Schaltaktor Badzimmer' (attempt 1/2)
debug 2024-01-30 14:13:28: Received Zigbee message from 'Schaltaktor Dusche', type 'attributeReport', cluster 'genOnOff', data '{"onOff":1}' from endpoint 2 with groupID 0
debug 2024-01-30 14:13:28: Received Zigbee message from 'Schaltaktor Dusche', type 'attributeReport', cluster 'genOnOff', data '{"onOff":1}' from endpoint 2 with groupID 0
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche', payload '{"action":"on_l2","elapsed":777453,"last_seen":"2024-01-30T14:13:28+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"ON"}'
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche', payload '{"action":"on_l2","elapsed":1,"last_seen":"2024-01-30T14:13:28+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"ON"}'
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche', payload '{"action":"","last_seen":"2024-01-30T14:13:28+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"ON"}'
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche', payload '{"action":"","last_seen":"2024-01-30T14:13:28+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"ON"}'
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche/action', payload 'on_l2'
info 2024-01-30 14:13:28: MQTT publish: topic 'zigbee2mqtt/Schaltaktor Dusche/action', payload 'on_l2'
And this is for the same time form the journalctl log export.
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: 2024-01-30 14:13:28: Sending PUBLISH to z2m (d0, q0, r0, m0, 'zigbee2mqtt/Schaltaktor Dusche', ... (159 bytes))
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 83 99 104 97 108 116 97 107 116 111 114 32 68 117 115 99 104 101 45 122 50 109 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 83 99 104 97 108 116 97 107 116 111 114 32 68 117 115 99 104 101 45 109 113 116 116 45 101 120 112 108 111 114 101 114 45 57 57 99 57 57 51 97 48 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 98 114 105 100 103 101 47 108 111 103 103 105 110 103 45 122 50 109 45 50 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 98 114 105 100 103 101 47 108 111 103 103 105 110 103 45 122 50 109 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 98 114 105 100 103 101 47 108 111 103 103 105 110 103 45 109 113 116 116 45 101 120 112 108 111 114 101 114 45 57 57 99 57 57 51 97 48 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 83 99 104 97 108 116 97 107 116 111 114 32 68 117 115 99 104 101 45 122 50 109 45 50 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 83 99 104 97 108 116 97 107 116 111 114 32 68 117 115 99 104 101 45 48 82 78 77 119 54 109 51 101 114 122 110 90 88 75 66 82 50 57 99 100 65 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="checking acl cache for z2m_mqtt"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="to auth record: [97 99 108 45 122 50 109 95 109 113 116 116 45 122 105 103 98 101 101 50 109 113 116 116 47 83 99 104 97 108 116 97 107 116 111 114 32 68 117 115 99 104 101 45 122 50 109 45 49 218 57 163 238 94 107 75 13 50 85 191 239 149 96 24 144 175 216 7 9]\n"
Jan 30 13:13:28 homepi addon_core_mosquitto[574]: time="2024-01-30T14:13:28+01:00" level=debug msg="found in cache: z2m_mqtt"
Jan 30 13:13:28 homepi homeassistant[574]: e[36m2024-01-30 14:13:28.439 DEBUG (MainThread) [homeassistant.components.mqtt.client] Received message on zigbee2mqtt/Schaltaktor Dusche (qos=0): b'{"action":"on_l2","elapsed":777453,"last_seen":"2024-01-30T14:13:28+01:00","linkquality":102,"power_on_behavior":"off","power_on_behavior_l1":"off","state_l1":"OFF","state_l2":"ON"}'e[0m
Question:
How can I find out, from where dies “ON” command does come from?
It must be some deep burried leftover from my tryings but I do not know where to search and how to solve.
A new installation is a NO GO and only as last way out possible as I would have to open several places where the Zigbee Switches and Dimmers ar placed behind blinds and reconfigure all and everything…
Thank you for suggestions.