Z-wave Lock Issue when connected to Zwave Network

Louis1 · December 7, 2020, 9:08am

Hi there,

Fairly new to HA and have run in to my first major hurdle for a key piece of hardware in my home, the front door deadbolt.

Basically, when connected to the Z-Wave network (using standard HA Zwave integration, Aeotec gen 5 zwave stick, HA running on Haswell i5 intel NUC in VM), when manually entering in the pincode, the lock poops itself the first time and doesn’t unlock, and goes dead for a second, but then I can re enter the code and it works.

Here is a video for reference: https://imgur.com/gallery/kjZtR1T

temporary code used for the video (don’t come to my house and try and get in )

If I remove the lock node from HA, the lock functions as a dumb lock perfectly again (code works first time).

Could it be that when manually unlocking using PIN there is something ‘overloading’ the lock or something?

Have updated batteries etc.

Should I post Zwave logs of what happens? Would that help possibly identify the problem?

Any help would be absolutely amazing.

Thanks

Louis1 · December 10, 2020, 8:27pm

Anyone? Really struggling with this.

firstof9 · December 10, 2020, 8:43pm

Contact the manufacturer, it shouldn’t be doing that.

Louis1 · December 16, 2020, 10:46pm

Thanks for the response. The manufacturer first advised that it is something to do with the fitting of the lock, which I dont think it is, as it works perfectly when it’s not connected to Zwave network.
They are now advising do factory reset which I have done but doesnt help.

Here is a copy of my Zwave log, in case any of you guru’s can see something that I am missing :

