Problem ZWave Binding - Adding devices - org.openhab.binding.zwave.event.BindingEvent

BernhardPeham · December 15, 2019, 3:29pm

Hallo !
I had the idea to setup an new openhab system for another flat.

So I used Openhabian and my backup stick Aeotec gen5 from my original openhab installation.

I bought a bunch of Fibario devices to switch of the heating and to detect motions in the flat. But the problem is:

Everytime I add a zwave device, I get this dump message org.openhab.binding.zwave.event.BindingEvent.

Who can help me?

The debug log:

2019-12-15 16:13:16.645 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:df84c5f0
2019-12-15 16:13:16.649 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2019-12-15 16:13:58.814 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:df84c5f0
2019-12-15 16:13:58.819 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do
2019-12-15 16:13:58.824 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:df84c5f0
2019-12-15 16:13:58.828 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
2019-12-15 16:13:58.834 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.
2019-12-15 16:13:58.838 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion
2019-12-15 16:13:58.843 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
2019-12-15 16:13:58.847 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 21 to queue - size 1
2019-12-15 16:13:58.851 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-12-15 16:13:58.857 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 0E 7F 
2019-12-15 16:13:58.863 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 0E 7F 
2019-12-15 16:13:58.868 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2019-12-15 16:13:58.870 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2019-12-15 16:13:58.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-12-15 16:13:58.872 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 21: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14
2019-12-15 16:13:58.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2019-12-15 16:13:58.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14
2019-12-15 16:13:58.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2019-12-15 16:13:58.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-12-15 16:13:58.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2019-12-15 16:13:58.893 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 0E 01 00 00 BD 
2019-12-15 16:13:58.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=14, payload=0E 01 00 00 
2019-12-15 16:13:58.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=14, payload=0E 01 00 00 
2019-12-15 16:13:58.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 21: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14
2019-12-15 16:13:58.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2019-12-15 16:13:58.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 21: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 14
2019-12-15 16:13:58.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 21: (Callback 14)
2019-12-15 16:13:58.920 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2019-12-15 16:13:58.922 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 21: callback 14
2019-12-15 16:13:58.924 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=14, payload=0E 01 00 00 
2019-12-15 16:13:58.926 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
2019-12-15 16:13:58.930 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart
2019-12-15 16:13:58.932 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 21: Transaction COMPLETED

==> /var/log/openhab2/events.log <==
2019-12-15 16:13:58.936 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@3cb2cf

==> /var/log/openhab2/openhab.log <==
2019-12-15 16:13:58.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 65ms
2019-12-15 16:13:58.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 21: Transaction completed
2019-12-15 16:13:58.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:21 DONE
2019-12-15 16:13:58.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-12-15 16:13:58.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Thanks a lot
Bernhard

chris · December 15, 2019, 3:50pm

This is normal - please can you describe what the problem is? Is it that devices aren’t being included? It’s unclear really where the problem lies since the event itself is perfectly normal.

BernhardPeham · December 15, 2019, 5:09pm

The problem is, that no device is detected. I have Fibaro FGS-223, FGDW-002 and Philio PST02-1B. No device is included.
The error message ist not the problem. ok.

chris · December 15, 2019, 5:10pm

What error message are you getting? (all useful things to know ).

BernhardPeham · December 15, 2019, 6:29pm

Of course. You need an error to help me:smile:. But there is no error and no new thing.
In my home I have a second ZWave stick (Aeotec gen5) on my regular openhab pi. And I have a third. He ist not connected to the rasperry. Could this be the source of the error?
If i change the sticks, I get the already connected things in my home. But not the new devices.
I remember, that I got things with the new stick. But after I got only one of three things included, I decided to reset the stick. I guess, the stick does have a memory. So maybe this is a part of the problem?

You told me, that the message and the debug-log has no error. So I have to change my search area to the three devices. Isn’t?

Thanks for your help.

chris · December 15, 2019, 6:38pm

Ok, sorry, it’s a little confusing as you mentioned “the error message is not the problem”.

So, everything looks fine, but the only problem is that the device is not joining.

Is the device close to the controller?
Are you putting the device into inclusion mode AFTER the binding is put into inclusion mode?
Is the device for the same region as your controller? ie you haven’t purchased a controller from the USA and devices in the EU or something like that?

If you have multiple devices and they all do the same thing, then maybe the stick is faulty (or the wrong region).

BernhardPeham · December 15, 2019, 7:11pm

ok. The device are not joining.
So I checked the spectrum. They use 868,42Mhz. The devices are 10cm away from the stick.
I changed the stick. The same behaviour

The inclusion mode is for all three devices a little tricky.
How could I figure out, if they are in the inclusion mode?

chris · December 15, 2019, 7:26pm

Please provide a debug log so I can take a look at what is happening.

What do you mean? What devices are you trying to join?

BernhardPeham · December 15, 2019, 8:21pm

Thanks,
here are the log’s …

2019-12-15 21:18:55.238 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 251: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-12-15 21:18:55.240 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 251: Transaction is current transaction, so clearing!!!!!

2019-12-15 21:18:55.247 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 251: Transaction CANCELLED

2019-12-15 21:18:55.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:251 CANCELLED

2019-12-15 21:18:55.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-12-15 21:19:39.291 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ad2ba90c

2019-12-15 21:19:39.295 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

2019-12-15 21:19:39.299 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:ad2ba90c

