Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

Gate (DiscreteGateWithPedestrianPosition) does not report static status #487

Closed
1 task done
jakovec opened this issue Jul 19, 2021 · 65 comments · Fixed by #488 or #658
Closed
1 task done

Gate (DiscreteGateWithPedestrianPosition) does not report static status #487

jakovec opened this issue Jul 19, 2021 · 65 comments · Fixed by #488 or #658
Labels
bug Something isn't working

Comments

@jakovec
Copy link

jakovec commented Jul 19, 2021

Did you read the instructions?

The problem

Hi there, since version 1.5.0 the gate does not report CLOSE or OPEN status, only statuses OPENING or CLOSING are reported while the gate is moving and after that status is switched back to UNKNOWN (for both OPEN or CLOSE gate). Thanks for checking that.

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

2.5.1

What version of Home Assistant Core has the issue?

2021.7.2

Which gateway / hub do you use?

Somfy TaHoma

Device model

DiscreteGateOpenerIOComponent

Device type

io:DiscreteGateOpenerIOComponent

Additional information

History log:
Became unavailable
12:04:09 AM - 14 minutes ago
Is closing by Miroslav Jakovec
12:03:23 AM - 15 minutes ago
Became unavailable
12:03:14 AM - 15 minutes ago
Is opening by Miroslav Jakovec
12:02:28 AM - 16 minutes ago

Relevant log output

No response

@jakovec jakovec added the bug Something isn't working label Jul 19, 2021
@iMicknl
Copy link
Owner

iMicknl commented Jul 19, 2021

Could you share the states for your devices from /developer-tools/state? I believe this is caused by a recent change. We will need to add your specific state back.

cc @tetienne

@jakovec
Copy link
Author

jakovec commented Jul 19, 2021

Sure, it seems that OpenClosedPedestrianState is representing current STATIC state...

WHEN CLOSED:
state: unknown

rssi_level: 62
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: closed
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 62
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

WHEN OPENING in progress:
state: opening

rssi_level: 62
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: unknown
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 62
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

WHEN CLOSING in progress:
state: closing

rssi_level: 56
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: unknown
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 56
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

WHEN OPEN:
state: unknown

rssi_level: 56
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: open
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 56
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

@iMicknl
Copy link
Owner

iMicknl commented Jul 19, 2021

Thanks @jakovec, this was indeed what I expected. We removed part of this functionality a few versions ago, but good to know that this is still required.

https://github.com/iMicknl/ha-tahoma/pull/449/files

@tetienne
Copy link
Collaborator

tetienne commented Jul 20, 2021

I don’t get why io:DiscreteGateOpenerIOComponent and io:GateOpenerIOComponent does not have the same set of commands and states. Sorry for that.
Can you please try this fix: https://github.com/iMicknl/ha-tahoma/archive/refs/heads/fix/DiscreteGateWithPedestrianPosition.zip (only cover.py file was modified). Confirm setPosition, open and close work as expected.

@tetienne
Copy link
Collaborator

For record:

