Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Sharp Air Purifier KI-PX70 Stuck at Integration's Initializing Stage #169

Closed
hldh214 opened this issue Feb 22, 2024 · 19 comments
Closed

Sharp Air Purifier KI-PX70 Stuck at Integration's Initializing Stage #169

hldh214 opened this issue Feb 22, 2024 · 19 comments

Comments

@hldh214
Copy link

hldh214 commented Feb 22, 2024

Maybe related to #167

My model is Sharp KI-PX70, and I thought it was similar to KI-HS70 which the README says is supported.

This issue is a bit amusing for me because everything was working fine when I first started using Version 3.8.0-beta9.

Until one day, when I happened to check the homeassistant logs, I noticed an error message indicating a syntax error in this plugin. You can refer to:
623e597#r138870175

After manually fixing the code, two additional sensors did appear as expected. Previously, there was only a switch for control and a select for mode selection. However, these two additional sensors have been stuck in a state without any data.

After a while, approximately 15-20 minutes later, the entire integration stopped receiving data. I tried reloading the integration, but the issue persisted.

I enabled debug mode and checked the logs, which I have provided below. Since I'm not as familiar with this project and the ECHONET protocol as everyone else, I wanted to raise this issue and seek your opinions. Thank you.

2024-02-22 17:55:58.168 ERROR (MainThread) [homeassistant.config_entries] Error setting up entry KIPX70 for echonetlite
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/config_entries.py", line 444, in async_setup
    result = await component.async_setup_entry(hass, self)
             ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/echonetlite/__init__.py", line 314, in async_setup_entry
    instances = await enumerate_instances(hass, host)
                ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/config/custom_components/echonetlite/config_flow.py", line 98, in enumerate_instances
    await server.discover(host)
  File "/usr/local/lib/python3.12/site-packages/pychonet/echonetapiclient.py", line 223, in discover
    return await self.echonetMessage(host, 0x0E, 0xF0, 0x01, GET, opc)
           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/pychonet/echonetapiclient.py", line 345, in echonetMessage
    await update_func(False)
  File "/config/custom_components/echonetlite/__init__.py", line 668, in async_update_callback
    await self.async_update_data(kwargs={"no_request": True})
  File "/config/custom_components/echonetlite/__init__.py", line 656, in async_update_data
    batch_data = await self._instance.update(flags, no_request)
                 ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/usr/local/lib/python3.12/site-packages/pychonet/EchonetInstance.py", line 137, in update
    self._api._state[self._host]["instances"][self._eojgc][self._eojcc][
    ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^
KeyError: 1
@hldh214
Copy link
Author

hldh214 commented Feb 22, 2024

After the entire integration stopped receiving data, I had to temporarily disable the integration. Surprisingly, after waiting for approximately half an hour to an hour, I re-enabled it and it started functioning normally. However, after around 15-20 minutes, the same issue reoccurred. Interestingly, if I modify the code and disable the sensor.py to revert it back to Version 3.8.0-beta9, the integration works flawlessly without any issues.

Here is my speculation based on my years of experience as a web designer:

This issue appears to be some form of flow control measure. It seems that we may be making incorrect requests for certain data or urls that we shouldn't be requesting. Alternatively, it could be due to a lack of ratelimit restrictions, resulting in a temporary ban that automatically lifts after a while.

I'm not sure if this speculation will be helpful to everyone, but it's worth considering.

@nao-pon
Copy link
Collaborator

nao-pon commented Feb 22, 2024

@hldh214 I looked at your logs and found a bug in pychonet, so I fixed it. I also reviewed the sensor configuration of HomeAirCleaner. However, I don't have an environment to test it, so could you help us verify it? This fix is currently available to try on my edge branch.

See below for how to deploy my edge branch.

#161 (comment)

@hldh214
Copy link
Author

hldh214 commented Feb 23, 2024

@nao-pon I've been tested for a while, and everything seems to be OK for now.

Additionally, a new sensor has appeared and is displaying the correct value, while the two old sensors with no data have disappeared.

Therefore, I believe this issue has been fixed. Thank you again.

image

@hldh214 hldh214 closed this as completed Feb 23, 2024
@nao-pon
Copy link
Collaborator

nao-pon commented Feb 23, 2024

@hldh214 Thank you for your cooperation in the verification! 👍

@hldh214
Copy link
Author

hldh214 commented Feb 26, 2024

Seems the original issue remains unresolved, and I am reopening it.

I have updated to the latest commit, and it appears that the sensor, which previously had no data, now has some data. I am unsure if it is the correct data. However, after approximately 15 minutes, the original issue reoccurred: the integration stopped receiving any data.

image

Here is the debug log, hope it helps.

2024-02-26 14:29:50.698 DEBUG (MainThread) [custom_components.echonetlite.config_flow] ECHONET Node Discovery Failed!
2024-02-26 14:29:50.698 DEBUG (MainThread) [custom_components.echonetlite] Called unload_config_entry() try to remove 192.168.10.27 from server._state.
2024-02-26 14:29:55.948 DEBUG (MainThread) [custom_components.echonetlite] ECHONETlite platform is already started.
2024-02-26 14:29:55.949 DEBUG (MainThread) [custom_components.echonetlite.config_flow] IP address is 192.168.10.27
2024-02-26 14:29:55.949 DEBUG (MainThread) [custom_components.echonetlite.config_flow] API listener has already been setup previously..
2024-02-26 14:29:55.949 DEBUG (MainThread) [custom_components.echonetlite.config_flow] Beginning ECHONET node discovery
2024-02-26 14:30:36.289 DEBUG (MainThread) [custom_components.echonetlite.config_flow] ECHONET Node Discovery Failed!
2024-02-26 14:30:36.290 DEBUG (MainThread) [custom_components.echonetlite] Called unload_config_entry() try to remove 192.168.10.27 from server._state.
2024-02-26 14:30:46.510 DEBUG (MainThread) [custom_components.echonetlite] ECHONETlite platform is already started.
2024-02-26 14:30:46.510 DEBUG (MainThread) [custom_components.echonetlite.config_flow] IP address is 192.168.10.27
2024-02-26 14:30:46.510 DEBUG (MainThread) [custom_components.echonetlite.config_flow] API listener has already been setup previously..
2024-02-26 14:30:46.511 DEBUG (MainThread) [custom_components.echonetlite.config_flow] Beginning ECHONET node discovery

@hldh214 hldh214 reopened this Feb 26, 2024
@hldh214
Copy link
Author

hldh214 commented Feb 26, 2024

Starting using commit ea2c875 and seems OK.

@nao-pon
Copy link
Collaborator

nao-pon commented Feb 26, 2024

Starting using commit ea2c875 and seems OK.

Does that mean reverted to a previous version? Does the latest version of the current master branch 0e6c320 cause problems?

@hldh214
Copy link
Author

hldh214 commented Feb 27, 2024

Does that mean reverted to a previous version?

Yes, please accept my apologies for the confusion. Previously, I was using the latest commit 0e6c320 .

I took some additional time to bisect those commits and discovered that the problem began to appear with commit 8047116 . This commit introduced the power-related sensors.

Is there anything I can provide for further debugging?

@nao-pon
Copy link
Collaborator

nao-pon commented Feb 27, 2024

Thank you for your detailed explanation. Well, there is a possibility that the value cannot be obtained from power-related inquiries and the system is determined to be offline.

If you enable debug logging in the latest version, restart HA, wait until the symptoms appear, then disable debug logging, you can download the logs. Please upload that log here.

@hldh214
Copy link
Author

hldh214 commented Feb 28, 2024

The log between 2024-02-28 09:16:06 and 2024-02-28 09:26:06 has been omitted as it appears to consist of repetitive pooling information.

2024-02-28 09:15:25.914 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration tapo 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
2024-02-28 09:15:25.915 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration dreame_vacuum 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
2024-02-28 09:15:25.916 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration nature_remo_nano 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
2024-02-28 09:15:25.917 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration 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
2024-02-28 09:15:25.917 WARNING (SyncWorker_2) [homeassistant.loader] We found a custom integration echonetlite 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
2024-02-28 09:15:31.630 DEBUG (MainThread) [custom_components.echonetlite] Starting up ECHONETlite platform..
2024-02-28 09:15:31.630 DEBUG (MainThread) [custom_components.echonetlite] pychonet version is 2.6.6
2024-02-28 09:15:31.633 DEBUG (MainThread) [custom_components.echonetlite.config_flow] IP address is 192.168.10.27
2024-02-28 09:15:31.633 DEBUG (MainThread) [custom_components.echonetlite.config_flow] API listener has already been setup previously..
2024-02-28 09:15:31.633 DEBUG (MainThread) [custom_components.echonetlite.config_flow] Beginning ECHONET node discovery
2024-02-28 09:15:32.951 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x01\x0e\xf0\x01\x05\xff\x01r\x04\x8a\x03\x00\x00\x05\x8c\x0cHW-A04\x00\x00\x00\x00\x00\x00\x83\x11\xfe\x00\x00\x05\x00\x00\x00\x00\x00|f\xef\xff\xfe\xc1L\xa6\xd6\x04\x01\x015\x01'
2024-02-28 09:15:32.951 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 1, 'SEOJGC': 14, 'SEOJCC': 240, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 138, 'PDC': 3, 'EDT': b'\x00\x00\x05'}, {'EPC': 140, 'PDC': 12, 'EDT': b'HW-A04\x00\x00\x00\x00\x00\x00'}, {'EPC': 131, 'PDC': 17, 'EDT': b'\xfe\x00\x00\x05\x00\x00\x00\x00\x00|f\xef\xff\xfe\xc1L\xa6'}, {'EPC': 214, 'PDC': 4, 'EDT': b'\x01\x015\x01'}]}
2024-02-28 09:15:32.951 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:15:33.729 DEBUG (MainThread) [custom_components.echonetlite.config_flow] ECHONET Node Discovery Successful!
2024-02-28 09:15:33.729 DEBUG (MainThread) [custom_components.echonetlite.config_flow] instance is 1
2024-02-28 09:15:34.044 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x02\x015\x01\x05\xff\x01r\x03\x9d\t\x08\x80\x81\x86\x88\x89\xa0\xc0\xf3\x9f\x11\x17\x95\x81\x81\x80\x81\x01\x01\x00\x01\x01\x01\x01\x81\x82\x02\x02\x9e\x06\x05\x80\x81\xa0\xf3\xf4'
2024-02-28 09:15:34.044 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 2, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 157, 'PDC': 9, 'EDT': b'\x08\x80\x81\x86\x88\x89\xa0\xc0\xf3'}, {'EPC': 159, 'PDC': 17, 'EDT': b'\x17\x95\x81\x81\x80\x81\x01\x01\x00\x01\x01\x01\x01\x81\x82\x02\x02'}, {'EPC': 158, 'PDC': 6, 'EDT': b'\x05\x80\x81\xa0\xf3\xf4'}]}
2024-02-28 09:15:34.044 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:15:34.199 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 3/3 from IP 192.168.10.27.
2024-02-28 09:15:34.199 DEBUG (MainThread) [custom_components.echonetlite.config_flow] 192.168.10.27 - ECHONET Instance 1-53-1 map attributes discovered!
2024-02-28 09:15:34.199 DEBUG (MainThread) [custom_components.echonetlite] Starting ECHONETLite HomeAirCleaner instance for 1-53-1 at 192.168.10.27
2024-02-28 09:15:34.199 DEBUG (MainThread) [custom_components.echonetlite] Echonet device 192.168.10.27's batch request flags list: [[128, 132, 133, 160, 192]]
2024-02-28 09:15:34.199 DEBUG (MainThread) [custom_components.echonetlite] UID for ECHONETLite instance at 192.168.10.27 is 00000500000000007c66effffec14ca6.
2024-02-28 09:15:34.727 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x03\x015\x01\x05\xff\x01r\x05\x80\x011\x84\x02\x00\x01\x85\x04\x00\x00\xc2\xb5\xa0\x01A\xc0\x01B'
2024-02-28 09:15:34.727 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 3, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'1'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x01'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc2\xb5'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-28 09:15:34.727 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:15:34.727 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-28 09:15:34.738 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-28 09:15:34.738 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-28 09:15:34.738 DEBUG (MainThread) [custom_components.echonetlite] Plaform entry data - {'host': '192.168.10.27', 'instances': [{'host': '192.168.10.27', 'name': None, 'eojgc': 1, 'eojcc': 53, 'eojci': 1, 'ntfmap': [128, 129, 134, 136, 137, 160, 192, 243], 'getmap': [128, 160, 192, 240, 129, 241, 130, 242, 243, 132, 244, 133, 134, 136, 137, 138, 139, 140, 252, 157, 253, 158, 159], 'setmap': [128, 129, 160, 243, 244], 'uid': '00000500000000007c66effffec14ca6', 'uidi': '00000500000000007c66effffec14ca6-1-53-1', 'manufacturer': 'Sharp', 'host_product_code': 'HW-A04'}]}
2024-02-28 09:15:35.820 DEBUG (MainThread) [custom_components.echonetlite.sensor] Configuring ECHONETLite sensor {'instance': {'host': '192.168.10.27', 'name': None, 'eojgc': 1, 'eojcc': 53, 'eojci': 1, 'ntfmap': [128, 129, 134, 136, 137, 160, 192, 243], 'getmap': [128, 160, 192, 240, 129, 241, 130, 242, 243, 132, 244, 133, 134, 136, 137, 138, 139, 140, 252, 157, 253, 158, 159], 'setmap': [128, 129, 160, 243, 244], 'uid': '00000500000000007c66effffec14ca6', 'uidi': '00000500000000007c66effffec14ca6-1-53-1', 'manufacturer': 'Sharp', 'host_product_code': 'HW-A04'}, 'echonetlite': <custom_components.echonetlite.ECHONETConnector object at 0x7f4cdf1aa750>}
2024-02-28 09:15:35.820 DEBUG (MainThread) [custom_components.echonetlite.sensor] Update flags for this sensor are [128, 132, 133, 160, 192]
2024-02-28 09:15:35.820 DEBUG (MainThread) [custom_components.echonetlite.sensor] Air pollution detection status(192): _should_poll is False
2024-02-28 09:15:35.820 DEBUG (MainThread) [custom_components.echonetlite.sensor] Measured instantaneous power consumption(132): _should_poll is True
2024-02-28 09:15:35.820 DEBUG (MainThread) [custom_components.echonetlite.sensor] Measured cumulative power consumption(133): _should_poll is True
2024-02-28 09:15:35.828 DEBUG (MainThread) [custom_components.echonetlite.select] KIPX70(160): _should_poll is False
2024-02-28 09:15:35.830 DEBUG (MainThread) [custom_components.echonetlite.light] Number of light devices to be added: 0
2024-02-28 09:15:35.831 DEBUG (MainThread) [custom_components.echonetlite.fan] KIPX70: _should_poll is False
2024-02-28 09:15:35.934 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x04\x015\x01\x05\xff\x01r\x05\x80\x011\x84\x02\x00\x01\x85\x04\x00\x00\xc2\xb5\xa0\x01A\xc0\x01B'
2024-02-28 09:15:35.934 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 4, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'1'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x01'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc2\xb5'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-28 09:15:35.934 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:15:36.032 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-28 09:15:36.032 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-28 09:16:06.172 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x05\x015\x01\x05\xff\x01r\x05\x80\x011\x84\x02\x00\x01\x85\x04\x00\x00\xc2\xb5\xa0\x01A\xc0\x01B'
2024-02-28 09:16:06.172 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 5, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'1'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x01'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc2\xb5'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-28 09:16:06.172 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:16:06.236 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-28 09:16:06.236 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

