nicole-ashley / homeassistant-goldair-climate

Home Assistant integration for Goldair WiFi heaters, dehumidifiers and fans
MIT License
19 stars 11 forks source link

Goldair component errros #30

Open kiwijunglist opened 4 years ago

kiwijunglist commented 4 years ago

Home Assistant 0.109.6 in Docker + Network_mode: host Setup heater via integration (not configuration.yaml) Integration version? = "Latest commit 02a9cb4" 16 hrs ago.

Log Details (ERROR)
Logger: homeassistant.util.json
Source: util/json.py:32
First occurred: 2:50:35 PM (5 occurrences)
Last logged: 2:51:49 PM

Could not parse JSON content: /config/custom_components/goldair_climate/translations/en.json
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
Log Details (ERROR)
Logger: pytuya
Source: custom_components/goldair_climate/device.py:145
First occurred: 2:57:38 PM (1 occurrences)
Last logged: 2:57:38 PM

Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x02\x80\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cxQ\xdcTO$^#`\xdc\xad\x84P\xa1V[\xd6\xd2\x9aHy\x9a\xc9W\xf2\xf9\\\x93\x10\xe2\x8e\xee'
Failed to call service climate/set_swing_mode. None is not in list
Log Details (ERROR)
Logger: homeassistant.core
Source: custom_components/goldair_climate/device.py:246
First occurred: 2:56:23 PM (30 occurrences)
Last logged: 3:31:37 PM

Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 550, in _update_entity_states
    await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 284, in async_update_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 323, in _async_write_ha_state
    attr.update(self.state_attributes or {})
  File "/usr/src/homeassistant/homeassistant/components/climate/__init__.py", line 257, in state_attributes
    data[ATTR_SWING_MODE] = self.swing_mode
  File "/config/custom_components/goldair_climate/heater/climate.py", line 215, in swing_mode
    return GoldairTuyaDevice.get_key_for_value(POWER_LEVEL_TO_DPS_LEVEL, dps_mode)
  File "/config/custom_components/goldair_climate/device.py", line 246, in get_key_for_value
    return keys[values.index(value)] or fallback
ValueError: None is not in list
Log Details (ERROR)
Logger: homeassistant.components.websocket_api.http.connection.140312346464208
Source: util/json.py:38
Integration: Home Assistant WebSocket API (documentation, issues)
First occurred: 2:50:35 PM (4 occurrences)
Last logged: 2:51:49 PM

Error handling message: Unknown error
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 20, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/frontend/__init__.py", line 550, in websocket_get_translations
    msg.get("config_flow"),
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 325, in async_get_translations
    results = await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 221, in async_get_component_strings
    loaded_translations = await load_translations_job
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 90, in load_translations_files
    loaded_json = load_json(translation_file)
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 38, in load_json
    raise HomeAssistantError(error)
homeassistant.exceptions.HomeAssistantError: Expecting value: line 1 column 1 (char 0)
Log Details (ERROR)
Logger: homeassistant.components.websocket_api.http.connection.140312346464208
Source: custom_components/goldair_climate/device.py:246
Integration: Home Assistant WebSocket API (documentation, issues)
First occurred: 2:56:04 PM (1 occurrences)
Last logged: 2:56:04 PM

None is not in list
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 130, in handle_call_service
    connection.context(msg),
  File "/usr/src/homeassistant/homeassistant/core.py", line 1247, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1282, in _execute_service
    await handler.func(service_call)
  File "/usr/src/homeassistant/homeassistant/helpers/entity_component.py", line 213, in handle_service
    self._platforms.values(), func, call, required_features
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 470, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 284, in async_update_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 323, in _async_write_ha_state
    attr.update(self.state_attributes or {})
  File "/usr/src/homeassistant/homeassistant/components/climate/__init__.py", line 257, in state_attributes
    data[ATTR_SWING_MODE] = self.swing_mode
  File "/config/custom_components/goldair_climate/heater/climate.py", line 215, in swing_mode
    return GoldairTuyaDevice.get_key_for_value(POWER_LEVEL_TO_DPS_LEVEL, dps_mode)
  File "/config/custom_components/goldair_climate/device.py", line 246, in get_key_for_value
    return keys[values.index(value)] or fallback
