home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
71.9k stars 30.12k forks source link

Home Assistant Elk integration does not discover Elk on non-secure port #69231

Closed DaveW42 closed 2 years ago

DaveW42 commented 2 years ago

The problem

The issue is described here: (https://community.home-assistant.io/t/elk-m1-interface/4461/982?u=davew42)

In short, the Elk integration currently does not permit configuring the Elk using a non-secure port (i.e., port 2101).

Logs are as follows:

2022-04-04 02:02:34 DEBUG (MainThread) [elkm1_lib.elk] got_data '66LW0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000091' 2022-04-04 02:02:48 DEBUG (MainThread) [homeassistant.components.elkm1] Timed out waiting for login event 2022-04-04 02:02:48 DEBUG (MainThread) [elkm1_lib.proto] disconnected callback 2022-04-04 02:02:48 WARNING (MainThread) [elkm1_lib.elk] ElkM1 at elk://x.x.x.x disconnected

Please let me know if there is any additional information that you need. Thank you for your help!

Dave

What version of Home Assistant Core has the issue?

core-2022.3.8

What was the last working version of Home Assistant Core?

No response

What type of installation are you running?

Home Assistant OS

Integration causing the issue

Elk

Link to integration documentation on our website

https://www.home-assistant.io/integrations/elkm1/

Diagnostics information

Can't configure Elk (see logs below)

Example YAML snippet

This is an auto-discovered integration.  However, the issue is also present when I use the following yaml:

elkm1:
  host: elk://x.x.x.x:2101
  auto_configure: true

Anything in the logs that might be useful for us?

Logs are as follows:

2022-04-04 02:02:34 DEBUG (MainThread) [elkm1_lib.elk] got_data '66LW0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000091'
2022-04-04 02:02:48 DEBUG (MainThread) [homeassistant.components.elkm1] Timed out waiting for login event
2022-04-04 02:02:48 DEBUG (MainThread) [elkm1_lib.proto] disconnected callback
2022-04-04 02:02:48 WARNING (MainThread) [elkm1_lib.elk] ElkM1 at elk://x.x.x.x disconnected

Additional information

Happy to answer any questions.

probot-home-assistant[bot] commented 2 years ago

elkm1 documentation elkm1 source (message by IssueLinks)

probot-home-assistant[bot] commented 2 years ago

Hey there @gwww, @bdraco, mind taking a look at this issue as it has been labeled with an integration (elkm1) you are listed as a code owner for? Thanks! (message by CodeOwnersMention)

bdraco commented 2 years ago

Is there a more complete log?

We should get a VN event back first if there is no login needed.

It looks like your log only has a LW event

DaveW42 commented 2 years ago

That was all I saw when I tried the first time, but based on your post I tried several more times and am hoping the log below may contain what you are looking for:

2022-04-04 21:55:59 DEBUG (MainThread) [homeassistant.components.elkm1] Waiting for login event for 20 seconds 2022-04-04 21:55:59 INFO (MainThread) [elkm1_lib.elk] Connecting to ElkM1 at elk://x.x.x.x 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.proto] connected callback 2022-04-04 21:55:59 INFO (MainThread) [elkm1_lib.elk] Connected to ElkM1 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.elk] Synchronizing panel... 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.proto] write_data '06vn0056' 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.proto] queueing write 06lw00 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.proto] queueing write 06ss00 2022-04-04 21:55:59 DEBUG (MainThread) [elkm1_lib.proto] queueing write 0Cua00000000 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.proto] write_data '06lw0057' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.elk] got_data '66LW0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000091' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.proto] write_data '06ss0054' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.elk] got_data '28SS00000000000000000000N00000000000000012' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.proto] write_data '0Cua0000000037' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.elk] got_data '17IC00000000000000000007C' 2022-04-04 21:56:04 DEBUG (MainThread) [elkm1_lib.elk] got_data '19UA000000003030129042F00E2' 2022-04-04 21:56:19 DEBUG (MainThread) [homeassistant.components.elkm1] Timed out waiting for login event 2022-04-04 21:56:19 DEBUG (MainThread) [elkm1_lib.proto] disconnected callback 2022-04-04 21:56:19 WARNING (MainThread) [elkm1_lib.elk] ElkM1 at elk://x.x.x.x disconnected

Here is another, but I think this basically replicates the one above:

2022-04-04 21:59:30 DEBUG (MainThread) [homeassistant.components.elkm1] Waiting for login event for 20 seconds 2022-04-04 21:59:30 INFO (MainThread) [elkm1_lib.elk] Connecting to ElkM1 at elk://x.x.x.x 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.proto] connected callback 2022-04-04 21:59:30 INFO (MainThread) [elkm1_lib.elk] Connected to ElkM1 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.elk] Synchronizing panel... 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.proto] write_data '06vn0056' 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.proto] queueing write 06lw00 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.proto] queueing write 06ss00 2022-04-04 21:59:30 DEBUG (MainThread) [elkm1_lib.proto] queueing write 0Cua00000000 2022-04-04 21:59:35 DEBUG (MainThread) [elkm1_lib.proto] write_data '06lw0057' 2022-04-04 21:59:35 DEBUG (MainThread) [elkm1_lib.elk] got_data '66LW0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000091' 2022-04-04 21:59:35 DEBUG (MainThread) [elkm1_lib.proto] write_data '06ss0054' 2022-04-04 21:59:36 DEBUG (MainThread) [elkm1_lib.elk] got_data '28SS00000000000000000000N00000000000000012' 2022-04-04 21:59:36 DEBUG (MainThread) [elkm1_lib.proto] write_data '0Cua0000000037' 2022-04-04 21:59:36 DEBUG (MainThread) [elkm1_lib.elk] got_data '17IC00000000000000000007C' 2022-04-04 21:59:36 DEBUG (MainThread) [elkm1_lib.elk] got_data '19UA000000003030129042F00E2' 2022-04-04 21:59:49 DEBUG (MainThread) [elkm1_lib.elk] got_data '66LW0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000091' 2022-04-04 21:59:50 DEBUG (MainThread) [homeassistant.components.elkm1] Timed out waiting for login event 2022-04-04 21:59:50 DEBUG (MainThread) [elkm1_lib.proto] disconnected callback 2022-04-04 21:59:50 WARNING (MainThread) [elkm1_lib.elk] ElkM1 at elk://x.x.x.x disconnected

Thanks! Dave

bdraco commented 2 years ago

It looks like for some reason your panel never responds to the vn request

gwww commented 2 years ago

Looks like the connection is non secure but the timer for auth success is started and fails, as it should, since secure connection not requested.