2019-12-15 21:19:39.303 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2019-12-15 21:19:39.309 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2019-12-15 21:19:39.313 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2019-12-15 21:19:39.317 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2019-12-15 21:19:39.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 252 to queue - size 1

2019-12-15 21:19:39.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-12-15 21:19:39.331 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 C4 B5 

2019-12-15 21:19:39.336 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 C4 B5 

2019-12-15 21:19:39.339 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-12-15 21:19:39.341 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-12-15 21:19:39.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 252: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 196

2019-12-15 21:19:39.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:19:39.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:19:39.348 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A C4 01 00 00 77 

2019-12-15 21:19:39.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 252: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 196

2019-12-15 21:19:39.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-12-15 21:19:39.352 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-12-15 21:19:39.355 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-12-15 21:19:39.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=196, payload=C4 01 00 00 

2019-12-15 21:19:39.358 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=196, payload=C4 01 00 00 

2019-12-15 21:19:39.360 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 252: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 196

2019-12-15 21:19:39.363 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-12-15 21:19:39.365 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 252: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 196

2019-12-15 21:19:39.367 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 252: (Callback 196)

2019-12-15 21:19:39.369 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-12-15 21:19:39.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 252: callback 196

2019-12-15 21:19:39.374 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=196, payload=C4 01 00 00 

2019-12-15 21:19:39.376 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2019-12-15 21:19:39.379 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart

2019-12-15 21:19:39.381 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 252: Transaction COMPLETED

==> /var/log/openhab2/events.log <==

2019-12-15 21:19:39.381 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@27a66

==> /var/log/openhab2/openhab.log <==

2019-12-15 21:19:39.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 41ms

2019-12-15 21:19:39.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 252: Transaction completed

2019-12-15 21:19:39.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:252 DONE

2019-12-15 21:19:39.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-12-15 21:19:39.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-12-15 21:20:06.491 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised

2019-12-15 21:20:06.494 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave inclusion controller finalised

2019-12-15 21:20:09.299 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:ad2ba90c

2019-12-15 21:20:09.303 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart

2019-12-15 21:20:09.306 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-12-15 21:20:09.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 253 to queue - size 1

2019-12-15 21:20:09.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-12-15 21:20:09.317 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 C5 70 

2019-12-15 21:20:09.321 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 C5 70 

2019-12-15 21:20:09.325 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-12-15 21:20:09.327 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-12-15 21:20:09.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 253: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 197

2019-12-15 21:20:09.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:20:09.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:20:09.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 253: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 197

2019-12-15 21:20:09.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-12-15 21:20:09.353 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-12-15 21:20:09.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-12-15 21:20:09.415 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A C5 06 00 00 71 

2019-12-15 21:20:09.418 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=197, payload=C5 06 00 00 

2019-12-15 21:20:09.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=197, payload=C5 06 00 00 

2019-12-15 21:20:09.422 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 253: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 197

2019-12-15 21:20:09.424 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-12-15 21:20:09.425 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 253: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 197

2019-12-15 21:20:09.427 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 253: (Callback 197)

2019-12-15 21:20:09.429 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-12-15 21:20:09.430 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 253: callback 197

2019-12-15 21:20:09.432 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=197, payload=C5 06 00 00 

2019-12-15 21:20:09.434 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.

2019-12-15 21:20:09.436 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone

2019-12-15 21:20:09.438 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-12-15 21:20:09.440 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 254 to queue - size 1

2019-12-15 21:20:09.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-12-15 21:20:09.444 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion

2019-12-15 21:20:09.446 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done

2019-12-15 21:20:09.447 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 253: Advanced to DONE

2019-12-15 21:20:09.449 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 122ms

2019-12-15 21:20:09.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 253: Transaction completed

2019-12-15 21:20:09.453 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:253 DONE

2019-12-15 21:20:09.455 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-12-15 21:20:09.457 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-12-15 21:20:09.459 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 

2019-12-15 21:20:09.461 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 

2019-12-15 21:20:09.463 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-12-15 21:20:09.464 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-12-15 21:20:09.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 254: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-12-15 21:20:09.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:20:09.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-12-15 21:20:09.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 254: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-12-15 21:20:09.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-12-15 21:20:09.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-12-15 21:20:09.477 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-12-15 21:20:14.467 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 254: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-12-15 21:20:14.471 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 254: Transaction is current transaction, so clearing!!!!!

2019-12-15 21:20:14.473 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 254: Transaction CANCELLED

2019-12-15 21:20:14.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:254 CANCELLED

2019-12-15 21:20:14.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

The devices are Fibaro FGS-223, FGDW-002 and Philio PST02-1B.

Thanks
Bernhard

chris · December 15, 2019, 9:27pm

Thanks. Unfortunately this doesn’t help too much other than to confirm the devices aren’t joining the network.

My guess is that the devices aren’t being put into inclusion mode properly. I’m not familiar with these devices though so I can’t help too much there other than to suggest to read the manuals (which you’ve no doubt done).

If you’ve tried two sticks, and multiple devices, it’s a little strange. Is the stick the Aeon stick? You could try including the devices by pushing the button, although I suspect that won’t work either as the binding seems to be going into inclusion mode and that’s the same thing the button on the stick does.

BernhardPeham · February 5, 2020, 9:44am

Hallo Chris,
I could solve this problem by reseting all my zwave devices. You gave me the important tipps, that there is no problem on the side of OH2.
So, I red the details of all the devices and could connect this to my system.
Thanks a lot!
Bernhard