Hi,
I just started out on using openhab2 and zwave in general
Having tried already lots of stuff I’m out of ideas. I alreaded posted a message in a different thread about the aeotec nano switch, but I decided it might be best since my problem is likely not related
So my setup consists out of
Linux pc (ubuntu 14.04)
zwave.me UZB1 controller (no license, I’ve read stuff about licenses, it’s not clear to me, from what I understood I don’t need one?)
aeotec nano dimmer
I guess my configuration of the zwave serial port is correct
I already added this in my cfg as well as I have read the soft reset can’t be used…)
cat /opt/openhab2/conf/services/zwave.cfg
zwave:port=/dev/ttyACM0
zwave:masterController=true
zwave:softReset=false
zwave:healtime=2
zwave:setSUC=true
this is the log of when I tried to add a device
2017-08-07 20:26:53.490 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2017-08-07 20:26:53.491 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-08-07 20:26:53.491 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-08-07 20:26:53.493 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70
2017-08-07 20:26:53.495 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70
2017-08-07 20:26:53.500 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2
2017-08-07 20:26:53.502 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:26:53.503 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2
2017-08-07 20:26:53.504 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2
2017-08-07 20:26:53.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00
2017-08-07 20:26:53.505 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2017-08-07 20:26:53.507 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-08-07 20:26:53.518 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01
2017-08-07 20:26:53.520 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00
2017-08-07 20:26:53.521 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false transaction complete!
2017-08-07 20:26:53.522 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 20:26:53.524 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 26ms/4188ms.
2017-08-07 20:27:02.835 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 02 00 00 B1
2017-08-07 20:27:02.838 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:27:02.838 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 02 00 00 B1
2017-08-07 20:27:02.839 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 02 00 00 B1
2017-08-07 20:27:02.840 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 02 00 00
2017-08-07 20:27:02.840 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: New node found.
2017-08-07 20:27:02.841 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01
2017-08-07 20:27:02.843 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 02 00 00
2017-08-07 20:27:02.844 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false transaction complete!
2017-08-07 20:27:02.845 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 20:27:05.675 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 07 00 00 B4
2017-08-07 20:27:05.677 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:27:05.677 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 07 00 00 B4
2017-08-07 20:27:05.678 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 07 00 00 B4
2017-08-07 20:27:05.679 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 07 00 00
2017-08-07 20:27:05.680 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Failed.
2017-08-07 20:27:05.680 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-08-07 20:27:05.682 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01
2017-08-07 20:27:05.683 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 07 00 00
2017-08-07 20:27:05.685 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false transaction complete!
2017-08-07 20:27:05.685 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
I think both the dimmer and the zwave controller are able to send/receive messages, but something goes wrong.
On a sidenote:it goes wrong with openhab2, domoticz, zway server, home assistant
I might be missing something very obvious?
The dimmer stops blinking once I add it (only if controller is inclusion mode), so I think there is communication in both directions
If I push a button on the dimmer it also shows up in my log:
strangely enough it has a differnt node number
2017-08-07 20:38:49.296 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1C 00 49 84 09 16 04 11 01 5E 86 72 5A 73 85 59 25 26 27 32 70 81 71 2C 2B 7A EF 82 FC
2017-08-07 20:38:49.300 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:38:49.301 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1C 00 49 84 09 16 04 11 01 5E 86 72 5A 73 85 59 25 26 27 32 70 81 71 2C 2B 7A EF 82 FC
2017-08-07 20:38:49.302 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 1C 00 49 84 09 16 04 11 01 5E 86 72 5A 73 85 59 25 26 27 32 70 81 71 2C 2B 7A EF 82 FC
2017-08-07 20:38:49.303 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 09 16 04 11 01 5E 86 72 5A 73 85 59 25 26 27 32 70 81 71 2C 2B 7A EF 82
2017-08-07 20:38:49.308 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 9: Application update request. Node information received.
2017-08-07 20:38:49.309 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 9: Application update request. Node not known!
2017-08-07 20:38:49.309 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-08-07 20:38:49.310 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovered
2017-08-07 20:38:49.325 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:cc7663cb:node9' to inbox.
2017-08-07 20:38:49.326 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 9: Newly included node doesn't exist - initialising from start.
2017-08-07 20:38:49.327 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2017-08-07 20:38:49.328 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Poll, dest=4, callback=8, payload=04 01 00
2017-08-07 20:38:49.330 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 09 16 04 11 01 5E 86 72 5A 73 85 59 25 26 27 32 70 81 71 2C 2B 7A EF 82
2017-08-07 20:38:49.330 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationUpdate, callback id=8, expected=SendData, cancelled=false MISMATCH
2017-08-07 20:38:49.331 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 9: Init node thread start
2017-08-07 20:38:49.368 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 9: Serializing from file /opt/openhab2/userdata/zwave/node9.xml
2017-08-07 20:38:49.369 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 9: Error serializing from file: file does not exist.
2017-08-07 20:38:49.370 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from EMPTYNODE
2017-08-07 20:38:49.371 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - EMPTYNODE: queue length(0), free to send(true)
2017-08-07 20:38:49.372 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - EMPTYNODE try 1: stageAdvanced(false)
2017-08-07 20:38:49.372 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: Initialisation starting
2017-08-07 20:38:49.373 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to PROTOINFO
2017-08-07 20:38:49.373 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2017-08-07 20:38:49.375 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - PROTOINFO try 0: stageAdvanced(true)
2017-08-07 20:38:49.377 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: PROTOINFO - send IdentifyNode
2017-08-07 20:38:49.379 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - queued packet. Queue length is 1
2017-08-07 20:38:49.381 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-08-07 20:38:49.382 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 41 09 B3
2017-08-07 20:38:49.382 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 41 09 B3
2017-08-07 20:38:49.383 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}
2017-08-07 20:38:49.385 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 9: Init node thread finished
2017-08-07 20:38:49.386 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 01 41 00 00 00 03 00 00 B5
2017-08-07 20:38:49.389 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:38:49.390 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 01 41 00 00 00 03 00 00 B5
2017-08-07 20:38:49.391 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 01 41 00 00 00 03 00 00 B5
2017-08-07 20:38:49.392 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=00 00 00 03 00 00
2017-08-07 20:38:49.393 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: ProtocolInfo
2017-08-07 20:38:49.393 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Listening = false
2017-08-07 20:38:49.394 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Routing = false
2017-08-07 20:38:49.394 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Beaming = false
2017-08-07 20:38:49.394 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Version = 1
2017-08-07 20:38:49.395 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: FLIRS = false
2017-08-07 20:38:49.395 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Security = false
2017-08-07 20:38:49.396 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Max Baud = 9600
2017-08-07 20:38:49.396 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Basic = Slave
2017-08-07 20:38:49.397 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Generic = Not Known
2017-08-07 20:38:49.397 [DEBUG] [rialmessage.IdentifyNodeMessageClass] - NODE 9: Specific = Not Known
2017-08-07 20:38:49.398 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=IdentifyNode[0x41], type=Request[0x00], priority=High, dest=255, callback=0, payload=09
2017-08-07 20:38:49.399 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=IdentifyNode[0x41], type=Response[0x01], priority=High, dest=255, callback=0, payload=00 00 00 03 00 00
2017-08-07 20:38:49.400 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=IdentifyNode, callback id=0, expected=IdentifyNode, cancelled=false transaction complete!
2017-08-07 20:38:49.400 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 20:38:49.401 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - PROTOINFO: Transaction complete (IdentifyNode:Request) success(true)
2017-08-07 20:38:49.401 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2017-08-07 20:38:49.402 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - message removed from queue. Queue size 0.
2017-08-07 20:38:49.402 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - PROTOINFO: queue length(0), free to send(true)
2017-08-07 20:38:49.404 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - PROTOINFO try 1: stageAdvanced(false)
2017-08-07 20:38:49.405 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to INIT_NEIGHBORS
2017-08-07 20:38:49.405 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2017-08-07 20:38:49.406 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - INIT_NEIGHBORS try 0: stageAdvanced(true)
2017-08-07 20:38:49.406 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: INIT_NEIGHBORS - send RoutingInfo
2017-08-07 20:38:49.407 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Request routing info
2017-08-07 20:38:49.408 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - queued packet. Queue length is 1
2017-08-07 20:38:49.408 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-08-07 20:38:49.409 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 25ms/4188ms.
2017-08-07 20:38:49.409 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-08-07 20:38:49.410 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 80 09 00 00 03 72
2017-08-07 20:38:49.411 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 07 00 80 09 00 00 03 72
2017-08-07 20:38:49.418 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 20 01 80 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 5E
2017-08-07 20:38:49.421 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:38:49.422 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 20 01 80 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 5E
2017-08-07 20:38:49.424 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 20 01 80 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 5E
2017-08-07 20:38:49.425 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2017-08-07 20:38:49.426 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: Got NodeRoutingInfo request.
2017-08-07 20:38:49.427 [DEBUG] [almessage.GetRoutingInfoMessageClass] - NODE 9: No neighbors reported
2017-08-07 20:38:49.428 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNetworkEvent
2017-08-07 20:38:49.429 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=GetRoutingInfo[0x80], type=Request[0x00], priority=High, dest=255, callback=0, payload=09 00 00 03
2017-08-07 20:38:49.430 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=GetRoutingInfo[0x80], type=Response[0x01], priority=High, dest=255, callback=0, payload=00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
2017-08-07 20:38:49.432 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=GetRoutingInfo, callback id=0, expected=GetRoutingInfo, cancelled=false transaction complete!
2017-08-07 20:38:49.432 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 20:38:49.433 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - INIT_NEIGHBORS: Transaction complete (GetRoutingInfo:Request) success(true)
2017-08-07 20:38:49.434 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2017-08-07 20:38:49.434 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - message removed from queue. Queue size 0.
2017-08-07 20:38:49.435 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - INIT_NEIGHBORS: queue length(0), free to send(true)
2017-08-07 20:38:49.436 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - INIT_NEIGHBORS try 1: stageAdvanced(false)
2017-08-07 20:38:49.437 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to FAILED_CHECK
2017-08-07 20:38:49.437 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2017-08-07 20:38:49.438 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - FAILED_CHECK try 0: stageAdvanced(true)
2017-08-07 20:38:49.438 [DEBUG] [rialmessage.IsFailedNodeMessageClass] - NODE 9: Requesting IsFailedNode status from controller.
2017-08-07 20:38:49.439 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - queued packet. Queue length is 1
2017-08-07 20:38:49.440 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-08-07 20:38:49.440 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 27ms/4188ms.
2017-08-07 20:38:49.441 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-08-07 20:38:49.442 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 62 09 90
2017-08-07 20:38:49.442 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 62 09 90
2017-08-07 20:38:49.445 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 62 01 99
2017-08-07 20:38:49.448 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 20:38:49.448 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 62 01 99
2017-08-07 20:38:49.449 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 62 01 99
2017-08-07 20:38:49.450 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=IsFailedNodeID[0x62], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-08-07 20:38:49.451 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 9: Is currently marked as failed by the controller!
2017-08-07 20:38:49.452 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 9: Node is DEAD.
2017-08-07 20:38:49.452 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveNodeStatusEvent
2017-08-07 20:38:49.453 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node Status event during initialisation - Node is FAILED
2017-08-07 20:38:49.453 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 9: Node Status event - Node is FAILED
2017-08-07 20:38:49.454 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=IsFailedNodeID[0x62], type=Request[0x00], priority=High, dest=255, callback=0, payload=09
2017-08-07 20:38:49.455 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=IsFailedNodeID[0x62], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-08-07 20:38:49.456 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=IsFailedNodeID, callback id=0, expected=IsFailedNodeID, cancelled=false transaction complete!
2017-08-07 20:38:49.457 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 20:38:49.458 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - FAILED_CHECK: Transaction complete (IsFailedNodeID:Request) success(true)
2017-08-07 20:38:49.459 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - checking initialisation queue. Queue size 1.
2017-08-07 20:38:49.460 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - message removed from queue. Queue size 0.
2017-08-07 20:38:49.461 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - FAILED_CHECK: queue length(0), free to send(true)
2017-08-07 20:38:49.461 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - FAILED_CHECK try 1: stageAdvanced(false)
2017-08-07 20:38:49.463 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer - advancing to WAIT
2017-08-07 20:38:49.464 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
2017-08-07 20:38:49.465 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: loop - WAIT try 0: stageAdvanced(true)
2017-08-07 20:38:49.465 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: WAIT - Listening=false, FrequentlyListening=false
2017-08-07 20:38:49.466 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Node advancer: WAIT - Still waiting!
2017-08-07 20:38:49.467 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 22ms/4188ms.
I also see no properties in the nodes.
Usually they appear as offline
Before I add the dimmer I give it a factory reset(by pushing the button 20 seconds)
Any ideas somebody ?
Help is much appreciated
Kind regards,
Ruben