ValueError: None is not in list
kiwijunglist commented 4 years ago

some of that might be related to the heater being off for a while.

I'm gonna leave the heater on and restart home assistant and then repost any errors i see.

The component seems to be worker better in the latest version, however sometimes updates from the heater aren't coming through to the GUI or vice versa.

kiwijunglist commented 4 years ago

Logs below are after restarting home assistant while heater is on. Didn't try to change any settings on the heater or in the GUI during the period of logging below.

2020-05-10 15:49:52 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for smartir which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-05-10 15:49:52 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for zigbee2mqtt_networkmap which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-05-10 15:49:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for goldair_climate which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-05-10 15:49:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for hacs which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant.
2020-05-10 15:49:54 DEBUG (MainThread) [custom_components.goldair_climate] Setting up entry for device: 25700720cc50e3148f84
2020-05-10 15:49:54 DEBUG (MainThread) [custom_components.goldair_climate] Creating device: 25700720cc50e3148f84
2020-05-10 15:49:54 INFO (MainThread) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 15:49:54 WARNING (MainThread) [homeassistant.components.mqtt] Data in your configuration entry is going to override your configuration.yaml: {'broker': '192.168.1.99', 'discovery': True, 'password': 'tig76ionha', 'port': 1883, 'username': 'mike'}
2020-05-10 15:49:55 DEBUG (MainThread) [custom_components.goldair_climate.lock] Domain data: {'25700720cc50e3148f84': {'device': <custom_components.goldair_climate.device.GoldairTuyaDevice object at 0x7f2f03384990>}}
2020-05-10 15:49:55 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:49:55 INFO (SyncWorker_33) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082595.0440428}}
2020-05-10 15:49:55 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082595.0440428}
2020-05-10 15:49:55 DEBUG (MainThread) [custom_components.goldair_climate.device] Inferring device type from cached state: {'1': True, '2': 13, '3': 19, '4': 'C', '6': False, '12': 0, '101': '4', '102': 0, '103': False, '104': True, '105': 'user', '106': 16, 'updated_at': 1589082595.0440428}
2020-05-10 15:49:55 DEBUG (MainThread) [custom_components.goldair_climate.device] Inferring device type from cached state: {'1': True, '2': 13, '3': 19, '4': 'C', '6': False, '12': 0, '101': '4', '102': 0, '103': False, '104': True, '105': 'user', '106': 16, 'updated_at': 1589082595.0440428}
2020-05-10 15:49:55 DEBUG (MainThread) [custom_components.goldair_climate.device] Inferring device type from cached state: {'1': True, '2': 13, '3': 19, '4': 'C', '6': False, '12': 0, '101': '4', '102': 0, '103': False, '104': True, '105': 'user', '106': 16, 'updated_at': 1589082595.0440428}

2020-05-10 15:50:05 ERROR (SyncWorker_34) [homeassistant.util.json] Could not parse JSON content: /config/custom_components/goldair_climate/translations/en.json
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2020-05-10 15:50:05 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection.139839749512784] Error handling message: Unknown error
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 20, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/frontend/__init__.py", line 550, in websocket_get_translations
    msg.get("config_flow"),
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 325, in async_get_translations
    results = await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 221, in async_get_component_strings
    loaded_translations = await load_translations_job
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 90, in load_translations_files
    loaded_json = load_json(translation_file)
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 38, in load_json
    raise HomeAssistantError(error)
homeassistant.exceptions.HomeAssistantError: Expecting value: line 1 column 1 (char 0)
2020-05-10 15:50:05 ERROR (SyncWorker_33) [homeassistant.util.json] Could not parse JSON content: /config/custom_components/goldair_climate/translations/en.json
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2020-05-10 15:50:05 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection.139839715029264] Error handling message: Unknown error
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 20, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/frontend/__init__.py", line 550, in websocket_get_translations
    msg.get("config_flow"),
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 325, in async_get_translations
    results = await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 221, in async_get_component_strings
    loaded_translations = await load_translations_job
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 90, in load_translations_files
    loaded_json = load_json(translation_file)
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 38, in load_json
    raise HomeAssistantError(error)
