zigbee2mqtt / hassio-zigbee2mqtt

Official Zigbee2MQTT Home Assistant add-on
https://www.zigbee2mqtt.io
Apache License 2.0
1.13k stars 406 forks source link

Aqara E1 roller - fail to open/close, stuck in position until restart #633

Closed Lauriz7 closed 3 weeks ago

Lauriz7 commented 2 months ago

Description of the issue

Randomly one or more shutters stops responding.

Entity stuck in log is called "Vindue Midt".

Addon version

1.39.1-1

Platform

Core 2024.8.0 Supervisor 2024.08.0 Operating System 13.0

Logs of the issue (if applicable)

[2024-08-19 17:34:05] debug: zh:ezsp:uart: <-- [73d0b1a96b2a1568a2904b28aa5493499d4e2719e1ce67000e7e03d0b1a97d312a15b658994a24ab5593499ce4bf6937359874f6de7d5d88a97d5e3f9eebcdde6f81dc7e] [2024-08-19 17:34:05] debug: zh:ezsp:uart: <-- DATA (7,3,0): 73d0b1a96b2a1568a2904b28aa5493499d4e2719e1ce67000e7e [2024-08-19 17:34:05] debug: zh:ezsp:uart: --> ACK (0) [2024-08-19 17:34:05] debug: zh:ezsp:uart: --> [8070787e] [2024-08-19 17:34:05] debug: zh:ezsp:uart: <-- ACK (3): 73d0b1a96b2a1568a2904b28aa5493499d4e2719e1ce67000e7e [2024-08-19 17:34:05] debug: zh:ezsp:ezsp: <== Frame: 9290013f0000dafb04010d00010100010000b20c0000 [2024-08-19 17:34:05] debug: zh:ezsp:ezsp: <== 0x3f: {"cls":"messageSentHandler","id":63,"isRequest":false,"type":0,"indexOrDestination":64474,"apsFrame":{"profileId":260,"sequence":178,"clusterId":13,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"messageTag":12,"status":0,"message":{"type":"Buffer","data":[]}} [2024-08-19 17:34:05] debug: zh:ezsp:uart: <-- DATA (0,3,0): 03d0b1a9112a15b658994a24ab5593499ce4bf6937359874f6de7d88a97e3f9eebcdde6f81dc7e [2024-08-19 17:34:05] debug: zh:ezsp:uart: --> ACK (1) [2024-08-19 17:34:05] debug: zh:ezsp:uart: --> [8160597e] [2024-08-19 17:34:05] debug: zh:ezsp:uart: <-- ACK (3): 03d0b1a9112a15b658994a24ab5593499ce4bf6937359874f6de7d88a97e3f9eebcdde6f81dc7e [2024-08-19 17:34:05] debug: zh:ezsp:ezsp: <== Frame: 92900145000004010d00010100010000aa98c2dafbffff0b181e015500003900000000 [2024-08-19 17:34:05] debug: zh:ezsp:ezsp: <== 0x45: {"cls":"incomingMessageHandler","id":69,"isRequest":false,"type":0,"apsFrame":{"profileId":260,"sequence":170,"clusterId":13,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lastHopLqi":152,"lastHopRssi":-62,"sender":64474,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[24,30,1,85,0,0,57,0,0,0,0]}} [2024-08-19 17:34:05] debug: zh:ezsp: processMessage: {"messageType":0,"apsFrame":{"profileId":260,"sequence":170,"clusterId":13,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lqi":152,"rssi":-62,"sender":64474,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[24,30,1,85,0,0,57,0,0,0,0]}} [2024-08-19 17:34:05] debug: zh:controller: Received payload: clusterID=13, address=64474, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=152, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":30,"commandIdentifier":1},"payload":[{"attrId":85,"status":0,"dataType":57,"attrData":0}],"command":{"ID":1,"name":"readRsp","parameters":[{"name":"attrId","type":33},{"name":"status","type":32},{"name":"dataType","type":32,"conditions":[{"type":"statusEquals","value":0}]},{"name":"attrData","type":1000,"conditions":[{"type":"statusEquals","value":0}]}]}} [2024-08-19 17:34:05] debug: z2m: Received Zigbee message from 'Vindue Sofa', type 'readResponse', cluster 'genAnalogOutput', data '{"presentValue":0}' from endpoint 1 with groupID 0 [2024-08-19 17:34:05] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Vindue Sofa', payload '{"battery":44,"charging_status":false,"device_temperature":23,"linkquality":152,"motor_speed":"low","motor_state":"stopped","position":0,"power_outage_count":12,"running":false,"state":"OFF","update":{"installed_version":3870,"latest_version":3870,"state":"idle"},"update_available":null}' [2024-08-19 17:34:14] debug: zh:ezsp:ezsp: Time to watchdog ... 0 [2024-08-19 17:34:14] debug: zh:ezsp:ezsp: ==> nop: null [2024-08-19 17:34:14] debug: zh:ezsp:ezsp: ==> {"cls":"nop","id":5,"isRequest":true} [2024-08-19 17:34:14] debug: zh:ezsp:uart: --> DATA (3,1,0): 9300010500 [2024-08-19 17:34:14] debug: zh:ezsp:uart: --> [31d121a9512a23de7e] [2024-08-19 17:34:14] debug: zh:ezsp:uart: -?- waiting (4) [2024-08-19 17:34:14] debug: zh:ezsp:uart: <-- [14d1a1a9512a88ef7e] [2024-08-19 17:34:14] debug: zh:ezsp:uart: <-- DATA (1,4,0): 14d1a1a9512a88ef7e [2024-08-19 17:34:14] debug: zh:ezsp:uart: --> ACK (2) [2024-08-19 17:34:14] debug: zh:ezsp:uart: --> [82503a7e] [2024-08-19 17:34:14] debug: zh:ezsp:uart: <-- ACK (4): 14d1a1a9512a88ef7e [2024-08-19 17:34:14] debug: zh:ezsp:ezsp: <== Frame: 9380010500 [2024-08-19 17:34:14] debug: zh:ezsp:ezsp: <== 0x5: {"cls":"nop","id":5,"isRequest":false} [2024-08-19 17:34:14] debug: zh:ezsp:uart: -+- waiting (4) success [2024-08-19 17:34:24] debug: zh:ezsp:ezsp: Time to watchdog ... 0 [2024-08-19 17:34:25] debug: zh:ezsp:ezsp: ==> nop: null [2024-08-19 17:34:25] debug: zh:ezsp:ezsp: ==> {"cls":"nop","id":5,"isRequest":true} [2024-08-19 17:34:25] debug: zh:ezsp:uart: --> DATA (4,2,0): 9400010500 [2024-08-19 17:34:25] debug: zh:ezsp:uart: --> [42d621a9512acf767e] [2024-08-19 17:34:25] debug: zh:ezsp:uart: -?- waiting (5) [2024-08-19 17:34:25] debug: zh:ezsp:uart: <-- [25d6a1a9512a85177e] [2024-08-19 17:34:25] debug: zh:ezsp:uart: <-- DATA (2,5,0): 25d6a1a9512a85177e [2024-08-19 17:34:25] debug: zh:ezsp:uart: --> ACK (3) [2024-08-19 17:34:25] debug: zh:ezsp:uart: --> [83401b7e] [2024-08-19 17:34:25] debug: zh:ezsp:uart: <-- ACK (5): 25d6a1a9512a85177e [2024-08-19 17:34:25] debug: zh:ezsp:ezsp: <== Frame: 9480010500 [2024-08-19 17:34:25] debug: zh:ezsp:ezsp: <== 0x5: {"cls":"nop","id":5,"isRequest":false} [2024-08-19 17:34:25] debug: zh:ezsp:uart: -+- waiting (5) success [2024-08-19 17:34:34] debug: zh:ezsp:ezsp: Time to watchdog ... 0 [2024-08-19 17:34:34] debug: zh:ezsp:ezsp: ==> nop: null [2024-08-19 17:34:34] debug: zh:ezsp:ezsp: ==> {"cls":"nop","id":5,"isRequest":true} [2024-08-19 17:34:34] debug: zh:ezsp:uart: --> DATA (5,3,0): 9500010500 [2024-08-19 17:34:34] debug: zh:ezsp:uart: --> [53d721a9512a3a037e] [2024-08-19 17:34:34] debug: zh:ezsp:uart: -?- waiting (6) [2024-08-19 17:34:34] debug: zh:ezsp:uart: <-- [36d7a1a9512afb227e] [2024-08-19 17:34:34] debug: zh:ezsp:uart: <-- DATA (3,6,0): 36d7a1a9512afb227e [2024-08-19 17:34:34] debug: zh:ezsp:uart: --> ACK (4) [2024-08-19 17:34:34] debug: zh:ezsp:uart: --> [8430fc7e] [2024-08-19 17:34:34] debug: zh:ezsp:uart: <-- ACK (6): 36d7a1a9512afb227e [2024-08-19 17:34:34] debug: zh:ezsp:ezsp: <== Frame: 9580010500 [2024-08-19 17:34:34] debug: zh:ezsp:ezsp: <== 0x5: {"cls":"nop","id":5,"isRequest":false} [2024-08-19 17:34:34] debug: zh:ezsp:uart: -+- waiting (6) success [2024-08-19 17:34:42] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/Vindue Midt/set' with data '{ "position": 100 }' [2024-08-19 17:34:42] debug: z2m: Publishing 'set' 'position' to 'Vindue Midt' [2024-08-19 17:34:42] debug: zh:controller:endpoint: ZCL command 0x54ef441000450bd2/1 genAnalogOutput.write({"presentValue":100}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":0,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false}) [2024-08-19 17:34:43] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/Vindue Midt/set' with data '{ "position": 0 }' [2024-08-19 17:34:43] debug: z2m: Publishing 'set' 'position' to 'Vindue Midt' [2024-08-19 17:34:43] debug: zh:controller:endpoint: ZCL command 0x54ef441000450bd2/1 genAnalogOutput.write({"presentValue":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":0,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false}) [2024-08-19 17:34:44] debug: zh:ezsp:ezsp: Time to watchdog ... 0 [2024-08-19 17:34:44] debug: zh:ezsp:ezsp: ==> nop: null [2024-08-19 17:34:44] debug: zh:ezsp:ezsp: ==> {"cls":"nop","id":5,"isRequest":true} [2024-08-19 17:34:44] debug: zh:ezsp:uart: --> DATA (6,4,0): 9600010500 [2024-08-19 17:34:44] debug: zh:ezsp:uart: --> [64d421a9512a331c7e] [2024-08-19 17:34:44] debug: zh:ezsp:uart: -?- waiting (7) [2024-08-19 17:34:44] debug: zh:ezsp:uart: <-- [47d4a1a9512a15cc7e] [2024-08-19 17:34:44] debug: zh:ezsp:uart: <-- DATA (4,7,0): 47d4a1a9512a15cc7e [2024-08-19 17:34:44] debug: zh:ezsp:uart: --> ACK (5) [2024-08-19 17:34:44] debug: zh:ezsp:uart: --> [8520dd7e] [2024-08-19 17:34:44] debug: zh:ezsp:uart: <-- ACK (7): 47d4a1a9512a15cc7e [2024-08-19 17:34:44] debug: zh:ezsp:ezsp: <== Frame: 9680010500 [2024-08-19 17:34:44] debug: zh:ezsp:ezsp: <== 0x5: {"cls":"nop","id":5,"isRequest":false} [2024-08-19 17:34:44] debug: zh:ezsp:uart: -+- waiting (7) success [2024-08-19 17:34:47] debug: zh:ezsp:uart: <-- [57d4b1a97d312a15b259914a25aa1593499ce5ab1470529874fe146389fef3667e] [2024-08-19 17:34:47] debug: zh:ezsp:uart: <-- DATA (5,7,0): 57d4b1a9112a15b259914a25aa1593499ce5ab1470529874fe146389fef3667e [2024-08-19 17:34:47] debug: zh:ezsp:uart: --> ACK (6) [2024-08-19 17:34:47] debug: zh:ezsp:uart: --> [8610be7e] [2024-08-19 17:34:47] debug: zh:ezsp:uart: <-- ACK (7): 57d4b1a9112a15b259914a25aa1593499ce5ab1470529874fe146389fef3667e [2024-08-19 17:34:47] debug: zh:ezsp:ezsp: <== Frame: 96900145000000000500000040010000ab8cbf9d9cffff03d2000002 [2024-08-19 17:34:47] debug: zh:ezsp:ezsp: <== 0x45: {"cls":"incomingMessageHandler","id":69,"isRequest":false,"type":0,"apsFrame":{"profileId":0,"sequence":171,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":320},"lastHopLqi":140,"lastHopRssi":-65,"sender":40093,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[210,0,0]}} [2024-08-19 17:34:47] debug: zh:ezsp: processMessage: {"messageType":0,"apsFrame":{"profileId":0,"sequence":171,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"groupId":0,"options":320},"lqi":140,"rssi":-65,"sender":40093,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[210,0,0]}} [2024-08-19 17:34:54] debug: zh:ezsp:ezsp: Time to watchdog ... 0 [2024-08-19 17:34:54] debug: zh:ezsp:ezsp: ==> nop: null [2024-08-19 17:34:54] debug: zh:ezsp:ezsp: ==> {"cls":"nop","id":5,"isRequest":true} [2024-08-19 17:34:54] debug: zh:ezsp:uart: --> DATA (7,6,0): 9700010500 [2024-08-19 17:34:54] debug: zh:ezsp:uart: --> [76d521a9512a08897e] [2024-08-19 17:34:54] debug: zh:ezsp:uart: -?- waiting (0) [2024-08-19 17:34:54] debug: zh:ezsp:uart: <-- [60d5a1a9512a42d47e] [2024-08-19 17:34:54] debug: zh:ezsp:uart: <-- DATA (6,0,0): 60d5a1a9512a42d47e [2024-08-19 17:34:54] debug: zh:ezsp:uart: --> ACK (7) [2024-08-19 17:34:54] debug: zh:ezsp:uart: --> [87009f7e] [2024-08-19 17:34:54] debug: zh:ezsp:uart: <-- ACK (0): 60d5a1a9512a42d47e [2024-08-19 17:34:54] debug: zh:ezsp:ezsp: <== Frame: 9780010500 [2024-08-19 17:34:54] debug: zh:ezsp:ezsp: <== 0x5: {"cls":"nop","id":5,"isRequest":false} [2024-08-19 17:34:54] debug: zh:ezsp:uart: -+- waiting (0) success

github-actions[bot] commented 4 weeks ago

This issue is stale because it has been open 30 days with no activity. Remove stale label or comment or this will be closed in 7 days