=========================================================================================

2024-02-28 09:26:06.197 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x19\x015\x01\x05\xff\x01r\x05\x80\x011\x84\x02\x00\x01\x85\x04\x00\x00\xc2\xb5\xa0\x01A\xc0\x01B'
2024-02-28 09:26:06.197 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 25, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'1'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x01'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc2\xb5'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-28 09:26:06.197 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-28 09:26:06.265 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-28 09:26:06.266 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-28 09:26:46.065 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds
2024-02-28 09:27:06.065 WARNING (MainThread) [homeassistant.components.sensor] Updating echonetlite sensor took longer than the scheduled update interval 0:00:30
2024-02-28 09:27:06.178 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): off
 - Measured instantaneous power consumption 0x84(132): 1
 - Measured cumulative power consumption 0x85(133): 49845
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-28 09:27:46.067 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds
2024-02-28 09:28:06.067 WARNING (MainThread) [homeassistant.components.sensor] Updating echonetlite sensor took longer than the scheduled update interval 0:00:30
2024-02-28 09:28:46.069 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds

@nao-pon
Copy link
Collaborator

nao-pon commented Feb 28, 2024

Operation status 0x80(128): off

The fact that the device is OFF may be contributing to this issue. Can you try turning on the device?

If there is no problem when ON, I will adjust the inquiry data when OFF.

