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

August plugin reports the wrong lock state (gen4, doorman l3) #54722

Closed
d96moe opened this issue Aug 16, 2021 · 46 comments
Closed

August plugin reports the wrong lock state (gen4, doorman l3) #54722

d96moe opened this issue Aug 16, 2021 · 46 comments

Comments

@d96moe
Copy link

d96moe commented Aug 16, 2021

The problem

The August plugin reports the Doorman L3 as unlocked when you lock it in secure (or secured away) mode.

What is version of Home Assistant Core has the issue?

core-2021.8.6

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

August plugin

Link to integration documentation on our website

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

Example YAML snippet

No response

Anything in the logs that might be useful for us?

No response

Additional information

No response

@probot-home-assistant
Copy link

august documentation
august source
(message by IssueLinks)

@probot-home-assistant
Copy link

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

@bdraco
Copy link
Member

bdraco commented Aug 16, 2021

The doorman l3 isn't supported at this time.

@bdraco
Copy link
Member

bdraco commented Aug 16, 2021

#54348

@d96moe d96moe changed the title August plugin remports the wrong lock state August plugin reports the wrong lock state Aug 16, 2021
@bdraco
Copy link
Member

bdraco commented Aug 18, 2021

Please turn on debug logging for yalexs and pubnub and provide logs.

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

@MortenVinding
Copy link

I tied to set up debug log for yalexs and pubnub, but it's not working.
Does any one know what's there full name?
I have:

logger:
default: info
logs:
homeassistant.components.yalexs: debug
homeassistant.components.pubnub: debug

@bdraco
Copy link
Member

bdraco commented Sep 7, 2021

logger:
  default: info
  logs:
     homeassistant.components.august: debug
     yalexs: debug
     pubnub: debug

@MortenVinding
Copy link

wow that was quick @bdraco!

I will test it as soon as I get home.
Thanks

@MortenVinding
Copy link