{
  "commands": [
    {
      "commandName": "advancedRefresh",
      "nparams": 1
    },
    {
      "commandName": "close",
      "nparams": 0
    },
    {
      "commandName": "delayedStopIdentify",
      "nparams": 1
    },
    {
      "commandName": "getName",
      "nparams": 0
    },
    {
      "commandName": "identify",
      "nparams": 0
    },
    {
      "commandName": "open",
      "nparams": 0
    },
    {
      "commandName": "refreshPedestrianPosition",
      "nparams": 0
    },
    {
      "commandName": "setName",
      "nparams": 1
    },
    {
      "commandName": "setPedestrianPosition",
      "nparams": 0
    },
    {
      "commandName": "startIdentify",
      "nparams": 0
    },
    {
      "commandName": "stop",
      "nparams": 0
    },
    {
      "commandName": "stopIdentify",
      "nparams": 0
    },
    {
      "commandName": "wink",
      "nparams": 1
    },
    {
      "commandName": "runManufacturerSettingsCommand",
      "nparams": 2
    },
    {
      "commandName": "keepOneWayControllersAndDeleteNode",
      "nparams": 0
    },
    {
      "commandName": "pairOneWayController",
      "nparams": 2
    },
    {
      "commandName": "sendIOKey",
      "nparams": 0
    },
    {
      "commandName": "setConfigState",
      "nparams": 1
    },
    {
      "commandName": "unpairAllOneWayControllersAndDeleteNode",
      "nparams": 0
    },
    {
      "commandName": "unpairAllOneWayControllers",
      "nparams": 0
    },
    {
      "commandName": "unpairOneWayController",
      "nparams": 2
    }
  ],
  "states": [
    {
      "type": "DataState",
      "qualifiedName": "core:AdditionalStatusState"
    },
    {
      "type": "DiscreteState",
      "values": [
        "good",
        "low",
        "normal",
        "verylow"
      ],
      "qualifiedName": "core:DiscreteRSSILevelState"
    },
    {
      "type": "DataState",
      "qualifiedName": "core:ManufacturerSettingsState"
    },
    {
      "type": "DataState",
      "qualifiedName": "core:NameState"
    },
    {
      "type": "DiscreteState",
      "values": [
        "closed",
        "open",
        "pedestrian",
        "unknown"
      ],
      "qualifiedName": "core:OpenClosedPedestrianState"
    },
    {
      "type": "ContinuousState",
      "qualifiedName": "core:PedestrianPositionState"
    },
    {
      "type": "ContinuousState",
      "qualifiedName": "core:PriorityLockTimerState"
    },
    {
      "type": "ContinuousState",
      "qualifiedName": "core:RSSILevelState"
    },
    {
      "type": "DiscreteState",
      "values": [
        "available",
        "unavailable"
      ],
      "qualifiedName": "core:StatusState"
    },
    {
      "type": "DiscreteState",
      "values": [
        "comfortLevel1",
        "comfortLevel2",
        "comfortLevel3",
        "comfortLevel4",
        "environmentProtection",
        "humanProtection",
        "userLevel1",
        "userLevel2"
      ],
      "qualifiedName": "io:PriorityLockLevelState"
    },
    {
      "type": "DiscreteState",
      "values": [
        "LSC",
        "SAAC",
        "SFC",
        "UPS",
        "externalGateway",
        "localUser",
        "myself",
        "rain",
        "security",
        "temperature",
        "timer",
        "user",
        "wind"
      ],
      "qualifiedName": "io:PriorityLockOriginatorState"
    }
  ],
  "dataProperties": [
    {
      "value": "500",
      "qualifiedName": "core:identifyInterval"
    }
  ],
  "widgetName": "DiscreteGateWithPedestrianPosition",
  "uiProfiles": [
    "OpenCloseGateOpener",
    "OpenClose"
  ],
  "uiClass": "Gate",
  "qualifiedName": "io:DiscreteGateOpenerIOComponent",
  "type": "ACTUATOR"
}

@jakovec
Copy link
Author

jakovec commented Jul 20, 2021

Thank you for such a quick fix! Tested that and working great! No issue now, all states as expected.

@tetienne
Copy link
Collaborator

tetienne commented Jul 20, 2021

@jakovec Can you confirm when you set a position your device behaves always the same ? Normally, you can only have one position. And that’s something we don’t handle, and it seems to work "by chance". And it’s why you have always PedestrianPositionState set to 50.

@jakovec
Copy link
Author

jakovec commented Jul 20, 2021

@tetienne Not sure how that should work, attribute current_position remains at value 50 at all states. If you call cover.set_cover_position service nothing happens.

@jakovec
Copy link
Author

jakovec commented Jul 20, 2021

If you set the position with physical remote controller, HA won't know current state until you kill the HA app and restart that or use a button to set position, for example:

  • I open the gate by remote
  • open HA UI, it still shows closed
  • I toggle the state of gate in HA by button (so it should change from open to close)
  • brief "opening" state happens for 1-2 seconds and then the state switches to "open" (which already have been before toggle button pressed - so now physical state and HA are in sync)
  • then I can press toggle again to close the gate and it works...

@tetienne
Copy link
Collaborator

So there is is still something not correctly handle. Perhaps a cover is not the right entity for this kind of devices. The new select entity seems make more sense as only few states can be set or retrieved. (cc. @iMicknl)

"values": [
        "closed",
        "open",
        "pedestrian",
        "unknown"
      ],

As you did here, can you control your device with the remote and report the states you see once refreshed in HA ?

And if not too intruisive may I have a picture or link to the commercial page of your device ? I have no idea of what it look like.

@jakovec
Copy link
Author

