Problems with Aeotec Gen5 Stick an Fibaro Shutter 2

Setup, Configuration and Use Beginners
1

Hey guys,

I’m trying to configure my Fibaro shutter 2 together with an Aeotec Gen 5 Stick, but I’m not able to discover my Firbaro shutter. Here’s an ouput of the log

2018-06-17 14:07:23.648 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.

2018-06-17 14:07:23.658 [DEBUG] [ve.internal.protocol.ZWaveController] - Neither inclusion nor exclusion was active!

2018-06-17 14:07:23.667 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Starting ZWave inclusion scan for zwave:serial_zstick:0ab974da

2018-06-17 14:07:23.673 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2018-06-17 14:07:23.677 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2018-06-17 14:07:23.682 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-06-17 14:07:23.682 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-06-17 14:07:23.686 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70 

2018-06-17 14:07:23.692 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70 

2018-06-17 14:07:23.701 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2 

2018-06-17 14:07:23.708 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-06-17 14:07:23.713 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2 

2018-06-17 14:07:23.717 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2 

2018-06-17 14:07:23.721 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00 

2018-06-17 14:07:23.725 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2018-06-17 14:07:23.728 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent

2018-06-17 14:07:23.735 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01 

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

2018-06-17 14:07:23.737 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@1a2ee0d

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

2018-06-17 14:07:23.739 [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 

2018-06-17 14:07:23.744 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!

2018-06-17 14:07:23.748 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-06-17 14:07:23.751 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 56ms/152ms.

2018-06-17 14:07:53.667 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.

2018-06-17 14:07:53.671 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2018-06-17 14:07:53.675 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-06-17 14:07:53.675 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-06-17 14:07:53.679 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 01 B4 

2018-06-17 14:07:53.678 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion

2018-06-17 14:07:53.685 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 01 B4 

2018-06-17 14:07:53.781 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 00 00 B5 

2018-06-17 14:07:53.785 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-06-17 14:07:53.789 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 00 00 B5 

2018-06-17 14:07:53.793 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 00 00 B5 

2018-06-17 14:07:53.797 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 00 00 

2018-06-17 14:07:53.801 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.

2018-06-17 14:07:53.804 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent

2018-06-17 14:07:53.810 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01 

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

2018-06-17 14:07:53.811 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@1651fc9

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

2018-06-17 14:07:53.814 [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 

2018-06-17 14:07:53.818 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!

2018-06-17 14:07:53.821 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-06-17 14:07:53.825 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 135ms/152ms.

From my point of view, there’s a communication ongoing between the devices, but I don’t see the shutter in my “Inbox”. Any ideas?

Btw.: It’s a fresh install of Openhab:

~$ dpkg --list | grep openhab
ii openhab2 2.3.0-1 all openhab2

Thanks,
Seb

2

There doesn’t appear to be any communication with the device. Maybe it’s too far away from the controller, or maybe it’s not in inclusion mode?

3

Well, it’s less than a meter and it’s in nclusion mode.

4

Ok, sorry, I’m only going on the log, and there is no communication from the device as far as the binding can tell. This communication is 100% managed by the controller itself - not the binding, so there’s not a lot I can add. What exactly are you doing?

5

Sorry Chris, you were right. The shutter wasn’t in inclusion mode. Now it was “discovered”, but there seems to be another issue. I cannot do anything via PaperUI. When I take a closer look at “Node 2” under “things” it says “UNINITIALIZED - HANDLER_INITIALIZING_ERROR”.

6

What is in the log? I guess there might be some sort of exception thrown? Also, how are you configuring the device? Are you just using the UI, or are you doing anything with text files?

7

Hmmm, there’s nothing in the logs… I’ll try to restart the OH services.

8

After the restart of the services, everything works fine. Thanks a lot Chris!