Long delay (10+ sec) controlling light using WA00Z-1 Scene Switch (Solved)

Hi everyone, I’m running into an issue that I hope someone may have some insight into.

I am running Home Assistant 0.114.4, and am having an issue with my new WA00Z-1 wall controls controlling a light which is connected to a ZEN23 toggle switch. There is a 10+ second delay between pushing a button on them before the light responds.

I have an [HKZW-SCN04] new users can only put two links in a post(h ttps://products.z-wavealliance.org/products/1967?selectedFrequencyId=2), which has no issues controlling this light.

What appears to be happening is that the hub receives the button press from the wall control (Node 16), and queues up the ‘turn on the light’ command for the light switch (Node 3), but it doesn’t seem to send it until a command sent to Node 16 times out:

2020-09-23 17:04:02.564 Error, Node016, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-09-23 17:04:02.564 Detail, Node016, Removing current message
2020-09-23 17:04:02.564 Detail, Node016, Notification: Notification - TimeOut

As soon as this message appears, the light responds.

So, I think that my hub receives the button press, but then asks the wall control something. It queues up the ‘turn on the light’ action until after it hears this response, but the response never comes. After the timeout expires, the action proceeds successfully.

I don’t know what command is expiring, but since the action succeeds after the timeout anyway, whatever it is must not be absolutely vital. Is there any way to find out what the message is, and is there any way to convince HA to not send it (perhaps removing ‘capabilities’ from the zwcfg?).

Any ideas?

Relevant log entries:

# NODE 3 is a ZEN23 toggle switch
# NODE 16 is the WA00Z-1 scene controller
# Button pressed on WA00Z-1
2020-09-23 17:03:52.563 Detail, Node016,   Received: 0x01, 0x18, 0x00, 0x49, 0x84, 0x10, 0x12, 0x04, 0x18, 0x01, 0x5e, 0x86, 0x72, 0x5b, 0x85, 0x59, 0x73, 0x70, 0x80, 0x84, 0x5a, 0x7a, 0xef, 0x5b, 0x20, 0xab
2020-09-23 17:03:52.563 Detail,
2020-09-23 17:03:52.563 Info, Node016, UPDATE_STATE_NODE_INFO_RECEIVED from node 16
2020-09-23 17:03:52.563 Detail, Node016, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2020-09-23 17:03:52.563 Detail, Node016, QueryStage_Dynamic
2020-09-23 17:03:52.563 Detail,
2020-09-23 17:03:52.563 Detail, Node016, Queuing (WakeUp) BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xe5, 0x15
2020-09-23 17:03:52.563 Info, Node016, CentralScene RequestState: 4
2020-09-23 17:03:52.563 Info, Node016, CentralScene: Not a StaticRequest
2020-09-23 17:03:52.563 Detail,
2020-09-23 17:03:52.563 Detail, Node016, Queuing (WakeUp) BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xe6, 0xb6
2020-09-23 17:03:52.563 Info,
2020-09-23 17:03:52.563 Detail, Node016, Queuing (WakeUp) Query Stage Complete (Dynamic)
2020-09-23 17:03:52.563 Info, Node016,   Node 16 has been marked as awake
2020-09-23 17:03:52.563 Detail, Node016, Queuing (WakeUp) BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xe5, 0x15
2020-09-23 17:03:52.563 Detail, Node016, Queuing (WakeUp) BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xe6, 0xb6
2020-09-23 17:03:52.563 Detail, Node016, Queuing (Query) Query Stage Complete (Dynamic)
2020-09-23 17:03:52.563 Detail, Node016, Notification: Notification - Node Awake
2020-09-23 17:03:52.563 Detail,
2020-09-23 17:03:52.563 Info, Node016, Sending (WakeUp) message (Callback ID=0xe5, Expected Reply=0x04) - BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xe5, 0x15
2020-09-23 17:03:52.571 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:03:52.571 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:03:52.579 Detail, Node016,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x10, 0x05, 0x5b, 0x03, 0x2a, 0x00, 0x01, 0x96
2020-09-23 17:03:52.579 Detail,
2020-09-23 17:03:52.580 Info, Node016, Response RTT 16 Average Response RTT 224
2020-09-23 17:03:52.580 Info, Node016, Received Central Scene set from node 16: scene id=1 in 0 seconds. Sending event notification.
2020-09-23 17:03:52.580 Detail, Node016, Refreshed Value: old value=0, new value=0, type=int
2020-09-23 17:03:52.580 Detail, Node016, Changes to this value are not verified
2020-09-23 17:03:52.580 Detail, Node016, Notification: ValueChanged

# It appears to queue the 'Turn on' command, but doesn't seem to send it until after a later timeout expires
2020-09-23 17:03:52.584 Info, Node003, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - True
2020-09-23 17:03:52.584 Info, Node003, SwitchBinary::Set - Setting node 3 to On
2020-09-23 17:03:52.584 Detail, Node003, Queuing (Send) SwitchBinaryCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x25, 0x01, 0xff, 0x25, 0xe7, 0xff
2020-09-23 17:03:52.584 Detail, Node003, Queuing (Send) SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x25, 0xe8, 0x0e
2020-09-23 17:03:52.597 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xe5, 0x00, 0x00, 0x03, 0x0d
2020-09-23 17:03:52.597 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xe5 received (expected 0xe5)
2020-09-23 17:03:52.597 Info, Node016, Request RTT 33 Average Request RTT 138
2020-09-23 17:03:52.597 Detail,   Expected callbackId was received
2020-09-23 17:03:52.874 Detail, Node016,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x10, 0x03, 0x80, 0x03, 0x5b, 0x39
2020-09-23 17:03:52.874 Detail,
2020-09-23 17:03:52.874 Info, Node016, Response RTT 310 Average Response RTT 267
2020-09-23 17:03:52.874 Info, Node016, Received Battery report from node 16: level=91
2020-09-23 17:03:52.874 Detail, Node016, Refreshed Value: old value=79, new value=91, type=byte
2020-09-23 17:03:52.874 Detail, Node016, Changes to this value are not verified
2020-09-23 17:03:52.874 Detail, Node016, Notification: ValueChanged
2020-09-23 17:03:53.874 Detail, Node016,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x10, 0x02, 0x84, 0x07, 0x62
2020-09-23 17:03:53.874 Detail,
2020-09-23 17:03:53.874 Info, Node016, Response RTT 1310 Average Response RTT 788
2020-09-23 17:03:53.874 Info, Node016, Received Wakeup Notification from node 16

# Now 9 seconds later a timeout expires, and the light finally responds
2020-09-23 17:04:02.564 Error, Node016, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-09-23 17:04:02.564 Detail, Node016, Removing current message
2020-09-23 17:04:02.564 Detail, Node016, Notification: Notification - TimeOut
2020-09-23 17:04:02.565 Detail,
2020-09-23 17:04:02.565 Info, Node016, Sending (WakeUp) message (Callback ID=0xe6, Expected Reply=0x04) - BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xe6, 0xb6
2020-09-23 17:04:02.572 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:02.572 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:02.588 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xe6, 0x00, 0x00, 0x02, 0x0f
2020-09-23 17:04:02.588 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xe6 received (expected 0xe6)
2020-09-23 17:04:02.588 Info, Node016, Request RTT 23 Average Request RTT 80
2020-09-23 17:04:02.588 Detail,   Expected callbackId was received
2020-09-23 17:04:02.599 Detail, Node016,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x10, 0x03, 0x80, 0x03, 0x4c, 0x2e
2020-09-23 17:04:02.599 Detail,
2020-09-23 17:04:02.599 Info, Node016, Response RTT 34 Average Response RTT 411
2020-09-23 17:04:02.599 Info, Node016, Received Battery report from node 16: level=76
2020-09-23 17:04:02.599 Detail, Node016, Refreshed Value: old value=91, new value=76, type=byte
2020-09-23 17:04:02.600 Detail, Node016, Changes to this value are not verified
2020-09-23 17:04:02.600 Detail, Node016,   Expected reply and command class was received
2020-09-23 17:04:02.600 Detail, Node016,   Message transaction complete
2020-09-23 17:04:02.600 Detail,
2020-09-23 17:04:02.600 Detail, Node016, Removing current message
2020-09-23 17:04:02.600 Detail, Node016, Notification: ValueChanged
2020-09-23 17:04:02.601 Detail,
2020-09-23 17:04:02.601 Info, Node003, Sending (Send) message (Callback ID=0xe7, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x25, 0x01, 0xff, 0x25, 0xe7, 0xff
2020-09-23 17:04:02.609 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:02.609 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:02.772 Detail, Node003,   Received: 0x01, 0x07, 0x00, 0x13, 0xe7, 0x00, 0x00, 0x10, 0x1c
2020-09-23 17:04:02.772 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0xe7 received (expected 0xe7)
2020-09-23 17:04:02.772 Info, Node003, Request RTT 171 Average Request RTT 193
2020-09-23 17:04:02.772 Detail,   Expected callbackId was received
2020-09-23 17:04:02.772 Detail,   Expected reply was received
2020-09-23 17:04:02.772 Detail,   Message transaction complete
2020-09-23 17:04:02.772 Detail,
2020-09-23 17:04:02.772 Detail, Node003, Removing current message
2020-09-23 17:04:02.772 Detail,
2020-09-23 17:04:02.772 Info, Node003, Sending (Send) message (Callback ID=0xe8, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x25, 0xe8, 0x0e
2020-09-23 17:04:02.779 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:02.779 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:03.006 Detail, Node003,   Received: 0x01, 0x07, 0x00, 0x13, 0xe8, 0x00, 0x00, 0x17, 0x14
2020-09-23 17:04:03.006 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0xe8 received (expected 0xe8)
2020-09-23 17:04:03.006 Info, Node003, Request RTT 235 Average Request RTT 214
2020-09-23 17:04:03.006 Detail,   Expected callbackId was received
2020-09-23 17:04:03.076 Detail, Node003,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0xff, 0x2b
2020-09-23 17:04:03.076 Detail,
2020-09-23 17:04:03.076 Info, Node003, Response RTT 305 Average Response RTT 329
2020-09-23 17:04:03.076 Info, Node003, Received SwitchBinary report from node 3: level=On
2020-09-23 17:04:03.076 Detail, Node003, Refreshed Value: old value=false, new value=true, type=bool
2020-09-23 17:04:03.076 Detail, Node003, Changes to this value are not verified
2020-09-23 17:04:03.076 Detail, Node003,   Expected reply and command class was received
2020-09-23 17:04:03.076 Detail, Node003,   Message transaction complete
2020-09-23 17:04:03.076 Detail,
2020-09-23 17:04:03.076 Detail, Node003, Removing current message
2020-09-23 17:04:03.076 Detail, Node003, Notification: ValueChanged
2020-09-23 17:04:03.077 Detail, Node016, Query Stage Complete (Dynamic)
2020-09-23 17:04:03.077 Detail, Node016, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-09-23 17:04:03.077 Detail, Node016, QueryStage_Configuration
2020-09-23 17:04:03.077 Detail, Node016, QueryStage_Complete
2020-09-23 17:04:03.077 Detail, Node016, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x84, 0x08, 0x25, 0xe9, 0xb7
2020-09-23 17:04:03.077 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=1
2020-09-23 17:04:03.077 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=1
2020-09-23 17:04:03.077 Detail, Node016, Notification: NodeQueriesComplete
2020-09-23 17:04:03.078 Detail,
2020-09-23 17:04:03.078 Info, Node016, Sending (WakeUp) message (Callback ID=0xe9, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x84, 0x08, 0x25, 0xe9, 0xb7
2020-09-23 17:04:03.085 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:03.085 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:03.207 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xe9, 0x00, 0x00, 0x0d, 0x0f
2020-09-23 17:04:03.207 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xe9 received (expected 0xe9)
2020-09-23 17:04:03.207 Info, Node016, Request RTT 129 Average Request RTT 104
2020-09-23 17:04:03.207 Info, Node016,   Node 16 has been marked as asleep
2020-09-23 17:04:03.207 Detail,   Expected callbackId was received
2020-09-23 17:04:03.208 Detail,   Expected reply was received
2020-09-23 17:04:03.208 Detail,   Message transaction complete
2020-09-23 17:04:03.208 Detail,
2020-09-23 17:04:03.208 Detail, Node016, Removing current message
2020-09-23 17:04:03.208 Detail, Node016, Notification: Notification - Node Asleep
2020-09-23 17:04:03.340 Detail, Node003,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0xff, 0x2b
2020-09-23 17:04:03.340 Detail,
2020-09-23 17:04:03.340 Info, Node003, Received SwitchBinary report from node 3: level=On
2020-09-23 17:04:03.340 Detail, Node003, Refreshed Value: old value=true, new value=true, type=bool
2020-09-23 17:04:03.340 Detail, Node003, Changes to this value are not verified
2020-09-23 17:04:03.340 Detail, Node003, Notification: ValueChanged
2020-09-23 17:04:03.514 Detail, Node003,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0xff, 0x2b
2020-09-23 17:04:03.514 Detail,
2020-09-23 17:04:03.514 Info, Node003, Received SwitchBinary report from node 3: level=On
2020-09-23 17:04:03.514 Detail, Node003, Refreshed Value: old value=true, new value=true, type=bool
2020-09-23 17:04:03.514 Detail, Node003, Changes to this value are not verified
2020-09-23 17:04:03.514 Detail, Node003, Notification: ValueChanged
2020-09-23 17:04:08.604 Detail, Node016,   Received: 0x01, 0x18, 0x00, 0x49, 0x84, 0x10, 0x12, 0x04, 0x18, 0x01, 0x5e, 0x86, 0x72, 0x5b, 0x85, 0x59, 0x73, 0x70, 0x80, 0x84, 0x5a, 0x7a, 0xef, 0x5b, 0x20, 0xab
2020-09-23 17:04:08.604 Detail,
2020-09-23 17:04:08.604 Info, Node016, UPDATE_STATE_NODE_INFO_RECEIVED from node 16
2020-09-23 17:04:08.604 Detail, Node016, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Dynamic live=1
2020-09-23 17:04:08.604 Detail, Node016, QueryStage_Dynamic
2020-09-23 17:04:08.604 Detail,
2020-09-23 17:04:08.604 Detail, Node016, Queuing (WakeUp) BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xea, 0x1a
2020-09-23 17:04:08.605 Info, Node016, CentralScene RequestState: 4
2020-09-23 17:04:08.605 Info, Node016, CentralScene: Not a StaticRequest
2020-09-23 17:04:08.605 Detail,
2020-09-23 17:04:08.605 Detail, Node016, Queuing (WakeUp) BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xeb, 0xbb
2020-09-23 17:04:08.605 Info,
2020-09-23 17:04:08.605 Detail, Node016, Queuing (WakeUp) Query Stage Complete (Dynamic)
2020-09-23 17:04:08.605 Info, Node016,   Node 16 has been marked as awake
2020-09-23 17:04:08.605 Detail, Node016, Queuing (WakeUp) BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xea, 0x1a
2020-09-23 17:04:08.605 Detail, Node016, Queuing (WakeUp) BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xeb, 0xbb
2020-09-23 17:04:08.605 Detail, Node016, Queuing (Query) Query Stage Complete (Dynamic)
2020-09-23 17:04:08.605 Detail, Node016, Notification: Notification - Node Awake
2020-09-23 17:04:08.605 Detail,
2020-09-23 17:04:08.605 Info, Node016, Sending (WakeUp) message (Callback ID=0xea, Expected Reply=0x04) - BasicCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x20, 0x02, 0x25, 0xea, 0x1a
2020-09-23 17:04:08.613 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:08.613 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:08.621 Detail, Node016,   Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x10, 0x05, 0x5b, 0x03, 0x2b, 0x00, 0x02, 0x94
2020-09-23 17:04:08.621 Detail,
2020-09-23 17:04:08.621 Info, Node016, Response RTT 15 Average Response RTT 213
2020-09-23 17:04:08.621 Info, Node016, Received Central Scene set from node 16: scene id=2 in 0 seconds. Sending event notification.
2020-09-23 17:04:08.621 Detail, Node016, Refreshed Value: old value=0, new value=0, type=int
2020-09-23 17:04:08.621 Detail, Node016, Changes to this value are not verified
2020-09-23 17:04:08.621 Detail, Node016, Notification: ValueChanged
2020-09-23 17:04:08.629 Info, Node003, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-09-23 17:04:08.629 Info, Node003, SwitchBinary::Set - Setting node 3 to Off
2020-09-23 17:04:08.629 Detail, Node003, Queuing (Send) SwitchBinaryCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x25, 0x01, 0x00, 0x25, 0xec, 0x0b
2020-09-23 17:04:08.629 Detail, Node003, Queuing (Send) SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x25, 0xed, 0x0b
2020-09-23 17:04:08.638 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xea, 0x00, 0x00, 0x03, 0x02
2020-09-23 17:04:08.638 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xea received (expected 0xea)
2020-09-23 17:04:08.638 Info, Node016, Request RTT 33 Average Request RTT 68
2020-09-23 17:04:08.638 Detail,   Expected callbackId was received
2020-09-23 17:04:08.925 Detail, Node016,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x10, 0x03, 0x80, 0x03, 0x52, 0x30
2020-09-23 17:04:08.925 Detail,
2020-09-23 17:04:08.925 Info, Node016, Response RTT 319 Average Response RTT 266
2020-09-23 17:04:08.925 Info, Node016, Received Battery report from node 16: level=82
2020-09-23 17:04:08.925 Detail, Node016, Refreshed Value: old value=76, new value=82, type=byte
2020-09-23 17:04:08.925 Detail, Node016, Changes to this value are not verified
2020-09-23 17:04:08.925 Detail, Node016, Notification: ValueChanged
2020-09-23 17:04:09.925 Detail, Node016,   Received: 0x01, 0x08, 0x00, 0x04, 0x00, 0x10, 0x02, 0x84, 0x07, 0x62
2020-09-23 17:04:09.925 Detail,
2020-09-23 17:04:09.925 Info, Node016, Response RTT 1319 Average Response RTT 792
2020-09-23 17:04:09.925 Info, Node016, Received Wakeup Notification from node 16
2020-09-23 17:04:18.606 Error, Node016, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-09-23 17:04:18.606 Detail, Node016, Removing current message
2020-09-23 17:04:18.606 Detail, Node016, Notification: Notification - TimeOut
2020-09-23 17:04:18.607 Detail,
2020-09-23 17:04:18.607 Info, Node016, Sending (WakeUp) message (Callback ID=0xeb, Expected Reply=0x04) - BatteryCmd_Get (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x80, 0x02, 0x25, 0xeb, 0xbb
2020-09-23 17:04:18.614 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:18.614 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:18.630 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xeb, 0x00, 0x00, 0x02, 0x02
2020-09-23 17:04:18.630 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xeb received (expected 0xeb)
2020-09-23 17:04:18.630 Info, Node016, Request RTT 23 Average Request RTT 45
2020-09-23 17:04:18.630 Detail,   Expected callbackId was received
2020-09-23 17:04:18.641 Detail, Node016,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x10, 0x03, 0x80, 0x03, 0x49, 0x2b
2020-09-23 17:04:18.641 Detail,
2020-09-23 17:04:18.641 Info, Node016, Response RTT 34 Average Response RTT 413
2020-09-23 17:04:18.641 Info, Node016, Received Battery report from node 16: level=73
2020-09-23 17:04:18.641 Detail, Node016, Refreshed Value: old value=82, new value=73, type=byte
2020-09-23 17:04:18.641 Detail, Node016, Changes to this value are not verified
2020-09-23 17:04:18.641 Detail, Node016,   Expected reply and command class was received
2020-09-23 17:04:18.641 Detail, Node016,   Message transaction complete
2020-09-23 17:04:18.641 Detail,
2020-09-23 17:04:18.641 Detail, Node016, Removing current message
2020-09-23 17:04:18.641 Detail, Node016, Notification: ValueChanged
2020-09-23 17:04:18.643 Detail,
2020-09-23 17:04:18.643 Info, Node003, Sending (Send) message (Callback ID=0xec, Expected Reply=0x13) - SwitchBinaryCmd_Set (Node=3): 0x01, 0x0a, 0x00, 0x13, 0x03, 0x03, 0x25, 0x01, 0x00, 0x25, 0xec, 0x0b
2020-09-23 17:04:18.651 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:18.651 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:18.814 Detail, Node003,   Received: 0x01, 0x07, 0x00, 0x13, 0xec, 0x00, 0x00, 0x11, 0x16
2020-09-23 17:04:18.814 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0xec received (expected 0xec)
2020-09-23 17:04:18.814 Info, Node003, Request RTT 171 Average Request RTT 192
2020-09-23 17:04:18.814 Detail,   Expected callbackId was received
2020-09-23 17:04:18.814 Detail,   Expected reply was received
2020-09-23 17:04:18.814 Detail,   Message transaction complete
2020-09-23 17:04:18.814 Detail,
2020-09-23 17:04:18.814 Detail, Node003, Removing current message
2020-09-23 17:04:18.814 Detail,
2020-09-23 17:04:18.814 Info, Node003, Sending (Send) message (Callback ID=0xed, Expected Reply=0x04) - SwitchBinaryCmd_Get (Node=3): 0x01, 0x09, 0x00, 0x13, 0x03, 0x02, 0x25, 0x02, 0x25, 0xed, 0x0b
2020-09-23 17:04:18.822 Detail, Node003,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:18.822 Detail, Node003,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:19.109 Detail, Node003,   Received: 0x01, 0x07, 0x00, 0x13, 0xed, 0x00, 0x00, 0x1d, 0x1b
2020-09-23 17:04:19.109 Detail, Node003,   ZW_SEND_DATA Request with callback ID 0xed received (expected 0xed)
2020-09-23 17:04:19.109 Info, Node003, Request RTT 295 Average Request RTT 243
2020-09-23 17:04:19.109 Detail,   Expected callbackId was received
2020-09-23 17:04:19.177 Detail, Node003,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0x00, 0xd4
2020-09-23 17:04:19.177 Detail,
2020-09-23 17:04:19.177 Info, Node003, Response RTT 364 Average Response RTT 346
2020-09-23 17:04:19.177 Info, Node003, Received SwitchBinary report from node 3: level=Off
2020-09-23 17:04:19.177 Detail, Node003, Refreshed Value: old value=true, new value=false, type=bool
2020-09-23 17:04:19.177 Detail, Node003, Changes to this value are not verified
2020-09-23 17:04:19.177 Detail, Node003,   Expected reply and command class was received
2020-09-23 17:04:19.177 Detail, Node003,   Message transaction complete
2020-09-23 17:04:19.178 Detail,
2020-09-23 17:04:19.178 Detail, Node003, Removing current message
2020-09-23 17:04:19.178 Detail, Node003, Notification: ValueChanged
2020-09-23 17:04:19.179 Detail, Node016, Query Stage Complete (Dynamic)
2020-09-23 17:04:19.179 Detail, Node016, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Configuration live=1
2020-09-23 17:04:19.179 Detail, Node016, QueryStage_Configuration
2020-09-23 17:04:19.179 Detail, Node016, QueryStage_Complete
2020-09-23 17:04:19.179 Detail, Node016, Queuing (WakeUp) WakeUpCmd_NoMoreInformation (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x84, 0x08, 0x25, 0xee, 0xb0
2020-09-23 17:04:19.179 Warning, CheckCompletedNodeQueries m_allNodesQueried=0 m_awakeNodesQueried=1
2020-09-23 17:04:19.179 Warning, CheckCompletedNodeQueries all=0, deadFound=0 sleepingOnly=1
2020-09-23 17:04:19.179 Detail, Node016, Notification: NodeQueriesComplete
2020-09-23 17:04:19.179 Detail,
2020-09-23 17:04:19.179 Info, Node016, Sending (WakeUp) message (Callback ID=0xee, Expected Reply=0x13) - WakeUpCmd_NoMoreInformation (Node=16): 0x01, 0x09, 0x00, 0x13, 0x10, 0x02, 0x84, 0x08, 0x25, 0xee, 0xb0
2020-09-23 17:04:19.187 Detail, Node016,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-09-23 17:04:19.187 Detail, Node016,   ZW_SEND_DATA delivered to Z-Wave stack
2020-09-23 17:04:19.556 Detail, Node016,   Received: 0x01, 0x07, 0x00, 0x13, 0xee, 0x00, 0x00, 0x26, 0x23
2020-09-23 17:04:19.556 Detail, Node016,   ZW_SEND_DATA Request with callback ID 0xee received (expected 0xee)
2020-09-23 17:04:19.556 Info, Node016, Request RTT 377 Average Request RTT 211
2020-09-23 17:04:19.556 Info, Node016,   Node 16 has been marked as asleep
2020-09-23 17:04:19.556 Detail,   Expected callbackId was received
2020-09-23 17:04:19.556 Detail,   Expected reply was received
2020-09-23 17:04:19.556 Detail,   Message transaction complete
2020-09-23 17:04:19.556 Detail,
2020-09-23 17:04:19.556 Detail, Node016, Removing current message
2020-09-23 17:04:19.556 Detail, Node016, Notification: Notification - Node Asleep
2020-09-23 17:04:19.748 Detail, Node003,   Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x03, 0x03, 0x25, 0x03, 0x00, 0xd4
2020-09-23 17:04:19.748 Detail,
2020-09-23 17:04:19.748 Info, Node003, Received SwitchBinary report from node 3: level=Off
2020-09-23 17:04:19.748 Detail, Node003, Refreshed Value: old value=false, new value=false, type=bool
2020-09-23 17:04:19.748 Detail, Node003, Changes to this value are not verified
2020-09-23 17:04:19.748 Detail, Node003, Notification: ValueChanged

I removed all “CommandClass” except “COMMAND_CLASS_CENTRAL_SCENE” from my zcfg_.xml, and the problem appears to have gone away. Now to try to track down which CommandClass is causing issues…

Ok, it seems that removing the CC “COMMAND_CLASS_BASIC” from the zwcfg_xxx.cfg makes the issue go away.

			<CommandClass id="32" name="COMMAND_CLASS_BASIC" version="1" request_flags="4" innif="true">
				<Instance index="1" />
				<Value type="byte" genre="basic" instance="1" index="0" label="Basic" units="" read_only="false" write_only="false" verify_changes="false" poll_intensity="0" min="0" max="255" value="0" />
			</CommandClass>

This seems to make HA stop sending out whatever message the device is not responding to.