homeassistant.exceptions.HomeAssistantError: Expecting value: line 1 column 1 (char 0)
2020-05-10 15:50:05 ERROR (SyncWorker_0) [homeassistant.util.json] Could not parse JSON content: /config/custom_components/goldair_climate/translations/en.json
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2020-05-10 15:50:05 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection.139839749512784] Error handling message: Unknown error
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 20, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/frontend/__init__.py", line 550, in websocket_get_translations
    msg.get("config_flow"),
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 341, in async_get_translations
    loaded_comp_resources = await async_get_translations(hass, language, category)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 325, in async_get_translations
    results = await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 221, in async_get_component_strings
    loaded_translations = await load_translations_job
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 90, in load_translations_files
    loaded_json = load_json(translation_file)
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 38, in load_json
    raise HomeAssistantError(error)
homeassistant.exceptions.HomeAssistantError: Expecting value: line 1 column 1 (char 0)

2020-05-10 15:50:26 DEBUG (SyncWorker_2) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:50:26 DEBUG (SyncWorker_2) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 15:50:26 INFO (SyncWorker_2) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 15:50:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds
2020-05-10 15:50:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-10 15:50:36 DEBUG (SyncWorker_2) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-10 15:50:36 INFO (SyncWorker_2) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 15:50:36 INFO (SyncWorker_2) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082636.278207}}
2020-05-10 15:50:36 DEBUG (SyncWorker_2) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082636.278207}
2020-05-10 15:50:57 DEBUG (SyncWorker_39) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:50:57 INFO (SyncWorker_39) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082657.0501215}}
2020-05-10 15:50:57 DEBUG (SyncWorker_39) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082657.0501215}
2020-05-10 15:51:28 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:51:28 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 15:51:28 INFO (SyncWorker_34) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 15:51:38 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds
2020-05-10 15:51:38 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-10 15:51:38 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-10 15:51:38 INFO (SyncWorker_34) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 15:51:38 INFO (SyncWorker_34) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082698.1981874}}
2020-05-10 15:51:38 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082698.1981874}
2020-05-10 15:51:59 DEBUG (SyncWorker_21) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:51:59 INFO (SyncWorker_21) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082719.182953}}
2020-05-10 15:51:59 DEBUG (SyncWorker_21) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082719.182953}
2020-05-10 15:52:30 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:52:30 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 15:52:30 INFO (SyncWorker_6) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 15:52:40 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds
2020-05-10 15:52:40 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-10 15:52:40 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-10 15:52:40 INFO (SyncWorker_6) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 15:52:40 INFO (SyncWorker_6) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082760.1012082}}
2020-05-10 15:52:40 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082760.1012082}
2020-05-10 15:53:31 DEBUG (SyncWorker_35) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:53:31 INFO (SyncWorker_35) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082811.0692086}}
2020-05-10 15:53:31 DEBUG (SyncWorker_35) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082811.0692086}
2020-05-10 15:53:51 WARNING (MainThread) [homeassistant.components.binary_sensor] Updating ping binary_sensor took longer than the scheduled update interval 0:00:02
2020-05-10 15:53:59 WARNING (MainThread) [homeassistant.components.binary_sensor] Updating ping binary_sensor took longer than the scheduled update interval 0:00:02
2020-05-10 15:53:59 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 15:53:59 INFO (SyncWorker_6) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082839.0979285}}
2020-05-10 15:53:59 DEBUG (SyncWorker_6) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 19, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589082839.0979285}
kiwijunglist commented 4 years ago
wifi
kiwijunglist commented 4 years ago

I want to add that this seems way better than before it's a lot more reliable, but still some commands don't make it to the heater. Maybe I have a bad esp8266 chip? Are you in Wellington?

kiwijunglist commented 4 years ago

This log below the heater is currently at 13 degrees. @ 1607 - I try to change the temperature to 15 degrees using homeassistant GUI. The heater stays at 13 degrees