jakovec commented Jul 20, 2021

@tetienne On the other side, it's kind of comfortable to see the "opening" and "closing" status when you are not at the device and cannot check that visualy...

It is a two-leaf gate which includes two IXENGO 24V gate openers and one Control Box Somfy 3S IO, see https://service.somfy.com/downloads/bc_v5/5135006a001-manueld-installation-illustrations_control_box_3s_io.pdf

picture from camera here (you can see control box in the bottom right corner on the wall - grey box):
gate

So you just communicate with the 3S box and it controls the IXENGO 24V gate motors and get statuses from these.

Will be back home to check the statuses while remote controlling late night today or tommorow morning, but will post that then.

@jakovec
Copy link
Author

jakovec commented Jul 21, 2021

@tetienne So checked the states of device while remote controlling by IO remote controller. It seems that it works just fine, the only problem is that the states are not "pushed" and the correct state appear after refresh interval is due. So 30 seconds in my case. That is if I have my Android app open. If I have it "minimized" (in phone memory but not active) and open that, correct status does not refresh at all until I press a button or kill the app process and restart it again - but that is something you cannot affect, I suppose.

States while remote controlling with Somfy remote:
OPENING with remote:

state: close
current_position: 50
rssi_level: 48
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: closed
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 48
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

after refresh interval:

state: open
current_position: 50
rssi_level: 48
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: unknown
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 48
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

CLOSING:

state: open
current_position: 50
rssi_level: 42
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: unknown
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 42
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

after refresh interval:

state: closed
current_position: 50
rssi_level: 42
core:FirmwareRevision: 5102007F05?
core:Manufacturer: Somfy
core:NameState: brana
core:PriorityLockTimerState: 0
core:OpenClosedPedestrianState: closed
core:StatusState: available
core:DiscreteRSSILevelState: normal
core:RSSILevelState: 42
core:PedestrianPositionState: 50
io:PriorityLockOriginatorState: null
io:PriorityLockLevelState: null
friendly_name: brana
supported_features: 11
device_class: gate

@iMicknl iMicknl reopened this Jul 21, 2021
@tetienne
Copy link
Collaborator

tetienne commented Jul 21, 2021

Thank for this. That’s really strange OpenClosedPedestrianState is only set to unknown or closed. There is no use case where its value is set to pedestrian or open?

@jakovec
Copy link
Author

jakovec commented Jul 21, 2021

There might be any, but I don't use the pedestrian feature (which is that only one leaf will partly open for pedestrians to pass)... If you want me to check that, I can try to setup this state sometimes :)

@tetienne
Copy link
Collaborator

@jakovec It would be nice if you can have a try. It will allow me to understand better the device and what we can do with it.

@iMicknl
Copy link
Owner

iMicknl commented Jul 21, 2021

@tetienne as extra context, we have had @francodutch who also owns a Somfy gate. His gate closes automatically after 2 minutes, and this state is not refreshed. We implemented #271 as a test, see #167.

It seems that we need to refresh the state for some devices, HomeBridge calls this endpoint periodically. + they have similar problems, dubocr/homebridge-tahoma#282 (in French..)

@francodutch
Copy link

francodutch commented Jul 21, 2021

@jakovec i have the same behaviour. As @iMicknl said the change in state is not pushed after automatic closing nor is it after opening or closing with an IO remote control. An additional issue is that when I block the gate in open position with the files line control panel, the close command through HA is ineffective.

@themaninspain
Copy link

I also have a similar problem. My gate shows as open when it is closed and in fact never gives me a closed status.
Core = 2021.7.4
Tahoma = 2.5.1
Entity ID: cover.ixengo_io open

@tetienne
Copy link
Collaborator

@themaninspain entity_id is not enough to help up. Can you please open the devices page for your gate and give us the firmware that is displayed.
It looks like this
image

@themaninspain
Copy link

Device info
DiscreteGateWithPedestrianPosition
by Somfy
Firmware: io:DiscreteGateOpenerIOComponent

@tetienne
Copy link
Collaborator

Can you please try the master branch of this component (instead of choosing a version within HACS)?

@themaninspain
Copy link

Apologies for the dumb question: how do I do that?

@tetienne
Copy link
Collaborator

@themaninspain Within HACS, on ha-tahoma card, click on the 3 dots > reinstall > select master > install

@themaninspain
Copy link

