I am trying to get a zwave signal to devices in a detached garage, the garage isn’t too far away from the house (about 2 meters) however it is mostly underground and made entirely of concrete. I’ve found that with one device at the closest corner of the garage I get an intermittent response, so I thought the best bet would be to put a repeater at the closest corner of the house to bridge the gap and reinforce the network.
To that end I purchased a pair of Aeotec Range Extender 6 modules (1 for the garage, 1 for the house) and figured this would bridge my gap. Both repeaters pair fine to the zwave network, however I looked at my OZW_Log.txt this morning, and it was over 10MB in size, and none of my Z-Wave devices were responding to commands. I opened OZW_Log.txt with tail -f and it was scrolling so fast I couldn’t read anything. Here’s a sample, but note that this repeated text goes on for 10MB of log!. Node027 is the repeater in the house:
2018-05-04 09:40:06.015 Detail,
2018-05-04 09:40:06.015 Info, Node027, Response RTT 37 Average Response RTT 36
2018-05-04 09:40:06.015 Info, Node027, Received Association report from node 27, group 1, containing 1 associations
2018-05-04 09:40:06.015 Info, Node027, The group contains:
2018-05-04 09:40:06.015 Info, Node027, Node 1
2018-05-04 09:40:06.016 Info, Node027, Get Associations for group 2 of node 27
2018-05-04 09:40:06.016 Detail, Node027, Queuing (Send) AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x36, 0x68
2018-05-04 09:40:06.016 Detail, Node027, Expected reply and command class was received
2018-05-04 09:40:06.016 Detail, Node027, Message transaction complete
2018-05-04 09:40:06.016 Detail,
2018-05-04 09:40:06.016 Detail, Node027, Removing current message
2018-05-04 09:40:06.017 Detail,
2018-05-04 09:40:06.017 Info, Node027, Sending (Send) message (Callback ID=0x1f, Expected Reply=0x04) - AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x1f, 0x41
2018-05-04 09:40:06.026 Detail, Node027, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-05-04 09:40:06.026 Detail, Node027, ZW_SEND_DATA delivered to Z-Wave stack
2018-05-04 09:40:06.041 Detail, Node027, Received: 0x01, 0x07, 0x00, 0x13, 0x1f, 0x00, 0x00, 0x02, 0xf6
2018-05-04 09:40:06.041 Detail, Node027, ZW_SEND_DATA Request with callback ID 0x1f received (expected 0x1f)
2018-05-04 09:40:06.042 Info, Node027, Request RTT 24 Average Request RTT 24
2018-05-04 09:40:06.042 Detail, Expected callbackId was received
2018-05-04 09:40:06.053 Detail, Node027, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x1b, 0x06, 0x85, 0x03, 0x01, 0x01, 0x00, 0x01, 0x6d
2018-05-04 09:40:06.053 Detail,
2018-05-04 09:40:06.053 Info, Node027, Response RTT 36 Average Response RTT 36
2018-05-04 09:40:06.053 Info, Node027, Received Association report from node 27, group 1, containing 1 associations
2018-05-04 09:40:06.054 Info, Node027, The group contains:
2018-05-04 09:40:06.054 Info, Node027, Node 1
2018-05-04 09:40:06.054 Info, Node027, Get Associations for group 2 of node 27
2018-05-04 09:40:06.054 Detail, Node027, Queuing (Send) AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x37, 0x69
2018-05-04 09:40:06.054 Detail, Node027, Expected reply and command class was received
2018-05-04 09:40:06.054 Detail, Node027, Message transaction complete
2018-05-04 09:40:06.054 Detail,
2018-05-04 09:40:06.055 Detail, Node027, Removing current message
2018-05-04 09:40:06.055 Detail,
2018-05-04 09:40:06.055 Info, Node027, Sending (Send) message (Callback ID=0x20, Expected Reply=0x04) - AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x20, 0x7e
2018-05-04 09:40:06.063 Detail, Node027, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-05-04 09:40:06.063 Detail, Node027, ZW_SEND_DATA delivered to Z-Wave stack
2018-05-04 09:40:06.080 Detail, Node027, Received: 0x01, 0x07, 0x00, 0x13, 0x20, 0x00, 0x00, 0x02, 0xc9
2018-05-04 09:40:06.080 Detail, Node027, ZW_SEND_DATA Request with callback ID 0x20 received (expected 0x20)
2018-05-04 09:40:06.080 Info, Node027, Request RTT 24 Average Request RTT 24
2018-05-04 09:40:06.080 Detail, Expected callbackId was received
2018-05-04 09:40:06.091 Detail, Node027, Received: 0x01, 0x0c, 0x00, 0x04, 0x00, 0x1b, 0x06, 0x85, 0x03, 0x01, 0x01, 0x00, 0x01, 0x6d
2018-05-04 09:40:06.091 Detail,
2018-05-04 09:40:06.091 Info, Node027, Response RTT 36 Average Response RTT 36
2018-05-04 09:40:06.092 Info, Node027, Received Association report from node 27, group 1, containing 1 associations
2018-05-04 09:40:06.092 Info, Node027, The group contains:
2018-05-04 09:40:06.092 Info, Node027, Node 1
2018-05-04 09:40:06.092 Info, Node027, Get Associations for group 2 of node 27
2018-05-04 09:40:06.092 Detail, Node027, Queuing (Send) AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x38, 0x66
2018-05-04 09:40:06.092 Detail, Node027, Expected reply and command class was received
2018-05-04 09:40:06.093 Detail, Node027, Message transaction complete
2018-05-04 09:40:06.093 Detail,
2018-05-04 09:40:06.093 Detail, Node027, Removing current message
2018-05-04 09:40:06.093 Detail,
2018-05-04 09:40:06.093 Info, Node027, Sending (Send) message (Callback ID=0x21, Expected Reply=0x04) - AssociationCmd_Get (Node=27): 0x01, 0x0a, 0x00, 0x13, 0x1b, 0x03, 0x85, 0x02, 0x02, 0x25, 0x21, 0x7f
2018-05-04 09:40:06.101 Detail, Node027, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2018-05-04 09:40:06.102 Detail, Node027, ZW_SEND_DATA delivered to Z-Wave stack
2018-05-04 09:40:06.118 Detail, Node027, Received: 0x01, 0x07, 0x00, 0x13, 0x21, 0x00, 0x00, 0x02, 0xc8
2018-05-04 09:40:06.118 Detail, Node027, ZW_SEND_DATA Request with callback ID 0x21 received (expected 0x21)
2018-05-04 09:40:06.118 Info, Node027, Request RTT 24 Average Request RTT 24
I thought maybe the repeater was faulty, so I factory reset it, removed it, and tried the other one, but I got exactly the same result as soon as I hit refresh node.
Any ideas on what’s going on? and what can be done about it?