2020-05-10 16:06:56 DEBUG (SyncWorker_32) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 16:06:56 INFO (SyncWorker_32) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 18, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589083616.125475}}
2020-05-10 16:06:56 DEBUG (SyncWorker_32) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 18, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589083616.125475}
2020-05-10 16:07:27 DEBUG (SyncWorker_25) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 16:07:27 DEBUG (SyncWorker_25) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:27 INFO (SyncWorker_25) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 16:07:31 DEBUG (SyncWorker_12) [custom_components.goldair_climate.device] new pending updates: {"2": {"value": 15, "updated_at": 1589083651.2257524}}
2020-05-10 16:07:32 INFO (Thread-7) [custom_components.goldair_climate.device] sending dps update: {"2": 15}
2020-05-10 16:07:32 DEBUG (Thread-7) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:32 INFO (Thread-7) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 16:07:32 DEBUG (Thread-7) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:32 INFO (Thread-7) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 16:07:32 DEBUG (Thread-7) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:32 INFO (Thread-7) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 16:07:32 DEBUG (Thread-7) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:32 ERROR (Thread-7) [custom_components.goldair_climate.device] Failed to update device state.
2020-05-10 16:07:37 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds
2020-05-10 16:07:37 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-10 16:07:37 DEBUG (SyncWorker_25) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-10 16:07:37 INFO (SyncWorker_25) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-10 16:07:41 WARNING (MainThread) [homeassistant.helpers.entity] Update of climate.alex is taking over 10 seconds
2020-05-10 16:07:47 DEBUG (SyncWorker_25) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-10 16:07:47 INFO (SyncWorker_25) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-10 16:07:47 DEBUG (SyncWorker_25) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-10 16:07:47 ERROR (SyncWorker_25) [custom_components.goldair_climate.device] Failed to refresh device state for Alex.
2020-05-10 16:07:47 ERROR (MainThread) [homeassistant.core] Error executing service: <ServiceCall climate.set_temperature (c:7623dac1042d4e0abf07df0319fe6bdc): temperature=15.0, entity_id=['climate.alex']>
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/core.py", line 1263, in catch_exceptions
    await coro_or_task
  File "/usr/src/homeassistant/homeassistant/core.py", line 1282, in _execute_service
    await handler.func(service_call)
  File "/usr/src/homeassistant/homeassistant/helpers/entity_component.py", line 213, in handle_service
    self._platforms.values(), func, call, required_features
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 470, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 284, in async_update_ha_state
    self._async_write_ha_state()
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 323, in _async_write_ha_state
    attr.update(self.state_attributes or {})
  File "/usr/src/homeassistant/homeassistant/components/climate/__init__.py", line 257, in state_attributes
    data[ATTR_SWING_MODE] = self.swing_mode
  File "/config/custom_components/goldair_climate/heater/climate.py", line 215, in swing_mode
    return GoldairTuyaDevice.get_key_for_value(POWER_LEVEL_TO_DPS_LEVEL, dps_mode)
  File "/config/custom_components/goldair_climate/device.py", line 246, in get_key_for_value
    return keys[values.index(value)] or fallback
ValueError: None is not in list
2020-05-10 16:07:58 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-10 16:07:58 INFO (SyncWorker_30) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 18, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589083678.1457808}}
2020-05-10 16:07:58 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 18, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589083678.1457808}
2020-05-10 16:07:59 ERROR (SyncWorker_5) [homeassistant.util.json] Could not parse JSON content: /config/custom_components/goldair_climate/translations/en.json
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2020-05-10 16:07:59 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection.139839749745552] Error handling message: Unknown error
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 32, in load_json
    return json.loads(fdesc.read())  # type: ignore
  File "/usr/local/lib/python3.7/json/__init__.py", line 348, in loads
    return _default_decoder.decode(s)
  File "/usr/local/lib/python3.7/json/decoder.py", line 337, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/local/lib/python3.7/json/decoder.py", line 355, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/decorators.py", line 20, in _handle_async_response
    await func(hass, connection, msg)
  File "/usr/src/homeassistant/homeassistant/components/frontend/__init__.py", line 550, in websocket_get_translations
    msg.get("config_flow"),
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 325, in async_get_translations
    results = await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 221, in async_get_component_strings
    loaded_translations = await load_translations_job
  File "/usr/local/lib/python3.7/concurrent/futures/thread.py", line 57, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/translation.py", line 90, in load_translations_files
    loaded_json = load_json(translation_file)
  File "/usr/src/homeassistant/homeassistant/util/json.py", line 38, in load_json
    raise HomeAssistantError(error)
