search

mill1000 / midea-ac-py

Home Assistant custom integration to control Midea (and associated brands) air conditioners via LAN.
MIT License
150 stars 15 forks source link

Integration fails to display correctly running state. #246

Closed diesel437 closed 3 days ago

diesel437 commented 4 days ago

Integration was working fine for a while. Suddently it has stopped showing correct running state.. and sometimes even wont work. I went to logs see if I could find anything and found the error below. Hope it helps.

Registrador: midealocal.device
Fonte: /usr/local/lib/python3.12/site-packages/midealocal/device.py:494
Ocorreu pela primeira vez: 12:51:21 (27 ocorrências)
Último registro: 19:21:30

[150633093569209] Error in process message, msg = aa11ac00000000000304a100027c66566100
[150633093569198] Error in process message, msg = aa11ac00000000000304a10000fbac57623b
[150633093569209] Error in process message, msg = aa11ac00000000000304a100027cc05762a4
[150633093569198] Error in process message, msg = aa11ac00000000000304a10000fbad586338
[150633093569209] Error in process message, msg = aa11ac00000000000304a100027d765863eb
Traceback (most recent call last):
  File "/usr/local/lib/python3.12/site-packages/midealocal/device.py", line 485, in parse_message
    status = self.process_message(bytes(decrypted))
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/midealocal/devices/ac/__init__.py", line 189, in process_message
    message = MessageACResponse(bytearray(msg), self._power_analysis_method)
              ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/midealocal/devices/ac/message.py", line 907, in __init__
    self.set_body(XA1MessageBody(super().body))
                  ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/midealocal/devices/ac/message.py", line 645, in __init__
    if body[13] != MAX_BYTE_VALUE:
       ~~~~^^^^
IndexError: bytearray index out of range
diesel437 commented 4 days ago

Before you ask, maximum connection lifetime is set to 90.

The AC with strange behaviour is Ar Bancada. No idea why. Those devices have MAC/IP reservation in router.

I found those logs aswell:

