Yale Z-wave lock - add secure mode error

apat183 · July 30, 2019, 2:50am

Hi,

I’m struggling to get my Yale Z-wave locked paired with HASS.

Current setup is:

Aeotec Z-Stick Gen5
ZWM-1 Z wave module - for Yale Touchscreen Digital Deadbolt SYEDDB/220/N
Home assistant on docker
Only other Z-wave items are bunch of Aeotec Nano Dimmers (all working fine).

I moved my PC right next to lock to even make sure it wasn’t distance problem.

I’ve created a secure network key and have that in my config:

zwave:
  network_key: " 0x86, ............hidden.........., 0x2B"

To include device I go into z-wave settings, click “Add Secure Node”, and then on lock I put into inclusion mode.

Lock tries to join network, but fails (times out).

I check OZW log and it shows the device is starting to pair, it detects device information correctly, but I notice I get a warning: MAC Authentication of Packet Failed. Dropping=

When I check home assistant, the device is added as:

Unknown Node 22. here are details:

But none of the lock functionality is added. None of the entities are added. Pulling my hair out trying to fix this, pulled apart lock and did factroy reset, removed and added multiple times.

The lock still thinks its not connected to any network.

For log if it helps, I removed any lines of log that had “x0a, 0x00, 0x13” etc value as not sure if its key information.

