- Platform information:
- Hardware: Raspberry 4 (2GB RAM)
- OS: Raspbian GNU/Linux
- openHAB version: 2.5.10 (Latest openhabian)
- Issue of the topic: I can’t add my Aeotec devices to the ZME controller
- Devices: two Aeotec Sensor 6 and a range 6 device.
- Controller ZME USB (in one of the USB ports on the device)
I replaced my trusty PI3 a couple of months ago with a new Pi4 and moved over the zwave controller. The three zwave devices continue to work.
Then a month or so ago my zwave devices stopped working and after a number of frustrating attempts to re-include after resetting them, hard resetting the controller a number of times, installing and reinstalling the binding, I finally decided to get a new controller.
That new controller arrived today and I have re-installed the binding, verified that the zwave device is “online”.
I’ve tried to include the device a couple of times: at a distance to the controller and right next to it (literally 5 cm’s away from the controller), I’ve verified that the controller blinks (blue) when pressing the plus sign in the paper UI (Inbox). Neither the sensor 6 nor the range 6 was found. When I press the “inclusion” button on the devices, they blink green for a couple, then goes red.
This is what I see in the logs:
2020-11-27 16:13:54.155 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ZMEUZB1
2020-11-27 16:13:54.158 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2020-11-27 16:13:54.163 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:ZMEUZB1
2020-11-27 16:13:54.166 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2020-11-27 16:13:54.169 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2020-11-27 16:13:54.172 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2020-11-27 16:13:54.175 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2020-11-27 16:13:54.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 59 to queue - size 1
2020-11-27 16:13:54.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-27 16:13:54.185 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 1B 6A
2020-11-27 16:13:54.189 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 1B 6A
2020-11-27 16:13:54.192 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-27 16:13:54.193 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-27 16:13:54.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:13:54.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 59: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 27
2020-11-27 16:13:54.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:13:54.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-27 16:13:54.199 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 1B 01 00 00 A8
2020-11-27 16:13:54.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-27 16:13:54.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-27 16:13:54.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-27 16:13:54.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=27, payload=1B 01 00 00
2020-11-27 16:13:54.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=27, payload=1B 01 00 00
2020-11-27 16:13:54.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 59: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 27
2020-11-27 16:13:54.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-27 16:13:54.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 59: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 27
2020-11-27 16:13:54.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 59: (Callback 27)
2020-11-27 16:13:54.215 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-27 16:13:54.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 59: callback 27
2020-11-27 16:13:54.219 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=27, payload=1B 01 00 00
2020-11-27 16:13:54.220 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2020-11-27 16:13:54.222 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2020-11-27 16:13:54.223 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 59: Transaction COMPLETED
2020-11-27 16:13:54.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 31ms
2020-11-27 16:13:54.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 59: Transaction completed
2020-11-27 16:13:54.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:59 DONE
2020-11-27 16:13:54.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-27 16:13:54.230 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-27 16:14:54.162 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ZMEUZB1
2020-11-27 16:14:54.164 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart
2020-11-27 16:14:54.165 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-11-27 16:14:54.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 60 to queue - size 1
2020-11-27 16:14:54.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-27 16:14:54.171 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 1C A9
2020-11-27 16:14:54.174 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 1C A9
2020-11-27 16:14:54.177 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-27 16:14:54.176 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-27 16:14:54.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:14:54.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 60: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 28
2020-11-27 16:14:54.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:14:54.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-27 16:14:54.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-27 16:14:54.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-27 16:14:54.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-27 16:14:54.232 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 1C 06 00 00 A8
2020-11-27 16:14:54.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=28, payload=1C 06 00 00
2020-11-27 16:14:54.239 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=28, payload=1C 06 00 00
2020-11-27 16:14:54.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 60: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 28
2020-11-27 16:14:54.242 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-27 16:14:54.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 60: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 28
2020-11-27 16:14:54.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 60: (Callback 28)
2020-11-27 16:14:54.248 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-27 16:14:54.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 60: callback 28
2020-11-27 16:14:54.253 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=28, payload=1C 06 00 00
2020-11-27 16:14:54.255 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2020-11-27 16:14:54.266 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone
2020-11-27 16:14:54.268 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2020-11-27 16:14:54.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 61 to queue - size 1
2020-11-27 16:14:54.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-27 16:14:54.273 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion
2020-11-27 16:14:54.275 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done
2020-11-27 16:14:54.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 60: Advanced to DONE
2020-11-27 16:14:54.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 98ms
2020-11-27 16:14:54.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 60: Transaction completed
2020-11-27 16:14:54.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:60 DONE
2020-11-27 16:14:54.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-27 16:14:54.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-27 16:14:54.294 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5
2020-11-27 16:14:54.297 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5
2020-11-27 16:14:54.300 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-27 16:14:54.299 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-27 16:14:54.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:14:54.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 61: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-11-27 16:14:54.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-27 16:14:54.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 61: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0
2020-11-27 16:14:54.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-27 16:14:54.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-27 16:14:54.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-27 16:14:59.305 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 61: Timeout at state WAIT_REQUEST. 3 retries remaining.
2020-11-27 16:14:59.307 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 61: Transaction is current transaction, so clearing!!!!!
2020-11-27 16:14:59.308 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 61: Transaction CANCELLED
2020-11-27 16:14:59.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:61 CANCELLED
2020-11-27 16:14:59.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-27 16:18:15.427 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised
2020-11-27 16:18:34.397 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ZMEUZB1
2020-11-27 16:18:34.399 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
Any suggestions and good ideas are very welcomed.
Thanks!