Here is what I hope is the relevant log lines.
I have tried to obfuscate it a bit (the XXX's), hope the meaning is still pressent.

2021-09-07 21:25:49 DEBUG (MainThread) [pubnub] {'t': {'t': '16310427496482006', 'r': 12}, 'm': [{'a': '5', 'f': 0, 'i': 'pn-bd5311e3-f983-4a3c-b0dc-XXXXXXXXXXXXXXX', 'p': {'t': '16310427496480796', 'r': 12}, 'k': 'sub-c-1030e062-0ebe-11e5-a5c2-XXXXXXXXXXXX', 'c': 'c3650509-4214-45d3-b120-XXXXXXXXXXXX', 'd': {'status': 'securemode', 'callingUserID': 'fb0edfd1-1af6-4925-b760-XXXXXXXXXXXXX'}}]}
2021-09-07 21:25:49 DEBUG (MainThread) [yalexs.pubnub_async] Recieved new messages on channel c3650509-4214-45d3-b120-XXXXXXXXXXXX for device_id: A5XXXXXXXXXXXXXXXXXXXXXXX with timetoken: 16310427496480796: {'status': 'securemode', 'callingUserID': 'fb0edfd1-1af6-4925-b760-XXXXXXXXXXXXX'}
2021-09-07 21:25:49 DEBUG (MainThread) [pubnub] GET https://ps.pndsn.com/v2/subscribe/sub-c-1030e062-0ebe-11e5-a5c2-XXXXXXXXXXXX/c3650509-4214-45d3-b120-XXXXXXXXXXXX/0?tt=16310427496482006&tr=12&pnsdk=PubNub-Python-Asyncio%2F5.1.4&uuid=pn-FB0EDFD1-1AF6-4925-B760-XXXXXXXXXXX&l_pres=0.1216728687286377 None
2021-09-07 21:25:52 DEBUG (MainThread) [pubnub] {'t': {'t': '16310427526422576', 'r': 12}, 'm': [{'a': '5', 'f': 0, 'i': 'pn-11805b84-e8f3-4d8e-a046-b682aee0fe32', 'p': {'t': '16310427526432774', 'r': 12}, 'k': 'sub-c-1030e062-0ebe-11e5-a5c2-XXXXXXXXXXXX', 'c': 'c3650509-4214-45d3-b120-XXXXXXXXXXXX', 'd': {'status': 'securemode', 'callingUserID': 'fb0edfd1-1af6-4925-b760-XXXXXXXXXXXXX'}}]}
2021-09-07 21:25:52 DEBUG (MainThread) [yalexs.pubnub_async] Recieved new messages on channel c3650509-4214-45d3-b120-XXXXXXXXXXXX for device_id: A5XXXXXXXXXXXXXXXXXXXXXXX with timetoken: 16310427526432774: {'status': 'securemode', 'callingUserID': 'fb0edfd1-1af6-4925-b760-XXXXXXXXXXXXX'}

Please let me know if I should try to finde some other info.

Did also try the doorbell, but haven't found the relevant log lines yet. I will look for them now.

@bdraco
Copy link
Member

bdraco commented Sep 7, 2021 via email

@MortenVinding
Copy link

hmm can't seem to find anything relevant to the doorbell.
Maybe we need to request updates for that separately?

This is what I can find in the last status update from the lock:

$ head -n 1563 yale-lock2.log | tail -n 1 | grep -o "\[{.*\]" | jq "." | grep -i action
    "action": "lock",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:35:40 GMT+0000 (Coordinated Universal Time)lock"
    "action": "doorclosed",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:31 GMT+0000 (Coordinated Universal Time)doorclosed"
    "action": "dooropen",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:25 GMT+0000 (Coordinated Universal Time)dooropen"
    "action": "doorclosed",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:17 GMT+0000 (Coordinated Universal Time)doorclosed"
    "action": "dooropen",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:12 GMT+0000 (Coordinated Universal Time)dooropen"
    "action": "doorclosed",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:09 GMT+0000 (Coordinated Universal Time)doorclosed"
    "action": "dooropen",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:07 GMT+0000 (Coordinated Universal Time)dooropen"
    "action": "unlock",
      "action": "unlock",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:26:06 GMT+0000 (Coordinated Universal Time)unlock"
    "action": "lock",
      "action": "lock",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:25:49 GMT+0000 (Coordinated Universal Time)lock"
    "action": "doorclosed",
      "DateLogActionID": "XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 07 2021 19:25:43 GMT+0000 (Coordinated Universal Time)doorclosed"

The doorbell button was pushed between 19:26:17 and 19:26:25

@MortenVinding
Copy link

BTW:
My Home Assistant history log show the lock and unlock state correct, all though it doesn't distinguish between normal locked and secure locked.

@bdraco
Copy link
Member

bdraco commented Sep 7, 2021 via email

@bdraco
Copy link
Member

bdraco commented Sep 7, 2021 via email

@MortenVinding
Copy link

Thanks @bdraco

It a bit late here now, so I will try to get a "clean" log for secure locking and unlocking tomorrow. And use consistent obfuscating on the ID's tomorrow.

I personally is not to interested in the secure lock, after all my HA is representing the lock state correctly, and I don't think I will use the secure mode much (you have to manually activate it each time).

But I would be very interested if it was possible to get the doorbell notifications, so it could trigger my surveillance cameras.
Is there any specific logs you will need for that? (other than a "clean" log, with consistent obfuscated ID's).

@bdraco
Copy link
Member

bdraco commented Sep 8, 2021 via email

@MortenVinding
Copy link

okay I will try to get a log.
right now is the connect gw however offline... again... so I can't do it.
I have a support case open with Yale about that. do you experience the same, or am I just unlucky?
my network consist of 2 x Unifi NanoHD's and a Mikrotik Hex S router.

@bdraco
Copy link
Member

bdraco commented Sep 8, 2021

okay I will try to get a log.

right now is the connect gw however offline... again... so I can't do it.

I have a support case open with Yale about that. do you experience the same, or am I just unlucky?

my network consist of 2 x Unifi NanoHD's and a Mikrotik Hex S router.

UniFi 5.60.9 firmware?

If so grab 50.60.13 from the beta channel

@MortenVinding
Copy link

UniFi 5.60.9 firmware?

If so grab 50.60.13 from the beta channel

yes.
sorry for the delay, busy with other things, but your help is highly appreciated 😊
So I just signed up for the beta and installed it on both my AP's, hope that it's better.

Do you self use 5.60.13, and do you feel any difference?

I will test the doorbell and get the logs as soon as I have the time (when the family isn't sleeping 😉)

@bdraco
Copy link
Member

bdraco commented Sep 13, 2021

Do you self use 5.60.13, and do you feel any difference?

Yes, with .9, I can't keep the connects online. .13 is stable for me. I upgraded all of sites and haven't had an issue since.

@MortenVinding
Copy link

cool! hope it will be the same for me then 👍

@MortenVinding
Copy link

MortenVinding commented Sep 15, 2021

didn't seem to help.
my gateway was offline the whole day yesterday, but I just mannaged to get it online again, and I have tested the doorbell.

here is the anonymized log from that. the time where I pushed the doorbell is in the middle separated with a couple of newlines. (2021-09-15 09:30:02)

what I guess is status updates just before and after, is coming every 10 seconds.

I hope it is useful.

2021-09-15 09:29:57 DEBUG (MainThread) [homeassistant.components.august.activity] Start retrieving device activities
2021-09-15 09:29:57 DEBUG (MainThread) [homeassistant.components.august.activity] Updating device activity for house id 11111111-2222-3333-4444-555555555555
2021-09-15 09:29:57 DEBUG (MainThread) [yalexs.api_async] About to call https://api-production.august.com/houses/11111111-2222-3333-4444-555555555555/activities with header={'Accept-Version': '0.0.1', 'x-august-api-key': 'aaaaaaaa-bbbb-cccc-dddd-eeeeeeeeeeee', 'x-kease-api-key': 'aaaaaaaa-bbbb-cccc-dddd-eeeeeeeeeeee', 'Content-Type': 'application/json', 'User-Agent': 'August/2019.12.16.4708 CFNetwork/1121.2.2 Darwin/19.3.0', 'x-august-access-token': '<secret-access-key>'} and payload={'limit': 10}
2021-09-15 09:29:57 DEBUG (MainThread) [yalexs.api_async] Received API response: 200, b'[{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141896de00a70b1d085f9be"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"64dfbb3f-41b6-4ee3-b866-a52de3a366fa","dateTime":1631684971000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:49:31 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141896168b4dc9625b58a12"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"cb4a6d84-030b-46c7-b838-0889bb6d2f27","dateTime":1631684956000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:49:16 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"614187547c3cc843166f4067"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"11fcf21f-07e3-4fda-99fc-02c562554411","dateTime":1631684434000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:40:34 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141874f31124b47b75474b1"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"719445da-a430-4826-a5cf-f89cda09cf77","dateTime":1631684428000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:40:28 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141734b60e2bbaefca1ce8b"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"145cabd3-0370-4feb-a5b0-718e0063fa1c","dateTime":1631679306000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:15:06 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141733f673f574ad5baeddc"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"7b588be8-2a1d-4a43-bfc6-c7336be10d5c","dateTime":1631679292000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:14:52 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141713ab6f5db9421f999a6"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"956d7d49-acbb-4df5-8d61-f2be3ff1bfec","dateTime":1631678777000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:17 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"61417135d5accf8787ab4f54"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"f38a09d9-ae23-4c73-95dc-72de3bb71011","dateTime":1631678771000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:11 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"manualunlock","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"61417135e00a702d2b857c1e"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"89275f0e-b8ee-4e20-9288-2ed1a3d5f8d8","dateTime":1631678770000,"action":"unlock","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"FirstName":"Manual","LastName":"Unlock","UserID":"manualunlock"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:10 GMT+0000 (Coordinated Universal Time)unlock"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"manuallock","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141255eb26d2141bf5dcf8b"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"d7a1c7db-dfca-4330-9ad8-9c1beb782789","dateTime":1631656671000,"action":"lock","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"FirstName":"Manual","LastName":"Lock","UserID":"manuallock"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 14 2021 21:57:51 GMT+0000 (Coordinated Universal Time)lock"}}]'
2021-09-15 09:29:57 DEBUG (MainThread) [homeassistant.components.august.activity] Completed retrieving device activities for house id 11111111-2222-3333-4444-555555555555


2021-09-15 09:30:02 DEBUG (MainThread) [pubnub] {'t': {'t': '16316910027439234', 'r': 12}, 'm': [{'a': '5', 'f': 0, 'i': 'pn-15a9f0a2-be99-40e1-917a-abb838211445', 'p': {'t': '16316910027431681', 'r': 12}, 'k': 'sub-c-a1a1a1a1-a1a1-a1a1-a1a1-a1a1a1a1a1a1', 'c': 'b2b2b2b2-b2b2-b2b2-b2b2-b2b2b2b2b2b2', 'd': {'status': 'unlocked', 'callingUserID': 'manualunlock', 'doorState': 'closed'}}]}
2021-09-15 09:30:02 DEBUG (MainThread) [yalexs.pubnub_async] Recieved new messages on channel b2b2b2b2-b2b2-b2b2-b2b2-b2b2b2b2b2b2 for device_id: XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX with timetoken: 16316910027431681: {'status': 'unlocked', 'callingUserID': 'manualunlock', 'doorState': 'closed'}
2021-09-15 09:30:02 DEBUG (MainThread) [pubnub] GET https://ps.pndsn.com/v2/subscribe/sub-c-a1a1a1a1-a1a1-a1a1-a1a1-a1a1a1a1a1a1/b2b2b2b2-b2b2-b2b2-b2b2-b2b2b2b2b2b2/0?tt=16316910027439234&tr=12&pnsdk=PubNub-Python-Asyncio%2F5.1.4&uuid=pn-ABABABAB-ABAB-ABAB-ABAB-ABABABABABAB None


2021-09-15 09:30:07 DEBUG (MainThread) [homeassistant.components.august.activity] Start retrieving device activities
2021-09-15 09:30:07 DEBUG (MainThread) [homeassistant.components.august.activity] Updating device activity for house id 11111111-2222-3333-4444-555555555555
2021-09-15 09:30:07 DEBUG (MainThread) [yalexs.api_async] About to call https://api-production.august.com/houses/11111111-2222-3333-4444-555555555555/activities with header={'Accept-Version': '0.0.1', 'x-august-api-key': 'aaaaaaaa-bbbb-cccc-dddd-eeeeeeeeeeee', 'x-kease-api-key': 'aaaaaaaa-bbbb-cccc-dddd-eeeeeeeeeeee', 'Content-Type': 'application/json', 'User-Agent': 'August/2019.12.16.4708 CFNetwork/1121.2.2 Darwin/19.3.0', 'x-august-access-token': '<secret-access-key>'} and payload={'limit': 10}
2021-09-15 09:30:07 DEBUG (MainThread) [yalexs.api_async] Received API response: 200, b'[{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141896de00a70b1d085f9be"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"64dfbb3f-41b6-4ee3-b866-a52de3a366fa","dateTime":1631684971000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:49:31 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141896168b4dc9625b58a12"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"cb4a6d84-030b-46c7-b838-0889bb6d2f27","dateTime":1631684956000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:49:16 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"614187547c3cc843166f4067"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"11fcf21f-07e3-4fda-99fc-02c562554411","dateTime":1631684434000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:40:34 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141874f31124b47b75474b1"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"719445da-a430-4826-a5cf-f89cda09cf77","dateTime":1631684428000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 05:40:28 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141734b60e2bbaefca1ce8b"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"145cabd3-0370-4feb-a5b0-718e0063fa1c","dateTime":1631679306000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:15:06 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141733f673f574ad5baeddc"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"7b588be8-2a1d-4a43-bfc6-c7336be10d5c","dateTime":1631679292000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:14:52 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141713ab6f5db9421f999a6"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"956d7d49-acbb-4df5-8d61-f2be3ff1bfec","dateTime":1631678777000,"action":"doorclosed","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:17 GMT+0000 (Coordinated Universal Time)doorclosed"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"deleted","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"61417135d5accf8787ab4f54"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"f38a09d9-ae23-4c73-95dc-72de3bb71011","dateTime":1631678771000,"action":"dooropen","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:11 GMT+0000 (Coordinated Universal Time)dooropen"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"manualunlock","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"61417135e00a702d2b857c1e"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"89275f0e-b8ee-4e20-9288-2ed1a3d5f8d8","dateTime":1631678770000,"action":"unlock","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"FirstName":"Manual","LastName":"Unlock","UserID":"manualunlock"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Wed Sep 15 2021 04:06:10 GMT+0000 (Coordinated Universal Time)unlock"}},{"entities":{"device":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","callingUser":"manuallock","otherUser":"deleted","house":"11111111-2222-3333-4444-555555555555","activity":"6141255eb26d2141bf5dcf8b"},"house":{"houseID":"11111111-2222-3333-4444-555555555555","houseName":"Casa de Svendsens"},"eventID":"d7a1c7db-dfca-4330-9ad8-9c1beb782789","dateTime":1631656671000,"action":"lock","deviceName":"Front Door","deviceID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX","deviceType":"lock","callingUser":{"FirstName":"Manual","LastName":"Lock","UserID":"manuallock"},"otherUser":{"UserID":"deleted","FirstName":"Unknown","LastName":"User","UserName":"deleteduser","PhoneNo":"deleted"},"info":{"DateLogActionID":"XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX+Tue Sep 14 2021 21:57:51 GMT+0000 (Coordinated Universal Time)lock"}}]'
2021-09-15 09:30:07 DEBUG (MainThread) [homeassistant.components.august.activity] Completed retrieving device activities for house id 11111111-2222-3333-4444-555555555555

@bdraco
Copy link
Member

bdraco commented Sep 15, 2021

There doesn't seem to be anything related to the doorbell in the log.

Does it show up in the activity log in the app?

@MortenVinding
Copy link

yes it's in the log in the app:
IMG_2655

I just tried again but could not see anything in the log.
but when I checked the app, I realised the gateway was offline again 😔 (no notifications from the app either)

@bdraco
Copy link
Member

bdraco commented Sep 15, 2021

Good that its showing up in the app as that usually means it is in the activity feed. It may not be showing up though because of the wifi issue

@MortenVinding
Copy link

Yes I hope.

Just had a look through the activities in home assistant log "Updating device activity for house id ....."
and there is no signs of the doorbell. but there is enteries from before I pushed it and after.

Is the activity feed build according to request (so you specify types of activities to retrieve) or is it just general?

If it's the last thing, I'm pretty sure the doorbell is not in it, and the app must receive it from somewhere else.

@MortenVinding
Copy link

by no signs of the doorbell in home assistant log I mean:
grep -P -o '"action":".*?"' /home/homeassistant/.homeassistant/home-assistant.log | grep -v "doorclosed|dooropen|lock"
returns empty.

but there is lots of info about open/close/lock

@github-actions
Copy link

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates.
Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍
This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.

@github-actions github-actions bot added the stale label Dec 14, 2021
@ttaidapos
Copy link

I was also tracking this as I have the same issue. Also on Gen4 of the lock. I noticed it because I have a mobile hass app alert when the door remains open for more than 5min. The alert now fires about a minute or two after the door is closed and locked about 6min after the elapsed time. Hass is not registering the closed/locked state which triggers the notification. This all changed a few months ago and it was working 100% for a very long time. In my case, the integration doesn't get the updated status as the OP mentioned.

image

- alias: Side door open message
  trigger:
    platform: state
    entity_id: lock.side_door
    to: 'unlocked'
    for:
      minutes: 5
  action:
    service: notify.all_devices
    data:
      message: "Side door left open for 5 minutes."
      data:
        color: 'red'
        sticky: 'true'

@github-actions github-actions bot removed the stale label Dec 15, 2021
@bdraco
Copy link
Member

bdraco commented Dec 16, 2021

There haven't been any significant changes to the underlying api module https://github.com/bdraco/yalexs/tags since March

If something isn't working now its likely:

  • A problem with the connect module
  • Poor wifi signal leading to dropped pubnub messages
    Or:
  • An api change on August's side

Either way, we probably can't fix this

@ttaidapos
Copy link

There haven't been any significant changes to the underlying api module https://github.com/bdraco/yalexs/tags since March

If something isn't working now its likely:

  • A problem with the connect module
  • Poor wifi signal leading to dropped pubnub messages
    Or:
  • An api change on August's side

Either way, we probably can't fix this

Thanks for the prompt reply. This is a gen4 lock, not sure if it works differently. WiFi is built-in therefore there's no issue with the connect module like gen3 locks. Strong signal in both of my locks and same issue. I'll re-install the integration again to be sure but I have a feeling it's an API issue.

@bdraco
Copy link
Member

bdraco commented Dec 16, 2021

I have 52 august locks in service without the problem. However I have trashed all the August connects and replaced them with the new Yale connect modules.

None of them are the gen4 or the doorman l3 so it might be something new in these models, and thus something new in the api

@bdraco
Copy link
Member

bdraco commented Dec 16, 2021

It wouldn't surprise me if its something new in the gen4 since #57024 also shows they changed the battery reporting

@ttaidapos
Copy link

It wouldn't surprise me if its something new in the gen4 since #57024 also shows they changed the battery reporting

Has to be and it makes sense since I also have the battery issue. To be honest though, August has been harassing me for critically low battery for two weeks now lol HA is showing 55%, go figure. They do however send the same email even if your keypad is low on batter and your lock isn't! My keypad has been on 10% for two weeks and just today it flipped to "very low". I'll see how long I can ride this and adjust my HA notifications accordingly.

Back to this though, yes, wrong status is definitely being reported. For gen3 I used z-wave vs. WiFi for integration, much faster and accurate.

@bdraco bdraco changed the title August plugin reports the wrong lock state August plugin reports the wrong lock state (gen4, doorman l3) Dec 17, 2021
@bdraco
Copy link
Member

bdraco commented Jan 15, 2022

#64027 might help with this issue, but since I've never actually seen it happen, I'm not sure either way

@bdraco
Copy link
Member

bdraco commented Jan 20, 2022

If anyone feels comfortable sharing their lock, I setup an account for testing/dev. It was helpful for fixing the Gen1/Gen2 locks:

#64023 (comment)

@MortenVinding
Copy link

sorry if I pollute this thread but I think this might be the best place to ask:
I just looked at #64023 about the switch to async and was wondering if that might also solve at problem of mine.

When someone unlocks my Yale Doorman L3 I (normally) immediately get an update in HA about the lock being unlocked, but only after 15 seconds (seems to be consistent) is the "operator" updated.

the problem is, that I would like to use the unlock state to disarm my alarm system (also in HA), but ONLY if it wasn't manually unlocked (don't want an intruder to be able to disarm by simply unlocking the door ;) )
so does the async also means that I might get a faster update on the "operator" status?
15 seconds is a long time waiting for HA to (hopefully) disarm the alarm system while it is beeping at me...

@bdraco
Copy link
Member

bdraco commented Jan 22, 2022

When someone unlocks my Yale Doorman L3 I (normally) immediately get an update in HA about the lock being unlocked, but only after 15 seconds (seems to be consistent) is the "operator" updated.

There isn't a faster way to do it since we have to poll for the operator when we get a locked event

@MortenVinding
Copy link

MortenVinding commented Jan 22, 2022

There isn't a faster way to do it since we have to poll for the operator when we get a locked event

wow you are FAST! (faster than the August servers :) )