2019-07-30 13:03:48.406 Info, contrlr, FUNC_ID_ZW_ADD_NODE_TO_NETWORK:\
2019-07-30 13:03:48.406 Info, contrlr, ADD_NODE_STATUS_DONE\
2019-07-30 13:03:48.409 Info, Node022,   Basic device class    (0x04) - Routing Slave\
2019-07-30 13:03:48.410 Info, Node022,   Generic device Class  (0x40) - Entry Control\
2019-07-30 13:03:48.410 Info, Node022,   Specific device class (0x03) - Secure Keypad Door Lock\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_BASIC will be mapped to COMMAND_CLASS_DOOR_LOCK\
2019-07-30 13:03:48.410 Info, Node022,   Mandatory Command Classes for Node 22:\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_BASIC\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_DOOR_LOCK\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_USER_CODE\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_MANUFACTURER_SPECIFIC\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_VERSION\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_SECURITY\
2019-07-30 13:03:48.410 Info, Node022,   Mandatory Command Classes controlled by Node 22:\
2019-07-30 13:03:48.410 Info, Node022,     None\
2019-07-30 13:03:48.410 Detail, Node022, Queuing (Security) SecurityCmd_SchemeGet (Node=22): 0x01...\
2019-07-30 13:03:48.410 Info, Node022,   Optional command classes for node 22:\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_ZWAVEPLUS_INFO\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_MANUFACTURER_SPECIFIC (Existing)\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_SECURITY (Existing)\
2019-07-30 13:03:48.410 Info, Node022,     COMMAND_CLASS_DEVICE_RESET_LOCALLY\
2019-07-30 13:03:48.411 Info, Node022,     COMMAND_CLASS_POWERLEVEL\
2019-07-30 13:03:48.411 Info, Node022,     COMMAND_CLASS_VERSION (Existing)\
2019-07-30 13:03:48.411 Detail, Node022, AdvanceQueries queryPending=0 queryRetries=0 queryStage=None live=1\
2019-07-30 13:03:48.411 Detail, Node022, QueryStage_ProtocolInfo\
2019-07-30 13:03:48.411 Detail, Node022, Queuing (Query) Get Node Protocol Info (Node=22): 0x01, 0x04, 0x00, 0x41, 0x16, 0xac\
2019-07-30 13:03:48.411 Detail, Node022, Queuing (Query) Query Stage Complete (ProtocolInfo)\
2019-07-30 13:03:48.411 Info, Node022, Initializing Node. New Node: true (true)\
2019-07-30 13:03:48.411 Detail, Node006,   Expected reply was received\
2019-07-30 13:03:48.411 Detail, Node006,   Message transaction complete\
2019-07-30 13:03:48.411 Detail,\
2019-07-30 13:03:48.411 Detail, contrlr, Removing current message\
2019-07-30 13:03:48.411 Detail, Node022, Notification: NodeAdded\
2019-07-30 13:03:48.417 Detail, Node022, Notification: NodeProtocolInfo\
2019-07-30 13:03:48.419 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.436 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.448 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.460 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.472 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.484 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.495 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.507 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.519 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.530 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.542 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.554 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.566 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.578 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.589 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.601 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.613 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.625 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.637 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.648 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.660 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.672 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.684 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.696 Detail, Node022, Notification: ValueAdded\
2019-07-30 13:03:48.708 Detail, Notification: ControllerCommand - Completed\
2019-07-30 13:03:48.708 Detail,\
2019-07-30 13:03:48.708 Info, Node022, Sending (Security) message (Callback ID=0xdb, Expected Reply=0x04) - SecurityCmd_SchemeGet (Node=22): 0x01....\
2019-07-30 13:03:48.719 Detail, Node022,   Received: 0x01, ....\
2019-07-30 13:03:48.719 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack\
2019-07-30 13:03:49.866 Detail, Node022,   Received: 0x01, 0x07, ....\
2019-07-30 13:03:49.866 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0xdb received (expected 0xdb)\
2019-07-30 13:03:49.866 Info, Node022, Request RTT 1157 Average Request RTT 1157\
2019-07-30 13:03:49.866 Detail,   Expected callbackId was received\
2019-07-30 13:03:49.880 Detail, Node022,   Received: 0x01, ...\
2019-07-30 13:03:49.880 Detail,\
2019-07-30 13:03:49.880 Info, Node022, Response RTT 1171 Average Response RTT 1171\
2019-07-30 13:03:49.880 Info, Node022, Received SecurityCmd_SchemeReport from node 22: 0\
2019-07-30 13:03:49.880 Info, Node022,     Security scheme agreed.\
2019-07-30 13:03:49.880 Detail, Node022, Queuing (Security) SecurityCmd_NetworkKeySet (Node=22): 0x01, 0x19, .....\
2019-07-30 13:03:49.880 Detail, Node022,   Expected reply and command class was received\
2019-07-30 13:03:49.880 Detail, Node022,   Message transaction complete\
2019-07-30 13:03:49.880 Detail,\
2019-07-30 13:03:49.880 Detail, Node022, Removing current message\
2019-07-30 13:03:49.880 Detail,\
2019-07-30 13:03:49.880 Info, Node022, Processing (Security) Nonce Request message (Callback ID=0xdc, Expected Reply=0x04)\
2019-07-30 13:03:49.880 Info, Node022, Sending (Security) message (Callback ID=0xdc, Expected Reply=0x04) - Nonce_Get(SecurityCmd_NetworkKeySet) - 0x01, 0x09, ....:\
2019-07-30 13:03:49.887 Detail, Node022,   Received: 0x01, 0x04, ...\
2019-07-30 13:03:49.887 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack\
2019-07-30 13:03:49.909 Detail, Node022,   Received: 0x01, 0x07, .....\
2019-07-30 13:03:49.909 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)\
2019-07-30 13:03:49.909 Info, Node022, Request RTT 28 Average Request RTT 592\
2019-07-30 13:03:49.924 Detail, Node022,   Received: 0x01, .....\
2019-07-30 13:03:49.924 Info, Node022, Received SecurityCmd_NonceReport from node 22\
2019-07-30 13:03:49.924 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.924 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:49.925 Info, Node022, Sending (Security) message (Callback ID=0xdd, Expected Reply=0x04) - SecurityCmd_NetworkKeySet (Node=22): 0x01, 0x19, ....\
2019-07-30 13:03:49.936 Detail, Node022,   Received: 0x01, 0x04, ...\
2019-07-30 13:03:49.936 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack\
2019-07-30 13:03:49.964 Detail, Node022,   Received: 0x01, 0x07, ...\
2019-07-30 13:03:49.964 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0xdd received (expected 0xdd)\
2019-07-30 13:03:49.964 Info, Node022, Request RTT 83 Average Request RTT 337\
2019-07-30 13:03:49.964 Detail,   Expected callbackId was received\
2019-07-30 13:03:49.990 Detail, Node022,   Received: 0x01, 0x08, ..\
2019-07-30 13:03:49.990 Info, Node022, Received SecurityCmd_NonceGet from node 22\
2019-07-30 13:03:49.990 Info, NONCES: 0x47, 0x8d...\
2019-07-30 13:03:49.990 Info, NONCES: 0x00, 0x00..\
2019-07-30 13:03:49.990 Info, NONCES: 0x00, 0x00,..\
2019-07-30 13:03:49.990 Info, NONCES: 0x00, 0x00, ..\
2019-07-30 13:03:49.990 Info, NONCES: 0x00, 0x00,..\
2019-07-30 13:03:49.991 Info, Node022, Sending (Security) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, .....:\
2019-07-30 13:03:49.998 Detail, Node022,   Received: 0x01, 0x04, ...\
2019-07-30 13:03:49.998 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack\
2019-07-30 13:03:50.022 Detail, Node022,   Received: 0x01, 0x07, ...\
2019-07-30 13:03:50.022 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)\
2019-07-30 13:03:50.022 Info, Node022, Request RTT 142 Average Request RTT 239\
2019-07-30 13:03:50.079 Detail, Node022,   Received: 0x01, ....\
2019-07-30 13:03:50.079 Info, Raw: 0x98, 0x81, 0x32, ....\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Detail, Node022, Decrypted Packet: 0x8a, ...\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Info, Node001, Setting Up Inclusion Network Key for Secure Communications\
2019-07-30 13:03:50.079 Warning, Node022, MAC Authentication of Packet Failed. Dropping\
2019-07-30 13:03:50.079 Detail, Node022, Removing current message\