Done and the gate now shows as closed.
Thanks

@github-actions
Copy link

'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.'

@tetienne
Copy link
Collaborator

OK. Can you please open your device then log in tahomalink.com, then:

  • Press F12 to open the dev tools
  • On the new displayed pane, click on Network
  • There is a filter bar, type fetch
  • You will see every seconds a new request the size is around 476 B
  • Now close your device
  • You will see the size of the fetch response increase
  • Open your device
  • Sort the requests in the network pane by size
  • Copy and paste here the content of each response where the size is not 476 B.

image

@tetienne
Copy link
Collaborator

tetienne commented Aug 30, 2021

@themaninspain If too complex for you, wait I update our code (during the week), I saw we miss some logs.

@themaninspain
Copy link

I can't check it now - I will do it in 2 hours time.

@themaninspain
Copy link

I think I'll wait for your update. Tried to capture the data but I couldn't copy it.

@themaninspain
Copy link

I got a notification of an update, which I installed yesterday.
The problem is still there.

@tetienne
Copy link
Collaborator

Sure, looking at the changelog you will see I didn't work on it.
It's a work in progress.

@themaninspain
Copy link

OK. I was going to suggest that if it would help we could do a video conference and I could share my screen so that you could see what is happening. (The fetch responses I could not capture)

@themaninspain
Copy link

Apologies, I have had some major electrical issues at home which have now been rectified.
Are you still interested in capturing the events?

@tetienne
Copy link
Collaborator

tetienne commented Oct 6, 2021

Hello, no problem with that.

We greatly improve our log, so be sure to use the latest version and have enabled the debug log.
Can you please open your gate, clear the log and close the gate, wait at least 30 seconds, then copy/paste the logs related to our components?
And same actions but opening the gate.

You will see, it’s a lot more verbose now.

@themaninspain
Copy link

themaninspain commented Oct 9, 2021

Done. Here's the log of the open/close event

2021-10-09 09:56:44 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1633766186433, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=8ecc7b3d-a2fe-4cad-96ed-bae383e6d98f, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-10-09 09:56:44 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.090 seconds (success: True)
2021-10-09 09:57:14 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.088 seconds (success: True)
2021-10-09 09:57:44 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1633766235211, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=8ecc7b3d-a2fe-4cad-96ed-bae383e6d98f, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-10-09 09:57:44 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.090 seconds (success: True)

@tetienne
Copy link
Collaborator

Again this unknown state.

Can you please log in on tahomalink website, save the page (ctrl+s), and attached a zip with the html page and the js files you will get?

@themaninspain
Copy link

ZipFile attached. Taken after an open/close operation. I hope this helps.
If not please let me know what else you need.
Tahoma.zip

@themaninspain
Copy link

themaninspain commented Oct 28, 2021

Just installed the latest version.
The gate status is now always open, here's the log file:

2021-10-28` 09:47:39 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635407238360, name='DeviceStateChangedEvent', setupoid=_CountingAttr(counter=260, _default=None, repr=<function obfuscate_id at 0x7f9abaa7bc10>, 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=None, exec_id=None, device_url=io://--9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-10-28 09:47:39 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.223 seconds (success: True)
2021-10-28 09:48:09 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.086 seconds (success: True)
2021-10-28 09:48:39 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635407299335, name='DeviceStateChangedEvent', setupoid=_CountingAttr(counter=260, _default=None, repr=<function obfuscate_id at 0x7f9abaa7bc10>, 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=None, exec_id=None, device_url=io://--9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)

@NicoLeOca
Copy link

NicoLeOca commented Oct 30, 2021

Hi

I also have the same issue, which "open" shown even if the device is closed.
Here are my logs, in case @tetienne can have a look

2021-10-30 18:30:15 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.010 seconds (success: True)
2021-10-30 18:30:16 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611415401, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='76.0')], old_state=None, new_state=None)
2021-10-30 18:30:16 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:17 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.014 seconds (success: True)
2021-10-30 18:30:18 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.016 seconds (success: True)
2021-10-30 18:30:19 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.010 seconds (success: True)
2021-10-30 18:30:20 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.028 seconds (success: True)
2021-10-30 18:30:21 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:22 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.010 seconds (success: True)
2021-10-30 18:30:23 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611422914, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='64.0')], old_state=None, new_state=None)
2021-10-30 18:30:23 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:24 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.036 seconds (success: True)
2021-10-30 18:30:25 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.025 seconds (success: True)
2021-10-30 18:30:26 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:27 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:28 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611427876, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='low'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='36.0'), State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='open')], old_state=None, new_state=None)
2021-10-30 18:30:28 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611427878, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, owner_key=c26db5f****-b2e****-43dd-87a****-04baa88c8829, type=1, sub_type=1, time_to_next_state=-1, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='d208f2d9-ac10-3e01-3fcf-0c13a90c4d61', deviceurl=None, device_states=[], old_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>, new_state=<ExecutionState.COMPLETED: 'COMPLETED'>)
2021-10-30 18:30:28 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.010 seconds (success: True)
2021-10-30 18:30:36 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611436383, name=<EventName.EXECUTION_REGISTERED: 'ExecutionRegisteredEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label='Home Assistant', metadata='', camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='d209e55d-ac10-3e01-3fcf-0c13c2165fca', deviceurl=None, device_states=[], old_state=None, new_state=None)
2021-10-30 18:30:36 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611436383, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, owner_key=c26db5f****-b2e****-43dd-87a****-04baa88c8829, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='d209e55d-ac10-3e01-3fcf-0c13c2165fca', deviceurl=None, device_states=[], old_state=<ExecutionState.INITIALIZED: 'INITIALIZED'>, new_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>)
2021-10-30 18:30:36 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611436383, name=<EventName.GATEWAY_SYNCHRONIZATION_STARTED: 'GatewaySynchronizationStartedEvent'>, setupoid=_CountingAttr(counter=338, _default=None, repr=<function obfuscate_id at 0x7f1ddd47ba60>, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-5836, exec_id=None, deviceurl=None, device_states=[], old_state=None, new_state=None)
2021-10-30 18:30:36 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:37 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611436590, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, owner_key=c26db5f****-b2e****-43dd-87a****-04baa88c8829, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='d209e55d-ac10-3e01-3fcf-0c13c2165fca', deviceurl=None, device_states=[], old_state=<ExecutionState.NOT_TRANSMITTED: 'NOT_TRANSMITTED'>, new_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>)
2021-10-30 18:30:37 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611436590, name=<EventName.GATEWAY_SYNCHRONIZATION_ENDED: 'GatewaySynchronizationEndedEvent'>, setupoid=_CountingAttr(counter=338, _default=None, repr=<function obfuscate_id at 0x7f1ddd47ba60>, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=****-****-5836, exec_id=None, deviceurl=None, device_states=[], old_state=None, new_state=None)
2021-10-30 18:30:37 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:38 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.017 seconds (success: True)
2021-10-30 18:30:39 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.019 seconds (success: True)
2021-10-30 18:30:40 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:41 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:42 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:43 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:44 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:45 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.027 seconds (success: True)
2021-10-30 18:30:46 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611445315, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='open')], old_state=None, new_state=None)
2021-10-30 18:30:46 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611445318, name=<EventName.EXECUTION_STATE_CHANGED: 'ExecutionStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, owner_key=c26db5f****-b2e****-43dd-87a****-04baa88c8829, type=1, sub_type=1, time_to_next_state=0, failed_commands=None, failure_type_code=None, failure_type=None, condition_groupoid=None, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id='d209e55d-ac10-3e01-3fcf-0c13c2165fca', deviceurl=None, device_states=[], old_state=<ExecutionState.TRANSMITTED: 'TRANSMITTED'>, new_state=<ExecutionState.IN_PROGRESS: 'IN_PROGRESS'>)
2021-10-30 18:30:46 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:47 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611446501, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='low'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='34.0'), State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-10-30 18:30:47 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:48 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:49 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:50 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611449945, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='76.0')], old_state=None, new_state=None)
2021-10-30 18:30:50 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.013 seconds (success: True)
2021-10-30 18:30:51 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:52 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:53 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611452952, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='64.0')], old_state=None, new_state=None)
2021-10-30 18:30:53 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:54 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.010 seconds (success: True)
2021-10-30 18:30:55 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.015 seconds (success: True)
2021-10-30 18:30:56 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611455803, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='58.0')], old_state=None, new_state=None)
2021-10-30 18:30:56 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:30:57 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:58 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:30:59 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.015 seconds (success: True)
2021-10-30 18:31:00 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611459051, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='72.0')], old_state=None, new_state=None)
2021-10-30 18:31:00 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.029 seconds (success: True)
2021-10-30 18:31:01 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:31:02 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611461717, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='70.0')], old_state=None, new_state=None)
2021-10-30 18:31:02 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.021 seconds (success: True)
2021-10-30 18:31:03 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.049 seconds (success: True)
2021-10-30 18:31:04 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.027 seconds (success: True)
2021-10-30 18:31:05 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611464691, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='66.0')], old_state=None, new_state=None)
2021-10-30 18:31:05 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:31:06 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.013 seconds (success: True)
2021-10-30 18:31:07 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.024 seconds (success: True)
2021-10-30 18:31:08 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611467303, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='68.0')], old_state=None, new_state=None)
2021-10-30 18:31:08 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:31:09 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.030 seconds (success: True)
2021-10-30 18:31:10 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611469564, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='70.0')], old_state=None, new_state=None)
2021-10-30 18:31:10 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.046 seconds (success: True)
2021-10-30 18:31:11 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:31:12 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611471719, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='44.0')], old_state=None, new_state=None)
2021-10-30 18:31:12 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.011 seconds (success: True)
2021-10-30 18:31:13 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.012 seconds (success: True)
2021-10-30 18:31:14 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1635611473609, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=c26db5f****-b2e****-43dd-87a****-04baa88c8829, 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, placeoid=None, label=None, metadata=None, camera_id=None, deleted_raw_devices_count=None, protocol_type=None, gateway_id=None, exec_id=None, deviceurl=io://****-****-5836/877679, device_states=[State(name='core:DiscreteRSSILevelState', type=<DataType.STRING: 3>, value='normal'), State(name='core:RSSILevelState', type=<DataType.FLOAT: 2>, value='62.0')], old_state=None, new_state=None)
2021-10-30 18:31:14 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.017 seconds (success: True)

@tetienne
Copy link
Collaborator

tetienne commented Nov 1, 2021

@NicoLeOca @themaninspain sorry to read this. This device is not easy to understand. Can you tell if you have position value for your gate? If yes the issue should probably comes from https://github.com/iMicknl/ha-tahoma/pull/578/files

@jakovec
Copy link
Author

jakovec commented Nov 1, 2021

Hi @tetienne,

have same issue with the gate as mentioned above - tried to catch the XHR in devtools, it looks like this:

click open, request is sent:

[{"timestamp":1635751667778,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da65a83f-ac10-3e01-3fcf-0c13e25e9bf7","label":"brana - Otevřít - TaHoma PC","metadata":"","type":1,"subType":1,"actions":[{"deviceURL":"io://1226-0828-9380/9623917","commands":[{"name":"open"}]}],"source":"mobile:mobile","owner":"*****@gmail.com","name":"ExecutionRegisteredEvent"},{"timestamp":1635751667778,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da65a83f-ac10-3e01-3fcf-0c13e25e9bf7","newState":"NOT_TRANSMITTED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"INITIALIZED","timeToNextState":0,"name":"ExecutionStateChangedEvent"},{"timestamp":1635751667778,"gatewayId":"1226-0828-9380","name":"GatewaySynchronizationStartedEvent"},{"timestamp":1635751667780,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da65a83f-ac10-3e01-3fcf-0c13e25e9bf7","newState":"TRANSMITTED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"NOT_TRANSMITTED","timeToNextState":0,"name":"ExecutionStateChangedEvent"},{"timestamp":1635751667781,"gatewayId":"1226-0828-9380","name":"GatewaySynchronizationEndedEvent"}]
{"startTime":1635751667778,"owner":"****@gmail.com","actionGroup":{"label":"brana - Otevřít - TaHoma PC","shortcut":false,"notificationTypeMask":0,"notificationCondition":"NEVER","actions":[{"deviceURL":"io://1226-0828-9380/9623917","commands":[{"type":1,"name":"open","parameters":[]}]}]},"description":"Execution : brana - Otevřít - TaHoma PC","id":"da65a83f-ac10-3e01-3fcf-0c13e25e9bf7","executionType":"Immediate execution","executionSubType":"MANUAL_CONTROL","state":"TRANSMITTED"}`