2024-09-26 19:51:33.018 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Connecting to 192.168.10.41:6444
2024-09-26 19:51:33.025 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Connected
2024-09-26 19:51:33.025 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Enabling device
2024-09-26 19:51:33.025 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Status update: {'available': True}
2024-09-26 19:51:33.027 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Authentication handshaking
2024-09-26 19:51:33.236 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Received auth response with 72 bytes: 8370004020011b47881194aa6aebe0e1e58e567eee5113647f18c5c260350f05dba44c8c2230956c5e273327dc6df25be1501dbad1e1f8b3b47f8dbd142f9cb635baef57a937e868
2024-09-26 19:51:33.236 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Authentication success
2024-09-26 19:51:33.236 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': 172, '_message_type': <MessageType.query_appliance: 160>, '_body_type': 0, '_protocol_version': 0, 'header': 'aa1dac000000000000a0', 'body': '00000000000000000000000000000000000000', 'message_type': 'query_appliance', 'body_type': '00', 'self': <midealocal.message.MessageQueryAppliance object at 0x7f25aba636e0>}
2024-09-26 19:51:33.283 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e20665fead398c24105209de55419949e1c4b18f1eb7ccc65fc4e6ae2d672f776e82c4289a1dd3963085d10ebbb35f3474b70d3582432cbba60e72d337ce5ec36dd291ecbf3c2f6e8b5928f1260bd8ecf66d2ddae3cf3400583bfae16d940c0cf7e4f
2024-09-26 19:51:34.485 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e206657b4140d328f277524072c0c92b7fb4cee2527e59741779354152ea11f347dfdad2100ffeaf4de4264fbddbf486fb292acbf9635e927a10a80866e71e409e39100110a293455697ffae0d9a2bd630223124183e8d022668953422f3ca784f52f
2024-09-26 19:51:34.585 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066bea5ab30e30203d6e303b39e3812d5494e40a7138c14d2e3c50b4750e6ad0e24895ec3e857971519e78723e8fb98e3aaf7131590fd4b4f410bae82e5aa0b0a86044caf8224174899df415544d3c9a5fa7a12bc02c19d71c24c3289c270233803
2024-09-26 19:51:35.240 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] _recv_message Socket timed out with check_protocol True
2024-09-26 19:51:35.240 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] protocol MessageQueryAppliance, cmd {'_device_type': 172, '_message_type': <MessageType.query_appliance: 160>, '_body_type': 0, '_protocol_version': 0, 'header': 'aa1dac000000000000a0', 'body': '00000000000000000000000000000000000000', 'message_type': 'query_appliance', 'body_type': '00', 'self': <midealocal.message.MessageQueryAppliance object at 0x7f25aba636e0>}, timeout
2024-09-26 19:51:35.240 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Does not supports the protocol MessageQueryAppliance, cmd {'_device_type': 172, '_message_type': <MessageType.query_appliance: 160>, '_body_type': 0, '_protocol_version': 0, 'header': 'aa1dac000000000000a0', 'body': '00000000000000000000000000000000000000', 'message_type': 'query_appliance', 'body_type': '00', 'self': <midealocal.message.MessageQueryAppliance object at 0x7f25aba636e0>}, ignored
2024-09-26 19:51:35.240 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.X41: 65>, '_protocol_version': 0, '_message_id': 153, 'header': 'aa20ac00000000000003', 'body': '418100ff000000000000000000000000000000009978', 'message_type': 'query', 'body_type': '41', 'self': <midealocal.devices.ac.message.MessageQuery object at 0x7f25abbcf7d0>}
2024-09-26 19:51:36.443 DEBUG (Ar Bancada) [midealocal.devices.ac] [150633093569198] Received: {'_device_type': 172, '_message_type': 3, '_body_type': 192, '_protocol_version': 0, '_header': 'aa22ac00000000000003', '_body': <midealocal.devices.ac.message.XC0MessageBody object at 0x7f258a93ee40>, '_data': 'c00148667f7f000c000000616f0c70760000000000009979', 'parser_list': [], 'power': True, 'mode': 2, 'target_temperature': 24.0, 'fan_speed': 102, 'swing_vertical': True, 'swing_horizontal': False, 'boost_mode': False, 'smart_eye': False, 'natural_wind': False, 'dry': False, 'eco_mode': False, 'aux_heating': False, 'temp_fahrenheit': False, 'sleep_mode': False, 'indoor_temperature': 23.6, 'outdoor_temperature': 30.7, 'full_dust': False, 'screen_display': False, 'frost_protect': False, 'comfort_mode': True, 'header': 'aa22ac00000000000003', 'body': 'c00148667f7f000c000000616f0c70760000000000009979', 'message_type': 'query', 'body_type': 'c0', 'self': <midealocal.devices.ac.message.MessageACResponse object at 0x7f258a93c800>}
2024-09-26 19:51:36.443 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Status update: {'power': True, 'mode': 2, 'target_temperature': 24.0, 'fan_speed': 102, 'swing_vertical': True, 'swing_horizontal': False, 'smart_eye': False, 'dry': False, 'aux_heating': False, 'boost_mode': False, 'sleep_mode': False, 'frost_protect': False, 'comfort_mode': True, 'eco_mode': False, 'natural_wind': False, 'temp_fahrenheit': False, 'screen_display': False, 'full_dust': False, 'indoor_temperature': 23.6, 'outdoor_temperature': 30.7, 'indirect_wind': False}
2024-09-26 19:51:36.444 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.B1: 177>, '_protocol_version': 0, '_message_id': 154, 'header': 'aa1aac00000000000003', 'body': 'b106420018001500170033024b009acf', 'message_type': 'query', 'body_type': 'b1', 'self': <midealocal.devices.ac.message.MessageNewProtocolQuery object at 0x7f2597af0e60>}
2024-09-26 19:51:38.449 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] _recv_message Socket timed out with check_protocol True
2024-09-26 19:51:38.449 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] protocol MessageNewProtocolQuery, cmd {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.B1: 177>, '_protocol_version': 0, '_message_id': 154, 'header': 'aa1aac00000000000003', 'body': 'b106420018001500170033024b009acf', 'message_type': 'query', 'body_type': 'b1', 'self': <midealocal.devices.ac.message.MessageNewProtocolQuery object at 0x7f2597af0e60>}, timeout
2024-09-26 19:51:38.449 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Does not supports the protocol MessageNewProtocolQuery, cmd {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.B1: 177>, '_protocol_version': 0, '_message_id': 154, 'header': 'aa1aac00000000000003', 'body': 'b106420018001500170033024b009acf', 'message_type': 'query', 'body_type': 'b1', 'self': <midealocal.devices.ac.message.MessageNewProtocolQuery object at 0x7f2597af0e60>}, ignored
2024-09-26 19:51:38.449 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.X41: 65>, '_protocol_version': 0, '_message_id': 155, 'header': 'aa11ac00000000000003', 'body': '41210144000109', 'message_type': 'query', 'body_type': '41', 'self': <midealocal.devices.ac.message.MessagePowerQuery object at 0x7f259e787350>}
2024-09-26 19:51:38.540 DEBUG (Ar Bancada) [midealocal.devices.ac] [150633093569198] Received: {'_device_type': 172, '_message_type': 3, '_body_type': 193, '_protocol_version': 0, '_header': 'aa20ac00000000000003', '_body': <midealocal.devices.ac.message.XC1MessageBody object at 0x7f259783c410>, '_data': 'c1210144000000000000000000000000003f5900010a', 'parser_list': [], 'total_energy_consumption': 0.0, 'current_energy_consumption': 0.0, 'realtime_power': 455.9, 'header': 'aa20ac00000000000003', 'body': 'c1210144000000000000000000000000003f5900010a', 'message_type': 'query', 'body_type': 'c1', 'self': <midealocal.devices.ac.message.MessageACResponse object at 0x7f25abb56270>}
2024-09-26 19:51:38.540 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Status update: {'total_energy_consumption': 0.0, 'current_energy_consumption': 0.0, 'realtime_power': 455.9}
2024-09-26 19:51:38.541 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.B5: 181>, '_protocol_version': 0, '_message_id': 156, '_additional_capabilities': False, 'header': 'aa0fac00000000000003', 'body': 'b501009c09', 'message_type': 'query', 'body_type': 'b5', 'self': <midealocal.devices.ac.message.MessageCapabilitiesQuery object at 0x7f259e787350>}
2024-09-26 19:51:38.542 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': <DeviceType.AC: 172>, '_message_type': <MessageType.query: 3>, '_body_type': <BodyType.B5: 181>, '_protocol_version': 0, '_message_id': 157, '_additional_capabilities': True, 'header': 'aa10ac00000000000003', 'body': 'b50101019d91', 'message_type': 'query', 'body_type': 'b5', 'self': <midealocal.devices.ac.message.MessageCapabilitiesQuery object at 0x7f25aba636e0>}
2024-09-26 19:51:39.992 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066cff1366f5ac811a834130cebd7b197850bff831a146b92a8ad3b16fcef4891f4e563b595db908cf45aec79054fd78508439782b867e514a240b8342f11a6bcb142f05b38185bd01111dc054e7fab32fd62a80cacacd6aca913e8caaf6bcd5778
2024-09-26 19:51:40.678 ERROR (MainThread) [homeassistant.components.command_line.utils] Command failed (with return code 2): {{
 'ping -W 1 -c 1 ' + states('sensor.escritorio_rack_endereco_ip') + ' > /dev/null 2>&1 && echo ON|| echo OFF'
}}
2024-09-26 19:51:42.410 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e20665b7f95e1f6594d55b5743540416bfedf26f3ded8afff2868072f00a7d267fc66e93316d46b8c59fc702f166f978cfd0347dbe318978b347f40a71a0c4c0e9d88d8f85becfd24748fad3ddf3eb66d68ad4bca0541940ae033a5906376885e27ab
2024-09-26 19:51:43.296 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e20667524da704ed3b5b717dc9698add35386ab8795df099890fe2db40eedaba4e36f66b4cda7eab11e887fea3844040e86fa139bcd57241f0e302eb00426dc087ca766d21f9a3e5d684b58c5689db49a7ca8a5ef50c9309ba7faa7ff03f593661b16
2024-09-26 19:51:44.500 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066d7541ba16f15a9406dabdb19f18a36d68932c376bdda5c774a068d965900ae32d5ecf6a6fc7d6a4eb2223cbfea4c1ebc38511041ff2aa2bedd1105a7c0cbae691e7a63dd5deb45bf5a2ce630985eded935398a9922173498b16578747adcf881
2024-09-26 19:51:44.600 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066b788ad9423facb55cbde1ecd2258862a73b92eabd2ceece843466952ed3761634df516a4530e8c71c334cd82256cac85930e18ef8ef5827daa9a94224b773ea3dfa18d92fe69d82b0f788f8a7cc5739492f0d8a55e79ad28a5d3b99dcf80fd5b
2024-09-26 19:51:49.177 INFO (MainThread) [greeclimate.cipher] Decrypting data: UP6/IEbMhg/vVEnG0iXp08MqfMbxS4wHIGBMLQeA86zUU2CpJS+yjWwQV5FFBKmTaqo0LjW0/KBsRsAIOBe64oJmuRC5TZYSddT9pTW2KHCNZn5BF0Hi1IaLpZyxTKgQCpqLtKgVxw6TpRiChL3ETzxv/1RbF6ZzfIkJxTBRQbfoG4t/aJk+CR6acCSE81xV414C8PidyqwaNrcurleLJsveZs38fnmmQGCQ6Rvrm2aAnIdBL8bwPoOhrqOp6hqyx2ak/YIe39eEU5Sni9IfkZczNXiEPC6nb/6m2GngB2+k7Ry+bPZwTvTsXK/twNfzPGn1Bb3kOI+unLQ4PK1PvYmlV1tksNCM4Cekc8hGeDyoxwcW/G8/SE2bgcLx/73GyyyQxOeJwNQWf43EO+72bmHmX5z99v1IDQkSFSETTSpKzWe1anHG/MNZhzm3wC33ilXXZwvsl/yd
2024-09-26 19:51:50.012 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066c9da5e877f34897c1c03b5c8f4c17e56bb42a4c69934aad8ee1aba7c6d5df2bf6481e9f513c4fd968ad6339a8a5aaea8bed9099173e934cd98686f4c5431751c952f55d0d4aabce1c1e63398f8c2e2b1bd697520716e2839483b0257172502e7
2024-09-26 19:51:52.425 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066c3c29451b528d00f675d4ea6a549664303327f01bdcffde1c122bcd014d6ac6cc8f1575e498e30b1c20d8954ea509e03986d2c37f3717c2ade8311be2aefef0c27569f50bc9962ace5e7c482a497951410dd9aa8b8c02c1702961ea9d58a69a9
2024-09-26 19:51:53.316 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e206649d6b5b77df06e7a37bb34fd0d50ab037ee40c32b34f9cdbf406f03e5df0f5f189d8d259b48d28f48cc65edd039d31dca0738afc28fa90f6156033f98b8ba437bb6f1a97e3d5d3391ee4c676d7ead9353cf8a9a8dd33b79abcc30ce59d2cf81c
2024-09-26 19:51:54.018 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Sending: {'_device_type': 172, '_message_type': <MessageType.query_appliance: 160>, '_body_type': 0, '_protocol_version': 0, 'header': 'aa1dac000000000000a0', 'body': '00000000000000000000000000000000000000', 'message_type': 'query_appliance', 'body_type': '00', 'self': <midealocal.message.MessageQueryAppliance object at 0x7f258b584860>}
2024-09-26 19:51:54.019 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370007e2066c99b77902997774fe67527ecb6b29df0d99c30bd81eafd3a19860435e06e820a3d1c521da7efa09e59c7fcc248856741db680d4904b7fda31f14997163a1097030dc990b6e5c20deb02a468ed2678310aca8bd21833516958801ed0fd0087bf9229c7fb63f910841ecc4973d38c605a3a3d7a81e109ccc861743bf81117b6d63
2024-09-26 19:51:54.019 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] _recv_message socket error, reconnect
2024-09-26 19:51:54.019 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] refresh_status socket error, close and reconnect
2024-09-26 19:51:54.520 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e2066dbe5f46385dc84dcfaa092dec09babb19cc25cc9bdfc07dfaed959d9d4e98dc31dee6311fc98ef5365da7b6eadd60dcbbb656e4482f86454704a7887d7102495ac373659736aa743d4da42d73dcc22d13561e7b6ade53a33f96c71b44f32c694
2024-09-26 19:51:54.618 DEBUG (Ar Bancada) [midealocal.device] [150633093569198] Send failure, device socket is none, data: 8370005e20662087f6f5e1685e104290d4b64f0d56c97335d8895fda040efd75e98829bbbac244e132cde7f107d72a5f9d4b38decd2a2d557f391a7b660b1091f7d1a1e57b6e66a65f7dcfa62bd805641cb0da55e3914519383da682a31f6057e7735edbc60b
mill1000 commented 4 days ago

These logs are not from this integration. You appear to be running another integration (perhaps https://github.com/wuwentao/midea_ac_lan)

diesel437 commented 3 days ago

Indeed. I'm sorry for the trouble. I'll raise a bug ticket there. You can close it!