Update, noticed the following in the main log page, wasn’t seeing this before.

Z-Wave entity Unknown Node 22 Power Management (node_id: 22) not ready after 30 seconds, continuing anyway

3:09 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

Z-Wave entity Unknown Node 22 Access Control (node_id: 22) not ready after 30 seconds, continuing anyway

3:09 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

Z-Wave entity Unknown Node 22 SourceNodeId (node_id: 22) not ready after 30 seconds, continuing anyway

3:09 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

Z-Wave entity Unknown Node 22 Alarm Level (node_id: 22) not ready after 30 seconds, continuing anyway

3:08 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

Z-Wave entity Unknown Node 22 Alarm Type (node_id: 22) not ready after 30 seconds, continuing anyway

3:08 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

Z-Wave entity Unknown Node 22 Locked (node_id: 22) not ready after 30 seconds, continuing anyway

3:08 PM components/zwave/__init__.py (WARNING) - message first occured at 3:04 PM and shows up 2 times

firstof9 · July 30, 2019, 6:28pm

Have you tried excluding it and re-adding it?

apat183 · July 30, 2019, 7:43pm

Yep, the Yale lock only gives open to exclude if it’s paired and vice versa, for ages I had to try and exclude it (it might have been paired with something during testing), it just wasn’t getting excluded. Now it’s just all ways trying to pair, even though it’s showing up in HASS in the limited state the lock still thinks it’s not paired.

I really think its a time out issue as the lock only tries to connect for like 20 secs, but looking at the logs, it looks like it’s taken longer then that and there are some errors which show response not received.

Honestly battery powered z-wave device just wasting so much of my time to pair. Have a sensor which is also driving me nuts. Have had no problems with all my powered devices.

sokraski · July 30, 2019, 8:42pm

Have you tried factory resetting Yale?