after some time, gate opens and two responses are read:

[{"timestamp":1635751623257,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","deviceURL":"io://1226-0828-9380/9623917","deviceStates":[{"name":"core:OpenClosedPedestrianState","type":3,"value":"open"}],"name":"DeviceStateChangedEvent"},{"timestamp":1635752193660,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da6cfcd2-ac10-3e01-3fcf-0c1339a901c7","newState":"COMPLETED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"IN_PROGRESS","timeToNextState":-1,"name":"ExecutionStateChangedEvent"}]
{"execution":{"eventTime":1635751667778,"commandLogs":[{"deviceURL":"io://1226-0828-9380/9623917","command":"open","parameters":[],"rank":0,"dynamic":false,"state":"COMPLETED","failureType":"NO_FAILURE"}],"owner":"******@gmail.com","source":"mobile:mobile","endTime":1635751713226,"effectiveStartTime":1635751667778,"duration":45448,"id":"da65a83f-ac10-3e01-3fcf-0c13e25e9bf7","label":"brana - Otevřít - TaHoma PC","type":"Immediate execution - MANUAL_CONTROL","commands":[{"deviceURL":"io://1226-0828-9380/9623917","command":"open","parameters":[],"rank":0,"dynamic":false,"state":"COMPLETED","failureType":"NO_FAILURE"}],"executionType":"Immediate execution","executionSubType":"MANUAL_CONTROL","state":"COMPLETED","failureType":"NO_FAILURE"}}`

gate is now opened ("deviceStates":[{"name":"core:OpenClosedPedestrianState","type":3,"value":"open"}])

next, I've sent the request to close:

[{"timestamp":1635752058816,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","label":"brana - Zavřít - TaHoma PC","metadata":"","type":1,"subType":1,"actions":[{"deviceURL":"io://1226-0828-9380/9623917","commands":[{"name":"close"}]}],"source":"mobile:mobile","owner":"*****@gmail.com","name":"ExecutionRegisteredEvent"},{"timestamp":1635752058816,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","newState":"NOT_TRANSMITTED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"INITIALIZED","timeToNextState":0,"name":"ExecutionStateChangedEvent"},{"timestamp":1635752058816,"gatewayId":"1226-0828-9380","name":"GatewaySynchronizationStartedEvent"},{"timestamp":1635752058847,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","newState":"TRANSMITTED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"NOT_TRANSMITTED","timeToNextState":0,"name":"ExecutionStateChangedEvent"},{"timestamp":1635752058848,"gatewayId":"1226-0828-9380","name":"GatewaySynchronizationEndedEvent"}]
{"startTime":1635752058816,"owner":"******@gmail.com","actionGroup":{"label":"brana - Zavřít - TaHoma PC","shortcut":false,"notificationTypeMask":0,"notificationCondition":"NEVER","actions":[{"deviceURL":"io://1226-0828-9380/9623917","commands":[{"type":1,"name":"close","parameters":[]}]}]},"description":"Execution : brana - Zavřít - TaHoma PC","id":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","executionType":"Immediate execution","executionSubType":"MANUAL_CONTROL","state":"TRANSMITTED"}`

and when the gate closed, it read following:

[{"timestamp":1635752104251,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","deviceURL":"io://1226-0828-9380/9623917","deviceStates":[{"name":"core:OpenClosedPedestrianState","type":3,"value":"closed"}],"name":"DeviceStateChangedEvent"},{"timestamp":1635752104253,"setupOID":"a84d2454-522f-4a36-a63b-a151923a7599","execId":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","newState":"COMPLETED","ownerKey":"a84d7599","type":1,"subType":1,"oldState":"IN_PROGRESS","timeToNextState":-1,"name":"ExecutionStateChangedEvent"}]`
{"execution":{"eventTime":1635752058816,"commandLogs":[{"deviceURL":"io://1226-0828-9380/9623917","command":"close","parameters":[],"rank":0,"dynamic":false,"state":"COMPLETED","failureType":"NO_FAILURE"}],"owner":"*****@gmail.com","source":"mobile:mobile","endTime":1635752104253,"effectiveStartTime":1635752058816,"duration":45437,"id":"da6b9fbe-ac10-3e01-3fcf-0c1335f5a591","label":"brana - Zavřít - TaHoma PC","type":"Immediate execution - MANUAL_CONTROL","commands":[{"deviceURL":"io://1226-0828-9380/9623917","command":"close","parameters":[],"rank":0,"dynamic":false,"state":"COMPLETED","failureType":"NO_FAILURE"}],"executionType":"Immediate execution","executionSubType":"MANUAL_CONTROL","state":"COMPLETED","failureType":"NO_FAILURE"}}

gate is closed now ("deviceStates":[{"name":"core:OpenClosedPedestrianState","type":3,"value":"closed"}])

I can provide you my tahomalink usr/pwd if you are interested to debug more...

*Just to note, I've changed the ownerKey string in JSON, it is not relevant to you...

Mira.

@themaninspain
Copy link

themaninspain commented Nov 6, 2021

Apologies for the delay, I've been away. Here is the log of an open/close event. When the gate is closed the state is "unknown"

2021-11-06 11:02:26 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1636192919544, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=_CountingAttr(counter=311, _default=None, repr=<function obfuscate_id at 0x7f3e9a67e1f0>, 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=None, exec_id=None, device_url=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-11-06 11:02:26 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.116 seconds (success: True)
2021-11-06 11:02:56 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.087 seconds (success: True)
2021-11-06 11:03:26 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1636192986731, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=_CountingAttr(counter=311, _default=None, repr=<function obfuscate_id at 0x7f3e9a67e1f0>, 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=None, exec_id=None, device_url=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)
2021-11-06 11:03:26 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.093 seconds (success: True)

@NicoLeOca
Copy link

@iMicknl
why does the select entity report the correct status when the gate is closed?
while the main cover entity considers the gate as open at the same time

@tetienne
Copy link
Collaborator

tetienne commented Nov 9, 2021

@iMicknl

why does the select entity report the correct status when the gate is closed?

while the main cover entity considers the gate as open at the same time

Good to know! The select is always right?

@NicoLeOca
Copy link

@tetienne
yes the select is always right

@iMicknl
Copy link
Owner

iMicknl commented Nov 14, 2021

The weird thing is that the select should be pulling from the same state, thus I wonder why this is always right... Is the select never set to 'unknown'?

@NicoLeOca
Copy link

NicoLeOca commented Nov 14, 2021

@iMicknl
your select only haas three states : open, closed and pedestrian
when the gate is moving, none of these 3 is shown in the select and the select is greyed.
If I remember well, when @tetienne created the first select it used to have an "unknown" state, which was shown during movement. (PS: don't introduce this state, it doesn't make much sense in the UI).

Apart of this, when the gate is physically in any of the 3 states, the select is always right

@tetienne
Copy link
Collaborator

Hi, sorry to have be silent these last days about this issue. Regarding the wrong state for the main entity, I guess it’s due to this line: https://github.com/iMicknl/ha-tahoma/blob/master/custom_components/tahoma/cover_devices/tahoma_cover.py#L106
Indeed, if I’m correct for the gate PedestrianPositionState is always set to 50, so we always displayed the gate as open. We should rollback our logic.

@tetienne
Copy link
Collaborator

@NicoLeOca
Copy link

@tetienne
it works! thanks a lot

@themaninspain
Copy link

I still have an "unknown" state after a gate open/close. Here is the log:

'DeviceStateChangedEvent'>, setupoid=_CountingAttr(counter=263, _default=None, repr=<function obfuscate_id at 0x7f6ade3cbb80>, 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=None, exec_id=None, device_url=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None) 2021-11-19 16:11:31 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.088 seconds (success: True) 2021-11-19 16:12:01 DEBUG (MainThread) [custom_components.tahoma] Finished fetching device events data in 0.097 seconds (success: True) 2021-11-19 16:12:31 DEBUG (MainThread) [custom_components.tahoma.coordinator] Event(timestamp=1637334728305, name=<EventName.DEVICE_STATE_CHANGED: 'DeviceStateChangedEvent'>, setupoid=_CountingAttr(counter=263, _default=None, repr=<function obfuscate_id at 0x7f6ade3cbb80>, 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=None, exec_id=None, device_url=io://****-****-9384/13850395, device_states=[State(name='core:OpenClosedPedestrianState', type=<DataType.STRING: 3>, value='unknown')], old_state=None, new_state=None)

@jakovec
Copy link
Author

jakovec commented Nov 25, 2021

@themaninspain @NicoLeOca Can you please try https://github.com/iMicknl/ha-tahoma/archive/refs/heads/fix/gate-position.zip?

This fix is working just fine! Thank you!

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
6 participants