@hldh214
Copy link
Author

hldh214 commented Feb 29, 2024

Seems like a similar situation to a turned-off case. I thought that we may need to add a sensor selection in the config flow, allowing the user to choose which sensor to poll.

2024-02-29 09:45:22.491 DEBUG (MainThread) [custom_components.echonetlite] Starting up ECHONETlite platform..
2024-02-29 09:45:22.491 DEBUG (MainThread) [custom_components.echonetlite] pychonet version is 2.6.6
2024-02-29 09:45:22.492 DEBUG (MainThread) [custom_components.echonetlite.config_flow] IP address is 192.168.10.27
2024-02-29 09:45:22.492 DEBUG (MainThread) [custom_components.echonetlite.config_flow] API listener has already been setup previously..
2024-02-29 09:45:22.492 DEBUG (MainThread) [custom_components.echonetlite.config_flow] Beginning ECHONET node discovery
2024-02-29 09:45:33.683 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x01\x0e\xf0\x01\x05\xff\x01r\x04\x8a\x03\x00\x00\x05\x8c\x0cHW-A04\x00\x00\x00\x00\x00\x00\x83\x11\xfe\x00\x00\x05\x00\x00\x00\x00\x00|f\xef\xff\xfe\xc1L\xa6\xd6\x04\x01\x015\x01'
2024-02-29 09:45:33.683 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 1, 'SEOJGC': 14, 'SEOJCC': 240, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 138, 'PDC': 3, 'EDT': b'\x00\x00\x05'}, {'EPC': 140, 'PDC': 12, 'EDT': b'HW-A04\x00\x00\x00\x00\x00\x00'}, {'EPC': 131, 'PDC': 17, 'EDT': b'\xfe\x00\x00\x05\x00\x00\x00\x00\x00|f\xef\xff\xfe\xc1L\xa6'}, {'EPC': 214, 'PDC': 4, 'EDT': b'\x01\x015\x01'}]}
2024-02-29 09:45:33.683 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:45:34.410 DEBUG (MainThread) [custom_components.echonetlite.config_flow] ECHONET Node Discovery Successful!
2024-02-29 09:45:34.410 DEBUG (MainThread) [custom_components.echonetlite.config_flow] instance is 1
2024-02-29 09:45:34.736 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x02\x015\x01\x05\xff\x01r\x03\x9d\t\x08\x80\x81\x86\x88\x89\xa0\xc0\xf3\x9f\x11\x17\x95\x81\x81\x80\x81\x01\x01\x00\x01\x01\x01\x01\x81\x82\x02\x02\x9e\x06\x05\x80\x81\xa0\xf3\xf4'
2024-02-29 09:45:34.736 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 2, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 157, 'PDC': 9, 'EDT': b'\x08\x80\x81\x86\x88\x89\xa0\xc0\xf3'}, {'EPC': 159, 'PDC': 17, 'EDT': b'\x17\x95\x81\x81\x80\x81\x01\x01\x00\x01\x01\x01\x01\x81\x82\x02\x02'}, {'EPC': 158, 'PDC': 6, 'EDT': b'\x05\x80\x81\xa0\xf3\xf4'}]}
2024-02-29 09:45:34.736 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:45:34.768 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 3/3 from IP 192.168.10.27.
2024-02-29 09:45:34.769 DEBUG (MainThread) [custom_components.echonetlite.config_flow] 192.168.10.27 - ECHONET Instance 1-53-1 map attributes discovered!
2024-02-29 09:45:34.769 DEBUG (MainThread) [custom_components.echonetlite] Starting ECHONETLite HomeAirCleaner instance for 1-53-1 at 192.168.10.27
2024-02-29 09:45:34.769 DEBUG (MainThread) [custom_components.echonetlite] Echonet device 192.168.10.27's batch request flags list: [[128, 132, 133, 160, 192]]
2024-02-29 09:45:34.769 DEBUG (MainThread) [custom_components.echonetlite] UID for ECHONETLite instance at 192.168.10.27 is 00000500000000007c66effffec14ca6.
2024-02-29 09:45:34.896 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x03\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\r\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:45:34.897 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 3, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\r'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:45:34.897 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:45:34.897 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 13
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:45:35.207 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:45:35.207 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 13
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:45:35.207 DEBUG (MainThread) [custom_components.echonetlite] Plaform entry data - {'host': '192.168.10.27', 'instances': [{'host': '192.168.10.27', 'name': None, 'eojgc': 1, 'eojcc': 53, 'eojci': 1, 'ntfmap': [128, 129, 134, 136, 137, 160, 192, 243], 'getmap': [128, 160, 192, 240, 129, 241, 130, 242, 243, 132, 244, 133, 134, 136, 137, 138, 139, 140, 252, 157, 253, 158, 159], 'setmap': [128, 129, 160, 243, 244], 'uid': '00000500000000007c66effffec14ca6', 'uidi': '00000500000000007c66effffec14ca6-1-53-1', 'manufacturer': 'Sharp', 'host_product_code': 'HW-A04'}]}
2024-02-29 09:45:36.528 DEBUG (MainThread) [custom_components.echonetlite.sensor] Configuring ECHONETLite sensor {'instance': {'host': '192.168.10.27', 'name': None, 'eojgc': 1, 'eojcc': 53, 'eojci': 1, 'ntfmap': [128, 129, 134, 136, 137, 160, 192, 243], 'getmap': [128, 160, 192, 240, 129, 241, 130, 242, 243, 132, 244, 133, 134, 136, 137, 138, 139, 140, 252, 157, 253, 158, 159], 'setmap': [128, 129, 160, 243, 244], 'uid': '00000500000000007c66effffec14ca6', 'uidi': '00000500000000007c66effffec14ca6-1-53-1', 'manufacturer': 'Sharp', 'host_product_code': 'HW-A04'}, 'echonetlite': <custom_components.echonetlite.ECHONETConnector object at 0x7f7d1b5f80e0>}
2024-02-29 09:45:36.528 DEBUG (MainThread) [custom_components.echonetlite.sensor] Update flags for this sensor are [128, 132, 133, 160, 192]
2024-02-29 09:45:36.528 DEBUG (MainThread) [custom_components.echonetlite.sensor] Air pollution detection status(192): _should_poll is False
2024-02-29 09:45:36.528 DEBUG (MainThread) [custom_components.echonetlite.sensor] Measured instantaneous power consumption(132): _should_poll is True
2024-02-29 09:45:36.528 DEBUG (MainThread) [custom_components.echonetlite.sensor] Measured cumulative power consumption(133): _should_poll is True
2024-02-29 09:45:36.529 DEBUG (MainThread) [custom_components.echonetlite.select] KIPX70(160): _should_poll is False
2024-02-29 09:45:36.530 DEBUG (MainThread) [custom_components.echonetlite.light] Number of light devices to be added: 0
2024-02-29 09:45:36.531 DEBUG (MainThread) [custom_components.echonetlite.fan] KIPX70: _should_poll is False
2024-02-29 09:45:36.653 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x04\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\r\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:45:36.653 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 4, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\r'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:45:36.653 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:45:36.730 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:45:36.731 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 13
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:46:06.840 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x05\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:46:06.840 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 5, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:46:06.840 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:46:06.840 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:46:06.934 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:46:06.934 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:46:36.858 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x06\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:46:36.858 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 6, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:46:36.858 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:46:36.936 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:46:36.936 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:47:06.853 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x07\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:47:06.853 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 7, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:47:06.853 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:47:06.936 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:47:06.936 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:47:36.862 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x08\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:47:36.862 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 8, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:47:36.862 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:47:36.937 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:47:36.937 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:48:06.870 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\t\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc39\xa0\x01A\xc0\x01B'
2024-02-29 09:48:06.870 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 9, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc39'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:48:06.870 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:48:06.938 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:48:06.939 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49977
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:48:36.877 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\n\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:48:36.878 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 10, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:48:36.878 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:48:36.878 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:48:36.939 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:48:36.939 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:49:06.863 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x0b\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:49:06.863 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 11, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:49:06.863 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:49:06.940 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:49:06.940 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:49:36.834 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x0c\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:49:36.835 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 12, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:49:36.835 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:49:36.840 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:49:36.840 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:50:06.826 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\r\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:50:06.826 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 13, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:50:06.826 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:50:06.841 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:50:06.841 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:50:36.860 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x0e\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:50:36.860 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 14, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:50:36.860 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:50:36.943 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:50:36.943 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:51:06.879 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x0f\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3:\xa0\x01A\xc0\x01B'
2024-02-29 09:51:06.879 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 15, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3:'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:51:06.879 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:51:06.944 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:51:06.944 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49978
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:51:36.845 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x10\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3;\xa0\x01A\xc0\x01B'
2024-02-29 09:51:36.845 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 16, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3;'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:51:36.845 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:51:36.845 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:51:36.946 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:51:36.946 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:52:06.890 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x11\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3;\xa0\x01A\xc0\x01B'
2024-02-29 09:52:06.890 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 17, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3;'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:52:06.890 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:52:06.946 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:52:06.946 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:52:36.846 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x12\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3;\xa0\x01A\xc0\x01B'
2024-02-29 09:52:36.846 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 18, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3;'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:52:36.846 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:52:36.946 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:52:36.946 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:53:06.876 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x13\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3;\xa0\x01A\xc0\x01B'
2024-02-29 09:53:06.876 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 19, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3;'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:53:06.876 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:53:06.948 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:53:06.948 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:53:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x14\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3;\xa0\x01A\xc0\x01B'
2024-02-29 09:53:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 20, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3;'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:53:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:53:36.847 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:53:36.847 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49979
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:54:06.857 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x15\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3<\xa0\x01A\xc0\x01B'
2024-02-29 09:54:06.857 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 21, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3<'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:54:06.857 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:54:06.857 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:54:06.948 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:54:06.948 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:54:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x16\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3<\xa0\x01A\xc0\x01B'
2024-02-29 09:54:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 22, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3<'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:54:36.844 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:54:36.848 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:54:36.848 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:55:06.819 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x17\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3<\xa0\x01A\xc0\x01B'
2024-02-29 09:55:06.819 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 23, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3<'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:55:06.819 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:55:06.849 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:55:06.849 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:55:36.912 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Raw data is b'\x10\x81\x00\x18\x015\x01\x05\xff\x01r\x05\x80\x010\x84\x02\x00\x15\x85\x04\x00\x00\xc3<\xa0\x01A\xc0\x01B'
2024-02-29 09:55:36.912 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - Processed data is {'EHD1': 16, 'EHD2': 129, 'TID': 24, 'SEOJGC': 1, 'SEOJCC': 53, 'SEOJCI': 1, 'DEOJGC': 5, 'DEOJCC': 255, 'DEOJCI': 1, 'ESV': 114, 'OPC': [{'EPC': 128, 'PDC': 1, 'EDT': b'0'}, {'EPC': 132, 'PDC': 2, 'EDT': b'\x00\x15'}, {'EPC': 133, 'PDC': 4, 'EDT': b'\x00\x00\xc3<'}, {'EPC': 160, 'PDC': 1, 'EDT': b'A'}, {'EPC': 192, 'PDC': 1, 'EDT': b'B'}]}
2024-02-29 09:55:36.912 DEBUG (MainThread) [custom_components.echonetlite] ECHONETLite Message Received from 192.168.10.27 - tid_data is {}
2024-02-29 09:55:36.951 DEBUG (MainThread) [custom_components.echonetlite] OPC count in results is 5/5 from IP 192.168.10.27.
2024-02-29 09:55:36.951 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:56:16.751 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds
2024-02-29 09:56:36.751 WARNING (MainThread) [homeassistant.components.sensor] Updating echonetlite sensor took longer than the scheduled update interval 0:00:30
2024-02-29 09:56:36.894 DEBUG (MainThread) [custom_components.echonetlite] 
ECHONETlite polling update data:
 - Operation status 0x80(128): on
 - Measured instantaneous power consumption 0x84(132): 21
 - Measured cumulative power consumption 0x85(133): 49980
 - Air flow rate setting 0xa0(160): auto
 - Air pollution detection status 0xc0(192): fresh

