Continous 'Not authenticated' errors in log since 2.12.1

Question

Continous 'Not authenticated' errors in log since 2.12.1

Closed this issue 2 years ago · 37 comments

callifo commented 3 years ago

Did you read the instructions?

I have read the README, including the advanced debugging section.

The problem

Updated to 2.12.1 and the log continuously fills with log shown below. No other changes since using 2.11.0.

Somfy blinds are RTS so stateless in HA. Rolled back in case this causes me to get banned.

What version of this integration (ha-tahoma) has the issue?

2.12.1

What version of Home Assistant Core has the issue?

2021.12.10

Which gateway / hub do you use?

Connexoon Rts Australia hub

Device model

Tahoma Basic

Home Assistant log

Logs

2022-01-27 11:44:58 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:46:00 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:46:32 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:48:33 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:49:05 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:50:06 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:50:38 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:51:39 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:52:40 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:53:41 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:54:42 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:56:13 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:56:44 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:58:45 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 11:59:46 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:00:17 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:01:48 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:03:19 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:04:50 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:05:51 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:06:23 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:08:24 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:09:55 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:10:56 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:11:27 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:11:59 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:13:00 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:13:32 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:14:33 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:15:05 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:16:06 INFO (MainThread) [backoff] Backing off fetch_events(...) for 1.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:16:38 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:17:40 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:19:11 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:19:42 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:20:14 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:21:45 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:24:17 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-27 12:25:48 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)

Additional information

No response

callifo commented 3 years ago

log.txt

Answer 1 · 2022-01-27T19:21:01.000Z

@callifo could you please include your full debug log?

Answer 2 · 2022-01-28T13:41:00.000Z

Will try to, it hasnt happened today, will keep an eye on it.

Answer 3 · 2022-01-28T14:03:05.000Z

Will try to, it hasnt happened today, will keep an eye on it.

But you did downgrade? Isn't that why you don't face the errors anymore?

Answer 4 · 2022-01-29T08:53:57.000Z

No I have upgraded again. it was doing it every hour today, but not every minute like previously,

2022-01-29 09:27:32 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 09:27:34 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.050 seconds (success: True)
2022-01-29 10:27:34 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 10:27:35 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.448 seconds (success: True)
2022-01-29 11:27:35 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 11:27:36 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.584 seconds (success: True)
2022-01-29 12:27:36 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 12:27:38 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.271 seconds (success: True)
2022-01-29 13:27:39 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 13:27:40 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.320 seconds (success: True)
2022-01-29 14:27:40 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 14:27:42 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.540 seconds (success: True)
2022-01-29 15:27:43 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 15:27:44 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.097 seconds (success: True)
2022-01-29 16:27:44 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 16:27:45 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.894 seconds (success: True)
2022-01-29 17:27:46 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 17:27:47 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.664 seconds (success: True)
2022-01-29 18:27:48 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-29 18:27:49 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.546 seconds (success: True)

Answer 5 · 2022-01-30T12:03:42.000Z

Ok happened again after a HA restart.


2022-01-30 22:17:44 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:17:45 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.490 seconds (success: True)
2022-01-30 22:18:15 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.261 seconds (success: True)
2022-01-30 22:18:45 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:18:46 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.379 seconds (success: True)
2022-01-30 22:19:16 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.315 seconds (success: True)
2022-01-30 22:19:46 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.296 seconds (success: True)
2022-01-30 22:20:16 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.284 seconds (success: True)
2022-01-30 22:20:46 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:20:48 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.093 seconds (success: True)
2022-01-30 22:21:18 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:21:20 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.150 seconds (success: True)
2022-01-30 22:21:50 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:21:51 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.791 seconds (success: True)
2022-01-30 22:22:21 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.278 seconds (success: True)
2022-01-30 22:22:51 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.264 seconds (success: True)
2022-01-30 22:23:21 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.262 seconds (success: True)
2022-01-30 22:23:51 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:23:52 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.971 seconds (success: True)
2022-01-30 22:24:22 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.279 seconds (success: True)
2022-01-30 22:24:52 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.274 seconds (success: True)
2022-01-30 22:25:22 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:25:23 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.708 seconds (success: True)
2022-01-30 22:25:53 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.580 seconds (success: True)
2022-01-30 22:26:23 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.263 seconds (success: True)
2022-01-30 22:26:53 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.261 seconds (success: True)
2022-01-30 22:27:23 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.263 seconds (success: True)
2022-01-30 22:27:53 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.257 seconds (success: True)
2022-01-30 22:28:23 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-30 22:28:24 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.415 seconds (success: True)

Been doing that every minute all night.

