Has anyone understood why this is happening. I have all powered z-wave nodes (should be more reliable) and i get dropped messages after some time as the network appears to overload.
I can confirm that my stick is correctly connected as logs show good connects for heal start attempts etc.
Using z-wave integration on latest HASS.io
2019-12-28 08:53:26.700 Detail,
2019-12-28 08:53:26.700 Info, Node007, Sending (Send) message (Callback ID=0x1c, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x26, 0x02, 0x25, 0x1c, 0xfd
2019-12-28 08:53:27.700 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 08:53:27.700 Detail, Node007, Removing current message
2019-12-28 08:53:27.700 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 08:53:27.701 Detail,
2019-12-28 08:53:27.701 Info, Node007, Sending (Send) message (Callback ID=0x1d, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x26, 0x02, 0x25, 0x1d, 0xfc
2019-12-28 08:53:28.701 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 08:53:28.701 Detail, Node007, Removing current message
2019-12-28 08:53:28.701 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 10:50:33.509 Detail, Node001, Queuing (Controller) Request Node Neighbor Update
2019-12-28 10:50:33.510 Detail, Node006, Queuing (Controller) Request Node Neighbor Update
2019-12-28 10:50:33.510 Detail, Node007, Queuing (Controller) Request Node Neighbor Update
2019-12-28 10:50:33.510 Detail, Node009, Queuing (Controller) Request Node Neighbor Update
2019-12-28 10:50:33.510 Info, Requesting Neighbor Update for node 1
2019-12-28 10:50:33.511 Detail, Node001, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x1e, 0xad
2019-12-28 10:50:33.511 Detail, Notification: ControllerCommand - Starting
2019-12-28 10:50:33.512 Detail,
2019-12-28 10:50:33.512 Info, Node001, Sending (Command) message (Callback ID=0x1e, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=1): 0x01, 0x05, 0x00, 0x48, 0x01, 0x1e, 0xad
2019-12-28 10:50:34.512 Error, Node001, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 10:50:34.512 Detail, Node001, Removing current message
2019-12-28 10:50:34.512 Detail, Node001, Notification: Notification - TimeOut
2019-12-28 10:50:34.518 Detail, Notification: ControllerCommand - Error - Failed
2019-12-28 10:50:34.518 Info, Requesting Neighbor Update for node 6
2019-12-28 10:50:34.518 Detail, Node006, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=6): 0x01, 0x05, 0x00, 0x48, 0x06, 0x1f, 0xab
2019-12-28 10:50:34.518 Detail, Notification: ControllerCommand - Starting
2019-12-28 10:50:34.518 Detail,
2019-12-28 10:50:34.518 Info, Node006, Sending (Command) message (Callback ID=0x1f, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=6): 0x01, 0x05, 0x00, 0x48, 0x06, 0x1f, 0xab
2019-12-28 10:50:35.518 Error, Node006, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 10:50:35.518 Detail, Node006, Removing current message
2019-12-28 10:50:35.518 Detail, Node006, Notification: Notification - TimeOut
2019-12-28 10:50:35.519 Detail, Notification: ControllerCommand - Error - Failed
2019-12-28 10:50:35.519 Info, Requesting Neighbor Update for node 7
2019-12-28 10:50:35.519 Detail, Node007, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=7): 0x01, 0x05, 0x00, 0x48, 0x07, 0x20, 0x95
2019-12-28 10:50:35.519 Detail, Notification: ControllerCommand - Starting
2019-12-28 10:50:35.519 Detail,
2019-12-28 10:50:35.519 Info, Node007, Sending (Command) message (Callback ID=0x20, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=7): 0x01, 0x05, 0x00, 0x48, 0x07, 0x20, 0x95
2019-12-28 10:50:36.519 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 10:50:36.520 Detail, Node007, Removing current message
2019-12-28 10:50:36.520 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 10:50:36.520 Detail, Notification: ControllerCommand - Error - Failed
2019-12-28 10:50:36.520 Info, Requesting Neighbor Update for node 9
2019-12-28 10:50:36.520 Detail, Node009, Queuing (Command) ControllerCommand_RequestNodeNeighborUpdate (Node=9): 0x01, 0x05, 0x00, 0x48, 0x09, 0x21, 0x9a
2019-12-28 10:50:36.520 Detail, Notification: ControllerCommand - Starting
2019-12-28 10:50:36.520 Detail,
2019-12-28 10:50:36.520 Info, Node009, Sending (Command) message (Callback ID=0x21, Expected Reply=0x48) - ControllerCommand_RequestNodeNeighborUpdate (Node=9): 0x01, 0x05, 0x00, 0x48, 0x09, 0x21, 0x9a
2019-12-28 10:50:37.521 Error, Node009, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 10:50:37.521 Detail, Node009, Removing current message
2019-12-28 10:50:37.521 Detail, Node009, Notification: Notification - TimeOut
2019-12-28 10:50:37.522 Detail, Notification: ControllerCommand - Error - Failed
Any suggestions is much appreciated as i am restarting a lot.
Example, restarted and re-configured a few switch types - first two work then it fails and now no nodes are responsive.
2019-12-28 11:00:47.428 Info, Node009, Configuration::Set - Parameter=120, Value=1 Size=1
2019-12-28 11:00:47.429 Detail, Node009, Queuing (Send) ConfigurationCmd_Set (Node=9): 0x01, 0x0c, 0x00, 0x13, 0x09, 0x05, 0x70, 0x04, 0x78, 0x01, 0x01, 0x25, 0x49, 0x8c
2019-12-28 11:00:47.429 Detail, Node009, Queuing (Send) ConfigurationCmd_Get (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x70, 0x05, 0x78, 0x25, 0x4a, 0x8e
2019-12-28 11:00:47.429 Detail,
2019-12-28 11:00:47.429 Info, Node009, Sending (Send) message (Callback ID=0x49, Expected Reply=0x13) - ConfigurationCmd_Set (Node=9): 0x01, 0x0c, 0x00, 0x13, 0x09, 0x05, 0x70, 0x04, 0x78, 0x01, 0x01, 0x25, 0x49, 0x8c
2019-12-28 11:00:47.436 Detail, Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-12-28 11:00:47.436 Detail, Node009, ZW_SEND_DATA delivered to Z-Wave stack
2019-12-28 11:00:47.453 Detail, Node009, Received: 0x01, 0x07, 0x00, 0x13, 0x49, 0x00, 0x00, 0x02, 0xa0
2019-12-28 11:00:47.453 Detail, Node009, ZW_SEND_DATA Request with callback ID 0x49 received (expected 0x49)
2019-12-28 11:00:47.453 Info, Node009, Request RTT 24 Average Request RTT 23
2019-12-28 11:00:47.453 Detail, Expected callbackId was received
2019-12-28 11:00:47.453 Detail, Expected reply was received
2019-12-28 11:00:47.453 Detail, Message transaction complete
2019-12-28 11:00:47.453 Detail,
2019-12-28 11:00:47.453 Detail, Node009, Removing current message
2019-12-28 11:00:47.453 Detail,
2019-12-28 11:00:47.453 Info, Node009, Sending (Send) message (Callback ID=0x4a, Expected Reply=0x04) - ConfigurationCmd_Get (Node=9): 0x01, 0x0a, 0x00, 0x13, 0x09, 0x03, 0x70, 0x05, 0x78, 0x25, 0x4a, 0x8e
2019-12-28 11:00:47.461 Detail, Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-12-28 11:00:47.461 Detail, Node009, ZW_SEND_DATA delivered to Z-Wave stack
2019-12-28 11:00:47.477 Detail, Node009, Received: 0x01, 0x07, 0x00, 0x13, 0x4a, 0x00, 0x00, 0x02, 0xa3
2019-12-28 11:00:47.477 Detail, Node009, ZW_SEND_DATA Request with callback ID 0x4a received (expected 0x4a)
2019-12-28 11:00:47.477 Info, Node009, Request RTT 23 Average Request RTT 23
2019-12-28 11:00:47.477 Detail, Expected callbackId was received
2019-12-28 11:00:47.489 Detail, Node009, Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x09, 0x05, 0x70, 0x06, 0x78, 0x01, 0x01, 0xf2
2019-12-28 11:00:47.489 Detail,
2019-12-28 11:00:47.489 Info, Node009, Response RTT 35 Average Response RTT 35
2019-12-28 11:00:47.489 Detail, Node009, Refreshed Value: old value=3, new value=1, type=list
2019-12-28 11:00:47.489 Detail, Node009, Changes to this value are not verified
2019-12-28 11:00:47.489 Info, Node009, Received Configuration report: Parameter=120, Value=1
2019-12-28 11:00:47.489 Detail, Node009, Expected reply and command class was received
2019-12-28 11:00:47.489 Detail, Node009, Message transaction complete
2019-12-28 11:00:47.489 Detail,
2019-12-28 11:00:47.489 Detail, Node009, Removing current message
2019-12-28 11:00:47.489 Detail, Node009, Notification: ValueChanged
2019-12-28 11:02:08.125 Info, Node007, Value::Set - COMMAND_CLASS_CONFIGURATION - Set the external switch mode for S1 - 120 - 1 - 2-state switch
2019-12-28 11:02:08.125 Info, Node007, Configuration::Set - Parameter=120, Value=1 Size=1
2019-12-28 11:02:08.126 Detail, Node007, Queuing (Send) ConfigurationCmd_Set (Node=7): 0x01, 0x0c, 0x00, 0x13, 0x07, 0x05, 0x70, 0x04, 0x78, 0x01, 0x01, 0x25, 0x4b, 0x80
2019-12-28 11:02:08.126 Detail, Node007, Queuing (Send) ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0x78, 0x25, 0x4c, 0x86
2019-12-28 11:02:08.126 Detail,
2019-12-28 11:02:08.126 Info, Node007, Sending (Send) message (Callback ID=0x4b, Expected Reply=0x13) - ConfigurationCmd_Set (Node=7): 0x01, 0x0c, 0x00, 0x13, 0x07, 0x05, 0x70, 0x04, 0x78, 0x01, 0x01, 0x25, 0x4b, 0x80
2019-12-28 11:02:09.126 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 11:02:09.126 Detail, Node007, Removing current message
2019-12-28 11:02:09.126 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 11:02:09.126 Detail,
2019-12-28 11:02:09.127 Info, Node007, Sending (Send) message (Callback ID=0x4c, Expected Reply=0x04) - ConfigurationCmd_Get (Node=7): 0x01, 0x0a, 0x00, 0x13, 0x07, 0x03, 0x70, 0x05, 0x78, 0x25, 0x4c, 0x86
2019-12-28 11:02:10.127 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 11:02:10.127 Detail, Node007, Removing current message
2019-12-28 11:02:10.127 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 11:05:49.418 Info, Node007, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Dimming Duration - 5 - 1 - 255
2019-12-28 11:05:49.418 Detail, Node007, Initial read of value
2019-12-28 11:05:49.418 Detail, Node007, Notification: ValueChanged
2019-12-28 11:05:49.418 Info, Node007, Value::Set - COMMAND_CLASS_SWITCH_MULTILEVEL - Level - 0 - 1 - 255
2019-12-28 11:05:49.418 Info, Node007, SwitchMultilevel::Set - Setting to level 255
2019-12-28 11:05:49.418 Info, Node007, Duration: Default
2019-12-28 11:05:49.418 Detail, Node007, Queuing (Send) SwitchMultilevelCmd_Set (Node=7): 0x01, 0x0b, 0x00, 0x13, 0x07, 0x04, 0x26, 0x01, 0xff, 0xff, 0x25, 0x4d, 0xab
2019-12-28 11:05:49.418 Detail, Node007, Queuing (Send) SwitchMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x26, 0x02, 0x25, 0x4e, 0xaf
2019-12-28 11:05:49.421 Detail,
2019-12-28 11:05:49.421 Info, Node007, Sending (Send) message (Callback ID=0x4d, Expected Reply=0x13) - SwitchMultilevelCmd_Set (Node=7): 0x01, 0x0b, 0x00, 0x13, 0x07, 0x04, 0x26, 0x01, 0xff, 0xff, 0x25, 0x4d, 0xab
2019-12-28 11:05:50.421 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 11:05:50.421 Detail, Node007, Removing current message
2019-12-28 11:05:50.421 Detail, Node007, Notification: Notification - TimeOut
2019-12-28 11:05:50.422 Detail,
2019-12-28 11:05:50.422 Info, Node007, Sending (Send) message (Callback ID=0x4e, Expected Reply=0x04) - SwitchMultilevelCmd_Get (Node=7): 0x01, 0x09, 0x00, 0x13, 0x07, 0x02, 0x26, 0x02, 0x25, 0x4e, 0xaf
2019-12-28 11:05:51.422 Error, Node007, ERROR: Dropping command, expected response not received after 1 attempt(s)
2019-12-28 11:05:51.422 Detail, Node007, Removing current message
2019-12-28 11:05:51.422 Detail, Node007, Notification: Notification - TimeOut