Cannot get Aeotec Z-Stick Gen5 to go into inclusion mode when plugged in

MrScripter · February 22, 2019, 2:50am

Platform information:
- Hardware: Raspberry Pi
- OS: openHABian v1.4.1
- Java Runtime Environment: openjdk version “1.8.0_152”
- openHAB version: 2.4.0

I am attempting to run an inclusion with my Aeotec Z-Stick Gen5, but for the life of me I cannot get any devices to include when running inclusion mode through Paper UI or HABmin.
I can include devices just fine when I unplug the Z-Stick and hit the action button.

Here are some logs when I start scanning for items in Paper UI.

2019-02-21 19:47:32.898 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@13010a7

2019-02-21 19:47:32.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 19:47:32.902 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion

2019-02-21 19:47:32.904 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done

2019-02-21 19:47:32.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 111: Advanced to DONE

2019-02-21 19:47:32.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 117ms

2019-02-21 19:47:32.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 111: Transaction completed

2019-02-21 19:47:32.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:111 DONE

2019-02-21 19:47:32.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 19:47:32.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 19:47:32.927 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5

2019-02-21 19:47:32.930 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5

2019-02-21 19:47:32.933 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-02-21 19:47:32.934 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-02-21 19:47:32.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 112: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-02-21 19:47:32.937 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0&lt;&gt;128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

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

2019-02-21 19:47:32.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 112: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-02-21 19:47:32.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-02-21 19:47:32.946 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 19:47:32.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 19:47:37.938 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 112: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-02-21 19:47:37.941 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 112: Transaction is current transaction, so clearing!!!!!

2019-02-21 19:47:37.947 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 112: Transaction CANCELLED

2019-02-21 19:47:37.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:112 CANCELLED

2019-02-21 19:47:37.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 19:48:53.807 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:d56ae344

2019-02-21 19:48:53.811 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

Any help would be greatly appreciated!

anonymous.one · February 22, 2019, 2:58am

Can you include one of the zwave devices if the z-stick is pulled out of the Pi and blinking blue (inclusion mode)?

Is openhab a member of the tty & dialout group in /etc/group?

Did you first add the z-stick as a thing in PaperUI?
Is the z-stick defined correctly in PaperUI for the Thing (/dev/ttyACM0 or have you defined your own udev rule for it)?

MrScripter · February 22, 2019, 3:29am

Yes, I can include devices when pulled out of the Pi and in inclusion mode (blinking blue light)
Here are the groups openhab is a member of:
[20:24:44] openhabian@openHABianPi:~$ grep openhab /etc/group
adm:x:4:openhabian
tty:x:5:openhab,openhabian
dialout:x:20:openhabian,openhab
cdrom:x:24:openhabian
sudo:x:27:openhabian
audio:x:29:openhabian,openhab
video:x:44:openhabian
plugdev:x:46:openhabian
games:x:60:openhabian
users:x:100:openhabian
input:x:101:openhabian
netdev:x:108:openhabian
bluetooth:x:111:openhab,openhabian
spi:x:999:openhabian
i2c:x:998:openhabian
gpio:x:997:openhabian,openhab
openhabian:x:1000:
openhab:x:114:openhabian
The Z-Stick has been added as a thing and is listed at “Z-Wave Serial Controller”
The Z-Stick has been defined as /dev/ttyACM1

Let me know if any of this info looks off to you.

Appreciate the help!!

anonymous.one · February 22, 2019, 3:58am

By default when the Pi boots it loads /dev/ttyACM0 as the device, it typically changes when you pull out the stick and plug it back into the Pi (and breaking OH’s ability to talk to it correctly as the number keeps changing vs what you define in PaperUI).

Since inclusion mode works outside the Pi, did you search your inbox for zwave devices to see if it populates what has been included?

MrScripter · February 22, 2019, 4:22am

So are you saying that the Z-Stick must be attached to /dev/ttyACM0 for communication to work properly? The device is showing as “ONLINE” in the “Things” list when assigned to /dev/ttyACM1.

Yes, when I do inclusion when the Z-Stick is unplugged, the devices do show up in the inbox.

The reason I am attempting to include with the stick plugged in is so that I can do a secure inclusion with my door lock. I have secure inclusion set to “All Devices” and inclusion mode set to “Network Wide Inclusion”?

chris · February 22, 2019, 5:07am

No.

This has nothing to do with serial ports or port permissions. From the log you can see that the device is communicating with the controller.

However, in the log you provided, the second line is the end of the inclusion, so it’s hard to say why it’s not working (since it seems to have worked from the very little information in this log). A longer log would be needed - preferably starting at the beginning of the inclusion or shortly before, rather than at the end .

ps. please also use code fences to format the logs as it’s otherwise very difficult to view (I’ve edited your post to fix it).

MrScripter · February 22, 2019, 5:30am