homeassistant.exceptions.HomeAssistantError: Expecting value: line 1 column 1 (char 0)
kiwijunglist commented 4 years ago

I was pinging the heater constantly during the above log and there were no droped pings and pings were mostly around 3-5ms occasionally spiking to 20ms and once it briefly went to 100ms.

In the log I changed the heater settings multiple times in HA and eventually got a failure.

2020-05-10 16:22:54 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:22:54 INFO (SyncWorker_13) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084574.044095}}

2020-05-10 16:22:54 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084574.044095}

2020-05-10 16:23:25 DEBUG (SyncWorker_8) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:23:25 DEBUG (SyncWorker_8) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:23:25 INFO (SyncWorker_8) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

2020-05-10 16:23:35 WARNING (MainThread) [homeassistant.helpers.entity] Update of climate.alex is taking over 10 seconds

2020-05-10 16:23:35 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds

2020-05-10 16:23:35 DEBUG (SyncWorker_8) [custom_components.goldair_climate.device] Retrying after exception timed out

2020-05-10 16:23:35 INFO (SyncWorker_8) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.

2020-05-10 16:23:35 INFO (SyncWorker_8) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084615.062829}}

2020-05-10 16:23:35 DEBUG (SyncWorker_8) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084615.062829}

2020-05-10 16:23:56 DEBUG (SyncWorker_31) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:23:56 INFO (SyncWorker_31) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084636.073027}}

2020-05-10 16:23:56 DEBUG (SyncWorker_31) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084636.073027}

2020-05-10 16:24:26 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:24:26 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:24:26 INFO (SyncWorker_33) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

2020-05-10 16:24:36 WARNING (MainThread) [homeassistant.helpers.entity] Update of climate.alex is taking over 10 seconds

2020-05-10 16:24:36 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] Retrying after exception timed out

2020-05-10 16:24:36 INFO (SyncWorker_33) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.

2020-05-10 16:24:36 INFO (SyncWorker_33) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084676.0681915}}

2020-05-10 16:24:36 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084676.0681915}

2020-05-10 16:24:58 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:24:58 INFO (SyncWorker_13) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084698.0638156}}

2020-05-10 16:24:58 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084698.0638156}

2020-05-10 16:25:27 DEBUG (SyncWorker_14) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:25:27 INFO (SyncWorker_14) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084727.0353167}}

2020-05-10 16:25:27 DEBUG (SyncWorker_14) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 13, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084727.0353167}

2020-05-10 16:25:34 DEBUG (SyncWorker_4) [custom_components.goldair_climate.device] new pending updates: {"2": {"value": 15, "updated_at": 1589084734.8458712}}

2020-05-10 16:25:35 INFO (Thread-8) [custom_components.goldair_climate.device] sending dps update: {"2": 15}

2020-05-10 16:26:00 DEBUG (SyncWorker_19) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:26:00 INFO (SyncWorker_19) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084760.045136}}

2020-05-10 16:26:00 DEBUG (SyncWorker_19) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "4", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084760.045136}

2020-05-10 16:26:12 DEBUG (SyncWorker_8) [custom_components.goldair_climate.device] new pending updates: {"101": {"value": "3", "updated_at": 1589084772.4309964}}

2020-05-10 16:26:13 INFO (Thread-9) [custom_components.goldair_climate.device] sending dps update: {"101": "3"}

2020-05-10 16:26:28 DEBUG (SyncWorker_24) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:26:28 INFO (SyncWorker_24) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084788.0437381}}

2020-05-10 16:26:28 DEBUG (SyncWorker_24) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084788.0437381}

2020-05-10 16:27:02 DEBUG (SyncWorker_37) [custom_components.goldair_climate.device] Refreshing device state for Alex.