Restarting fixes it. Whilst it was doing it, an automation closed all the blinds in the house, and that went through fine.

2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810427, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e3f7a-0a32-0482-5c07-b861547995fb', device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810427, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e3f7a-0a32-0482-5c07-b861547995fb', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810427, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810429, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e3f7a-0a32-0482-5c07-b861547995fb', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810429, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.086 seconds (success: True)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810616, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e4037-0a32-0482-5c07-b86163d5f521', device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810616, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e4037-0a32-0482-5c07-b86163d5f521', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810616, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810617, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e4037-0a32-0482-5c07-b86163d5f521', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810617, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.087 seconds (success: True)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810828, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e410b-0a32-0482-5c07-b8611294c8be', device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810828, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e410b-0a32-0482-5c07-b8611294c8be', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810828, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810830, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e410b-0a32-0482-5c07-b8611294c8be', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535810830, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-01-30 20:13:30 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.088 seconds (success: True)
2022-01-30 20:14:00 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535811687, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e3f7a-0a32-0482-5c07-b861547995fb', device_url=None, device_states=[], old_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>, new_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>)
2022-01-30 20:14:00 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535813284, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e4037-0a32-0482-5c07-b86163d5f521', device_url=None, device_states=[], old_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>, new_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>)
2022-01-30 20:14:00 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535814571, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e410b-0a32-0482-5c07-b8611294c8be', device_url=None, device_states=[], old_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>, new_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>)
2022-01-30 20:14:00 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.282 seconds (success: True)
2022-01-30 20:14:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535841966, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=-1, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e3f7a-0a32-0482-5c07-b861547995fb', device_url=None, device_states=[], old_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>, new_state=<ExecutionState.COMPLETED: 'COMPLETED'>)
2022-01-30 20:14:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535843280, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=-1, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e4037-0a32-0482-5c07-b86163d5f521', device_url=None, device_states=[], old_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>, new_state=<ExecutionState.COMPLETED: 'COMPLETED'>)
2022-01-30 20:14:30 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1643535844593, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=382, _default=None, repr=<function obfuscate_id at 0xa241a220>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=-1, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='aa5e410b-0a32-0482-5c07-b8611294c8be', device_url=None, device_states=[], old_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>, new_state=<ExecutionState.COMPLETED: 'COMPLETED'>)

Answer 6 · 2022-01-30T17:29:21.000Z

Can you include your full debug log? The reason why I asked this previous time, is I need to understand which devices you have and why it seems that sometimes your interval is not set to every hour.

Answer 7 · 2022-01-31T10:30:15.000Z

Sorry I thoughts that's what I did using the tahoma logger setting = debug.

Do you want the output from 'tahoma.get_execution_history'?

Answer 8 · 2022-01-31T10:34:36.000Z

No, but there should be more lines like 'Added device' and more.

Answer 9 · 2022-01-31T12:14:00.000Z

Ah, well at boot I get this,

2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.108 seconds (success: True)
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] All devices have assumed state. Update interval has been reduced to: 1:00:00
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16732264, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16742596, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=**, device_url=rts://****-****-2775/16763048, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16764082, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] Added gateway (Gateway(partners=[], functions='INTERNET_AUTHORIZATION,SCENARIO_DOWNLOAD,SCENARIO_TELECO_LAUNCHING,INTERNET_UPLOAD,INTERNET_UPDATE,TRIGGERS_SENSORS', sub_type=<GatewaySubType.TAHOMA_BASIC: 1>, id=****-****-2775, gateway_id=****-****-2775, alive=True, mode='NO_AUTO', place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b', time_reliable=True, connectivity=Connectivity(status='OK', protocol_version='2021.2.4'), up_to_date=True, update_status=<UpdateBoxStatus.UP_TO_DATE: 'UP_TO_DATE'>, sync_in_progress=False, type=<GatewayType.CONNEXOON_RTS_AUSTRALIA: 62>))

Exactly 5 minutes after that message, it started that message up every 1 minute,

2022-01-31 22:23:12 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-01-31 22:23:13 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.667 seconds (success: True)
2022-01-31 22:23:43 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.264 seconds (success: True)

Answer 10 · 2022-02-01T09:42:02.000Z

Might need to roll back for now, got banned today, after it started auth'ing every minute for 6 hours.

Answer 11 · 2022-02-06T22:39:36.000Z

I have the same issue as well. I originally had polling option on which created a lot of Backing Off Info messages. Turning it off reduced the number but it still does it every time I open/close a blind, but everything works fine except for the Info message. I too am using the Connexoon RTS in Australia. I have upgraded to the core integration, and other than the INFO messages all works fine.