Thank you for the response and the feedback, Chris! Below is a longer output of my log during the inclusion process. This was captured a few lines before inclusion start until the end of inclusion. Random question. During inclusion from PaperUI, should the lights on the Z-Stick continue to cycle through its colors or blink blue like when you are in inclusion mode when the Z-Stick is unplugged. Currently it continues its color cycle when placed into inclusion through PaperUI.

2019-02-21 22:24:18.026 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - ZWave product zwave:device has no references!

2019-02-21 22:24:18.068 [DEBUG] [g.zwave.internal.ZWaveConfigProvider] - ZWave product zwave:serial_zstick has no references!

2019-02-21 22:24:24.623 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:d56ae344

2019-02-21 22:24:24.628 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave inclusion process not running - nothing to do

2019-02-21 22:24:24.635 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:d56ae344

2019-02-21 22:24:24.640 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2019-02-21 22:24:24.650 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2019-02-21 22:24:24.655 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2019-02-21 22:24:24.663 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.

2019-02-21 22:24:24.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 25 to queue - size 1

2019-02-21 22:24:24.672 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 22:24:24.678 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 02 73 

2019-02-21 22:24:24.683 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 02 73 

2019-02-21 22:24:24.689 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-02-21 22:24:24.691 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-02-21 22:24:24.695 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:24.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:24.698 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 02 01 00 00 B1 

2019-02-21 22:24:24.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null

2019-02-21 22:24:24.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-02-21 22:24:24.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=2, payload=02 01 00 00 

2019-02-21 22:24:24.693 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 2

2019-02-21 22:24:24.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 22:24:24.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 22:24:24.708 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=2, payload=02 01 00 00 

2019-02-21 22:24:24.710 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 2

2019-02-21 22:24:24.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-02-21 22:24:24.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 25: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 2

2019-02-21 22:24:24.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 25: (Callback 2)

2019-02-21 22:24:24.718 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-02-21 22:24:24.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 25: callback 2

2019-02-21 22:24:24.722 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=2, payload=02 01 00 00 

2019-02-21 22:24:24.724 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.

2019-02-21 22:24:24.732 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeSent, new event IncludeStart

2019-02-21 22:24:24.734 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 25: Transaction COMPLETED

2019-02-21 22:24:24.734 [arthome.event.BindingEvent] - org.openhab.binding.zwave.event.BindingEvent@1776da9

2019-02-21 22:24:24.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 43ms

2019-02-21 22:24:24.738 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 25: Transaction completed

2019-02-21 22:24:24.739 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:25 DONE

2019-02-21 22:24:24.741 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 22:24:24.743 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 22:24:44.635 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:d56ae344

2019-02-21 22:24:44.640 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeStart

2019-02-21 22:24:44.643 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-02-21 22:24:44.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 26 to queue - size 1

2019-02-21 22:24:44.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 22:24:44.654 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 03 B6 

2019-02-21 22:24:44.658 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 03 B6 

2019-02-21 22:24:44.661 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-02-21 22:24:44.664 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-02-21 22:24:44.666 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:44.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 3

2019-02-21 22:24:44.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:44.678 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 3

2019-02-21 22:24:44.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-02-21 22:24:44.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 22:24:44.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 22:24:44.753 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 03 06 00 00 B7 

2019-02-21 22:24:44.757 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=3, payload=03 06 00 00 

2019-02-21 22:24:44.760 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=3, payload=03 06 00 00 

2019-02-21 22:24:44.762 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 3

2019-02-21 22:24:44.765 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1

2019-02-21 22:24:44.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 26: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 3

2019-02-21 22:24:44.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 26: (Callback 3)

2019-02-21 22:24:44.771 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!

2019-02-21 22:24:44.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 26: callback 3

2019-02-21 22:24:44.776 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=3, payload=03 06 00 00 

2019-02-21 22:24:44.778 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.

2019-02-21 22:24:44.781 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion event: Current state IncludeDone, new event IncludeDone

2019-02-21 22:24:44.783 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.

2019-02-21 22:24:44.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 27 to queue - size 1

2019-02-21 22:24:44.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 22:24:44.791 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion

2019-02-21 22:24:44.793 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done

2019-02-21 22:24:44.795 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 26: Advanced to DONE

2019-02-21 22:24:44.797 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Response processed after 132ms

2019-02-21 22:24:44.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: TID 26: Transaction completed

2019-02-21 22:24:44.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:26 DONE

2019-02-21 22:24:44.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 22:24:44.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-02-21 22:24:44.825 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 00 B5 

2019-02-21 22:24:44.828 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 00 B5 

2019-02-21 22:24:44.843 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-02-21 22:24:44.846 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-02-21 22:24:44.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:44.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 27: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-02-21 22:24:44.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-02-21 22:24:44.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 27: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 0

2019-02-21 22:24:44.863 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK

2019-02-21 22:24:44.865 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

2019-02-21 22:24:44.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