2020-05-10 16:27:02 DEBUG (SyncWorker_37) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:27:02 INFO (SyncWorker_37) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

2020-05-10 16:27:02 DEBUG (SyncWorker_11) [custom_components.goldair_climate.device] new pending updates: {"2": {"value": 10, "updated_at": 1589084822.1880987}}

2020-05-10 16:27:03 INFO (Thread-10) [custom_components.goldair_climate.device] sending dps update: {"2": 10}

2020-05-10 16:27:03 DEBUG (Thread-10) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:27:03 INFO (Thread-10) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.

2020-05-10 16:27:03 DEBUG (Thread-10) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:27:03 INFO (Thread-10) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

2020-05-10 16:27:03 DEBUG (Thread-10) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:27:03 INFO (Thread-10) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.

2020-05-10 16:27:03 DEBUG (Thread-10) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer

2020-05-10 16:27:03 ERROR (Thread-10) [custom_components.goldair_climate.device] Failed to update device state.

2020-05-10 16:27:12 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds

2020-05-10 16:27:12 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds

2020-05-10 16:27:12 DEBUG (SyncWorker_37) [custom_components.goldair_climate.device] Retrying after exception timed out

2020-05-10 16:27:12 INFO (SyncWorker_37) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

2020-05-10 16:27:12 WARNING (MainThread) [homeassistant.helpers.entity] Update of climate.alex is taking over 10 seconds

2020-05-10 16:27:22 DEBUG (SyncWorker_37) [custom_components.goldair_climate.device] Retrying after exception timed out

2020-05-10 16:27:22 INFO (SyncWorker_37) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.

2020-05-10 16:27:22 INFO (SyncWorker_37) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084842.1232843}}

2020-05-10 16:27:22 DEBUG (SyncWorker_37) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 15, "3": 17, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589084842.1232843}

kiwijunglist commented 4 years ago
alex

^ 3pm is when i updated to the latest version of the custom_component.

nicole-ashley commented 4 years ago

Thanks for all the info @kiwijunglist, I'll take a look at this soon.