2024-02-29 09:57:16.751 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds
2024-02-29 09:57:36.751 WARNING (MainThread) [homeassistant.components.sensor] Updating echonetlite sensor took longer than the scheduled update interval 0:00:30
2024-02-29 09:58:16.753 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds
2024-02-29 09:58:36.753 WARNING (MainThread) [homeassistant.components.sensor] Updating echonetlite sensor took longer than the scheduled update interval 0:00:30
2024-02-29 09:59:16.755 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.measured_instantaneous_power_consumption is taking over 10 seconds

@nao-pon
Copy link
Collaborator

nao-pon commented Mar 3, 2024

I agree. This is a result that is not included in the specifications, so it seems that an option that can be handled individually is required.

@nao-pon nao-pon closed this as completed in 2583b9f Mar 5, 2024
@nao-pon
Copy link
Collaborator

nao-pon commented Mar 5, 2024

@hldh214 I pushed the changes to master. Select master in HACS, install it, and try disabling energy-related sensors using the options. You can reopen it if there are any problems.

@hldh214
Copy link
Author

hldh214 commented Mar 6, 2024

After modifying this option, it seems that reloading the integration does not have any effect. It is necessary to restart Home Assistant for the changes to take effect. I'm not sure if this is the expected behavior.

Additionally, I have noticed that even though the "energy-related sensors" option is not checked, after restarting, the output values of those sensors are indeed not displayed. However, when I manually reload the integration once(without any options change), the sensor data reappears and the original issue still exists.

