Aoetec Doorbell 6 ZW162 connection issues

Tags: #<Tag:0x00007f73ac659e98>

I’ve been running into some issues connecting the Doorbell 6 to my home assistant core installation on my pi4. Using the regular z-wave integration, I could not add it at all, so I switched to the OpenZwave integration using the docker image.

From there I still could not add the Doorbell 6, even though the controller (ZME-UZB1 stick) is recognized and can be configured (commands like heal/add/cancel etc work). I had to revert to using my windows system to add the Doorbell 6 node to the network, using the instructions I found on: https://diymediahome.org/getting-openzwave-working-with-home-assistant/

I was also able to change the volume there, so the device itself seems to be OK. When switching things back over to my pi4 however, the node is detected in the network but it does not really respond to commands. Name and manufacturer information is displayed as empty in the OpenZwave GUI and while the node is visible in the HA integration configuration as a ‘sound switch’, I can’t really do anything with it. Refreshing the node makes no difference, though at times it seems to register as a ‘dead node’. The Doorbel 6 itself indicates it is connected however through led light status information (led off = connected).

I have tried healing the network, rebooting HA, the docker image and the pi4 etc, to no avail. I’m pretty new to HA and Zwave so I might be missing something here. Anyone have an idea on how to proceed?

I’d recommend migrating to Hassos, then using the managed addons to get OpenZwave working. It’s real simple in that environment. After that, you should not have problems. I"m running this chime just fine. See here for more information on my setup Aeotech ZW162 Doorbell

The device uses MQTT for everything and there is no official support yet, but you can support it yourself, and it’s not that hard.

Do you maybe have a range issue with the Doorbell and your Pi4? Did you wake the device to get the correct initialization status?

The Doorbell and Pi4 are next to each other, so that can’t be the issue. Device should be in the correct initialization status as well, as I press the phsyical button on the device which causes the association sequence to start. This is confirmed by the LED light pulsing with high frequency.

After looking at the logs for a bit more, it seems that it doesn’t really swap to Encrypted mode. I get the message “Encrypted Flag is 0” as a status message. This happens both when I try to add the device from the openzwave VNC environment selecting “Yes” as option when I get the question “Do you wish to include the new device with encryption”, as well as when I have already added the device using my windows 10 machine.

For some reason, the openzwave/docker service does not want to communicate to the Doorbell using encryption I think.

I believe the encrypted flag isn’t supported by all devices. As long as you set the network security key in your Docker it should be fine. Which docker image and what version are you running? If it’s the openzwave/ozwdaemon image you should be running version 150 because of issues in later builds.

In the OZW Admin tool, can you check the query stage? If you select the node, under the Node Status it should be visible.
image

The device definitely supports Encrypted mode. Without it, not all functions are exposed. I successfully paired it to the network on my windows 10 pc using encryption.

I was using the -latest docker image, but based on your advice I’ve changed to version 150 to no avail. The node remains unresponsive.

Here’s what the node looks like in the manager:

While the node says “awake”, it is functionally dead / nonresponsive. And here’s the output for the ozwdaemon.log when I selected “Refresh node info” in the manager.

