Zwave not opening or closing kwikset lock

Paul_Macie · December 17, 2020, 5:23pm

hi everyone,i have a terrible problem. i can use any help .
i have a raspi 4 with home assistant installed i have a usbz1 zwave and zigbee usb stick configured but try as i might i can not get home assistant to open a kwikset lock.
here is the log file

2020-12-17 11:09:46.218 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_GET_INIT_DATA:
2020-12-17 11:09:46.218 Info, contrlr, Node 001 - Known
2020-12-17 11:09:46.218 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-12-17 11:09:46.218 Detail, Node001, QueryStage_CacheLoad
2020-12-17 11:09:46.218 Info, Node001, Node Identity Codes: 0000:0001:0001
2020-12-17 11:09:46.218 Detail, Node001, QueryStage_Associations
2020-12-17 11:09:46.218 Detail, Node001, QueryStage_Neighbors
2020-12-17 11:09:46.218 Detail, contrlr, Requesting routing info (neighbor list) for Node 1
2020-12-17 11:09:46.218 Detail, Node001, Queuing (Command) Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2020-12-17 11:09:46.218 Detail, Node001, Queuing (Query) Query Stage Complete (Neighbors)
2020-12-17 11:09:46.218 Info, contrlr, Node 003 - Known
2020-12-17 11:09:46.218 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=CacheLoad live=1
2020-12-17 11:09:46.218 Detail, Node003, QueryStage_CacheLoad
2020-12-17 11:09:46.218 Info, Node003, Node Identity Codes: 0090:0003:0541
2020-12-17 11:09:46.219 Info, Node003, NoOperation::Set - Routing=true
2020-12-17 11:09:46.219 Detail, Node003, Queuing (NoOp) NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcb
2020-12-17 11:09:46.219 Detail, Node003, Queuing (Query) Query Stage Complete (CacheLoad)
2020-12-17 11:09:46.219 Detail, Node008, Expected reply was received
2020-12-17 11:09:46.219 Detail, Node008, Message transaction complete
2020-12-17 11:09:46.219 Detail,
2020-12-17 11:09:46.219 Detail, contrlr, Removing current message
2020-12-17 11:09:46.219 Detail, Node001, Notification: DriverReady
2020-12-17 11:09:46.220 Detail, Node001, Notification: NodeAdded
2020-12-17 11:09:46.223 Detail, Node001, Notification: NodeProtocolInfo
2020-12-17 11:09:46.224 Detail, Node001, Notification: EssentialNodeQueriesComplete
2020-12-17 11:09:46.224 Detail, Node001, Notification: ValueAdded
2020-12-17 11:09:46.225 Detail, Node003, Notification: NodeAdded
2020-12-17 11:09:46.227 Detail, Node003, Notification: NodeProtocolInfo
2020-12-17 11:09:46.227 Detail, Node003, Notification: EssentialNodeQueriesComplete
2020-12-17 11:09:46.227 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.228 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.228 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.229 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.231 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.233 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.237 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.240 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.241 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.241 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.242 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.242 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.243 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.244 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.245 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.246 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.247 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.248 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.249 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.250 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.251 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.254 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.255 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.258 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.259 Detail, Node003, Notification: ValueAdded
2020-12-17 11:09:46.259 Detail, Node003, Notification: NodeNaming
2020-12-17 11:09:46.260 Detail,
2020-12-17 11:09:46.260 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x06) - FUNC_ID_SERIAL_API_SET_TIMEOUTS: 0x01, 0x05, 0x00, 0x06, 0x64, 0x0f, 0x97
2020-12-17 11:09:46.262 Detail, contrlr, Received: 0x01, 0x05, 0x01, 0x06, 0x64, 0x0f, 0x96
2020-12-17 11:09:46.262 Detail,
2020-12-17 11:09:46.262 Info, contrlr, Received reply to FUNC_ID_SERIAL_API_SET_TIMEOUTS
2020-12-17 11:09:46.262 Detail, Node015, Expected reply was received
2020-12-17 11:09:46.262 Detail, Node015, Message transaction complete
2020-12-17 11:09:46.262 Detail,
2020-12-17 11:09:46.262 Detail, contrlr, Removing current message
2020-12-17 11:09:46.262 Detail,
2020-12-17 11:09:46.262 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x00) - FUNC_ID_SERIAL_API_APPL_NODE_INFORMATION: 0x01, 0x07, 0x00, 0x03, 0x01, 0x02, 0x01, 0x00, 0xf9
2020-12-17 11:09:46.264 Detail, contrlr, Removing current message
2020-12-17 11:09:46.264 Detail,
2020-12-17 11:09:46.264 Info, Node001, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x07, 0x00, 0x80, 0x01, 0x00, 0x00, 0x03, 0x7a
2020-12-17 11:09:46.269 Detail, Node001, Received: 0x01, 0x20, 0x01, 0x80, 0x04, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5a
2020-12-17 11:09:46.269 Detail,
2020-12-17 11:09:46.269 Info, Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2020-12-17 11:09:46.269 Info, Node001, Neighbors of this node are:
2020-12-17 11:09:46.269 Info, Node001, Node 3
2020-12-17 11:09:46.269 Detail, Expected reply was received
2020-12-17 11:09:46.269 Detail, Message transaction complete
2020-12-17 11:09:46.269 Detail,
2020-12-17 11:09:46.269 Detail, Node001, Removing current message
2020-12-17 11:09:46.269 Detail,
2020-12-17 11:09:46.269 Info, Node003, Sending (NoOp) message (Callback ID=0x0a, Expected Reply=0x13) - NoOperation_Set (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x00, 0x00, 0x25, 0x0a, 0xcb
2020-12-17 11:09:46.275 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 11:09:46.275 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 11:09:47.505 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x0a, 0x00, 0xe3
2020-12-17 11:09:47.505 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x0a received (expected 0x0a)
2020-12-17 11:09:47.505 Info, Node003, Request RTT 1235 Average Request RTT 1235
2020-12-17 11:09:47.505 Detail, Expected callbackId was received
2020-12-17 11:09:47.505 Detail, Expected reply was received
2020-12-17 11:09:47.506 Detail, Message transaction complete
2020-12-17 11:09:47.506 Detail,
2020-12-17 11:09:47.506 Detail, Node003, Removing current message
2020-12-17 11:09:47.506 Detail, Node003, Notification: Notification - NoOperation
2020-12-17 11:09:47.521 Detail, Node001, Query Stage Complete (Neighbors)
2020-12-17 11:09:47.521 Detail, Node001, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2020-12-17 11:09:47.521 Detail, Node001, QueryStage_Session
2020-12-17 11:09:47.521 Detail, Node001, QueryStage_Dynamic
2020-12-17 11:09:47.521 Detail, Node001, QueryStage_Configuration
2020-12-17 11:09:47.521 Detail, Node001, QueryStage_Complete
2020-12-17 11:09:47.521 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=0
2020-12-17 11:09:47.521 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=1
2020-12-17 11:09:47.521 Info, Node query processing complete except for sleeping nodes.
2020-12-17 11:09:47.521 Detail, Node001, Notification: NodeQueriesComplete
2020-12-17 11:09:47.523 Detail, contrlr, Notification: AwakeNodesQueried
2020-12-17 11:09:47.525 Detail, Node003, Query Stage Complete (CacheLoad)
2020-12-17 11:09:47.525 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
2020-12-17 11:09:47.525 Detail, Node003, QueryStage_Associations
2020-12-17 11:09:47.525 Info, Node003, Number of association groups reported for node 3 is 0.
2020-12-17 11:09:47.525 Info, Node003, Get Associations for group 1 of node 3
2020-12-17 11:09:47.525 Detail, Node003, Queuing (Send) AssociationCmd_Get (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0b, 0x4e
2020-12-17 11:09:47.525 Detail, Node003, Queuing (Query) Query Stage Complete (Associations)
2020-12-17 11:09:47.525 Detail,
2020-12-17 11:09:47.525 Info, Node003, Sending (Send) message (Callback ID=0x0b, Expected Reply=0x04) - AssociationCmd_Get (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x85, 0x02, 0x01, 0x25, 0x0b, 0x4e
2020-12-17 11:09:47.531 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 11:09:47.531 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 11:09:47.550 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x0b, 0x00, 0xe2
2020-12-17 11:09:47.550 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x0b received (expected 0x0b)
2020-12-17 11:09:47.551 Info, Node003, Request RTT 25 Average Request RTT 630
2020-12-17 11:09:47.551 Detail, Expected callbackId was received
2020-12-17 11:09:57.526 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-17 11:09:57.526 Detail, Node003, Removing current message
2020-12-17 11:09:57.526 Detail, Node003, Notification: Notification - TimeOut
2020-12-17 11:09:57.528 Detail, Node003, Query Stage Complete (Associations)
2020-12-17 11:09:57.528 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Neighbors live=1
2020-12-17 11:09:57.528 Detail, Node003, QueryStage_Neighbors
2020-12-17 11:09:57.528 Detail, Requesting routing info (neighbor list) for Node 3
2020-12-17 11:09:57.528 Detail, Node003, Queuing (Command) Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
2020-12-17 11:09:57.528 Detail, Node003, Queuing (Query) Query Stage Complete (Neighbors)
2020-12-17 11:09:57.528 Detail,
2020-12-17 11:09:57.529 Info, Node003, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
2020-12-17 11:09:57.534 Detail, Node003, Received: 0x01, 0x20, 0x01, 0x80, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5f
2020-12-17 11:09:57.534 Detail,
2020-12-17 11:09:57.534 Info, Node003, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2020-12-17 11:09:57.535 Info, Node003, Neighbors of this node are:
2020-12-17 11:09:57.535 Info, Node003, Node 1
2020-12-17 11:09:57.535 Detail, Expected reply was received
2020-12-17 11:09:57.535 Detail, Message transaction complete
2020-12-17 11:09:57.535 Detail,
2020-12-17 11:09:57.535 Detail, Node003, Removing current message
2020-12-17 11:09:57.535 Detail, Node003, Query Stage Complete (Neighbors)
2020-12-17 11:09:57.535 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Session live=1
2020-12-17 11:09:57.535 Detail, Node003, QueryStage_Session
2020-12-17 11:09:57.535 Detail, Node003, Queuing (Query) PowerlevelCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x73, 0x02, 0x25, 0x0c, 0xbc
2020-12-17 11:09:57.535 Detail, Node003, Queuing (Query) Query Stage Complete (Session)
2020-12-17 11:09:57.535 Detail,
2020-12-17 11:09:57.535 Info, Node003, Sending (Query) message (Callback ID=0x0c, Expected Reply=0x04) - PowerlevelCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x73, 0x02, 0x25, 0x0c, 0xbc
2020-12-17 11:09:57.541 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 11:09:57.541 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 11:09:58.786 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x0c, 0x00, 0xe5
2020-12-17 11:09:58.786 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x0c received (expected 0x0c)
2020-12-17 11:09:58.786 Info, Node003, Request RTT 1250 Average Request RTT 940
2020-12-17 11:09:58.786 Detail, Expected callbackId was received
2020-12-17 11:09:58.798 Detail, Node003, Received: 0x01, 0x0a, 0x00, 0x04, 0x00, 0x03, 0x04, 0x73, 0x03, 0x00, 0x00, 0x86
2020-12-17 11:09:58.799 Detail,
2020-12-17 11:09:58.799 Info, Node003, Response RTT 1263 Average Response RTT 1263
2020-12-17 11:09:58.799 Info, Node003, Received a PowerLevel report: PowerLevel=Normal, Timeout=0
2020-12-17 11:09:58.799 Detail, Node003, Initial read of value
2020-12-17 11:09:58.799 Detail, Node003, Initial read of value
2020-12-17 11:09:58.799 Detail, Node003, Expected reply and command class was received
2020-12-17 11:09:58.799 Detail, Node003, Message transaction complete
2020-12-17 11:09:58.799 Detail,
2020-12-17 11:09:58.799 Detail, Node003, Removing current message
2020-12-17 11:09:58.799 Detail, Node003, Notification: ValueChanged
2020-12-17 11:09:58.801 Detail, Node003, Notification: ValueChanged
2020-12-17 11:09:58.803 Detail, Node003, Query Stage Complete (Session)
2020-12-17 11:09:58.803 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2020-12-17 11:09:58.803 Detail, Node003, QueryStage_Dynamic
2020-12-17 11:09:58.803 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x0d, 0xac
2020-12-17 11:09:58.803 Detail, Node003, Queuing (Send) BatteryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x80, 0x02, 0x25, 0x0e, 0x4d
2020-12-17 11:09:58.803 Detail, Node003, Queuing (Query) Query Stage Complete (Dynamic)
2020-12-17 11:09:58.804 Detail,
2020-12-17 11:09:58.804 Info, Node003, Sending (Send) message (Callback ID=0x0d, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x0d, 0xac
2020-12-17 11:09:58.809 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 11:09:58.809 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 11:09:58.828 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x0d, 0x00, 0xe4
2020-12-17 11:09:58.829 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x0d received (expected 0x0d)
2020-12-17 11:09:58.829 Info, Node003, Request RTT 24 Average Request RTT 482
2020-12-17 11:09:58.829 Detail, Expected callbackId was received
2020-12-17 11:10:08.804 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-17 11:10:08.804 Detail, Node003, Removing current message
2020-12-17 11:10:08.804 Detail, Node003, Notification: Notification - TimeOut
2020-12-17 11:10:08.806 Detail,
2020-12-17 11:10:08.806 Info, Node003, Sending (Send) message (Callback ID=0x0e, Expected Reply=0x04) - BatteryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x80, 0x02, 0x25, 0x0e, 0x4d
2020-12-17 11:10:08.812 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-17 11:10:08.812 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-17 11:10:10.087 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x0e, 0x00, 0xe7
2020-12-17 11:10:10.088 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x0e received (expected 0x0e)
2020-12-17 11:10:10.088 Info, Node003, Request RTT 1282 Average Request RTT 882
2020-12-17 11:10:10.088 Detail, Expected callbackId was received
2020-12-17 11:10:18.806 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-17 11:10:18.806 Detail, Node003, Removing current message
2020-12-17 11:10:18.806 Detail, Node003, Notification: Notification - TimeOut
2020-12-17 11:10:18.808 Detail, Node003, Query Stage Complete (Dynamic)
2020-12-17 11:10:18.808 Detail, Node003, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-12-17 11:10:18.808 Detail, Node003, QueryStage_Configuration
2020-12-17 11:10:18.808 Detail, Node003, QueryStage_Complete
2020-12-17 11:10:18.808 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=1
2020-12-17 11:10:18.808 Warning, CheckCompletedNodeQueries all=1, deadFound=0 sleepingOnly=1
2020-12-17 11:10:18.808 Info, Node query processing complete.
2020-12-17 11:10:18.809 Detail, Node003, Notification: NodeQueriesComplete

not a programmer but seems problem is,

2020-12-17 11:10:08.804 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s

can anyone point me in the right direction any help would be appreciated

kreene1987 · December 17, 2020, 9:41pm

Did you add it with security?

Paul_Macie · December 18, 2020, 1:03pm

yes i added as secure node
and yes i did put in network keys here is log showing successfully adding network keys

2020-12-17 11:09:35.630 Always, OpenZwave Version 1.4.3469 Starting Up
2020-12-17 11:09:46.075 Info, Setting Up Provided Network Key for Secure Communications
2020-12-17 11:09:46.075 Info, mgr, Added driver for controller /dev/ttyUSB0
2020-12-17 11:09:46.076 Info, Opening controller /dev/ttyUSB0
2020-12-17 11:09:46.078 Info, Trying to open serial port /dev/ttyUSB0 (attempt 1)
2020-12-17 11:09:46.087 Info, Serial port /dev/ttyUSB0 opened (attempt 1)
2020-12-17 11:09:46.088 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-12-17 11:09:46.088 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2020-12-17 11:09:46.088 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 0xf9
2020-12-17 11:09:46.088 Detail, contrlr, Queuing (Command) FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2020-12-17 11:09:46.088 Detail, contrlr, Queuing (Command) FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2020-12-17 11:09:46.088 Detail,
2020-12-17 11:09:46.088 Info, contrlr, Sending (Command) message (Callback ID=0x00, Expected Reply=0x15) - FUNC_ID_ZW_GET_VERSION: 0x01, 0x03, 0x00, 0x15, 0xe9
2020-12-17 11:09:46.091 Detail, contrlr, Received: 0x01, 0x10, 0x01, 0x15, 0x5a, 0x2d, 0x57, 0x61, 0x76, 0x65, 0x20, 0x34, 0x2e, 0x30, 0x35, 0x00, 0x01, 0x97
2020-12-17 11:09:46.091 Detail,
2020-12-17 11:09:46.091 Info, contrlr, Received reply to FUNC_ID_ZW_GET_VERSION:
2020-12-17 11:09:46.091 Info, contrlr, Static Controller library, version Z-Wave 4.05
2020-12-17 11:09:46.091 Detail, Node045, Expected reply was received
2020-12-17 11:09:46.091 Detail, Node045, Message transaction complete
2020-12-17 11:09:46.091 Detail,
2020-12-17 11:09:46.091 Detail, contrlr, Removing current message
2020-12-17 11:09:4

as you can see from this line
.075 Info, Setting Up Provided Network Key for Secure Communications
and as we can see by this line
2020-12-17 11:09:46.091 Detail, Node045, Message transaction complete

that it took the network keys successfully

firstof9 · December 18, 2020, 1:46pm

Re-add the lock with “Add Secure Node” based on your log it didn’t add securely.

Paul_Macie · December 18, 2020, 1:49pm

OK i will try again
thank you. its still early here so it will be a couple hours but will try again and post what happened

Paul_Macie · December 18, 2020, 7:38pm

re-added, made sure to use add secure node
same results

firstof9 · December 18, 2020, 7:46pm

The log you posted shows no NONCE messages, those are required for the secure communications.

Paul_Macie · December 19, 2020, 1:02pm

I reset the lock making sure to use secure node but no help. Can any one help lock still will not lock or unlock

2020-12-18 14:22:00.357 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:00.357 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x19, 0xb9
2020-12-18 14:22:00.358 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1a, 0xbb
2020-12-18 14:22:01.550 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x17, 0x00, 0xfe
2020-12-18 14:22:01.550 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x17 received (expected 0x17)
2020-12-18 14:22:01.550 Info, Node003, Request RTT 1297 Average Request RTT 1277
2020-12-18 14:22:01.551 Detail, Expected callbackId was received
2020-12-18 14:22:01.827 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:01.828 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:01.828 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1b, 0xbb
2020-12-18 14:22:01.828 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1c, 0xbd
2020-12-18 14:22:01.930 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:01.930 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:01.930 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1d, 0xbd
2020-12-18 14:22:01.930 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1e, 0xbf
2020-12-18 14:22:03.158 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:03.158 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:03.158 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1f, 0xbf
2020-12-18 14:22:03.158 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x20, 0x81
2020-12-18 14:22:03.263 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:03.263 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:03.263 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x21, 0x81
2020-12-18 14:22:03.263 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x22, 0x83
2020-12-18 14:22:06.961 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:06.961 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:06.962 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x23, 0x83
2020-12-18 14:22:06.962 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x24, 0x85
2020-12-18 14:22:07.375 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:22:07.375 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:22:07.375 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x25, 0x85
2020-12-18 14:22:07.375 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x26, 0x87
2020-12-18 14:22:10.254 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:22:10.254 Detail, Node003, Removing current message
2020-12-18 14:22:10.254 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:22:10.256 Detail,
2020-12-18 14:22:10.256 Info, Node003, Sending (Send) message (Callback ID=0x18, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x18, 0xb9
2020-12-18 14:22:10.262 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:22:10.262 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:22:11.537 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x18, 0x00, 0xf1
2020-12-18 14:22:11.538 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x18 received (expected 0x18)
2020-12-18 14:22:11.538 Info, Node003, Request RTT 1281 Average Request RTT 1279
2020-12-18 14:22:11.538 Detail, Expected callbackId was received
2020-12-18 14:22:20.257 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:22:20.257 Detail, Node003, Removing current message
2020-12-18 14:22:20.257 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:22:20.259 Detail,
2020-12-18 14:22:20.260 Info, Node003, Sending (Send) message (Callback ID=0x19, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x19, 0xb9
2020-12-18 14:22:20.266 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:22:20.266 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:22:21.571 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x19, 0x00, 0xf0
2020-12-18 14:22:21.572 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x19 received (expected 0x19)
2020-12-18 14:22:21.572 Info, Node003, Request RTT 1311 Average Request RTT 1295
2020-12-18 14:22:21.572 Detail, Expected callbackId was received
2020-12-18 14:22:30.260 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:22:30.260 Detail, Node003, Removing current message
2020-12-18 14:22:30.260 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:22:30.263 Detail,
2020-12-18 14:22:30.263 Info, Node003, Sending (Send) message (Callback ID=0x1a, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1a, 0xbb
2020-12-18 14:22:30.269 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:22:30.269 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:22:31.514 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1a, 0x00, 0xf3
2020-12-18 14:22:31.514 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1a received (expected 0x1a)
2020-12-18 14:22:31.514 Info, Node003, Request RTT 1251 Average Request RTT 1273
2020-12-18 14:22:31.515 Detail, Expected callbackId was received
2020-12-18 14:22:40.264 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:22:40.264 Detail, Node003, Removing current message
2020-12-18 14:22:40.264 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:22:40.266 Detail,
2020-12-18 14:22:40.266 Info, Node003, Sending (Send) message (Callback ID=0x1b, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1b, 0xbb
2020-12-18 14:22:40.272 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:22:40.273 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:22:41.518 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1b, 0x00, 0xf2
2020-12-18 14:22:41.518 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1b received (expected 0x1b)
2020-12-18 14:22:41.519 Info, Node003, Request RTT 1252 Average Request RTT 1262
2020-12-18 14:22:41.519 Detail, Expected callbackId was received
2020-12-18 14:22:50.267 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:22:50.267 Detail, Node003, Removing current message
2020-12-18 14:22:50.267 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:22:50.269 Detail,
2020-12-18 14:22:50.269 Info, Node003, Sending (Send) message (Callback ID=0x1c, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1c, 0xbd
2020-12-18 14:22:50.275 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:22:50.275 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:22:51.565 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1c, 0x00, 0xf5
2020-12-18 14:22:51.566 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1c received (expected 0x1c)
2020-12-18 14:22:51.566 Info, Node003, Request RTT 1296 Average Request RTT 1279
2020-12-18 14:22:51.566 Detail, Expected callbackId was received
2020-12-18 14:23:00.270 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:00.270 Detail, Node003, Removing current message
2020-12-18 14:23:00.270 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:00.272 Detail,
2020-12-18 14:23:00.273 Info, Node003, Sending (Send) message (Callback ID=0x1d, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1d, 0xbd
2020-12-18 14:23:00.279 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:00.279 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:01.524 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1d, 0x00, 0xf4
2020-12-18 14:23:01.525 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1d received (expected 0x1d)
2020-12-18 14:23:01.525 Info, Node003, Request RTT 1251 Average Request RTT 1265
2020-12-18 14:23:01.525 Detail, Expected callbackId was received
2020-12-18 14:23:10.273 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:10.273 Detail, Node003, Removing current message
2020-12-18 14:23:10.273 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:10.275 Detail,
2020-12-18 14:23:10.276 Info, Node003, Sending (Send) message (Callback ID=0x1e, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x1e, 0xbf
2020-12-18 14:23:10.281 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:10.282 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:11.527 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1e, 0x00, 0xf7
2020-12-18 14:23:11.527 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1e received (expected 0x1e)
2020-12-18 14:23:11.527 Info, Node003, Request RTT 1251 Average Request RTT 1258
2020-12-18 14:23:11.527 Detail, Expected callbackId was received
2020-12-18 14:23:20.276 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:20.276 Detail, Node003, Removing current message
2020-12-18 14:23:20.277 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:20.279 Detail,
2020-12-18 14:23:20.279 Info, Node003, Sending (Send) message (Callback ID=0x1f, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x1f, 0xbf
2020-12-18 14:23:20.285 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:20.285 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:21.546 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x1f, 0x00, 0xf6
2020-12-18 14:23:21.546 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x1f received (expected 0x1f)
2020-12-18 14:23:21.546 Info, Node003, Request RTT 1266 Average Request RTT 1262
2020-12-18 14:23:21.546 Detail, Expected callbackId was received
2020-12-18 14:23:30.279 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:30.279 Detail, Node003, Removing current message
2020-12-18 14:23:30.279 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:30.282 Detail,
2020-12-18 14:23:30.282 Info, Node003, Sending (Send) message (Callback ID=0x20, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x20, 0x81
2020-12-18 14:23:30.288 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:30.288 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:31.533 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x20, 0x00, 0xc9
2020-12-18 14:23:31.533 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x20 received (expected 0x20)
2020-12-18 14:23:31.533 Info, Node003, Request RTT 1251 Average Request RTT 1256
2020-12-18 14:23:31.533 Detail, Expected callbackId was received
2020-12-18 14:23:40.283 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:40.283 Detail, Node003, Removing current message
2020-12-18 14:23:40.283 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:40.285 Detail,
2020-12-18 14:23:40.285 Info, Node003, Sending (Send) message (Callback ID=0x21, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x21, 0x81
2020-12-18 14:23:40.291 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:40.291 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:41.597 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x21, 0x00, 0xc8
2020-12-18 14:23:41.597 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2020-12-18 14:23:41.597 Info, Node003, Request RTT 1311 Average Request RTT 1283
2020-12-18 14:23:41.598 Detail, Expected callbackId was received
2020-12-18 14:23:50.286 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:23:50.286 Detail, Node003, Removing current message
2020-12-18 14:23:50.286 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:23:50.288 Detail,
2020-12-18 14:23:50.288 Info, Node003, Sending (Send) message (Callback ID=0x22, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x22, 0x83
2020-12-18 14:23:50.294 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:23:50.294 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:23:51.539 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x22, 0x00, 0xcb
2020-12-18 14:23:51.539 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x22 received (expected 0x22)
2020-12-18 14:23:51.539 Info, Node003, Request RTT 1250 Average Request RTT 1266
2020-12-18 14:23:51.539 Detail, Expected callbackId was received
2020-12-18 14:24:00.289 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:24:00.290 Detail, Node003, Removing current message
2020-12-18 14:24:00.290 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:24:00.292 Detail,
2020-12-18 14:24:00.292 Info, Node003, Sending (Send) message (Callback ID=0x23, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x23, 0x83
2020-12-18 14:24:00.298 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:24:00.298 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:24:01.559 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x23, 0x00, 0xca
2020-12-18 14:24:01.559 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x23 received (expected 0x23)
2020-12-18 14:24:01.559 Info, Node003, Request RTT 1266 Average Request RTT 1266
2020-12-18 14:24:01.559 Detail, Expected callbackId was received
2020-12-18 14:24:10.293 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:24:10.293 Detail, Node003, Removing current message
2020-12-18 14:24:10.293 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:24:10.295 Detail,
2020-12-18 14:24:10.296 Info, Node003, Sending (Send) message (Callback ID=0x24, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x24, 0x85
2020-12-18 14:24:10.301 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:24:10.301 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:24:11.546 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x24, 0x00, 0xcd
2020-12-18 14:24:11.547 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x24 received (expected 0x24)
2020-12-18 14:24:11.547 Info, Node003, Request RTT 1250 Average Request RTT 1258
2020-12-18 14:24:11.547 Detail, Expected callbackId was received
2020-12-18 14:24:20.296 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:24:20.296 Detail, Node003, Removing current message
2020-12-18 14:24:20.296 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:24:20.298 Detail,
2020-12-18 14:24:20.299 Info, Node003, Sending (Send) message (Callback ID=0x25, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x25, 0x85
2020-12-18 14:24:20.304 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:24:20.305 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:24:21.610 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x25, 0x00, 0xcc
2020-12-18 14:24:21.610 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x25 received (expected 0x25)
2020-12-18 14:24:21.611 Info, Node003, Request RTT 1311 Average Request RTT 1284
2020-12-18 14:24:21.611 Detail, Expected callbackId was received
2020-12-18 14:24:30.300 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:24:30.300 Detail, Node003, Removing current message
2020-12-18 14:24:30.300 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:24:30.302 Detail,
2020-12-18 14:24:30.302 Info, Node003, Sending (Send) message (Callback ID=0x26, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x26, 0x87
2020-12-18 14:24:30.308 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:24:30.308 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:24:31.538 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x26, 0x00, 0xcf
2020-12-18 14:24:31.539 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x26 received (expected 0x26)
2020-12-18 14:24:31.539 Info, Node003, Request RTT 1236 Average Request RTT 1260
2020-12-18 14:24:31.539 Detail, Expected callbackId was received
2020-12-18 14:24:40.303 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:24:40.303 Detail, Node003, Removing current message
2020-12-18 14:24:40.303 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:25:18.972 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:25:18.973 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:25:18.973 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x27, 0x87
2020-12-18 14:25:18.973 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x28, 0x89
2020-12-18 14:25:18.973 Detail,
2020-12-18 14:25:18.973 Info, Node003, Sending (Send) message (Callback ID=0x27, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x27, 0x87
2020-12-18 14:25:18.979 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:25:18.979 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:25:19.050 Info, Node003, Value::Set - COMMAND_CLASS_DOOR_LOCK - Locked - 0 - 1 - False
2020-12-18 14:25:19.050 Info, Node003, Value_Lock::Set - Requesting lock to be Unlocked
2020-12-18 14:25:19.050 Detail, Node003, Queuing (Send) DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x29, 0x89
2020-12-18 14:25:19.050 Detail, Node003, Queuing (Send) DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x2a, 0x8b
2020-12-18 14:25:20.285 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x27, 0x00, 0xce
2020-12-18 14:25:20.285 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x27 received (expected 0x27)
2020-12-18 14:25:20.285 Info, Node003, Request RTT 1312 Average Request RTT 1286
2020-12-18 14:25:20.285 Detail, Expected callbackId was received
2020-12-18 14:25:28.973 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:25:28.974 Detail, Node003, Removing current message
2020-12-18 14:25:28.974 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:25:28.976 Detail,
2020-12-18 14:25:28.976 Info, Node003, Sending (Send) message (Callback ID=0x28, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x28, 0x89
2020-12-18 14:25:28.982 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:25:28.982 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:25:30.242 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x28, 0x00, 0xc1
2020-12-18 14:25:30.242 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x28 received (expected 0x28)
2020-12-18 14:25:30.242 Info, Node003, Request RTT 1266 Average Request RTT 1276
2020-12-18 14:25:30.242 Detail, Expected callbackId was received
2020-12-18 14:25:38.976 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:25:38.976 Detail, Node003, Removing current message
2020-12-18 14:25:38.976 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:25:38.979 Detail,
2020-12-18 14:25:38.979 Info, Node003, Sending (Send) message (Callback ID=0x29, Expected Reply=0x04) - DoorLockCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x62, 0x01, 0x00, 0x25, 0x29, 0x89
2020-12-18 14:25:38.985 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:25:38.985 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:25:40.245 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x29, 0x00, 0xc0
2020-12-18 14:25:40.246 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x29 received (expected 0x29)
2020-12-18 14:25:40.246 Info, Node003, Request RTT 1267 Average Request RTT 1271
2020-12-18 14:25:40.246 Detail, Expected callbackId was received
2020-12-18 14:25:48.979 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:25:48.979 Detail, Node003, Removing current message
2020-12-18 14:25:48.979 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:25:48.981 Detail,
2020-12-18 14:25:48.981 Info, Node003, Sending (Send) message (Callback ID=0x2a, Expected Reply=0x04) - DoorLockCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x62, 0x02, 0x25, 0x2a, 0x8b
2020-12-18 14:25:48.987 Detail, Node003, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-12-18 14:25:48.987 Detail, Node003, ZW_SEND_DATA delivered to Z-Wave stack
2020-12-18 14:25:50.232 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x13, 0x2a, 0x00, 0xc3
2020-12-18 14:25:50.232 Detail, Node003, ZW_SEND_DATA Request with callback ID 0x2a received (expected 0x2a)
2020-12-18 14:25:50.232 Info, Node003, Request RTT 1252 Average Request RTT 1261
2020-12-18 14:25:50.232 Detail, Expected callbackId was received
2020-12-18 14:25:58.980 Error, Node003, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-12-18 14:25:58.981 Detail, Node003, Removing current message
2020-12-18 14:25:58.981 Detail, Node003, Notification: Notification - TimeOut
2020-12-18 14:32:17.064 Detail, Node001, Queuing (Controller) Request Node Neighbor Update
2020-12-18 14:32:17.064 Detail, Node003, Queuing (Controller) Request Node Neighbor Update
2020-12-18 14:32:17.064 Info, Requesting Neighbor Update for node 1
2020-12-18 14:32:17.064 Detail, Node001, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x2b, 0x98
2020-12-18 14:32:17.065 Detail, Notification: ControllerCommand - Starting
2020-12-18 14:32:17.065 Detail,
2020-12-18 14:32:17.065 Info, Node001, Sending (Command) message (Callback ID=0x2b, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x2b, 0x98
2020-12-18 14:32:17.084 Detail, Node001, Received: 0x01, 0x05, 0x00, 0x48, 0x2b, 0x21, 0xb8
2020-12-18 14:32:17.084 Detail,
2020-12-18 14:32:17.084 Info, Node001, REQUEST_NEIGHBOR_UPDATE_STARTED
2020-12-18 14:32:17.084 Detail, Node033, Expected callbackId was received
2020-12-18 14:32:17.084 Detail, Node033, Expected reply was received
2020-12-18 14:32:17.084 Detail, Node033, Message transaction complete
2020-12-18 14:32:17.084 Detail,
2020-12-18 14:32:17.084 Detail, Node001, Removing current message
2020-12-18 14:32:17.084 Detail, Notification: ControllerCommand - InProgress
2020-12-18 14:32:17.084 Info, WriteNextMsg Controller nothing to do
2020-12-18 14:32:39.841 Detail, Received: 0x01, 0x05, 0x00, 0x48, 0x2b, 0x23, 0xba
2020-12-18 14:32:39.842 Detail,
2020-12-18 14:32:39.842 Warning, WARNING: REQUEST_NEIGHBOR_UPDATE_FAILED
2020-12-18 14:32:39.842 Detail, Notification: ControllerCommand - Failed
2020-12-18 14:32:39.842 Info, Requesting Neighbor Update for node 3
2020-12-18 14:32:39.842 Detail, Node003, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=3): 0x01, 0x05, 0x00, 0x48, 0x03, 0x2c, 0x9d
2020-12-18 14:32:39.842 Detail, Notification: ControllerCommand - Starting
2020-12-18 14:32:39.843 Detail,
2020-12-18 14:32:39.843 Info, Node003, Sending (Command) message (Callback ID=0x2c, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=3): 0x01, 0x05, 0x00, 0x48, 0x03, 0x2c, 0x9d
2020-12-18 14:32:39.856 Detail, Node003, Received: 0x01, 0x05, 0x00, 0x48, 0x2c, 0x21, 0xbf
2020-12-18 14:32:39.856 Detail,
2020-12-18 14:32:39.856 Info, Node003, REQUEST_NEIGHBOR_UPDATE_STARTED
2020-12-18 14:32:39.856 Detail, Node033, Expected callbackId was received
2020-12-18 14:32:39.856 Detail, Node033, Expected reply was received
2020-12-18 14:32:39.857 Detail, Node033, Message transaction complete
2020-12-18 14:32:39.857 Detail,
2020-12-18 14:32:39.857 Detail, Node003, Removing current message
2020-12-18 14:32:39.857 Detail, Notification: ControllerCommand - InProgress
2020-12-18 14:32:39.857 Info, WriteNextMsg Controller nothing to do
2020-12-18 14:32:41.542 Detail, Received: 0x01, 0x05, 0x00, 0x48, 0x2c, 0x22, 0xbc
2020-12-18 14:32:41.542 Detail,
2020-12-18 14:32:41.542 Info, REQUEST_NEIGHBOR_UPDATE_DONE
2020-12-18 14:32:41.542 Detail, Requesting routing info (neighbor list) for Node 3
2020-12-18 14:32:41.542 Detail, Node003, Queuing (Command) Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
2020-12-18 14:32:41.542 Detail, Notification: ControllerCommand - Completed
2020-12-18 14:32:41.542 Detail,
2020-12-18 14:32:41.543 Info, Node003, Sending (Command) message (Callback ID=0x00, Expected Reply=0x80) - Get Routing Info (Node=3): 0x01, 0x07, 0x00, 0x80, 0x03, 0x00, 0x00, 0x03, 0x78
2020-12-18 14:32:41.548 Detail, Node003, Received: 0x01, 0x20, 0x01, 0x80, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x5f
2020-12-18 14:32:41.548 Detail,
2020-12-18 14:32:41.548 Info, Node003, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2020-12-18 14:32:41.548 Info, Node003, Neighbors of this node are:
2020-12-18 14:32:41.548 Info, Node003, Node 1
2020-12-18 14:32:41.548 Detail, Expected reply was received
2020-12-18 14:32:41.548 Detail, Message transaction complete
2020-12-18 14:32:41.549 Detail,
2020-12-18 14:32:41.549 Detail, Node003, Removing current message

Can anyone at all help me or tell me if there is a zwave lock that works with home assistant
Is there a z-wave programmer here

firstof9 · December 19, 2020, 4:37pm

How did you generate your network key?

Did you use this command?

cat /dev/urandom | tr -dc '0-9A-F' | fold -w 32 | head -n 1 | sed -e 's/\(..\)/0x\1, /g' -e 's/, $//'

How far from the lock is the zwave stick?
Do you have any other powered (non-battery) nodes?

Paul_Macie · December 21, 2020, 4:49pm

no i went to a website that generated the network key for me

Paul_Macie · December 21, 2020, 4:51pm

the lock is pretty close to my raspberry pi thinking i might try resetting the lock to default maybe just grabbing at straws at this point

Paul_Macie · December 23, 2020, 3:45pm

thank you problem solved.tthe lock had codes put in to open the lock from keypad. this was stopping me from adding it as secure node. A factory reset of the lock and then adding it as secure node did the trick
lesson learned you cant put any codes in the lock you have to add lock to z-wave first