@nao-pon
Copy link
Collaborator

nao-pon commented Mar 6, 2024

The only device I own that I can test is a Panasonic air conditioner, so here's how it works on that device.

  1. When an energy-related sensor is disabled from enabled, the device automatically reloads and the associated entity becomes unavailable. At that time, the batch request flag changes as follows.

From: Echonet device 192.168.1.234's batch request flags list: [[128, 161, 160, 164, 176, 179, 186, 187, 190]]
To: Echonet device 192.168.1.234's batch request flags list: [[128, 160, 161, 164, 176, 179, 186, 187, 190, 133]]

133(0x85) is the sensor in question.

  1. The next time an energy-related sensor is enabled from a disabled state, the device will automatically reload, the associated entities will become available, and data updates will resume.

At least in my tests, there seems to be no problem, but in a situation where all sensor updates have stopped, there is a possibility that it will not recover unless you restart HA. Have you noticed anything?

Please note that even if you restart with the option unchecked, the sensor will remain unavailable. Reloading with it unchecked shouldn't change that state, making your experience a bit strange.

@hldh214
Copy link
Author

hldh214 commented Mar 7, 2024

Additionally, I have noticed that even though the "energy-related sensors" option is not checked, after restarting, the output values of those sensors are indeed not displayed. However, when I manually reload the integration once(without any options change), the sensor data reappears and the original issue still exists.

