Adding node failed on zwave.me UZB1 controller

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

You only need a license if you want to use the zway software.

You have a mix of binding version1 and binding version2 config, won’t work.
You don’t need a zwave.cfg at all if you are using the version2 binding, read through:

http://docs.openhab.org/addons/bindings/zwave/readme.html

Also a good place to start is:

http://docs.openhab.org/tutorials/beginner/index.html

thanks for clearing up those two things. Happy that I don’t need an extra license :slight_smile:
so i removed the zwave.cfg file and tried including again a device

I first did a hard reset of the controller and I did a factory reset of the dimmer

2017-08-07 22:32:43.511 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Starting ZWave inclusion scan for zwave:serial_zstick:cc7663cb
2017-08-07 22:32:43.512 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2017-08-07 22:32:43.514 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2017-08-07 22:32:43.515 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-08-07 22:32:43.515 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-08-07 22:32:43.516 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70
2017-08-07 22:32:43.517 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70
2017-08-07 22:32:43.522 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2
2017-08-07 22:32:43.524 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 22:32:43.525 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2
2017-08-07 22:32:43.526 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2
2017-08-07 22:32:43.527 [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 22:32:43.528 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2017-08-07 22:32:43.529 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-08-07 22:32:43.538 [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 22:32:43.539 [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 22:32:43.540 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-08-07 22:32:43.540 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 22:32:43.541 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 22ms/114ms.
2017-08-07 22:32:50.637 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 02 00 00 B1
2017-08-07 22:32:50.638 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 22:32:50.639 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 02 00 00 B1
2017-08-07 22:32:50.640 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 02 00 00 B1
2017-08-07 22:32:50.641 [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 22:32:50.642 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: New node found.
2017-08-07 22:32:50.643 [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 22:32:50.644 [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 22:32:50.645 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-08-07 22:32:50.645 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 22:32:53.597 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 07 00 00 B4
2017-08-07 22:32:53.599 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-08-07 22:32:53.600 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 07 00 00 B4
2017-08-07 22:32:53.601 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 07 00 00 B4
2017-08-07 22:32:53.602 [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 22:32:53.602 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Failed.
2017-08-07 22:32:53.603 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-08-07 22:32:53.605 [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 22:32:53.608 [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 22:32:53.608 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-08-07 22:32:53.609 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-08-07 22:33:13.512 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-08-07 22:33:13.512 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

Also no nodes show up in openhab user interface. Eventually if I press the button again a node appears but sometimes it’s offline and even if it’s online I can’t read anything from it

Something went wrong during the inclusion. Read the manual of the device how to include it properly.
Note that battery powered devices need a couple of manual wakeups (again, read the device manual on how to do that) after successful inclusion to get fully initialized and to show data.

I’ve read the manual already a couple of times. I’m really 100% sure i put the device into pairing mode (and before that I put the controller in pairing mode)
And the dimmer is no battery device, it’s connected to the power

According to the manual of the device if the pairing is unsuccessful the RED led is on for 2 seconds and it goes into a gradient. This is correct because when I put the device into pairing mode without putting the controller into pairing mode this is exactly what happens

If I follow the correct sequence because the light doesn’t go red but green. So my guess is the nano dimmer thinks it is paired.
I also tried this with putting the dimmer into pairing secure mode

When the controllers sends messages to start reading device information (MANUFACTURER,…) it receives no ACK’s
I’ve read this can be because of bad signal reception, but the devices are no further than a meter away…

I really think I might have a hardware issue on either my dimmer or my UZB1 controller

Something went wrong during the inclusion. Read the manual of the device how to include it properly.
Note that battery powered devices need a couple of manual wakeups (again, read the device manual on how to do that) after successful inclusion to get fully initialized and to show data.

:thinking:

I’ve tried the stick on three different pc’s (raspberry pi, intel atom chipset running ubuntu and laptop with windows 7)
I’ve tried domoticz, homeassistant, openhab2 and with all configurations it fails to discover the node type.
It sees messages coming in from the dimmer, but it is not able to read something from it.

I think there must be a hardware failure in the dimmer or in the uzb1 controller
might be time to ship it back
I really want this to work, I already spent hours, no exagerating

Kind regards

Ruben

That really sounds a bit weird.

Let’s start from scratch to find out if you really have defective hardware.

  1. Which version of the zwave binding are you running? Log into the karaf console with ssh openhab@localhost -p 8101
    and do a bundle:list | grep ZWave*
    You should see a line similar to this one:
    9 | Active | 80 | 2.1.0.201708041056 | ZWave Binding

Make sure you have a recent binding version and it is active. The reason: there have been a lot of database changes to the Aeotec Nano Dimmer recently, if you don’t have a recent zwave binding it may not work properly.

  1. If you are on a recent zwave binding, try to exclude the device, reset it according to the manual and include it again. Don’t use the secure inclusion, this is only supported in the development version of the zwave binding (that’s the one I am using)

All of this should be done via HABmin Graphical User Interface, that is the recommended way in openHAB for zwave devices as it is specially designed for that. Although it should also work with PaperUI, but you never know.

Changed to a zstick gen 5 … et voila…
Openhab2 said : "Let there be light"
And there was light
And the light was good :wink:

1 Like

Hi there,

I have a similar issue. I made a new install of openhabian 1.3, 2.1 stable and added the UZB1 controller connected as /dev/ttyACM0. I have the Thing in HABmin and I tried to add POPP smoke detectors, but I can’t get the inclusion working.
Last 3 days I tried several things, also exclusion again, hard reset of the UZB1, tried again… nothing … grrr!
I can send the inclusion/exclusion commands, this looks ok.
What I am wondering: I dont see any information from the controller in the setting:


Is that correct, or can this be the issue? I have seen other screenshots with data displayed here.

Here is a log of another inclusion trial:

2017-10-31 11:31:23.112 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-10-31 11:31:23.116 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01 
2017-10-31 11:31:23.116 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@1f4732b
2017-10-31 11:31:23.120 [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-10-31 11:31:23.123 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-10-31 11:31:23.124 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-10-31 11:31:23.127 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 58ms/2272ms.
2017-10-31 11:31:53.010 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-10-31 11:31:53.012 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-10-31 11:31:53.014 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion
2017-10-31 11:31:53.013 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-10-31 11:31:53.019 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 01 B4 
2017-10-31 11:31:53.021 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 01 B4 
2017-10-31 11:31:53.174 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 00 00 B5 
2017-10-31 11:31:53.177 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-10-31 11:31:53.179 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 00 00 B5 
2017-10-31 11:31:53.181 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 00 00 B5 
2017-10-31 11:31:53.183 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-10-31 11:31:53.186 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-10-31 11:31:53.208 [BindingEvent              ] - org.openhab.binding.zwave.event.BindingEvent@148c145
2017-10-31 11:31:53.209 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01 
2017-10-31 11:31:53.219 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-10-31 11:31:53.223 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-10-31 11:31:53.224 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-10-31 11:31:53.226 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 203ms/2272ms.

Does this makes sense?

@Chris, are you able to help? I am realy lost at the moment. Thanks :slight_smile:

I don’t think there’s too much I can say unfortunately. It looks like the command is being sent to the controller ok, and after that it’s down to pressing the right buttons on the device to actually get it included. The binding doesn’t have any involvement in the inclusion itself - it’s all handled in the controller stick.

Is it ok that the controller properties do not be recognized and displayed? Has this any influence?

Yes - the controller doesn’t have these properties.

Nice if this works, but others managed also the ZUM_UZB1 to get it to work as I could read from other posts.
I tried 2 of my POPP smoke detectors, same behavior. So I assume the fault is not on their side.

The stick at least receives the command to include correct, it starts flashing the blue LED for 30 sec.
What else can I test? finally maybe the RF section of the stick is defect???

I have the POPP running with a ZME_UZB1 without any problems.
Make sure you press the inclusion button on the smoke detector for one second to include it, also note that if you switch from battery power to mains power you need to exclude the device from the stick and include it again.
Try resetting the POPP: push the button for 10 seconds. After that try to include it again.

Good luck.

Ok, new trial:
I was sitting a meter away from the Raspi, I extended the stick with a 20cm USB extension to avoid maybe some disturbance from the Raspi board.
I made an exclusion of both POPPs, made a hard reset of the USB stick and rebooted the system.
(Both POPPs are battery powered, I have no mains supply for them)

Network Settings are:

  • controller is master: yes
  • controller is SUC: no
  • timeout: 30
  • wakeup: every hour
  • inclusion mode: network wide inclusion
  • secure inclusion mode: all devices
  • network security key: what was initially placed there…

Then I made the 10sec reset on both POPPs and started inclusion 1:

2017-11-01 09:02:18.285 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Starting ZWave inclusion scan for zwave:serial_zstick:15f71b6bf6d
2017-11-01 09:02:18.289 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2017-11-01 09:02:18.295 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2017-11-01 09:02:18.299 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-11-01 09:02:18.299 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-11-01 09:02:18.303 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70 
2017-11-01 09:02:18.305 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70 
2017-11-01 09:02:18.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2 
2017-11-01 09:02:18.344 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-11-01 09:02:18.346 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2 
2017-11-01 09:02:18.348 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2 
2017-11-01 09:02:18.350 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00 
2017-11-01 09:02:18.351 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2017-11-01 09:02:18.353 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-11-01 09:02:18.369 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01 
2017-11-01 09:02:18.371 [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-11-01 09:02:18.373 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-11-01 09:02:18.375 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-11-01 09:02:18.378 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 72ms/199ms.
2017-11-01 09:02:48.307 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-11-01 09:02:48.308 [DEBUG] [ve.internal.protocol.ZWaveController] - Ending inclusion mode.
2017-11-01 09:02:48.312 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
2017-11-01 09:02:48.313 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-11-01 09:02:48.315 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 01 B4 
2017-11-01 09:02:48.317 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 01 B4 
2017-11-01 09:02:48.320 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-11-01 09:02:48.322 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion
2017-11-01 09:02:48.324 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
2017-11-01 09:02:48.325 [DEBUG] [ve.internal.protocol.ZWaveController] - Neither inclusion nor exclusion was active!
2017-11-01 09:02:48.470 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:02:48.480 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-11-01 09:02:48.485 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:02:48.488 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:02:48.490 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-11-01 09:02:48.491 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2017-11-01 09:02:48.492 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-11-01 09:02:48.496 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01 
2017-11-01 09:02:48.498 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-11-01 09:02:48.499 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-11-01 09:02:48.500 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-11-01 09:02:48.501 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 183ms/199ms.

and inclusion 2:

2017-11-01 09:08:38.284 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:08:38.291 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-11-01 09:08:38.302 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:08:38.304 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 00 00 B5 
2017-11-01 09:08:38.307 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-11-01 09:08:38.310 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
2017-11-01 09:08:38.312 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
2017-11-01 09:08:38.318 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01 
2017-11-01 09:08:38.321 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 
2017-11-01 09:08:38.324 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
2017-11-01 09:08:38.327 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-11-01 09:08:38.330 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 203ms/203ms.

both without sucess !!!
:japanese_ogre::japanese_ogre::japanese_ogre:

I would suggest to put the binding into inclusion mode before putting the device into inclusion mode (I’m assuming here that resetting the device does that with these devices, although that’s not the case for most devices).

Also, don’t try and include both devices at the same time. It shouldn’t hurt (although I wouldn’t be 100% sure), but it won’t work. The binding will only allow a single device at once and it will stop include mode once the first device is reported by the controller.

Maybe I was not precise:
I made the reset of the devices,
then set the binding to inclusion mode, the LED on the stick flashes, and after a few seconds pressed the inclusion button on device 1 and wait until the end of the 30 sec.
Then I did the same for the 2. device in separate sequence.

Is there a specific reason you are using secure inclusion?
This is only required for secure devices, locks etc.
I don’t even know if the Popp supports this …
Try the normal inclusion.

Hmm, the description of the POPP says: simple click the button for inclusion, click for 2sec: inclusion with suppressed security function (Security Command Class).
Ok, then I will try a new round, set inclusion mode to “Do not use security” and do the 2sec learning (with headphones on :slight_smile: )