Hello Community,
I recently added an Eaton Z-Wave Dimmer to my Aeotec Z-Wave controller, and on startup is always remains stuck at the “Associations” stage. When I look at the logs, it keeps looping through the same thing. Here is a snippet of the log:
2019-09-12 19:37:24.100 Info, Node074, Association::Set - Adding node 1 to group 255 of node 74
2019-09-12 19:37:24.100 Detail, Node074, Queuing (Send) AssociationCmd_Set (Node=74): 0x01, 0x0b, 0x00, 0x13, 0x4a, 0x04, 0x85, 0x01, 0xff, 0x01, 0x25, 0x5a, 0xac
2019-09-12 19:37:24.100 Info, Node074, Get Associations for group 255 of node 74
2019-09-12 19:37:24.100 Detail, Node074, Queuing (Send) AssociationCmd_Get (Node=74): 0x01, 0x0a, 0x00, 0x13, 0x4a, 0x03, 0x85, 0x02, 0xff, 0x25, 0x5b, 0xa9
2019-09-12 19:37:24.100 Detail, Node074, Expected reply and command class was received
2019-09-12 19:37:24.100 Detail, Node074, Message transaction complete
2019-09-12 19:37:24.100 Detail,
2019-09-12 19:37:24.100 Detail, Node074, Removing current message
2019-09-12 19:37:24.100 Detail,
2019-09-12 19:37:24.100 Info, Node074, Sending (Send) message (Callback ID=0x9b, Expected Reply=0x13) - AssociationCmd_Set (Node=74): 0x01, 0x0b, 0x00, 0x13, 0x4a, 0x04, 0x85, 0x01, 0xff, 0x01, 0x25, 0x9b, 0x6d
2019-09-12 19:37:24.109 Detail, Node074, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-12 19:37:24.109 Detail, Node074, ZW_SEND_DATA delivered to Z-Wave stack
2019-09-12 19:37:24.128 Detail, Node074, Received: 0x01, 0x07, 0x00, 0x13, 0x9b, 0x00, 0x00, 0x03, 0x73
2019-09-12 19:37:24.129 Detail, Node074, ZW_SEND_DATA Request with callback ID 0x9b received (expected 0x9b)
2019-09-12 19:37:24.129 Info, Node074, Request RTT 28 Average Request RTT 27
2019-09-12 19:37:24.129 Detail, Expected callbackId was received
2019-09-12 19:37:24.129 Detail, Expected reply was received
2019-09-12 19:37:24.129 Detail, Message transaction complete
2019-09-12 19:37:24.129 Detail,
2019-09-12 19:37:24.129 Detail, Node074, Removing current message
2019-09-12 19:37:24.129 Detail,
2019-09-12 19:37:24.129 Info, Node074, Sending (Send) message (Callback ID=0x9c, Expected Reply=0x04) - AssociationCmd_Get (Node=74): 0x01, 0x0a, 0x00, 0x13, 0x4a, 0x03, 0x85, 0x02, 0xff, 0x25, 0x9c, 0x6e
2019-09-12 19:37:24.138 Detail, Node074, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-12 19:37:24.138 Detail, Node074, ZW_SEND_DATA delivered to Z-Wave stack
2019-09-12 19:37:24.155 Detail, Node074, Received: 0x01, 0x07, 0x00, 0x13, 0x9c, 0x00, 0x00, 0x03, 0x74
2019-09-12 19:37:24.155 Detail, Node074, ZW_SEND_DATA Request with callback ID 0x9c received (expected 0x9c)
2019-09-12 19:37:24.155 Info, Node074, Request RTT 26 Average Request RTT 26
2019-09-12 19:37:24.155 Detail, Expected callbackId was received
2019-09-12 19:37:24.166 Detail, Node074, Received: 0x01, 0x0b, 0x00, 0x04, 0x00, 0x4a, 0x05, 0x85, 0x03, 0xff, 0x00, 0x00, 0xc6
2019-09-12 19:37:24.166 Detail,
2019-09-12 19:37:24.166 Info, Node074, Response RTT 37 Average Response RTT 37
2019-09-12 19:37:24.167 Info, Node074, Max associations for node 74, group 255 is zero. Querying associations for this node is complete.
2019-09-12 19:37:24.167 Info, Node074, Adding the controller to group 255 (Group #255) of node 74
2019-09-12 19:37:24.167 Info, Node074, Association::Set - Adding node 1 to group 255 of node 74
2019-09-12 19:37:24.167 Detail, Node074, Queuing (Send) AssociationCmd_Set (Node=74): 0x01, 0x0b, 0x00, 0x13, 0x4a, 0x04, 0x85, 0x01, 0xff, 0x01, 0x25, 0x5c, 0xaa
2019-09-12 19:37:24.167 Info, Node074, Get Associations for group 255 of node 74
2019-09-12 19:37:24.167 Detail, Node074, Queuing (Send) AssociationCmd_Get (Node=74): 0x01, 0x0a, 0x00, 0x13, 0x4a, 0x03, 0x85, 0x02, 0xff, 0x25, 0x5d, 0xaf
2019-09-12 19:37:24.167 Detail, Node074, Expected reply and command class was received
2019-09-12 19:37:24.167 Detail, Node074, Message transaction complete
2019-09-12 19:37:24.167 Detail,
2019-09-12 19:37:24.167 Detail, Node074, Removing current message
2019-09-12 19:37:24.167 Detail,
2019-09-12 19:37:24.167 Info, Node074, Sending (Send) message (Callback ID=0x9d, Expected Reply=0x13) - AssociationCmd_Set (Node=74): 0x01, 0x0b, 0x00, 0x13, 0x4a, 0x04, 0x85, 0x01, 0xff, 0x01, 0x25, 0x9d, 0x6b
2019-09-12 19:37:24.176 Detail, Node074, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2019-09-12 19:37:24.176 Detail, Node074, ZW_SEND_DATA delivered to Z-Wave stack
The offending node is Node 74. Has anybody seen this before? If I remove the device by cutting power, everything goes back to normal. I have also tried removing and re-adding it.
Help is appreciated!
Jason