2020-12-17 09:40:26.718 Detail, Node022,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x16, 0x02, 0x98, 0x40, 0x3f
2020-12-17 09:40:26.718 Info, Node022, Received SecurityCmd_NonceGet from node 22
2020-12-17 09:40:26.718 Info, NONCES: 0xd5, 0x28, 0xab, 0xe0, 0xf3, 0x51, 0x4d, 0x1d
2020-12-17 09:40:26.718 Info, NONCES: 0xb3, 0xe4, 0x6a, 0x70, 0x6a, 0x02, 0x7e, 0xb1
2020-12-17 09:40:26.718 Info, NONCES: 0xb4, 0xe0, 0xef, 0xfb, 0x39, 0xeb, 0x64, 0x16
2020-12-17 09:40:26.718 Info, NONCES: 0x42, 0x9d, 0xcf, 0x68, 0x48, 0xa0, 0xaa, 0x29
2020-12-17 09:40:26.718 Info, NONCES: 0x4f, 0xc4, 0x39, 0xc1, 0xa4, 0xf8, 0x51, 0x4d
2020-12-17 09:40:26.719 Info, NONCES: 0xa5, 0xac, 0xf7, 0x51, 0xaf, 0xe2, 0x01, 0x3e
2020-12-17 09:40:26.719 Info, NONCES: 0x3b, 0x8f, 0x64, 0xdc, 0xdb, 0xeb, 0x5a, 0x5b
2020-12-17 09:40:26.719 Info, NONCES: 0x01, 0x35, 0xb6, 0xe9, 0xb4, 0x46, 0x16, 0x41
2020-12-17 09:40:26.719 Info, Node022, Sending (Send) message (Callback ID=0x01, Expected Reply=0x00) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x16, 0x0a, 0x98, 0x80, 0x01, 0x35, 0xb6, 0xe9, 0xb4, 0x46, 0x16, 0x41, 0x05, 0x01, 0x33:
2020-12-17 09:40:26.728 Detail,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:26.728 Detail,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:26.747 Detail,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x02, 0xe8
2020-12-17 09:40:26.748 Detail,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2020-12-17 09:40:26.772 Detail, Node022,   Received: 0x01, 0x1e, 0x00, 0x04, 0x00, 0x16, 0x18, 0x98, 0x81, 0x99, 0xc7, 0x73, 0x52, 0x40, 0x97, 0x69, 0x3f, 0x21, 0x28, 0x22, 0xe5, 0xf4, 0x01, 0x91, 0xb2, 0xe6, 0xf1, 0x38, 0x7b, 0x39, 0x25, 0x5c
2020-12-17 09:40:26.773 Info, Raw: 0x98, 0x81, 0x99, 0xc7, 0x73, 0x52, 0x40, 0x97, 0x69, 0x3f, 0x21, 0x28, 0x22, 0xe5, 0xf4, 0x01, 0x91, 0xb2, 0xe6, 0xf1, 0x38, 0x7b, 0x39, 0x25, 0x5c
2020-12-17 09:40:26.773 Detail, Node022, Decrypted Packet: 0x00, 0x71, 0x05, 0x13, 0x01
2020-12-17 09:40:26.773 Detail,
2020-12-17 09:40:26.773 Info, Node022, Received Alarm report: type=19, level=1
2020-12-17 09:40:26.773 Detail, Node022, Refreshed Value: old value=19, new value=19, type=byte
2020-12-17 09:40:26.773 Detail, Node022, Changes to this value are not verified
2020-12-17 09:40:26.773 Detail, Node022, Setting Encryption Flag on Message For Command Class COMMAND_CLASS_DOOR_LOCK
2020-12-17 09:40:26.773 Detail, Node022, Queuing (Send) DoorLockCmd_Get (Node=22): 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x62, 0x02, 0x25, 0x4e, 0xfa
2020-12-17 09:40:26.773 Detail, Node022, Refreshed Value: old value=3, new value=1, type=byte
2020-12-17 09:40:26.773 Detail, Node022, Changes to this value are not verified
2020-12-17 09:40:26.773 Detail, Node022, Notification: ValueChanged
2020-12-17 09:40:26.780 Detail, Node022, Notification: ValueChanged
2020-12-17 09:40:26.785 Detail,
2020-12-17 09:40:26.785 Info, Node022, Processing (Send) Nonce Request message (Callback ID=0x4e, Expected Reply=0x04)
2020-12-17 09:40:26.786 Info, Node022, Sending (Send) message (Callback ID=0x4e, Expected Reply=0x04) - Nonce_Get(DoorLockCmd_Get) - 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x98, 0x40, 0x05, 0x02:
2020-12-17 09:40:26.796 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:26.797 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:26.811 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x02, 0x00, 0x00, 0x03, 0xea
2020-12-17 09:40:26.811 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2020-12-17 09:40:26.811 Info, Node022, Request RTT 25 Average Request RTT 721
2020-12-17 09:40:26.823 Detail, Node022,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x16, 0x0a, 0x98, 0x80, 0x8f, 0x25, 0x85, 0xec, 0xbf, 0x99, 0x5c, 0xcb, 0x9d
2020-12-17 09:40:26.823 Info, Node022, Received SecurityCmd_NonceReport from node 22
2020-12-17 09:40:26.823 Info, Node022, Sending (Send) message (Callback ID=0x4f, Expected Reply=0x04) - DoorLockCmd_Get (Node=22): 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x62, 0x02, 0x25, 0x4f, 0xfb
2020-12-17 09:40:26.835 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:26.835 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:26.858 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x4f, 0x00, 0x00, 0x03, 0xa7
2020-12-17 09:40:26.858 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x4f received (expected 0x4f)
2020-12-17 09:40:26.858 Info, Node022, Request RTT 72 Average Request RTT 396
2020-12-17 09:40:26.858 Detail,   Expected callbackId was received
2020-12-17 09:40:30.274 Detail, Node022,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x16, 0x02, 0x98, 0x40, 0x3f
2020-12-17 09:40:30.274 Info, Node022, Received SecurityCmd_NonceGet from node 22
2020-12-17 09:40:30.274 Info, NONCES: 0x60, 0xa7, 0xa3, 0xfe, 0xe6, 0x77, 0x22, 0x17
2020-12-17 09:40:30.274 Info, NONCES: 0xb3, 0xe4, 0x6a, 0x70, 0x6a, 0x02, 0x7e, 0xb1
2020-12-17 09:40:30.274 Info, NONCES: 0xb4, 0xe0, 0xef, 0xfb, 0x39, 0xeb, 0x64, 0x16
2020-12-17 09:40:30.274 Info, NONCES: 0x42, 0x9d, 0xcf, 0x68, 0x48, 0xa0, 0xaa, 0x29
2020-12-17 09:40:30.274 Info, NONCES: 0x4f, 0xc4, 0x39, 0xc1, 0xa4, 0xf8, 0x51, 0x4d
2020-12-17 09:40:30.274 Info, NONCES: 0xa5, 0xac, 0xf7, 0x51, 0xaf, 0xe2, 0x01, 0x3e
2020-12-17 09:40:30.274 Info, NONCES: 0x3b, 0x8f, 0x64, 0xdc, 0xdb, 0xeb, 0x5a, 0x5b
2020-12-17 09:40:30.274 Info, NONCES: 0x01, 0x35, 0xb6, 0xe9, 0xb4, 0x46, 0x16, 0x41
2020-12-17 09:40:30.274 Info, Node022, Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x16, 0x0a, 0x98, 0x80, 0x60, 0xa7, 0xa3, 0xfe, 0xe6, 0x77, 0x22, 0x17, 0x05, 0x01, 0xc3:
2020-12-17 09:40:30.284 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:30.284 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:30.302 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x03, 0xe9
2020-12-17 09:40:30.303 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2020-12-17 09:40:30.303 Info, Node022, Request RTT 3517 Average Request RTT 1956
2020-12-17 09:40:30.324 Detail, Node022,   Received: 0x01, 0x1e, 0x00, 0x04, 0x00, 0x16, 0x18, 0x98, 0x81, 0xf0, 0x32, 0x25, 0x89, 0x2d, 0x6c, 0xec, 0x10, 0x6f, 0xdc, 0x95, 0xd2, 0x74, 0x60, 0x91, 0xc4, 0xc9, 0xb8, 0x4e, 0xcf, 0x6a, 0x88, 0x86
2020-12-17 09:40:30.324 Info, Raw: 0x98, 0x81, 0xf0, 0x32, 0x25, 0x89, 0x2d, 0x6c, 0xec, 0x10, 0x6f, 0xdc, 0x95, 0xd2, 0x74, 0x60, 0x91, 0xc4, 0xc9, 0xb8, 0x4e, 0xcf, 0x6a, 0x88, 0x86
2020-12-17 09:40:30.324 Detail, Node022, Decrypted Packet: 0x00, 0x71, 0x05, 0x09, 0x00
2020-12-17 09:40:30.324 Detail,
2020-12-17 09:40:30.324 Info, Node022, Response RTT 3539 Average Response RTT 2825
2020-12-17 09:40:30.324 Info, Node022, Received Alarm report: type=9, level=0
2020-12-17 09:40:30.324 Detail, Node022, Refreshed Value: old value=19, new value=9, type=byte
2020-12-17 09:40:30.324 Detail, Node022, Changes to this value are not verified
2020-12-17 09:40:30.324 Detail, Node022, Setting Encryption Flag on Message For Command Class COMMAND_CLASS_DOOR_LOCK
2020-12-17 09:40:30.325 Detail, Node022, Queuing (Send) DoorLockCmd_Get (Node=22): 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x62, 0x02, 0x25, 0x50, 0xe4
2020-12-17 09:40:30.325 Detail, Node022, Refreshed Value: old value=1, new value=0, type=byte
2020-12-17 09:40:30.325 Detail, Node022, Changes to this value are not verified
2020-12-17 09:40:30.325 Detail, Node022, Notification: ValueChanged
2020-12-17 09:40:30.326 Detail, Node022, Notification: ValueChanged
2020-12-17 09:40:36.820 Error, Node022, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-17 09:40:36.820 Detail, Node022, Removing current message
2020-12-17 09:40:36.820 Detail, Node022, Notification: Notification - TimeOut
2020-12-17 09:40:36.823 Detail,
2020-12-17 09:40:36.823 Info, Node022, Processing (Send) Nonce Request message (Callback ID=0x50, Expected Reply=0x04)
2020-12-17 09:40:36.823 Info, Node022, Sending (Send) message (Callback ID=0x50, Expected Reply=0x04) - Nonce_Get(DoorLockCmd_Get) - 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x98, 0x40, 0x05, 0x02:
2020-12-17 09:40:36.831 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:36.831 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:38.078 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x02, 0x00, 0x00, 0x7d, 0x94
2020-12-17 09:40:38.078 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x02 received (expected 0x02)
2020-12-17 09:40:38.078 Info, Node022, Request RTT 1255 Average Request RTT 1605
2020-12-17 09:40:38.091 Detail, Node022,   Received: 0x01, 0x10, 0x00, 0x04, 0x00, 0x16, 0x0a, 0x98, 0x80, 0x22, 0xb4, 0xb4, 0x7f, 0xb3, 0x8b, 0xb0, 0x84, 0xbe
2020-12-17 09:40:38.091 Info, Node022, Received SecurityCmd_NonceReport from node 22
2020-12-17 09:40:38.091 Info, Node022, Sending (Send) message (Callback ID=0x51, Expected Reply=0x04) - DoorLockCmd_Get (Node=22): 0x01, 0x09, 0x00, 0x13, 0x16, 0x02, 0x62, 0x02, 0x25, 0x51, 0xe5
2020-12-17 09:40:38.102 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:38.102 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:38.123 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x51, 0x00, 0x00, 0x03, 0xb9
2020-12-17 09:40:38.123 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x51 received (expected 0x51)
2020-12-17 09:40:38.123 Info, Node022, Request RTT 1301 Average Request RTT 1453
2020-12-17 09:40:38.123 Detail,   Expected callbackId was received
2020-12-17 09:40:39.026 Detail, Node022,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x16, 0x02, 0x98, 0x40, 0x3f
2020-12-17 09:40:39.026 Info, Node022, Received SecurityCmd_NonceGet from node 22
2020-12-17 09:40:39.026 Info, NONCES: 0x60, 0xa7, 0xa3, 0xfe, 0xe6, 0x77, 0x22, 0x17
2020-12-17 09:40:39.026 Info, NONCES: 0x29, 0xf3, 0x1e, 0x85, 0x57, 0x06, 0x2f, 0x48
2020-12-17 09:40:39.026 Info, NONCES: 0xb4, 0xe0, 0xef, 0xfb, 0x39, 0xeb, 0x64, 0x16
2020-12-17 09:40:39.026 Info, NONCES: 0x42, 0x9d, 0xcf, 0x68, 0x48, 0xa0, 0xaa, 0x29
2020-12-17 09:40:39.026 Info, NONCES: 0x4f, 0xc4, 0x39, 0xc1, 0xa4, 0xf8, 0x51, 0x4d
2020-12-17 09:40:39.026 Info, NONCES: 0xa5, 0xac, 0xf7, 0x51, 0xaf, 0xe2, 0x01, 0x3e
2020-12-17 09:40:39.026 Info, NONCES: 0x3b, 0x8f, 0x64, 0xdc, 0xdb, 0xeb, 0x5a, 0x5b
2020-12-17 09:40:39.026 Info, NONCES: 0x01, 0x35, 0xb6, 0xe9, 0xb4, 0x46, 0x16, 0x41
2020-12-17 09:40:39.026 Info, Node022, Sending (Send) message (Callback ID=0x01, Expected Reply=0x04) - Nonce_Report - 0x01, 0x11, 0x00, 0x13, 0x16, 0x0a, 0x98, 0x80, 0x29, 0xf3, 0x1e, 0x85, 0x57, 0x06, 0x2f, 0x48, 0x05, 0x01, 0x8a:
2020-12-17 09:40:39.034 Detail, Node022,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 09:40:39.035 Detail, Node022,   ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 09:40:39.053 Detail, Node022,   Received: 0x01, 0x07, 0x00, 0x13, 0x01, 0x00, 0x00, 0x02, 0xe8
2020-12-17 09:40:39.053 Detail, Node022,   ZW_SEND_DATA Request with callback ID 0x01 received (expected 0x01)
2020-12-17 09:40:39.053 Info, Node022, Request RTT 2231 Average Request RTT 1842
2020-12-17 09:40:39.077 Detail, Node022,   Received: 0x01, 0x21, 0x00, 0x04, 0x00, 0x16, 0x1b, 0x98, 0x81, 0xd8, 0x26, 0xe1, 0x43, 0x1a, 0x85, 0xd7, 0x40, 0x74, 0x61, 0x06, 0x18, 0xe7, 0x34, 0x31, 0x5b, 0x29, 0xe5, 0x14, 0x45, 0xb3, 0x2e, 0xd1, 0xfd, 0xdd, 0xd9
2020-12-17 09:40:39.077 Info, Raw: 0x98, 0x81, 0xd8, 0x26, 0xe1, 0x43, 0x1a, 0x85, 0xd7, 0x40, 0x74, 0x61, 0x06, 0x18, 0xe7, 0x34, 0x31, 0x5b, 0x29, 0xe5, 0x14, 0x45, 0xb3, 0x2e, 0xd1, 0xfd, 0xdd, 0xd9
2020-12-17 09:40:39.077 Detail, Node022, Decrypted Packet: 0x00, 0x62, 0x03, 0xff, 0x00, 0x00, 0xfe, 0xfe
2020-12-17 09:40:39.077 Detail,
2020-12-17 09:40:39.077 Info, Node022, Response RTT 2254 Average Response RTT 2539
2020-12-17 09:40:39.077 Info, Node022, Received DoorLock report: DoorLock is Secured
2020-12-17 09:40:39.077 Detail, Node022, Refreshed Value: old value=false, new value=true, type=bool
2020-12-17 09:40:39.077 Detail, Node022, Changes to this value are not verified
2020-12-17 09:40:39.077 Detail, Node022,   Expected reply and command class was received
2020-12-17 09:40:39.077 Detail, Node022,   Message transaction complete
2020-12-17 09:40:39.077 Detail,
2020-12-17 09:40:39.077 Detail, Node022, Removing current message
2020-12-17 09:40:39.077 Detail, Node022, Notification: ValueChanged`

firstof9 · December 17, 2020, 3:47pm

Logs look fine, except where the lock reboots (the timeouts). The issue is a hardware issue.