Answer 12 · 2022-02-07T22:03:10.000Z

@Tarawera could you share your debug logs? Same as for @callifo, we need to understand which devices you have and if you poll the event listener every 30 seconds or every hour. Please include all logs.

Answer 13 · 2022-02-24T10:42:44.000Z

Is there anything I can do to collect further info?

Answer 14 · 2022-02-24T10:48:22.000Z

I have no clue where to look for it... According to your logs, the polling interval is already set to 1 hour.

2022-01-31 21:23:10 DEBUG (MainThread) [custom_components.tahoma] All devices have assumed state. Update interval has been reduced to: 1:00:00

Answer 15 · 2022-02-25T10:52:16.000Z

I tried 2.13, and it does it within an hour, heres the full log,

2022-02-25 00:18:34 INFO (MainThread) [homeassistant.setup] Setting up tahoma
2022-02-25 00:18:34 INFO (MainThread) [homeassistant.setup] Setup of domain tahoma took 0.0 seconds
2022-02-25 00:18:35 INFO (MainThread) [homeassistant.setup] Setting up binary_sensor
2022-02-25 00:18:35 INFO (MainThread) [homeassistant.setup] Setup of domain binary_sensor took 0.0 seconds
2022-02-25 00:18:35 INFO (MainThread) [homeassistant.setup] Setting up tplink
2022-02-25 00:18:35 WARNING (MainThread) [homeassistant.components.alarm_control_panel] Setup of alarm_control_panel platform manual is taking over 10 seconds.
2022-02-25 00:18:35 WARNING (MainThread) [homeassistant.setup] Setup of hacs is taking over 10 seconds.
2022-02-25 00:18:36 WARNING (SyncWorker_5) [pyhik.hikvision] Sensor type "faceSnap" is unsupported.
2022-02-25 00:18:36 INFO (MainThread) [homeassistant.setup] Setup of domain camera took 3.8 seconds
2022-02-25 00:18:36 WARNING (SyncWorker_9) [pyhik.hikvision] Sensor type "faceSnap" is unsupported.
2022-02-25 00:18:36 INFO (MainThread) [homeassistant.setup] Setting up synology_dsm
2022-02-25 00:18:36 INFO (MainThread) [homeassistant.setup] Setup of domain synology_dsm took 0.0 seconds
2022-02-25 00:18:36 INFO (MainThread) [homeassistant.setup] Setting up template
2022-02-25 00:18:36 INFO (MainThread) [homeassistant.setup] Setup of domain template took 0.0 seconds
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setting up tts
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setup of domain smartir took 16.5 seconds
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setting up google_assistant
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 16.2 seconds
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 6.5 seconds
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setup of domain timer took 16.8 seconds
2022-02-25 00:18:37 INFO (MainThread) [homeassistant.setup] Setup of domain input_boolean took 17.0 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_number took 16.8 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_select took 16.5 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain google_assistant took 0.8 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setting up climate
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain climate took 0.0 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 1.3 seconds
2022-02-25 00:18:38 INFO (MainThread) [homeassistant.setup] Setup of domain input_datetime took 16.8 seconds
2022-02-25 00:18:39 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2022-02-25 00:18:39 INFO (MainThread) [homeassistant.setup] Setting up device_tracker
2022-02-25 00:18:39 INFO (MainThread) [homeassistant.setup] Setting up weather
2022-02-25 00:18:39 INFO (MainThread) [homeassistant.setup] Setup of domain weather took 0.0 seconds
2022-02-25 00:18:39 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 0.7 seconds
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setting up cast
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setting up sensor
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 0.0 seconds
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setting up tasmota
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setup of domain tasmota took 0.0 seconds
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setting up traccar
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setup of domain traccar took 0.0 seconds
2022-02-25 00:18:40 INFO (MainThread) [homeassistant.setup] Setting up ping
2022-02-25 00:18:41 INFO (MainThread) [homeassistant.setup] Setting up rest
2022-02-25 00:18:41 INFO (MainThread) [homeassistant.setup] Setup of domain rest took 0.0 seconds
2022-02-25 00:18:41 INFO (MainThread) [homeassistant.setup] Setup of domain ping took 0.5 seconds
2022-02-25 00:18:41 INFO (MainThread) [homeassistant.setup] Setup of domain hacs took 17.1 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain tplink took 6.6 seconds
2022-02-25 00:18:42 INFO (MainThread) [custom_components.izone_custom.climate] Controller UID=000036424 discovered
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.izone_custom.climate] Found external temperature sensor=sensor.living_room_temperature_f, for device_id=000036424
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.109 seconds (success: True)
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] All devices have assumed state. Update interval has been reduced to: 1:00:00
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16732264, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16742596, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=**, device_url=rts://****-****-2775/16763048, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Added device (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16764082, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-02-25 00:18:42 DEBUG (MainThread) [custom_components.tahoma] Added gateway (Gateway(partners=[], functions='INTERNET_AUTHORIZATION,SCENARIO_DOWNLOAD,SCENARIO_TELECO_LAUNCHING,INTERNET_UPLOAD,INTERNET_UPDATE,TRIGGERS_SENSORS', sub_type=<GatewaySubType.TAHOMA_BASIC: 1>, id=****-****-2775, gateway_id=****-****-2775, alive=True, mode='NO_AUTO', place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b', time_reliable=True, connectivity=Connectivity(status='OK', protocol_version='2021.2.4'), up_to_date=True, update_status=<UpdateBoxStatus.UP_TO_DATE: 'UP_TO_DATE'>, sync_in_progress=False, type=<GatewayType.CONNEXOON_RTS_AUSTRALIA: 62>))
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up scene
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up button
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain button took 0.0 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up number
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain number took 0.0 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up lock
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain lock took 0.0 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up water_heater
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain water_heater took 0.0 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setting up select
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain select took 0.0 seconds
2022-02-25 00:18:42 INFO (MainThread) [homeassistant.setup] Setup of domain scene took 0.1 seconds
2022-02-25 00:18:44 INFO (MainThread) [homeassistant.setup] Setting up vacuum
2022-02-25 00:18:44 INFO (MainThread) [homeassistant.setup] Setup of domain vacuum took 0.0 seconds
2022-02-25 00:18:46 INFO (MainThread) [homeassistant.setup] Setting up automation
2022-02-25 00:18:46 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.4 seconds
2022-02-25 00:18:46 INFO (MainThread) [homeassistant.setup] Setting up script
2022-02-25 00:18:47 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.1 seconds
2022-02-25 00:18:49 WARNING (MainThread) [homeassistant.components.fan] Setup of fan platform mqtt is taking over 10 seconds.
2022-02-25 00:18:49 INFO (MainThread) [homeassistant.setup] Setup of domain device_tracker took 9.8 seconds
2022-02-25 00:18:49 INFO (MainThread) [homeassistant.setup] Setting up proximity
2022-02-25 00:18:49 INFO (MainThread) [homeassistant.setup] Setup of domain proximity took 0.1 seconds
2022-02-25 00:18:50 WARNING (MainThread) [homeassistant.components.sensor] Setup of sensor platform mqtt is taking over 10 seconds.
2022-02-25 00:18:52 ERROR (Thread-11) [pychromecast.socket_client] [Living Room Chromecast(192.168.100.136):8009] Failed to connect to service ServiceInfo(type='mdns', data='Chromecast-088b196438104da762045b21153b6749._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:18:52 ERROR (Thread-12) [pychromecast.socket_client] [All Speakers(192.168.100.141):32205] Failed to connect to service ServiceInfo(type='mdns', data='Google-Cast-Group-ac71dafb59d54d8db0d4c6f32a03acf1._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:18:53 ERROR (Thread-13) [pychromecast.socket_client] [Living Room speaker(192.168.100.141):8009] Failed to connect to service ServiceInfo(type='mdns', data='Nest-Audio-6b83de3ef3f037a53fc674215239b639._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:18:55 ERROR (Thread-14) [pychromecast.socket_client] [Study speaker(192.168.100.139):8009] Failed to connect to service ServiceInfo(type='mdns', data='Google-Home-Mini-468655f877969ea384429ff8c13fba53._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:18:57 ERROR (Thread-15) [pychromecast.socket_client] [Master Bedroom display(192.168.100.140):8009] Failed to connect to service ServiceInfo(type='mdns', data='google-nest-hub-9bc47025247c008e8d66a4b544ad6298._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:19:05 INFO (MainThread) [homeassistant.setup] Setting up remote
2022-02-25 00:19:05 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds
2022-02-25 00:19:07 ERROR (MainThread) [custom_components.hacs] HacsTask<update_critical_repositories> failed: <Integration custom-components/sensor.custom_aftership> Repository has been requested to be removed.
2022-02-25 00:19:15 ERROR (Thread-26) [pychromecast.socket_client] [Kitchen display(192.168.100.137):8009] Failed to connect to service ServiceInfo(type='mdns', data='JBL-Link-View-c247ec6cd6ec4bff95f2a9c2c17819f2._googlecast._tcp.local.'), retrying in 5.0s
2022-02-25 00:19:42 ERROR (Thread-12) [pychromecast.socket_client] [All Speakers(192.168.100.140):32205] Error reading from socket.
2022-02-25 00:19:42 WARNING (Thread-12) [pychromecast.socket_client] [All Speakers(192.168.100.140):32205] Error communicating with socket, resetting connection
2022-02-25 00:19:42 ERROR (Thread-12) [pychromecast.socket_client] [All Speakers(192.168.100.139):32205] Failed to connect to service ServiceInfo(type='host', data=('192.168.100.139', 32205)), retrying in 5.0s
2022-02-25 00:54:47 WARNING (Thread-11) [pychromecast.socket_client] [Living Room Chromecast(192.168.100.136):8009] Heartbeat timeout, resetting connection
2022-02-25 00:54:49 WARNING (Thread-15) [pychromecast.socket_client] [Master Bedroom display(192.168.100.140):8009] Heartbeat timeout, resetting connection
2022-02-25 00:54:50 WARNING (Thread-26) [pychromecast.socket_client] [Kitchen display(192.168.100.137):8009] Heartbeat timeout, resetting connection
2022-02-25 00:54:50 WARNING (Thread-12) [pychromecast.socket_client] [All Speakers(192.168.100.137):32205] Heartbeat timeout, resetting connection
2022-02-25 01:12:37 WARNING (MainThread) [homeassistant.helpers.entity] Updating state for sensor.washing_machine_today_s_consumption (<class 'homeassistant.components.tplink.sensor.SmartPlugSensor'>) took 0.546 seconds. Please create a bug report at https://github.com/home-assistant/core/issues?q=is%3Aopen+is%3Aissue+label%3A%22integration%3A+tplink%22
2022-02-25 01:52:09 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:52:11 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.177 seconds (success: True)
2022-02-25 01:52:41 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.261 seconds (success: True)
2022-02-25 01:53:11 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:53:12 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.868 seconds (success: True)
2022-02-25 01:53:42 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.284 seconds (success: True)
2022-02-25 01:54:12 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:54:14 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.167 seconds (success: True)
2022-02-25 01:54:44 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:54:45 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.547 seconds (success: True)
2022-02-25 01:55:15 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.274 seconds (success: True)
2022-02-25 01:55:45 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.256 seconds (success: True)
2022-02-25 01:56:15 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.7s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:56:17 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.004 seconds (success: True)
2022-02-25 01:56:47 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:56:48 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.572 seconds (success: True)
2022-02-25 01:57:18 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.281 seconds (success: True)
2022-02-25 01:57:48 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.283 seconds (success: True)
2022-02-25 01:58:18 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:58:19 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.356 seconds (success: True)
2022-02-25 01:58:49 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.268 seconds (success: True)
2022-02-25 01:59:19 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 01:59:20 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.532 seconds (success: True)
2022-02-25 01:59:50 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.272 seconds (success: True)
2022-02-25 02:00:20 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.266 seconds (success: True)
2022-02-25 02:00:50 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:00:51 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.293 seconds (success: True)
2022-02-25 02:01:21 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.257 seconds (success: True)
2022-02-25 02:01:51 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.258 seconds (success: True)
2022-02-25 02:02:21 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.257 seconds (success: True)
2022-02-25 02:02:51 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:02:52 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.835 seconds (success: True)
2022-02-25 02:03:22 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.282 seconds (success: True)
2022-02-25 02:03:52 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:03:53 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.508 seconds (success: True)
2022-02-25 02:04:23 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:04:25 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.167 seconds (success: True)
2022-02-25 02:04:55 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:04:56 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.304 seconds (success: True)
2022-02-25 02:05:26 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.269 seconds (success: True)
2022-02-25 02:05:56 INFO (MainThread) [backoff] Backing off fetch_events(...) for 1.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:05:58 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.833 seconds (success: True)
2022-02-25 02:06:28 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.278 seconds (success: True)
2022-02-25 02:06:58 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:06:59 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.676 seconds (success: True)
2022-02-25 02:07:29 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.274 seconds (success: True)
2022-02-25 02:07:59 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.273 seconds (success: True)
2022-02-25 02:08:29 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.261 seconds (success: True)
2022-02-25 02:08:59 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:09:00 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.458 seconds (success: True)
2022-02-25 02:09:31 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.6s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:09:32 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.986 seconds (success: True)
2022-02-25 02:10:02 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.281 seconds (success: True)
2022-02-25 02:10:32 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.265 seconds (success: True)
2022-02-25 02:11:02 INFO (MainThread) [backoff] Backing off fetch_events(...) for 1.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:11:04 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.510 seconds (success: True)
2022-02-25 02:11:34 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.287 seconds (success: True)
2022-02-25 02:12:04 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.275 seconds (success: True)
2022-02-25 02:12:34 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:12:35 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.588 seconds (success: True)
2022-02-25 02:13:05 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.260 seconds (success: True)
2022-02-25 02:13:35 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:13:37 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.077 seconds (success: True)
2022-02-25 02:14:07 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.0s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:14:08 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 1.201 seconds (success: True)
2022-02-25 02:14:38 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-02-25 02:14:40 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 2.427 seconds (success: True)
2022-02-25 02:15:10 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.308 seconds (success: True)
2022-02-25 02:15:40 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.292 seconds (success: True)
2022-02-25 02:16:10 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)

Answer 16 · 2022-02-25T12:03:05.000Z

Can you also include your diagnostic information here? You can just drag and rop the file in a new comment.

Answer 17 · 2022-02-25T14:35:44.000Z

It seems that you included the same log as above? You can download your diagnostics information by going to your integrations page. Click on the 3 dots next to Overkiz. Select "Download diagnostics".

Answer 18 · 2022-02-26T02:18:39.000Z

Ah, sorry I thought it was the service, 'tahoma.get_execution_history' you meant. The Advanced Debugging page above doesn't mention that debug option 'Download diagnostics'.

As I had to roll back, I will get that in about an hour.

For comparison in case its any worth, I have attached 2.11 which works as expected.
log_1.txt
Download Diagnostics doesn't seem to be an option in 2.11 so I can't provide that.

Answer 19 · 2022-02-26T03:41:04.000Z

Diagnostics from when it was updating every minute.
config_entry-tahoma.json.txt

From the log, tahoma component was loaded at 12:54:05pm, start of minutely updates started at 13:54:12pm, its first hourly update.

Answer 20 · 2022-03-29T02:05:30.000Z

'There hasn't been any activity on this issue recently. Is this issue still present?
Please make sure to update to the latest Home Assistant version and version of this integration to see if that solves the issue. Let us know if that works for you by adding a comment 👍.
This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.'

Answer 21 · 2022-03-29T02:20:38.000Z

The problem seems to have resolved itself with the latest version. Thanks

…

On 29/03/2022, at 3:05 PM, github-actions[bot] ***@***.***> wrote: 'There hasn't been any activity on this issue recently. Is this issue still present? Please make sure to update to the latest Home Assistant version and version of this integration to see if that solves the issue. Let us know if that works for you by adding a comment 👍. This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.' — Reply to this email directly, view it on GitHub <#736 (comment)>, or unsubscribe <https://github.com/notifications/unsubscribe-auth/AIZQ6PS3VXA6YLFMZHRH2QDVCJQPLANCNFSM5M5RHTWA>. You are receiving this because you were mentioned.

Answer 22 · 2022-04-04T02:53:59.000Z

Hi @Tarawera, do you mean the latest HA version or latest tahoma 2.13.0?

Answer 23 · 2022-04-04T14:34:52.000Z

I meant latest HA Regards David

…

On 3/04/2022, at 8:54 PM, callifo ***@***.***> wrote: Hi @Tarawera, do you mean the latest HA version or latest tahoma 2.13.0? — Reply to this email directly, view it on GitHub, or unsubscribe. You are receiving this because you were mentioned.

Answer 24 · 2022-04-06T03:12:03.000Z

Cool thanks, will try, I've been running for 2.11 for a while, as for me, 2.13 was having said issue previously with older HA versions.

Answer 25 · 2022-04-06T04:28:13.000Z

Bugger not for me, exactly an hour after HA start, it reverts back to 60 seconds polling,

2022-04-06 12:42:14 INFO (MainThread) [homeassistant.setup] Setting up proximity
2022-04-06 12:42:14 INFO (MainThread) [homeassistant.setup] Setup of domain proximity took 0.1 seconds
2022-04-06 13:42:14 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-06 13:43:45 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.9s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)

Answer 26 · 2022-04-06T11:45:15.000Z

Interestingly I moved from the custom component over to the native HA integration and the issue has not appeared.

Answer 27 · 2022-04-06T11:52:17.000Z

@callifo perhaps this has been fixed in the core integration. My advice would for sure be to stay on the core integration, since we are updating that one more frequently.

However, if it is on the service side, big chance that this will happen with the core integration as well eventually...

Answer 28 · 2022-04-06T15:58:17.000Z

Will do, will keep an eye on it, and see if it does reoccur what release version it does relapse. Might provide more insight on where its being introduced.

Answer 29 · 2022-04-12T12:15:12.000Z

Looks like I was incorrect the native integration has the same issue, it just didn't appear to start after the first hour, but it was.

Is there anything else I can do to help nail down why its changing from hourly polling to minute polling after the first refresh?

Answer 30 · 2022-04-12T12:35:19.000Z

@callifo can you share a new log with the Overkiz core integration on debug? I can have a look.

We focus on the core integration mainly now.

Answer 31 · 2022-04-13T11:48:05.000Z

Attached,

debug.txt
config_entry-overkiz.json.txt

Answer 32 · 2022-04-14T11:45:28.000Z

Interestingly, after failing auth, its goine back to 1 hour polling,

2022-04-14 15:33:31 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 15:33:32 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 1.402 seconds (success: True)
2022-04-14 15:34:02 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.257 seconds (success: True)
2022-04-14 15:34:32 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 15:34:33 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 1.338 seconds (success: True)
2022-04-14 15:35:03 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.1s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 15:35:03 ERROR (MainThread) [homeassistant.components.overkiz] Authentication failed while fetching device events data: Invalid authentication.
2022-04-14 15:35:03 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.842 seconds (success: False)
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.082 seconds (success: True)
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] All devices have an assumed state. Update interval has been reduced to: 1:00:00
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] The following device has been retrieved. Report an issue if not supported correctly (Device(attributes=States(_states=[]), available=True, enabled=True, label=**, device_url=internal://****-****-2775/pod/0, controllable_name='internal:PodMiniComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='getName', nparams=0), CommandDefinition(command_name='update', nparams=0), CommandDefinition(command_name='setCountryCode', nparams=1), CommandDefinition(command_name='activateCalendar', nparams=0), CommandDefinition(command_name='deactivateCalendar', nparams=0), CommandDefinition(command_name='refreshPodMode', nparams=0), CommandDefinition(command_name='refreshUpdateStatus', nparams=0), CommandDefinition(command_name='setCalendar', nparams=1), CommandDefinition(command_name='setLightingLedPodMode', nparams=1), CommandDefinition(command_name='setPodLedOff', nparams=0), CommandDefinition(command_name='setPodLedOn', nparams=0)]), states=[StateDefinition(qualified_name='core:ConnectivityState', type='DiscreteState', values=['offline', 'online']), StateDefinition(qualified_name='core:CountryCodeState', type='DataState', values=None), StateDefinition(qualified_name='core:LocalIPv4AddressState', type='DataState', values=None), StateDefinition(qualified_name='core:NameState', type='DataState', values=None), StateDefinition(qualified_name='internal:LastActionConfigButtonState', type='DiscreteState', values=['doublePress', 'longPress', 'simplePress', 'triplePress', 'veryLongPress']), StateDefinition(qualified_name='internal:LightingLedPodModeState', type='ContinuousState', values=None)], widget_name='Pod', ui_class='Pod', qualified_name='internal:PodMiniComponent'), data_properties=None, widget=<UIWidget.POD: 'Pod'>, ui_class=<UIClass.POD: 'Pod'>, states=States(_states=[State(name='core:NameState', type=<DataType.STRING: 3>, value='Box'), State(name='internal:LightingLedPodModeState', type=<DataType.FLOAT: 2>, value=1.0), State(name='core:CountryCodeState', type=<DataType.STRING: 3>, value='AU'), State(name='core:LocalIPv4AddressState', type=<DataType.STRING: 3>, value='192.168.100.181')]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] The following device has been retrieved. Report an issue if not supported correctly (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16732264, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] The following device has been retrieved. Report an issue if not supported correctly (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16742596, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] The following device has been retrieved. Report an issue if not supported correctly (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=**, device_url=rts://****-****-2775/16763048, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] The following device has been retrieved. Report an issue if not supported correctly (Device(attributes=States(_states=[State(name='rts:diy', type=<DataType.BOOLEAN: 6>, value=False)]), available=True, enabled=True, label=** **, device_url=rts://****-****-2775/16764082, controllable_name='rts:BlindRTSComponent', definition=Definition(commands=CommandDefinitions(_commands=[CommandDefinition(command_name='close', nparams=1), CommandDefinition(command_name='down', nparams=1), CommandDefinition(command_name='identify', nparams=0), CommandDefinition(command_name='my', nparams=1), CommandDefinition(command_name='open', nparams=1), CommandDefinition(command_name='rest', nparams=1), CommandDefinition(command_name='stop', nparams=1), CommandDefinition(command_name='test', nparams=0), CommandDefinition(command_name='up', nparams=1), CommandDefinition(command_name='openConfiguration', nparams=1)]), states=[], widget_name='UpDownScreen', ui_class='Screen', qualified_name='rts:BlindRTSComponent'), data_properties=None, widget=<UIWidget.UP_DOWN_SCREEN: 'UpDownScreen'>, ui_class=<UIClass.SCREEN: 'Screen'>, states=States(_states=[]), type=<ProductType.ACTUATOR: 1>, place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b'))
2022-04-14 15:35:06 DEBUG (MainThread) [homeassistant.components.overkiz] Added gateway (Gateway(partners=[], functions='INTERNET_AUTHORIZATION,SCENARIO_DOWNLOAD,SCENARIO_TELECO_LAUNCHING,INTERNET_UPLOAD,INTERNET_UPDATE,TRIGGERS_SENSORS', sub_type=<GatewaySubType.TAHOMA_BASIC: 1>, id=****-****-2775, gateway_id=****-****-2775, alive=True, mode='NO_AUTO', place_oid='6cc426e1-61d2-420b-a807-d0687da74e0b', time_reliable=True, connectivity=Connectivity(status='OK', protocol_version='2021.2.4'), up_to_date=True, update_status=<UpdateBoxStatus.UP_TO_DATE: 'UP_TO_DATE'>, sync_in_progress=False, type=<GatewayType.CONNEXOON_RTS_AUSTRALIA: 62>))
2022-04-14 16:07:51 INFO (MainThread) [backoff] Backing off execute_commands(...) for 0.4s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649918272909, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26cae58d-0a32-0481-50c1-f9c6f1d4aa2b', device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649918272910, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26cae58d-0a32-0481-50c1-f9c6f1d4aa2b', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649918272910, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649918272924, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26cae58d-0a32-0481-50c1-f9c6f1d4aa2b', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649918272925, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 16:07:53 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.090 seconds (success: True)
2022-04-14 17:04:01 INFO (MainThread) [backoff] Backing off execute_commands(...) for 0.2s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642156, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4eab-0a32-0481-50c1-f9c612355486', device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642156, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4eab-0a32-0481-50c1-f9c612355486', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642156, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642166, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4eab-0a32-0481-50c1-f9c612355486', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642167, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.102 seconds (success: True)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642385, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4f90-0a32-0481-50c1-f9c6d032f2d8', device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642385, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4f90-0a32-0481-50c1-f9c6d032f2d8', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642385, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642395, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe4f90-0a32-0481-50c1-f9c6d032f2d8', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642395, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.088 seconds (success: True)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642587, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=1, sub_type=1, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe505b-0a32-0481-50c1-f9c68ff66a45', device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642587, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe505b-0a32-0481-50c1-f9c68ff66a45', device_url=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642587, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642589, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=0a8ab47e-ff7d-4e****-b3c****-1add27fa3eca, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='26fe505b-0a32-0481-50c1-f9c68ff66a45', device_url=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Event(timestamp=1649921642589, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=391, _default=None, repr=<function obfuscate_id at 0xa102f928>, eq=True, order=True, hash=None, init=True, on_setattr=None, metadata={}), owner_key=None, type=None, sub_type=None, time_to_next_state=None, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, place_oid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-2775, exec_id=None, device_url=None, device_states=[], old_state=None, new_state=None)
2022-04-14 17:04:02 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 0.085 seconds (success: True)
2022-04-14 18:04:02 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.5s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 18:04:04 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 2.136 seconds (success: True)
2022-04-14 19:04:04 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.8s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 19:04:06 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 2.707 seconds (success: True)
2022-04-14 20:04:07 INFO (MainThread) [backoff] Backing off fetch_events(...) for 0.3s (pyoverkiz.exceptions.NotAuthenticatedException: Not authenticated)
2022-04-14 20:04:08 DEBUG (MainThread) [homeassistant.components.overkiz] Finished fetching device events data in 2.254 seconds (success: True)

Answer 33 · 2022-05-05T09:00:04.000Z

I've had a lot of issues with getting banned lately, fortunately only shortly, but noticed I can stop the polling completely in the integration options by turning off 'Enable Polling for updates'. Given my covers are stateless, is there any consequence of doing this?

Answer 34 · 2022-05-10T18:54:00.000Z

@callifo I wouldn't know to be honest, this is a HA setting. You should just try it.

Answer 35 · 2022-05-16T11:28:56.000Z

It seems to stop the polling completely except for after a cover action, and I've not noticed any issue so far.

Answer 36 · 2022-06-16T02:15:50.000Z

'There hasn't been any activity on this issue recently. Is this issue still present?
Please make sure to update to the latest Home Assistant version and version of this integration to see if that solves the issue. Let us know if that works for you by adding a comment 👍.
This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.'