20201029 18:47:03.041 UTC] [ozw.library] [debug]: Detail - Node: 18   Received: 0x01, 0x18, 0x00, 0x13, 0x0b, 0x01, 0x02, 0xc0, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x42
[20201029 18:47:03.042 UTC] [ozw.library] [debug]: Detail - Node: 18   ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
[20201029 18:47:03.042 UTC] [ozw.library] [debug]: Detail - Node: 18 Extended TxStatus: Time: 7040, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
[20201029 18:47:03.042 UTC] [ozw.library] [info]: Info - Node: 18 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
[20201029 18:47:03.042 UTC] [ozw.library] [warning]: Warning - Node: 18 WARNING: Device is not a sleeping node.
[20201029 18:47:03.042 UTC] [ozw.library] [info]: Info - Node: 18 QueryStageRetry stage Probe requested stage Probe max 3 retries 0 pending 1
[20201029 18:47:03.042 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected callbackId was received
[20201029 18:47:03.043 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected reply was received
[20201029 18:47:03.043 UTC] [ozw.library] [debug]: Detail - Node: 18   Message transaction complete
[20201029 18:47:03.043 UTC] [ozw.library] [debug]: Detail - Node: 18 Removing current message
[20201029 18:47:03.043 UTC] [ozw.library] [debug]: Detail - Node: 18 Notification: Notification - NoOperation
[20201029 18:47:03.043 UTC] [ozw.library] [debug]: Detail - Node: 18 Query Stage Complete (Probe)
[20201029 18:47:03.043 UTC] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0xb6f04410
[20201029 18:47:03.044 UTC] [ozw.library] [debug]: Detail - Node: 18 AdvanceQueries queryPending=0 queryRetries=1 queryStage=Probe live=1
[20201029 18:47:03.044 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20201029 18:47:03.044 UTC] [ozw.library] [debug]: Detail - Node: 18 QueryStage_Probe
[20201029 18:47:03.044 UTC] [ozw.library] [info]: Info - Node: 18 NoOperation::Set - Routing=true
[20201029 18:47:03.044 UTC] [ozw.library] [debug]: Detail - Node: 18 Queuing (NoOp) NoOperation_Set (Node=18): 0x01, 0x09, 0x00, 0x13, 0x12, 0x02, 0x00, 0x00, 0x25, 0x0c, 0xdc
[20201029 18:47:03.044 UTC] [ozw.library] [debug]: Detail - Node: 18 Queuing (Query) Query Stage Complete (Probe)
[20201029 18:47:03.044 UTC] [ozw.library] [info]: Info - Node: 18 Sending (NoOp) message (Callback ID=0x0c, Expected Reply=0x13) - NoOperation_Set (Node=18): 0x01, 0x09, 0x00, 0x13, 0x12, 0x02, 0x00, 0x00, 0x25, 0x0c, 0xdc
[20201029 18:47:03.045 UTC] [ozw.library] [info]: Info - Node: 18 Encrypted Flag is 0
[20201029 18:47:03.053 UTC] [ozw.library] [debug]: Detail - Node: 18   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20201029 18:47:03.053 UTC] [ozw.library] [debug]: Detail - Node: 18   ZW_SEND_DATA delivered to Z-Wave stack
[20201029 18:47:05.768 UTC] [ozw.library] [info]: Info - Node: 0 HttpThread Exiting. No Transfers in timeout period
[20201029 18:47:09.938 UTC] [ozw.library] [debug]: Detail - Node: 18   Received: 0x01, 0x18, 0x00, 0x13, 0x0c, 0x01, 0x02, 0xc1, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x44
[20201029 18:47:09.939 UTC] [ozw.library] [debug]: Detail - Node: 18   ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
[20201029 18:47:09.939 UTC] [ozw.library] [debug]: Detail - Node: 18 Extended TxStatus: Time: 7050, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
[20201029 18:47:09.940 UTC] [ozw.library] [info]: Info - Node: 18 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
[20201029 18:47:09.940 UTC] [ozw.library] [warning]: Warning - Node: 18 WARNING: Device is not a sleeping node.
[20201029 18:47:09.940 UTC] [ozw.library] [info]: Info - Node: 18 QueryStageRetry stage Probe requested stage Probe max 3 retries 1 pending 1
[20201029 18:47:09.941 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected callbackId was received
[20201029 18:47:09.941 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected reply was received
[20201029 18:47:09.941 UTC] [ozw.library] [debug]: Detail - Node: 18   Message transaction complete
[20201029 18:47:09.942 UTC] [ozw.library] [debug]: Detail - Node: 18 Removing current message
[20201029 18:47:09.942 UTC] [ozw.library] [debug]: Detail - Node: 18 Notification: Notification - NoOperation
[20201029 18:47:09.942 UTC] [ozw.library] [debug]: Detail - Node: 18 Query Stage Complete (Probe)
[20201029 18:47:09.942 UTC] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0xb6f04410
[20201029 18:47:09.943 UTC] [ozw.library] [debug]: Detail - Node: 18 AdvanceQueries queryPending=0 queryRetries=2 queryStage=Probe live=1
[20201029 18:47:09.943 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20201029 18:47:09.943 UTC] [ozw.library] [debug]: Detail - Node: 18 QueryStage_Probe
[20201029 18:47:09.944 UTC] [ozw.library] [info]: Info - Node: 18 NoOperation::Set - Routing=true
[20201029 18:47:09.944 UTC] [ozw.library] [debug]: Detail - Node: 18 Queuing (NoOp) NoOperation_Set (Node=18): 0x01, 0x09, 0x00, 0x13, 0x12, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xdd
[20201029 18:47:09.944 UTC] [ozw.library] [debug]: Detail - Node: 18 Queuing (Query) Query Stage Complete (Probe)
[20201029 18:47:09.945 UTC] [ozw.library] [info]: Info - Node: 18 Sending (NoOp) message (Callback ID=0x0d, Expected Reply=0x13) - NoOperation_Set (Node=18): 0x01, 0x09, 0x00, 0x13, 0x12, 0x02, 0x00, 0x00, 0x25, 0x0d, 0xdd
[20201029 18:47:09.945 UTC] [ozw.library] [info]: Info - Node: 18 Encrypted Flag is 0
[20201029 18:47:09.957 UTC] [ozw.library] [debug]: Detail - Node: 18   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
[20201029 18:47:09.958 UTC] [ozw.library] [debug]: Detail - Node: 18   ZW_SEND_DATA delivered to Z-Wave stack
[20201029 18:47:15.000 UTC] [ozw.library] [debug]: Detail - Node: 18   Received: 0x01, 0x18, 0x00, 0x13, 0x0d, 0x01, 0x01, 0xf9, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0x7e
[20201029 18:47:15.000 UTC] [ozw.library] [debug]: Detail - Node: 18   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
[20201029 18:47:15.000 UTC] [ozw.library] [debug]: Detail - Node: 18 Extended TxStatus: Time: 5050, Hops: 0, Rssi: --- --- --- --- ---, AckChannel: 0, TxChannel: 0, RouteScheme: Explorer Route, Route: 0 0 0 0, RouteSpeed: 40K, RouteTries: 4, FailedLinkFrom: 0, FailedLinkTo: 0
[20201029 18:47:15.001 UTC] [ozw.library] [info]: Info - Node: 18 WARNING: ZW_SEND_DATA failed. No ACK received - device may be asleep.
[20201029 18:47:15.001 UTC] [ozw.library] [warning]: Warning - Node: 18 WARNING: Device is not a sleeping node.
[20201029 18:47:15.001 UTC] [ozw.library] [critical]: Error - Node: 18 ERROR: node presumed dead
[20201029 18:47:15.001 UTC] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
[20201029 18:47:15.001 UTC] [ozw.library] [warning]: Warning - Node: 0 CheckCompletedNodeQueries all=1, deadFound=1 sleepingOnly=1
[20201029 18:47:15.001 UTC] [ozw.library] [info]: Info - Node: 0          Node query processing complete except for dead nodes.
[20201029 18:47:15.002 UTC] [ozw.library] [info]: Info - Node: 0 Saving Cache
[20201029 18:47:15.002 UTC] [ozw.library] [info]: Info - Node: 1 Cache Save for Node 1 as its QueryStage_CacheLoad
[20201029 18:47:15.002 UTC] [ozw.library] [info]: Info - Node: 18 Skipping Cache Save for Node 18 as its not past QueryStage_CacheLoad
[20201029 18:47:15.019 UTC] [ozw.library] [info]: Info - Node: 18 QueryStageRetry stage Probe requested stage Probe max 3 retries 2 pending 1
[20201029 18:47:15.019 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected callbackId was received
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 18   Expected reply was received
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 18   Message transaction complete
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 18 Removing current message
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 18 Notification: Notification - NoOperation
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 255 Notification: AllNodesQueriedSomeDead
[20201029 18:47:15.020 UTC] [ozw.library] [debug]: Detail - Node: 18 Notification: Notification - Node Dead
[20201029 18:47:15.021 UTC] [ozw.library] [debug]: Detail - Node: 18 Query Stage Complete (Probe)
[20201029 18:47:15.021 UTC] [ozw.library] [debug]: Detail - Node: 18 AdvanceQueries queryPending=0 queryRetries=0 queryStage=Probe live=0
[20201029 18:47:15.022 UTC] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NoOperation Thread:  0xb6f04410
[20201029 18:47:15.022 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20201029 18:47:15.023 UTC] [ozw.notifications] [debug]: Notification pvt_driverAllNodesQueriedSomeDead Thread:  0xb6f04410
[20201029 18:47:15.023 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event driverAllNodesQueriedSomeDead:
[20201029 18:47:15.023 UTC] [ozw.notifications] [debug]: Notification pvt_ozwNotification NotificationTypes::Notification_Code_NodeDead Thread:  0xb6f04410
[20201029 18:47:15.023 UTC] [ozw.mqtt.publisher] [debug]: Publishing Event ozwNotification
[20201029 18:47:17.011 UTC] [ozw.library] [debug]: Detail - Node: 0   Received: 0x01, 0x18, 0x00, 0x13, 0x0d, 0x01, 0x05, 0x74, 0x00, 0x7f, 0x7f, 0x7f, 0x7f, 0x7f, 0x00, 0x00, 0x07, 0x00, 0x00, 0x00, 0x00, 0x02, 0x04, 0x00, 0x00, 0xf7
[20201029 18:47:17.012 UTC] [ozw.library] [debug]: Detail - Node: 0   ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x00)
[20201029 18:47:17.012 UTC] [ozw.library] [warning]: Warning - Node: 0 WARNING: Unexpected Callback ID received
[20201029 18:47:20.842 UTC] [ozw.manager] [debug]: ProductPic Path:  "/opt/ozw/config/"
[20201029 18:47:26.047 UTC] [default] [debug]: QVariant(int, 1)
[20201029 18:47:26.050 UTC] [default] [debug]: QVariant(int, 18)
[20201029 18:47:49.048 UTC] [ozw.mqtt.publisher] [debug]: "Thu Oct 29 18:47:49 2020 PingResponse\n"
[20201029 18:47:56.047 UTC] [default] [debug]: QVariant(int, 1)
[20201029 18:47:56.049 UTC] [default] [debug]: QVariant(int, 18)
[20201029 18:48:26.048 UTC] [default] [debug]: QVariant(int, 1)
[20201029 18:48:26.050 UTC] [default] [debug]: QVariant(int, 18)

Are you using the same network key for the ozwdaemon that you used for including the device on your Pc?
Have you factory reset or unpaired the Doorbell before adding it to a “new” Z-Wave network? Is the Doorbell your only device?

What happened between 2 and 17 (id)? Did you try that many times or? Did you resest the device (physically) before re-adding to ozw?