2019-02-21 22:24:49.861 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 27: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-02-21 22:24:49.864 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 27: Transaction is current transaction, so clearing!!!!!

2019-02-21 22:24:49.867 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 27: Transaction CANCELLED

2019-02-21 22:24:49.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:27 CANCELLED

2019-02-21 22:24:49.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

anonymous.one · February 22, 2019, 1:39pm

Valid, I was ensuring that things were set correctly; as sometimes it is the small things we overlook .

chris · February 22, 2019, 1:44pm

The log looks reasonably ok - the stick is put into inclusion ok, and all the responses from the stick look fine.

The only strange thing is the timer appears to be only 20 seconds, when I thought it should be 30 seconds so I will check that.

MrScripter · February 22, 2019, 5:44pm

Yeah, I lowered the timer to 20 seconds in the controller config in hopes it would spit out less logging for you to parse ha. Didn’t really make a difference.

I’m a bit at a loss of what to try next… I may be to the point of a complete OS re-install (not sure that would even help honestly)

Just a few things I would like clarification on if possible:

When the Z-Stick goes into inclusion mode while still plugged in, should the light pattern on the stick change at all to reflect inclusion being enabled?
I should be able to include any thing when plugged in, correct? Not just items that require a secure inclusion?
How close do I need to be to include items when the Z-Stick is plugged in? I literally made a 50ft patch cable and also plugged into a 50ft extension cord and am walking around my house so I can get the Z-Stick as close to items as possible ha.

Really appreciate all the help!!

chris · February 22, 2019, 5:48pm

Personally I don’t know as I have never looked at the light pattern.

Sure - putting the stick into inclusion should be exactly the same if you do it via the UI, or the button on the device.

It needs to be in wireless range - so let’s say within 30ft. For secure inclusion, there may be some old devices that need to be very close, but it’s not recommended to do this by the ZWA these days and I’ve personally never come across a device that actually requires this.

MrScripter · February 23, 2019, 7:07pm

Thank you for everyone helping me troubleshoot this. I was finally able to get things working. I found this from the Aeotec Support page:
https://aeotec.freshdesk.com/support/solutions/articles/6000115924-my-z-stick-gen5-cannot-be-put-into-inclusion-mode-primary-controller-

Went through the instructions and inclusion worked! Hope this helps for anyone else that runs into this issue.

chris · February 24, 2019, 9:36am

The binding will normally set the controller as an SUC - unless there is already one in the network (or the controller says there is another SUC in the network).

Wannes · June 14, 2019, 4:16pm

@chris @MrScripter
I have a simular issue i am afraid…
i can see the item appearing after restarting openhab
i think this is also the reason secure inclusion is not working
here is the logfile from the inclusion
Any ideas?

2019-06-14 18:12:25.494 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2

2019-06-14 18:12:25.499 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added.

2019-06-14 18:12:25.504 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion

2019-06-14 18:12:25.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 11 to queue - size 1

2019-06-14 18:12:25.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-06-14 18:12:25.526 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 04 75

2019-06-14 18:12:25.529 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-06-14 18:12:25.531 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-06-14 18:12:25.532 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 11: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 4

2019-06-14 18:12:25.534 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 5<>123 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-06-14 18:12:25.537 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 04 01 00 00 B7

2019-06-14 18:12:25.542 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 6<>122 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=1, callback=4, payload=04 01 00 00

2019-06-14 18:12:30.535 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 11: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-06-14 18:12:30.538 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 11: Transaction is current transaction, so clearing!!!

2019-06-14 18:12:30.541 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 11: Transaction CANCELLED

2019-06-14 18:12:30.544 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:11 CANCELLED

2019-06-14 18:12:30.547 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-06-14 18:12:55.488 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:16b56bde664

2019-06-14 18:12:55.492 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeSent

2019-06-14 18:12:55.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 12 to queue - size 1

2019-06-14 18:12:55.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-06-14 18:12:55.508 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 05 B0

2019-06-14 18:12:55.512 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT

2019-06-14 18:12:55.513 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06

2019-06-14 18:12:55.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 12: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 5

2019-06-14 18:12:55.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 7<>121 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=

2019-06-14 18:12:55.565 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 05 06 02 00 B3

2019-06-14 18:12:55.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 8<>120 : Message: class=AddNodeToNetwork[74], type=Request[0], dest=6, callback=5, payload=05 06 02 00

2019-06-14 18:13:00.517 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 12: Timeout at state WAIT_REQUEST. 3 retries remaining.

2019-06-14 18:13:00.524 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 12: Transaction is current transaction, so clearing!!!

2019-06-14 18:13:00.526 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 12: Transaction CANCELLED

2019-06-14 18:13:00.529 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:12 CANCELLED

2019-06-14 18:13:00.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

2019-06-14 18:13:10.526 [DEBUG] [al.protocol.ZWaveInclusionController] - Inclusion timer at IncludeDone