I have performed some basic debugging and have identified a potential cause for this. It appears that we unintentionally modified a constant, causing the if-condition to behave differently upon reloading the integration.

if (
_enabled_super_energy
or ENL_OP_CODES.get(self._eojgc, {})
.get(self._eojcc, {})
.get(ENL_INSTANTANEOUS_POWER)
or ENL_OP_CODES.get(self._eojgc, {})
.get(self._eojcc, {})
.get(ENL_CUMULATIVE_POWER)
):

And here is the possible fix.

diff --git a/custom_components/echonetlite/time.py b/custom_components/echonetlite/time.py
index 1bf5e42..863c8ec 100644
--- a/custom_components/echonetlite/time.py
+++ b/custom_components/echonetlite/time.py
@@ -23,7 +23,7 @@ async def async_setup_entry(hass, config, async_add_entities, discovery_info=Non
     for entity in hass.data[DOMAIN][config.entry_id]:
         eojgc = entity["instance"]["eojgc"]
         eojcc = entity["instance"]["eojcc"]
-        _enl_op_codes = ENL_OP_CODES.get(eojgc, {}).get(eojcc, {})
+        _enl_op_codes = ENL_OP_CODES.get(eojgc, {}).get(eojcc, {}).copy()
         _enl_op_codes.update(ENL_SUPER_CODES)
         # configure select entities by looking up full ENL_OP_CODE dict
         for op_code in entity["instance"]["setmap"]:

@hldh214
Copy link
Author

hldh214 commented Mar 7, 2024

Plus, above patch also fix this:

After modifying this option, it seems that reloading the integration does not have any effect. It is necessary to restart Home Assistant for the changes to take effect. I'm not sure if this is the expected behavior.

@nao-pon
Copy link
Collaborator

nao-pon commented Mar 7, 2024

@hldh214 You're right! I fixed it in 1619da1. Thanks! 👍

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants