Z-wave device spamming network

Configuration Z-Wave
1

Hi All,

I have an issue with my z-wave in home assistant. When I restart home assistant and z-wave is configuring it goes through all the nodes, but stops at one. From the OZW log I can see that the controller and this node is spamming the entire z-wave network to that extent that other messages gets a huge (2-3min) delay to go through. If I then cut the power to this node (aeotec dimmer 2) the z-wave network is able to complete configuring and everything works as normal, I can then power the node again and it still works. Any ideas or tips on how this can be fixed except removing the node?

Rpi with hassbian 0.79.3
Aeon z-stick
Node 29: Aeotec Dimmer 2

This is just a small part of the log, but it continues in much the same manner:

> 2018-10-24 15:58:38.080 Detail, Node029, Query Stage Complete (CacheLoad)
> 2018-10-24 15:58:38.080 Detail, Node029, AdvanceQueries queryPending=0 queryRetries=0 queryStage=Associations live=1
> 2018-10-24 15:58:38.080 Detail, Node029, QueryStage_Associations
> 2018-10-24 15:58:38.080 Info, Node029, Number of association groups reported for node 29 is 2.
> 2018-10-24 15:58:38.080 Info, Node029, Get Associations for group 1 of node 29
> 2018-10-24 15:58:38.080 Detail, Node029, Queuing (Send) AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x01, 0x25, 0x71, 0x2a
> 2018-10-24 15:58:38.080 Detail, Node029, Queuing (Query) Query Stage Complete (Associations)
> 2018-10-24 15:58:38.080 Detail, 
> 2018-10-24 15:58:38.081 Info, Node029, Sending (Send) message (Callback ID=0x71, Expected Reply=0x04) - AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x01, 0x25, 0x71, 0x2a
> 2018-10-24 15:58:38.089 Detail, Node029,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
> 2018-10-24 15:58:38.089 Detail, Node029,   ZW_SEND_DATA delivered to Z-Wave stack
> 2018-10-24 15:58:38.107 Detail, Node029,   Received: 0x01, 0x07, 0x00, 0x13, 0x71, 0x00, 0x00, 0x03, 0x99
> 2018-10-24 15:58:38.107 Detail, Node029,   ZW_SEND_DATA Request with callback ID 0x71 received (expected 0x71)
> 2018-10-24 15:58:38.107 Info, Node029, Request RTT 26 Average Request RTT 129
> 2018-10-24 15:58:38.107 Detail,   Expected callbackId was received
> 2018-10-24 15:58:38.305 Detail, Node029,   Received: 0x01, 0x0c, 0x00, 0x04, 0x10, 0x1d, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0x7f
> 2018-10-24 15:58:38.305 Detail, 
> 2018-10-24 15:58:38.305 Info, Node029, Response RTT 224 Average Response RTT 224
> 2018-10-24 15:58:38.305 Info, Node029, Received Association report from node 29, group 1, containing 1 associations
> 2018-10-24 15:58:38.305 Info, Node029,   The group contains:
> 2018-10-24 15:58:38.305 Info, Node029,     Node 1
> 2018-10-24 15:58:38.305 Info, Node029, Get Associations for group 2 of node 29
> 2018-10-24 15:58:38.306 Detail, Node029, Queuing (Send) AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x02, 0x25, 0x72, 0x2a
> 2018-10-24 15:58:38.306 Detail, Node029,   Expected reply and command class was received
> 2018-10-24 15:58:38.306 Detail, Node029,   Message transaction complete
> 2018-10-24 15:58:38.306 Detail, 
> 2018-10-24 15:58:38.306 Detail, Node029, Removing current message
> 2018-10-24 15:58:38.306 Detail, 
> 2018-10-24 15:58:38.306 Info, Node029, Sending (Send) message (Callback ID=0x72, Expected Reply=0x04) - AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x02, 0x25, 0x72, 0x2a
> 2018-10-24 15:58:38.315 Detail, Node029,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
> 2018-10-24 15:58:38.316 Detail, Node029,   ZW_SEND_DATA delivered to Z-Wave stack
> 2018-10-24 15:58:38.377 Detail, Node029,   Received: 0x01, 0x07, 0x00, 0x13, 0x72, 0x00, 0x00, 0x07, 0x9e
> 2018-10-24 15:58:38.377 Detail, Node029,   ZW_SEND_DATA Request with callback ID 0x72 received (expected 0x72)
> 2018-10-24 15:58:38.377 Info, Node029, Request RTT 70 Average Request RTT 99
> 2018-10-24 15:58:38.377 Detail,   Expected callbackId was received
> 2018-10-24 15:58:38.427 Detail, Node029,   Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x1d, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0x6f
> 2018-10-24 15:58:38.427 Detail, 
> 2018-10-24 15:58:38.427 Info, Node029, Response RTT 120 Average Response RTT 172
> 2018-10-24 15:58:38.427 Info, Node029, Received Association report from node 29, group 1, containing 1 associations
> 2018-10-24 15:58:38.428 Info, Node029,   The group contains:
> 2018-10-24 15:58:38.428 Info, Node029,     Node 1
> 2018-10-24 15:58:38.428 Info, Node029, Get Associations for group 2 of node 29
> 2018-10-24 15:58:38.428 Detail, Node029, Queuing (Send) AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x02, 0x25, 0x73, 0x2b
> 2018-10-24 15:58:38.428 Detail, Node029,   Expected reply and command class was received
> 2018-10-24 15:58:38.428 Detail, Node029,   Message transaction complete
> 2018-10-24 15:58:38.428 Detail, 
> 2018-10-24 15:58:38.428 Detail, Node029, Removing current message
> 2018-10-24 15:58:38.428 Detail, 
> 2018-10-24 15:58:38.428 Info, Node029, Sending (Send) message (Callback ID=0x73, Expected Reply=0x04) - AssociationCmd_Get (Node=29): 0x01, 0x0a, 0x00, 0x13, 0x1d, 0x03, 0x85, 0x02, 0x02, 0x25, 0x73, 0x2b
> 2018-10-24 15:58:38.438 Detail, Node029,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
> 2018-10-24 15:58:38.438 Detail, Node029,   ZW_SEND_DATA delivered to Z-Wave stack
> 2018-10-24 15:58:38.488 Detail, Node029,   Received: 0x01, 0x0c, 0x00, 0x04, 0x10, 0x1d, 0x06, 0x85, 0x03, 0x01, 0x05, 0x00, 0x01, 0x7f
> 2018-10-24 15:58:38.488 Detail,
2

I’m not familiar with this particular device but have you checked the parameter settings for it?

3

@tom_l Thanks for the reply. This suddenly happened after a power out and was not an issue before. The parameter settings have not been changed since then. But I will have another look to see if something have been reset by it self.

4

A similar thing has happened with me. I had some electrical work done yesterday and the power had to be turned off and on a few times. Since then the z-wave has stopped responding to commands. The OZW log shows it is repetitively churning through some sort of checking routine with the nodes. Each node is examined over hours. Their status doesn’t get beyond Initializing (CacheLoad).
I have about 80 nodes and 20 of them are encrypted but I haven’t had this problem before.
I have read that OZW does an excessive amount of checking but I don’t know how I look into this further, or how to stop it.
Any help much appreciated.
Here is a piece of the log:>

2018-11-04 09:14:07.282 Info, Node005, The group contains:
2018-11-04 09:14:07.282 Detail, Node005, Notification: Group
2018-11-04 09:14:07.293 Detail, Node005, Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x05, 0x05, 0x8e, 0x03, 0x04, 0x08, 0x00, 0x61
2018-11-04 09:14:07.293 Detail,
2018-11-04 09:14:07.293 Info, Node005, Response RTT 154 Average Response RTT 139
2018-11-04 09:14:07.293 Info, Node005, Received Multi Instance Association report from node 5, group 4
2018-11-04 09:14:07.293 Info, Node005, The group contains:
2018-11-04 09:14:07.293 Detail, Node005, Notification: Group
2018-11-04 09:14:07.313 Detail, Node005, Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x05, 0x05, 0x8e, 0x03, 0x04, 0x08, 0x00, 0x61
2018-11-04 09:14:07.313 Detail,
2018-11-04 09:14:07.313 Info, Node005, Response RTT 175 Average Response RTT 157
2018-11-04 09:14:07.313 Info, Node005, Received Multi Instance Association report from node 5, group 4
2018-11-04 09:14:07.314 Info, Node005, The group contains:
2018-11-04 09:14:07.314 Detail, Node005, Notification: Group
2018-11-04 09:14:07.343 Detail, Node004, Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x04, 0x05, 0x8e, 0x03, 0x04, 0x08, 0x00, 0x60
2018-11-04 09:14:07.344 Detail,
2018-11-04 09:14:07.355 Detail, Node005, Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x05, 0x05, 0x8e, 0x03, 0x04, 0x08, 0x00, 0x61
2018-11-04 09:14:07.355 Detail,
2018-11-04 09:14:07.355 Info, Node005, Response RTT 216 Average Response RTT 186
2018-11-04 09:14:07.355 Info, Node005, Received Multi Instance Association report from node 5, group 4
2018-11-04 09:14:07.355 Info, Node005, The group contains:
2018-11-04 09:14:07.355 Detail, Node005, Notification: Group
2018-11-04 09:14:07.377 Detail, Node005, Received: 0x01, 0x07, 0x00, 0x13, 0xf3, 0x00, 0x00, 0x17, 0x0f
2018-11-04 09:14:07.377 Detail, Node005, ZW_SEND_DATA Request with callback ID 0xf3 received (expected 0xf3)
2018-11-04 09:14:07.378 Info, Node005, Request RTT 239 Average Request RTT 249
2018-11-04 09:14:07.378 Detail, Expected callbackId was received
2018-11-04 09:14:07.411 Detail, Node005, Received: 0x01, 0x0b, 0x00, 0x04, 0x10, 0x05, 0x05, 0x8e, 0x03, 0x04, 0x08, 0x00, 0x61
2018-11-04 09:14:07.412 Detail,
2018-11-04 09:14:07.412 Info, Node005, Response RTT 273 Average Response RTT 229
2018-11-04 09:14:07.412 Info, Node005, Received Multi Instance Association report from node 5, group 4
2018-11-04 09:14:07.412 Info, Node005, The group contains:
2018-11-04 09:14:07.412 Detail, Node005, Expected reply and command class was received
2018-11-04 09:14:07.412 Detail, Node005, Message transaction complete
2018-11-04 09:14:07.412 Detail,
2018-11-04 09:14:07.412 Detail, Node005, Removing current message
2018-11-04 09:14:07.412 Detail, Node005, Notification: Group
2018-11-04 09:14:07.413 Detail,
2018-11-04 09:14:07.413 Info, Node005, Sending (Send) message (Callback ID=0xf4, Expected Reply=0x04) - MultiChannelAssociationCmd_Get (Node=5): 0x01, 0x0a, 0x00, 0x13, 0x05, 0x03, 0x8e, 0x02, 0x04, 0x25, 0xf4, 0xb9
2018-11-04 09:14:07.421 Detail, Node005, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-11-04 09:14:07.421 Detail, Node005, ZW_SEND_DATA delivered to Z-Wave stack
2018-11-04 09:14:07.728 Detail, Node005, Received: 0x01, 0x07, 0x00, 0x13, 0xf4, 0x00, 0x00, 0x1f, 0x00
2018-11-04 09:14:07.728 Detail, Node005, ZW_SEND_DATA Request with callback ID 0xf4 received (expected 0xf4)
2018-11-04 09:14:07.729 Info, Node005, Request RTT 315 Average Request RTT 282
2018-11-04 09:14:07.729 Detail, Expected callbackId was received