so the 15 seconds is the time it takes to poll the August servers? And there is now way this could be shortened?

@bdraco
Copy link
Member

bdraco commented Jan 22, 2022

so the 15 seconds is the time it takes to poll the August servers? And there is now way this could be shortened?

Nope. There are two different endpoints. The one that provides the operator is always a few seconds behind the pubsub one

@MortenVinding
Copy link

Nope. There are two different endpoints. The one that provides the operator is always a few seconds behind the pubsub one

okay. thank you for your prompt replies.
I guess I must put my hopes on, someday, somehow getting the "offline key" so i can integrate the lock directly by bluetooth then.

or getting the zigbee module I know exists but Yale refuses to sell me :(

@bdraco
Copy link
Member

bdraco commented Jan 22, 2022

The offline key is in the lock detail payload.

@MortenVinding
Copy link

The offline key is in the lock detail payload.

WHAT? so you are saying I can get it from the logs? or am I missing something?

@bdraco
Copy link
Member

bdraco commented Jan 22, 2022

Turn on debug logging for yalexs. It should be in there

@MortenVinding
Copy link

Turn on debug logging for yalexs. It should be in there

wow it is indeed!

{
  "created": [],
  "loaded": [
    {
      "created": "2021-09-07T19:25:49.512Z",
      "key": "1e4378a3569562a7b7d7c2638c57a72",
      "slot": 5,
      "UserID": "763f5a6b-12ca-62a7-87dc-98f3d3687a67",
      "loaded": "2021-09-14T22:43:06.745Z"
    }
  ],
  "deleted": []
}

obfuscated

awesome!
now to se if I can make it work with the bluetooth scripts.

thank you!

@bdraco
Copy link
Member

bdraco commented Jan 26, 2022

If someone wants to take me up on the offer to share their lock please send me an email and see #54722 (comment)

Since we use GitHub for tracking issues, not for providing support or tracking feature requests, I'm going to close this now as support for the doorman l3 amounts to a feature request, and with no access to the lock, it isn't likely to come soon unless someone is willing to share.

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

No branches or pull requests

4 participants