OK, so I set logging to debug (config YAML included just in case I did something wrong):
logger:
default: debug
logs:
zigbee2mqtt: debug
What I did during the time of the logs:
- Activate “Shutters Closed” scene (OK)
- Activate “Shutters Open” scene (KO)
- Edit “Shutters Open” scene and set each shutter to 100, although they should already be set to 100 (all shutters open as expected)
- Activate “Shutters Closed” scene (OK)
- Activate “Shutters Open” scene (KO)
Zigbee2mqtt log: zigbee2mqtt - Pastebin.com
Homeassistant log when I select the “Shutters Closed” scene:
2022-09-17 21:20:08.095 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547403391776] Sending {"id":3,"type":"event","event":{"c":{"scene.shutters_closed":{"+":{"s":"2022-09-17T19:20:08.089555+00:00","lc":1663442408.089821,"c":{"user_id":"f5a6ef5b8b3f4ae281b229f3589e4f86","id":"01GD6E9MMPCKAKMT50HHZMFMDJ"}}}}}}
2022-09-17 21:20:08.098 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c13839de6d453d, position=0>
2022-09-17 21:20:08.099 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c138257227158b, position=0>
2022-09-17 21:20:08.100 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c138deb016510e, position=0>
2022-09-17 21:20:08.100 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1381f55fc6698, position=0>
2022-09-17 21:20:08.101 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1388f1c418338, position=0>
2022-09-17 21:20:08.102 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1382ba5d4b92f, position=0>
2022-09-17 21:20:08.103 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c13839de6d453d, position=0>)
2022-09-17 21:20:08.104 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c138257227158b, position=0>)
2022-09-17 21:20:08.105 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c138deb016510e, position=0>)
2022-09-17 21:20:08.106 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1381f55fc6698, position=0>)
2022-09-17 21:20:08.107 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1388f1c418338, position=0>)
2022-09-17 21:20:08.107 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.0xa4c1382ba5d4b92f, position=0>)
2022-09-17 21:20:08.109 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.study_window, position=0>
2022-09-17 21:20:08.116 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=set_cover_position, service_data=entity_id=cover.study_window, position=0>)
2022-09-17 21:20:08.117 DEBUG (SyncWorker_1) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m208), 'b'zigbee2mqtt/Living Room Window/set'', ... (17 bytes)
2022-09-17 21:20:08.119 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Living Room Window/set: '{ "position": 0 }', mid: 208
2022-09-17 21:20:08.121 DEBUG (SyncWorker_1) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m209), 'b'zigbee2mqtt/Living Room Door/set'', ... (17 bytes)
2022-09-17 21:20:08.123 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Living Room Door/set: '{ "position": 0 }', mid: 209
2022-09-17 21:20:08.126 DEBUG (SyncWorker_7) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m210), 'b'zigbee2mqtt/Kitchen Door/set'', ... (17 bytes)
2022-09-17 21:20:08.128 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Kitchen Door/set: '{ "position": 0 }', mid: 210
2022-09-17 21:20:08.129 DEBUG (SyncWorker_5) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m211), 'b'zigbee2mqtt/Kitchen Window/set'', ... (17 bytes)
2022-09-17 21:20:08.130 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Kitchen Window/set: '{ "position": 0 }', mid: 211
2022-09-17 21:20:08.132 DEBUG (SyncWorker_6) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m212), 'b'zigbee2mqtt/Dining Room Bay/set'', ... (17 bytes)
2022-09-17 21:20:08.137 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Dining Room Bay/set: '{ "position": 0 }', mid: 212
2022-09-17 21:20:08.138 DEBUG (SyncWorker_4) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m213), 'b'zigbee2mqtt/Dining Room Door/set'', ... (17 bytes)
2022-09-17 21:20:08.141 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Dining Room Door/set: '{ "position": 0 }', mid: 213
2022-09-17 21:20:08.144 DEBUG (SyncWorker_7) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m214), 'b'zigbee2mqtt/Study Window/set'', ... (17 bytes)
2022-09-17 21:20:08.147 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Study Window/set: '{ "position": 0 }', mid: 214
2022-09-17 21:20:08.150 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547403391776] Sending {"id":53,"type":"result","success":true,"result":{"context":{"id":"01GD6E9MMPCKAKMT50HHZMFMDJ","parent_id":null,"user_id":"f5a6ef5b8b3f4ae281b229f3589e4f86"}}}
2022-09-17 21:20:08.283 DEBUG (Thread-2 (_thread_main)) [paho.mqtt.client] Received PUBLISH (d0, q0, r0, m0), 'zigbee2mqtt/Living Room Window', ... (153 bytes)
2022-09-17 21:20:08.284 DEBUG (MainThread) [homeassistant.components.mqtt.client] Received message on zigbee2mqtt/Living Room Window: b'{"backlight_mode":"LOW","calibration":"OFF","calibration_time":15.3,"linkquality":255,"motor_reversal":"ON","moving":"STOP","position":0,"state":"CLOSE"}'
2022-09-17 21:20:08.285 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.0xa4c13839de6d453d, old_state=<state cover.0xa4c13839de6d453d=closed; current_position=100, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>, new_state=<state cover.0xa4c13839de6d453d=closed; current_position=100, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>>
2022-09-17 21:20:08.286 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_calibration, old_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>, new_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>>
2022-09-17 21:20:08.287 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_motor_reversal, old_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>, new_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>>
2022-09-17 21:20:08.289 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_moving, old_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, friendly_name=Living Room Window moving @ 2022-09-17T21:19:27.842646+02:00>, new_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:19:27.842646+02:00>>
2022-09-17 21:20:08.291 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=cover.0xa4c13839de6d453d, old_state=<state cover.0xa4c13839de6d453d=closed; current_position=100, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>, new_state=<state cover.0xa4c13839de6d453d=closed; current_position=100, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>>)
2022-09-17 21:20:08.292 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_calibration, old_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>, new_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>>)
2022-09-17 21:20:08.293 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_motor_reversal, old_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>, new_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>>)
2022-09-17 21:20:08.294 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_moving, old_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, friendly_name=Living Room Window moving @ 2022-09-17T21:19:27.842646+02:00>, new_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:19:27.842646+02:00>>)
2022-09-17 21:20:08.296 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_calibration_time, old_state=<state sensor.0xa4c13839de6d453d_calibration_time=15.3; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, unit_of_measurement=S, icon=mdi:wrench-clock, friendly_name=Living Room Window calibration time @ 2022-09-17T21:17:45.579142+02:00>, new_state=<state sensor.0xa4c13839de6d453d_calibration_time=15.3; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, unit_of_measurement=S, icon=mdi:wrench-clock, friendly_name=Living Room Window calibration time @ 2022-09-17T21:17:45.579142+02:00>>
2022-09-17 21:20:08.298 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=select.0xa4c13839de6d453d_backlight_mode, old_state=<state select.0xa4c13839de6d453d_backlight_mode=LOW; options=['LOW', 'MEDIUM', 'HIGH'], backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=100, icon=mdi:lightbulb, friendly_name=Living Room Window backlight mode @ 2022-09-17T21:17:45.581880+02:00>, new_state=<state select.0xa4c13839de6d453d_backlight_mode=LOW; options=['LOW', 'MEDIUM', 'HIGH'], backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:lightbulb, friendly_name=Living Room Window backlight mode @ 2022-09-17T21:17:45.581880+02:00>>
2022-09-17 21:20:08.299 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.0xa4c13839de6d453d, old_state=<state cover.0xa4c13839de6d453d=closed; current_position=100, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>, new_state=<state cover.0xa4c13839de6d453d=closed; current_position=0, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:19:11.936223+02:00>>
Homeassistant log when I select the “Shutters Open” scene:
2022-09-17 21:20:30.325 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547403391776] Received {'type': 'call_service', 'domain': 'scene', 'service': 'turn_on', 'service_data': {'entity_id': 'scene.shutters_open'}, 'id': 54}
2022-09-17 21:20:30.327 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=scene, service=turn_on, service_data=entity_id=scene.shutters_open>
2022-09-17 21:20:30.330 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=scene.shutters_open, old_state=<state scene.shutters_open=2022-09-17T19:20:06.396766+00:00; entity_id=['cover.0xa4c13839de6d453d', 'cover.0xa4c138257227158b', 'cover.0xa4c138deb016510e', 'cover.0xa4c1381f55fc6698', 'cover.0xa4c1388f1c418338', 'cover.0xa4c1382ba5d4b92f', 'cover.study_window'], id=1662212551096, icon=mdi:window-shutter-open, friendly_name=Shutters Open @ 2022-09-17T21:20:06.397017+02:00>, new_state=<state scene.shutters_open=2022-09-17T19:20:30.329778+00:00; entity_id=['cover.0xa4c13839de6d453d', 'cover.0xa4c138257227158b', 'cover.0xa4c138deb016510e', 'cover.0xa4c1381f55fc6698', 'cover.0xa4c1388f1c418338', 'cover.0xa4c1382ba5d4b92f', 'cover.study_window'], id=1662212551096, icon=mdi:window-shutter-open, friendly_name=Shutters Open @ 2022-09-17T21:20:30.330059+02:00>>
2022-09-17 21:20:30.331 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547787541616] Sending {"id":2,"type":"event","event":{"c":{"scene.shutters_open":{"+":{"s":"2022-09-17T19:20:30.329778+00:00","lc":1663442430.330059,"c":"01GD6EAABQKGTXG5EZBJDVYQ3A"}}}}}
2022-09-17 21:20:30.332 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=scene, service=turn_on, service_data=entity_id=scene.shutters_open>)
2022-09-17 21:20:30.333 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=scene.shutters_open, old_state=<state scene.shutters_open=2022-09-17T19:20:06.396766+00:00; entity_id=['cover.0xa4c13839de6d453d', 'cover.0xa4c138257227158b', 'cover.0xa4c138deb016510e', 'cover.0xa4c1381f55fc6698', 'cover.0xa4c1388f1c418338', 'cover.0xa4c1382ba5d4b92f', 'cover.study_window'], id=1662212551096, icon=mdi:window-shutter-open, friendly_name=Shutters Open @ 2022-09-17T21:20:06.397017+02:00>, new_state=<state scene.shutters_open=2022-09-17T19:20:30.329778+00:00; entity_id=['cover.0xa4c13839de6d453d', 'cover.0xa4c138257227158b', 'cover.0xa4c138deb016510e', 'cover.0xa4c1381f55fc6698', 'cover.0xa4c1388f1c418338', 'cover.0xa4c1382ba5d4b92f', 'cover.study_window'], id=1662212551096, icon=mdi:window-shutter-open, friendly_name=Shutters Open @ 2022-09-17T21:20:30.330059+02:00>>)
2022-09-17 21:20:30.339 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547403391776] Sending {"id":3,"type":"event","event":{"c":{"scene.shutters_open":{"+":{"s":"2022-09-17T19:20:30.329778+00:00","lc":1663442430.330059,"c":"01GD6EAABQKGTXG5EZBJDVYQ3A"}}}}}
2022-09-17 21:20:30.342 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c13839de6d453d>
2022-09-17 21:20:30.343 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c138257227158b>
2022-09-17 21:20:30.344 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c138deb016510e>
2022-09-17 21:20:30.344 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1381f55fc6698>
2022-09-17 21:20:30.345 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1388f1c418338>
2022-09-17 21:20:30.346 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1382ba5d4b92f>
2022-09-17 21:20:30.347 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: CommitTask()
2022-09-17 21:20:30.350 DEBUG (Recorder) [homeassistant.components.recorder.pool.RecorderPool] Connection <sqlite3.Connection object at 0x7f8c48ee40> checked out from pool
2022-09-17 21:20:30.347 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.study_window>
2022-09-17 21:20:30.355 DEBUG (SyncWorker_3) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m215), 'b'zigbee2mqtt/Living Room Window/set'', ... (5 bytes)
2022-09-17 21:20:30.361 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Living Room Window/set: 'CLOSE', mid: 215
2022-09-17 21:20:30.363 DEBUG (SyncWorker_1) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m216), 'b'zigbee2mqtt/Living Room Door/set'', ... (5 bytes)
2022-09-17 21:20:30.379 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Living Room Door/set: 'CLOSE', mid: 216
2022-09-17 21:20:30.381 DEBUG (SyncWorker_0) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m217), 'b'zigbee2mqtt/Kitchen Door/set'', ... (5 bytes)
2022-09-17 21:20:30.389 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Kitchen Door/set: 'CLOSE', mid: 217
2022-09-17 21:20:30.392 DEBUG (Recorder) [homeassistant.components.recorder.pool.RecorderPool] Connection <sqlite3.Connection object at 0x7f8c48ee40> being returned to pool
2022-09-17 21:20:30.392 DEBUG (Recorder) [homeassistant.components.recorder.pool.RecorderPool] Connection <sqlite3.Connection object at 0x7f8c48ee40> rollback-on-return
2022-09-17 21:20:30.393 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c13839de6d453d>)
2022-09-17 21:20:30.394 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c138257227158b>)
2022-09-17 21:20:30.394 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c138deb016510e>)
2022-09-17 21:20:30.395 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1381f55fc6698>)
2022-09-17 21:20:30.396 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1388f1c418338>)
2022-09-17 21:20:30.396 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.0xa4c1382ba5d4b92f>)
2022-09-17 21:20:30.397 DEBUG (SyncWorker_5) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m218), 'b'zigbee2mqtt/Kitchen Window/set'', ... (5 bytes)
2022-09-17 21:20:30.397 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event call_service[L]: domain=cover, service=close_cover, service_data=entity_id=cover.study_window>)
2022-09-17 21:20:30.402 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Kitchen Window/set: 'CLOSE', mid: 218
2022-09-17 21:20:30.404 DEBUG (SyncWorker_6) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m219), 'b'zigbee2mqtt/Dining Room Bay/set'', ... (5 bytes)
2022-09-17 21:20:30.407 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Dining Room Bay/set: 'CLOSE', mid: 219
2022-09-17 21:20:30.410 DEBUG (SyncWorker_4) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m220), 'b'zigbee2mqtt/Dining Room Door/set'', ... (5 bytes)
2022-09-17 21:20:30.420 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Dining Room Door/set: 'CLOSE', mid: 220
2022-09-17 21:20:30.422 DEBUG (SyncWorker_7) [paho.mqtt.client] Sending PUBLISH (d0, q0, r0, m221), 'b'zigbee2mqtt/Study Window/set'', ... (5 bytes)
2022-09-17 21:20:30.426 DEBUG (MainThread) [homeassistant.components.mqtt.client] Transmitting message on zigbee2mqtt/Study Window/set: 'CLOSE', mid: 221
2022-09-17 21:20:30.427 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [547403391776] Sending {"id":54,"type":"result","success":true,"result":{"context":{"id":"01GD6EAABQKGTXG5EZBJDVYQ3A","parent_id":null,"user_id":"f5a6ef5b8b3f4ae281b229f3589e4f86"}}}
2022-09-17 21:20:30.464 DEBUG (Thread-2 (_thread_main)) [paho.mqtt.client] Received PUBLISH (d0, q0, r0, m0), 'zigbee2mqtt/Living Room Window', ... (152 bytes)
2022-09-17 21:20:30.467 DEBUG (MainThread) [homeassistant.components.mqtt.client] Received message on zigbee2mqtt/Living Room Window: b'{"backlight_mode":"LOW","calibration":"OFF","calibration_time":15.3,"linkquality":255,"motor_reversal":"ON","moving":"DOWN","position":0,"state":"OPEN"}'
2022-09-17 21:20:30.469 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=cover.0xa4c13839de6d453d, old_state=<state cover.0xa4c13839de6d453d=open; current_position=0, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:20:08.330517+02:00>, new_state=<state cover.0xa4c13839de6d453d=open; current_position=0, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:20:08.330517+02:00>>
2022-09-17 21:20:30.470 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_calibration, old_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>, new_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>>
2022-09-17 21:20:30.471 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_motor_reversal, old_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>, new_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>>
2022-09-17 21:20:30.472 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_moving, old_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:20:23.791649+02:00>, new_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:20:23.791649+02:00>>
2022-09-17 21:20:30.473 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=cover.0xa4c13839de6d453d, old_state=<state cover.0xa4c13839de6d453d=open; current_position=0, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:20:08.330517+02:00>, new_state=<state cover.0xa4c13839de6d453d=open; current_position=0, backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, friendly_name=Living Room Window, supported_features=15 @ 2022-09-17T21:20:08.330517+02:00>>)
2022-09-17 21:20:30.475 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_calibration, old_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>, new_state=<state switch.0xa4c13839de6d453d_calibration=off; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, icon=mdi:progress-wrench, friendly_name=Living Room Window calibration @ 2022-09-17T21:17:45.561651+02:00>>)
2022-09-17 21:20:30.476 DEBUG (Recorder) [homeassistant.components.recorder.core] Processing task: EventTask(event=<Event state_changed[L]: entity_id=switch.0xa4c13839de6d453d_motor_reversal, old_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>, new_state=<state switch.0xa4c13839de6d453d_motor_reversal=on; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, icon=mdi:arrow-left-right, friendly_name=Living Room Window motor_reversal @ 2022-09-17T21:17:45.576114+02:00>>)
2022-09-17 21:20:30.472 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_calibration_time, old_state=<state sensor.0xa4c13839de6d453d_calibration_time=15.3; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, unit_of_measurement=S, icon=mdi:wrench-clock, friendly_name=Living Room Window calibration time @ 2022-09-17T21:17:45.579142+02:00>, new_state=<state sensor.0xa4c13839de6d453d_calibration_time=15.3; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, unit_of_measurement=S, icon=mdi:wrench-clock, friendly_name=Living Room Window calibration time @ 2022-09-17T21:17:45.579142+02:00>>
2022-09-17 21:20:30.480 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=select.0xa4c13839de6d453d_backlight_mode, old_state=<state select.0xa4c13839de6d453d_backlight_mode=LOW; options=['LOW', 'MEDIUM', 'HIGH'], backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=STOP, position=0, icon=mdi:lightbulb, friendly_name=Living Room Window backlight mode @ 2022-09-17T21:17:45.581880+02:00>, new_state=<state select.0xa4c13839de6d453d_backlight_mode=LOW; options=['LOW', 'MEDIUM', 'HIGH'], backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, icon=mdi:lightbulb, friendly_name=Living Room Window backlight mode @ 2022-09-17T21:17:45.581880+02:00>>
2022-09-17 21:20:30.483 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.0xa4c13839de6d453d_moving, old_state=<state sensor.0xa4c13839de6d453d_moving=STOP; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:20:23.791649+02:00>, new_state=<state sensor.0xa4c13839de6d453d_moving=DOWN; backlight_mode=LOW, calibration=OFF, calibration_time=15.3, linkquality=255, motor_reversal=ON, moving=DOWN, position=0, friendly_name=Living Room Window moving @ 2022-09-17T21:20:30.483142+02:00>>
Weirdly, position=0 is sent in both cases.
The entire HA log is 4.4Mb large, if you really want all of it, I could fileshare it somewhere. Just ask