kiwijunglist commented 4 years ago
Log Details (ERROR)
Logger: pytuya
Source: custom_components/goldair_climate/device.py:145
First occurred: May 11, 2020, 9:50:47 PM (44 occurrences)
Last logged: 5:54:54 PM

Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00\x05\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cxQ\xdcTO$^#`\xdc\xad\x84P\xa1V[\xd7\xcf\xc8\xf5\xf6\xda\xd7\xc3\x9d3\x99\xc1\x0beVH'
Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00\t\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cxQ\xdcTO$^#`\xdc\xad\x84P\xa1V[\x1fQ\xbf\xa4R\x91\x0b\xd4\x83/\xe9\xba#e\xb0>'
Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00\x0b\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cKV\xacG=b\xfbQ*:qN\x99\xabw?\xec\xdd2hnc\xda7SW\x04"n\t\r;'
Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00\x0f\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cKV\xacG=b\xfbQ*:qN\x99\xabw?\x96\xcb!\x8e\x7f\xdf`\xd5-\x07\xc5\xa9O\xeb\xbf\xcc'
Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00\x13\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cKV\xacG=b\xfbQ*:qN\x99\xabw?\x85\xf4\xe7H\xbe\xb9WC\xa4p\x07|\xdf\x10\xf1\xb6'
2020-05-12 00:30:49 INFO (SyncWorker_33) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-12 00:30:49 INFO (SyncWorker_33) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200249.250938}}
2020-05-12 00:30:49 DEBUG (SyncWorker_33) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200249.250938}
2020-05-12 00:30:58 WARNING (MainThread) [homeassistant.components.binary_sensor] Updating ping binary_sensor took longer than the scheduled update interval 0:00:02
2020-05-12 00:31:10 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-12 00:31:10 INFO (SyncWorker_34) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200270.134919}}
2020-05-12 00:31:10 DEBUG (SyncWorker_34) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200270.134919}
2020-05-12 00:31:41 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-12 00:31:41 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-12 00:31:41 INFO (SyncWorker_13) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-12 00:31:46 ERROR (SyncWorker_13) [pytuya] Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00C\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cxQ\xdcTO$^#`\xdc\xad\x84P\xa1V[\xc8\xbcW`\xa9\xbd\x92\xc9\t\xe0\x91\xcb\x8c\x98\x1d\xf6'
2020-05-12 00:31:46 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] Retrying after exception byte indices must be integers or slices, not str
2020-05-12 00:31:46 INFO (SyncWorker_13) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-12 00:31:46 INFO (SyncWorker_13) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 19, "3": 20, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200306.4721992}}
2020-05-12 00:31:46 DEBUG (SyncWorker_13) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 19, "3": 20, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200306.4721992}
2020-05-12 00:32:12 DEBUG (SyncWorker_4) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-12 00:32:12 INFO (SyncWorker_4) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 19, "3": 20, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200332.1425424}}
2020-05-12 00:32:12 DEBUG (SyncWorker_4) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 19, "3": 20, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200332.1425424}
2020-05-12 00:32:43 DEBUG (SyncWorker_9) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-12 00:32:43 DEBUG (SyncWorker_9) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-12 00:32:43 INFO (SyncWorker_9) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.

Error part:

2020-05-12 00:32:46 ERROR (SyncWorker_9) [pytuya] Unexpected status() payload=b'3.3\x00\x00\x00\x00\x00\x00\x00E\x00\x00\x00\x01\xb1\x1aB\xf4\x80}T$y#\xfc#\xb2\xbcU\xc2\xcb\xa9\x05T8\xeb\xd3\xa1X\x824\xec\x92\xbcu\x0cxQ\xdcTO$^#`\xdc\xad\x84P\xa1V[\xc0H\xf5\xd0\xba[AT\x95\xdd[\x87\x13a\xce\xc4'
2020-05-12 00:32:46 DEBUG (SyncWorker_9) [custom_components.goldair_climate.device] Retrying after exception byte indices must be integers or slices, not str
2020-05-12 00:32:46 INFO (SyncWorker_9) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-12 00:32:46 DEBUG (SyncWorker_9) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-12 00:32:46 INFO (SyncWorker_9) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-12 00:32:53 WARNING (MainThread) [homeassistant.helpers.entity] Update of lock.alex is taking over 10 seconds
2020-05-12 00:32:56 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-12 00:32:57 DEBUG (SyncWorker_9) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-12 00:32:57 ERROR (SyncWorker_9) [custom_components.goldair_climate.device] Failed to refresh device state for Alex.
2020-05-12 00:33:14 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] Refreshing device state for Alex.
2020-05-12 00:33:24 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-12 00:33:24 INFO (SyncWorker_30) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-12 00:33:24 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] Retrying after exception [Errno 104] Connection reset by peer
2020-05-12 00:33:24 INFO (SyncWorker_30) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.1.
2020-05-12 00:33:27 WARNING (MainThread) [homeassistant.helpers.entity] Update of light.alex is taking over 10 seconds
2020-05-12 00:33:34 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] Retrying after exception timed out
2020-05-12 00:33:34 INFO (SyncWorker_30) [custom_components.goldair_climate.device] Setting protocol version for Alex to 3.3.
2020-05-12 00:33:34 INFO (SyncWorker_30) [custom_components.goldair_climate.device] refreshed device state: {"devId": "25700720cc50e3148f84", "dps": {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200414.3109212}}
2020-05-12 00:33:34 DEBUG (SyncWorker_30) [custom_components.goldair_climate.device] new cache state (including pending properties): {"1": true, "2": 19, "3": 19, "4": "C", "6": false, "12": 0, "101": "3", "102": 0, "103": false, "104": true, "105": "user", "106": 16, "updated_at": 1589200414.3109212}
kiwijunglist commented 4 years ago

Let me know if you want me to do any specific tests / logs. I note that it also will alternate between protocol 3.1 and 3.3.

puck commented 4 years ago

Hey, I'd seeing the same behaviour with my heaters.

kiwijunglist commented 4 years ago

I notice that if I turn off and then on the heater at the wall then the heater becomes unavailable in home assistant and I have to restart home assistant to get it to reconnect.