Testing Z-Wave binding on openHAB-2

[feens (Trevor)]

@chris

Before what .

Sorry, I thought I’d noted that in my original question. I was working for the last month or so on OH2 zwave 2.0 binding up until last week when I updated everything again (so newer snapshot of OH2 and the binding). Now, originally my rule was just checking for Item Doorbell_Sensor received update, but then I noticed that it had started receiving updates randomly (new polling mechanisms I’m guessing). So now it seems to fire that when it wakes up, but not when I press the doorbell.

[chris (Chris Jackson)]

@rmitsos

Anyone can give some light with one of my devices (FGBS001 - Binary switch) where the initialization status seems to stall to ONLINE: STATIC_VALUES.

@rmitsos are you using the latest version. I think your problem should be fixed with this PR -:

https://github.com/openhab/openhab2-addons/pull/971

You will see in the comments that this fixed the FGBS001 initialisation issue. If your binding version is older than 2 days then please update and see if the problem still exists.

[rmitsos (Dimi R)]

The latest update did the trick. Thank you very much !

[chris (Chris Jackson)]

Great - I’m glad that fixed the issue

[feens (Trevor)]

@feens

Sorry, I thought I’d noted that in my original question. I was working for the last month or so on OH2 zwave 2.0 binding up until last week when I updated everything again (so newer snapshot of OH2 and the binding). Now, originally my rule was just checking for Item Doorbell_Sensor received update, but then I noticed that it had started receiving updates randomly (new polling mechanisms I’m guessing). So now it seems to fire that when it wakes up, but not when I press the doorbell.

@chris any other info I can get you to help sort this out?

[chris (Chris Jackson)]

Not really. The command class hasn’t changed for a long time - I hope to get the new class implementation added soon…

[feens (Trevor)]

@chris Also, I think my other question got buried:

@feens

@chris I noticed that you added in an automatic refresh poll for multilevel switches in order to handle the case where their value doesn’t update right away (as is the case with GE dimmers). Is there any way to adjust the delay for this poll? I noticed that at least with my switches, it’s still too quick. Ideally if turning it on/off to 100%/0%, it would end up with those values when it fires.

[chris (Chris Jackson)]

@feens

I noticed that you added in an automatic refresh poll for multilevel switches

I don’t think so (although I might have forgotten ). What I did do was to remove the poll that was there before so that it uses the value set in the command.

Maybe you can provide a log. There are other instances where an automatic poll is done - it’s not linked to the multilevel switch, but there are other reasons for this so I can check the log to see…

[feens (Trevor)]

@chris

Maybe you can provide a log. There are other instances where an automatic poll is done - it’s not linked to the multilevel switch, but there are other reasons for this so I can check the log to see…

Here you go (if you search for Dimmer_Island you’ll see that it fires off 2 updates with one press):

2016-06-02 15:35:05.322 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.325 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 49 84 03 0A 04 11 01 26 27 73 70 86 72 77 BE 
2016-06-02 15:35:05.328 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 49 84 03 0A 04 11 01 26 27 73 70 86 72 77 BE 
2016-06-02 15:35:05.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 03 0A 04 11 01 26 27 73 70 86 72 77 
2016-06-02 15:35:05.333 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 3: Application update request. Node information received.
2016-06-02 15:35:05.335 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Starting initialisation from DONE
2016-06-02 15:35:05.346 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12a88d8 already registered
2016-06-02 15:35:05.348 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 3: Application update request. Requesting node state.
2016-06-02 15:35:05.349 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveDelayedPollEvent
2016-06-02 15:35:05.351 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveDelayedPollEvent
2016-06-02 15:35:05.356 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling intialised at 1800 seconds - start in 75 milliseconds.
2016-06-02 15:35:05.363 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=120, payload=02 02 26 02 
2016-06-02 15:35:05.371 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 03 0A 04 11 01 26 27 73 70 86 72 77 
2016-06-02 15:35:05.377 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationUpdate, callback id=120, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2016-06-02 15:35:05.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling...
2016-06-02 15:35:05.430 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:home:node3:switch_dimmer
2016-06-02 15:35:05.432 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 3: Generating poll message for SWITCH_MULTILEVEL, endpoint 0
2016-06-02 15:35:05.436 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Creating new message for command SWITCH_MULTILEVEL_GET
2016-06-02 15:35:05.442 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Polling zwave:device:home:node3:switch_dimmer
2016-06-02 15:35:05.446 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 3: Generating poll message for SWITCH_MULTILEVEL, endpoint 0
2016-06-02 15:35:05.450 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Creating new message for command SWITCH_MULTILEVEL_GET
2016-06-02 15:35:05.455 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2016-06-02 15:35:05.455 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-06-02 15:35:05.459 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 26 02 25 79 9C 
2016-06-02 15:35:05.462 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 26 02 25 79 9C 
2016-06-02 15:35:05.464 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2016-06-02 15:35:05.473 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2016-06-02 15:35:05.477 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.479 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2016-06-02 15:35:05.481 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2016-06-02 15:35:05.483 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2016-06-02 15:35:05.485 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: Sent Data successfully placed on stack.
2016-06-02 15:35:05.495 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 79 00 00 03 91 
2016-06-02 15:35:05.498 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.501 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 79 00 00 03 00 00 9F 
2016-06-02 15:35:05.504 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 79 00 00 03 00 00 9F 
2016-06-02 15:35:05.507 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=79 00 00 03 
2016-06-02 15:35:05.509 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 121, Status = Transmission complete and ACK received(0)
2016-06-02 15:35:05.512 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Starting initialisation from DONE
2016-06-02 15:35:05.512 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 26 03 15 C2 
2016-06-02 15:35:05.514 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12a88d8 already registered
2016-06-02 15:35:05.517 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=3, callback=121, payload=03 02 26 02 
2016-06-02 15:35:05.519 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=79 00 00 03 
2016-06-02 15:35:05.521 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=121, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2016-06-02 15:35:05.523 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.526 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 03 03 26 03 15 C2 
2016-06-02 15:35:05.529 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 03 03 26 03 15 C2 
2016-06-02 15:35:05.532 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 03 03 26 03 15 
2016-06-02 15:35:05.534 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 3: Application Command Request (ALIVE:DONE)
2016-06-02 15:35:05.536 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Starting initialisation from DONE
2016-06-02 15:35:05.538 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12a88d8 already registered
2016-06-02 15:35:05.540 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 3: Incoming command class SWITCH_MULTILEVEL
2016-06-02 15:35:05.542 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Received Switch Multi Level Request
2016-06-02 15:35:05.544 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 21
2016-06-02 15:35:05.546 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-06-02 15:35:05.548 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-06-02 15:35:05.550 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 21
2016-06-02 15:35:05.553 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:home:node3:switch_dimmer to 21 [PercentType]
2016-06-02 15:35:05.558 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=3, callback=121, payload=03 02 26 02 
2016-06-02 15:35:05.560 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 03 03 26 03 15 
2016-06-02 15:35:05.562 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=121, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2016-06-02 15:35:05.563 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-06-02 15:35:05.565 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2016-06-02 15:35:05.567 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 3: Response processed after 102ms/2140ms.
2016-06-02 15:35:05.569 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2016-06-02 15:35:05.571 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 03 02 26 02 25 7A 9F 
2016-06-02 15:35:05.574 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 3: Sending REQUEST Message = 01 09 00 13 03 02 26 02 25 7A 9F 
2016-06-02 15:35:05.574 [INFO ] [marthome.event.ItemStateChangedEvent] - Dimmer_Island changed from 0 to 21
2016-06-02 15:35:05.587 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2016-06-02 15:35:05.603 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.606 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2016-06-02 15:35:05.610 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2016-06-02 15:35:05.613 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 7A 00 00 03 92 
2016-06-02 15:35:05.618 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2016-06-02 15:35:05.617 [INFO ] [marthome.event.ItemStateChangedEvent] - Sensor_Living_Room_Temperature changed from 22.77778 to 22.66667
2016-06-02 15:35:05.622 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: Sent Data successfully placed on stack.
2016-06-02 15:35:05.628 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.632 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 03 03 26 03 21 F6 
2016-06-02 15:35:05.637 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 7A 00 00 03 00 00 9C 
2016-06-02 15:35:05.639 [INFO ] [marthome.event.ItemStateChangedEvent] - Sensor_Office_Temperature changed from 25.83333 to 25.72222
2016-06-02 15:35:05.644 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 7A 00 00 03 00 00 9C 
2016-06-02 15:35:05.647 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=7A 00 00 03 
2016-06-02 15:35:05.650 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 3: SendData Request. CallBack ID = 122, Status = Transmission complete and ACK received(0)
2016-06-02 15:35:05.652 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Starting initialisation from DONE
2016-06-02 15:35:05.655 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12a88d8 already registered
2016-06-02 15:35:05.658 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=3, callback=122, payload=03 02 26 02 
2016-06-02 15:35:05.661 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=7A 00 00 03 
2016-06-02 15:35:05.664 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=122, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2016-06-02 15:35:05.667 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2016-06-02 15:35:05.671 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 03 03 26 03 21 F6 
2016-06-02 15:35:05.675 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 03 03 26 03 21 F6 
2016-06-02 15:35:05.679 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 03 03 26 03 21 
2016-06-02 15:35:05.682 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 3: Application Command Request (ALIVE:DONE)
2016-06-02 15:35:05.686 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 3: Starting initialisation from DONE
2016-06-02 15:35:05.689 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@12a88d8 already registered
2016-06-02 15:35:05.693 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 3: Incoming command class SWITCH_MULTILEVEL
2016-06-02 15:35:05.697 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Received Switch Multi Level Request
2016-06-02 15:35:05.701 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 3: Switch Multi Level report, value = 33
2016-06-02 15:35:05.704 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2016-06-02 15:35:05.708 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2016-06-02 15:35:05.708 [INFO ] [pse.smarthome.model.script.Occupancy] - Update occupancy
2016-06-02 15:35:05.713 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 33
2016-06-02 15:35:05.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Updating channel state zwave:device:home:node3:switch_dimmer to 33 [PercentType]
2016-06-02 15:35:05.724 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=3, callback=122, payload=03 02 26 02 
2016-06-02 15:35:05.728 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 03 03 26 03 21 
2016-06-02 15:35:05.731 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=122, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2016-06-02 15:35:05.732 [INFO ] [marthome.event.ItemStateChangedEvent] - Dimmer_Island changed from 21 to 33
2016-06-02 15:35:05.735 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2016-06-02 15:35:05.740 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 3: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2016-06-02 15:35:05.743 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 3: Response processed after 164ms/2140ms.

[chris (Chris Jackson)]

Thanks. Ok - this is the NIF poll which is set at 75ms. I’ll double it and we’ll see if that’s ok… I’m not sure I can do it tonight, so can you open an issue please so I don’t forget…

[feens (Trevor)]

@chris

can you open an issue please so I don’t forget.

Done: https://github.com/openhab/openhab2-addons/issues/996

I wonder if it would make sense to have certain device types delay longer than others before polling (i.e. in this case dimmers), as I’d assume other items don’t need that wait…

[chris (Chris Jackson)]

Thanks.

@feens

I wonder if it would make sense to have certain device types delay longer than others before polling

There’s a couple of options for this I guess. This is a good idea, and it might be a good one to start with…

[mark03 (Mark H)]

I apologize in advance if this was already answered; I searched diligently but did not actually read all 1331 messages in the thread.

I’m setting up openhab2 from scratch (a snapshot from a few days ago). I used the paper UI to install some extensions, including zwave, and manually added some Things as well. Then I added habmin2 (also a recent snapshot from Chris’ repo). The zwave binding is talking to my Z-Stick. It shows up as “online” in the list of Things in habmin2, and the “network viewer” under tools shows a single round dot labeled “1”.

So AFAICT everything is working and I am ready to include a zwave device. Chris’ wiki says

HABmin provides functions to include and exclude devices from the ZWave network. In the ZWave Network Menu is an Include and Exclude option - selecting either of these will put the controller into include or exclude mode for 30 seconds.

but I cannot find this menu anywhere in habmin. If I go to the Things view in the sidebar Configuration menu and click on the Z-Wave Serial Controller, I get the same menu options I had in paper UI (master yes, network heal time 2 am, the five channels with packet counts, and so on). In the sidebar Tools menu I have “Z-Wave Log Viewer” and “Z-Wave Network Viewer” and nothing else.

I should also point out that the latest habmin2 doesn’t look the same on my computer as it does on Chris’ screenshots. For instance, the main menu options are in a left sidebar instead of along the top of the screen as shown. This makes me wonder if I need to change themes to get the menu the wiki is talking about… except I don’t see any options related to themes either.

What am I missing?

Thanks

[chris (Chris Jackson)]

@mark03

but I cannot find this menu anywhere in habmin.

For inclusion, just use the discovery button at the top of the things page. For exclusion, select the controller thing, then in the top right of the page is a tools menu - enable show advanced settings and there will then be options to exclude (among other things).

Take a look here -:
http://www.cd-jackson.com/index.php/openhab/habmin/10-habmin-zwave-binding-initialisation

@mark03

I should also point out that the latest habmin2 doesn’t look the same on my computer as it does on Chris’ screenshots.

No - “Chris’s screenshots” are out of date on the wiki, but the link above should look the same as what you see I think.

I hope that helps…

@mark03

I searched diligently but did not actually read all 1331 messages in the thread.

I agree - this thread is getting wayyyyy to big… If people wanna start other threads for specific questions, feel free to mention me and I will hopefully find them and respond. It might be better to have smaller, targeted questions so that people searching the forum might have a better chance of finding them…

[Heiko_Fanieng]

@chris

I agree - this thread is getting wayyyyy to big… If people wanna start other threads for specific questions, feel free to mention me and I will hopefully find them and respond. It might be better to have smaller, targeted questions so that people searching the forum might have a better chance of finding them…

I thing creating sub-forums under obenHAB2 > bindings > i. E. Z-Wave should be a solution for better handle the questions for this (in my opinion) especially most complex OH2-binding.

[mnoack (Michael Noack)]

I am setting up my openhab2 on pi2 an got also with the last build the problem that the fibaro wall plug set the power to on itself. I have seen the following debug message:

15:05:24.149 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 10 02 03 25 03 FF 3A 
15:05:24.153 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=10 02 03 25 03 FF 
15:05:24.156 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
15:05:24.158 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
15:05:24.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@11e7c4f already registered
15:05:24.162 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SWITCH_BINARY
15:05:24.166 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 2
15:05:24.167 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Switch Binary report, value = 255
15:05:24.169 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
15:05:24.171 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
15:05:24.173 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255
15:05:24.176 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:8396a088:node2:switch_binary to ON [OnOffType]

I dont get it why this happens and second i ty to include a AEON Multisensor 6 and it endsup withe the following error
[WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device could not be resolved to a thingType! 0086:0002:0064::1.4

and afterwards i cant configure anything.
Any help will be helpful
Thanks Michael

[chris (Chris Jackson)]

@mnoack

with the last build the problem that the fibaro wall plug set the power to on itself.

I’m afraid I can’t help. I don’t think this is anything to do with the binding - the binding isn’t sending anything here, it’s just receiving a message from the device to say that it is turned on.
Maybe there’s a device with an association that is turning it on somewhere in the network?

@mnoack

i ty to include a AEON Multisensor 6 and it endsup withe the following error

Can you provide a full debug log please.

[mnoack (Michael Noack)]

Regarding the Fibaro plug i have to say that i also think it has nothing to do with the binding, but if openhab ist not running it does not happen and i have only this plug and the sensor plu sthe aeon usb Stick gen 5…

Here ist th debug log when i add things:

17:52:25.492 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Starting ZWave inclusion scan for zwave:serial_zstick:8396a088
17:52:25.495 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed
17:52:25.519 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_wd500z_00_000
17:52:25.523 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:honeywell_th8320zw_00_000
17:52:25.524 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:devolo_mt02648_00_000
17:52:25.525 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz68_00_000
17:52:25.527 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz68_00_000
17:52:25.528 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:popp_123610_00_000
17:52:25.529 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:benext_molite_00_000
17:52:25.530 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3102_00_000
17:52:25.532 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3102_00_000
17:52:25.533 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3102_00_000
17:52:25.535 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3102_00_000
17:52:25.536 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3102_00_000
17:52:25.542 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:cooper_rf9517_00_000
17:52:25.546 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsi_00_000
17:52:25.549 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.551 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.554 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.557 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:popp_009303_00_000
17:52:25.559 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:mcohome_mhs411_00_000
17:52:25.561 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_dzpa1_00_000
17:52:25.563 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:widom_ubs_01_000
17:52:25.565 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgms001_03_002
17:52:25.567 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc08_00_000
17:52:25.569 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc27_00_000
17:52:25.571 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:zwaveme_zme05433_00_000
17:52:25.574 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zl7101_00_000
17:52:25.576 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:philio_pan06_00_000
17:52:25.578 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:philio_pan06_00_000
17:52:25.580 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:benext_plugindimmer_00_000
17:52:25.582 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:horstmann_hrt4zw_00_000
17:52:25.584 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_vrpd3_00_000
17:52:25.586 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:popp_004001_00_000
17:52:25.588 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:eurotronic_cometz_00_000
17:52:25.590 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs222_00_000
17:52:25.592 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.594 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.596 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.598 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.600 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.602 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.604 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.606 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.608 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.610 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.613 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.615 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.617 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.619 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.621 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.623 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsivnode_00_000
17:52:25.625 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc19_00_000
17:52:25.627 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_wa105dbz_00_000
17:52:25.629 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhaa_00_000
17:52:25.631 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:danfoss_lcz251_00_000
17:52:25.634 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zse40_00_000
17:52:25.636 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zm1601_00_000
17:52:25.638 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc11_00_000
17:52:25.641 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:yale_yrd110_00_000
17:52:25.643 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_12729_00_000
17:52:25.645 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:brk_zcombo_00_000
17:52:25.647 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_wapirz_00_000
17:52:25.649 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:homeseer_hsws100_00_000
17:52:25.651 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:everspring_an157_00_000
17:52:25.654 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:northq_nq9021_00_000
17:52:25.656 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:yale_yrd210_00_000
17:52:25.658 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:yale_yrd210_00_000
17:52:25.660 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:popp_005107_00_000
17:52:25.662 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zw4101_00_000
17:52:25.664 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:reitz_05433_00_000
17:52:25.666 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:homeseer_ezmultipli_00_000
17:52:25.668 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz65d_00_000
17:52:25.670 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:remotec_zxt-120au_00_000
17:52:25.672 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:everspring_st812_00_000
17:52:25.674 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_wo15z_00_000
17:52:25.676 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_zw3003_00_000
17:52:25.678 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:nexia_db100z_00_000
17:52:25.680 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgd212_00_000
17:52:25.683 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgd212_00_000
17:52:25.685 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:everspring_se812_00_000
17:52:25.687 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:act_45602_00_000
17:52:25.689 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_45609_00_000
17:52:25.691 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhvd_00_000
17:52:25.693 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:horstmann_asrzw_00_000
17:52:25.694 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_45605_00_000
17:52:25.696 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:zipato_rgbwe27zw_00_000
17:52:25.698 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc14_00_000
17:52:25.700 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhad_00_000
17:52:25.701 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:everspring_an181_00_000
17:52:25.703 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:stelpro_stzw402_00_000
17:52:25.705 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc18_00_000
17:52:25.706 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.708 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.710 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.712 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.713 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.715 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.717 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.718 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.720 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.721 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgs211_00_000
17:52:25.723 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:benext_p1dongle_00_000
17:52:25.725 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:popp_123665_00_000
17:52:25.726 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_vrp03_00_000
17:52:25.728 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:25.729 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:25.731 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:25.733 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:25.760 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct100_00_000
17:52:25.762 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct100_00_000
17:52:25.764 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct100_00_000
17:52:25.766 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct100_00_000
17:52:25.768 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:dlink_dchz110_00_000
17:52:25.770 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_dzpd3_00_000
17:52:25.772 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgk101_00_000
17:52:25.774 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgk101_00_000
17:52:25.776 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgk101_00_000
17:52:25.778 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:philio_pst02a_00_000
17:52:25.780 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:wintop_ishutter_00_000
17:52:25.781 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_wadwaz1_00_000
17:52:25.783 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz88_00_000
17:52:25.785 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz88_00_000
17:52:25.786 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct30_00_000
17:52:25.788 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct30_00_000
17:52:25.789 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct30_00_000
17:52:25.791 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct30_00_000
17:52:25.793 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rtc_ct30_00_000
17:52:25.794 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_45604_00_000
17:52:25.796 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_45604_00_000
17:52:25.798 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:evolve_lrmas_00_000
17:52:25.800 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_pd300z2_00_000
17:52:25.802 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_vrpa1_00_000
17:52:25.804 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgr221_00_000
17:52:25.805 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgr221_00_000
17:52:25.807 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgr221_00_000
17:52:25.808 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgr221_00_000
17:52:25.810 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:danfoss_lc13_00_000
17:52:25.812 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:danfoss_lc13_00_000
17:52:25.814 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:danfoss_lc13_00_000
17:52:25.815 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_ps15z_00_000
17:52:25.817 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_zw4002_00_000
17:52:25.819 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgwpe_00_000
17:52:25.824 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed
17:52:25.886 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:somfy_zrtsi_00_000
17:52:25.888 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.891 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.893 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw097_00_000
17:52:25.906 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc08_00_000
17:52:25.908 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc27_00_000
17:52:26.038 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc14_00_000
17:52:26.043 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc18_00_000
17:52:26.079 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:26.083 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:26.085 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:26.087 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw112_00_000
17:52:26.170 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw062_00_000
17:52:26.172 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw062_00_000
17:52:26.173 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhid_00_000
17:52:26.175 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw096_00_000
17:52:26.177 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw096_00_000
17:52:26.178 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw096_00_000
17:52:26.180 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw096_00_000
17:52:26.181 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zd2102_00_000
17:52:26.183 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zd2102_00_000
17:52:26.185 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zd2102_00_000
17:52:26.188 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:intermatic_ha04c_00_000
17:52:26.189 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsa03202_00_000
17:52:26.191 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsa03202_00_000
17:52:26.193 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zl7432_00_000
17:52:26.194 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zp3103_00_000
17:52:26.196 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_fs20z_00_000
17:52:26.218 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsd37_00_000
17:52:26.220 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsd37_00_000
17:52:26.221 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsb09_00_000
17:52:26.223 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:trane_xl624_00_000
17:52:26.225 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:trane_tzemt400bb32maa_00_000
17:52:26.226 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:telldus_tzdw100_00_000
17:52:26.228 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc26_00_000
17:52:26.229 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:eco_dwzwave2_00_000
17:52:26.231 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:econet_ebv105_00_000
17:52:26.232 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsb05_00_000
17:52:26.234 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:danfoss_014g0160_00_000
17:52:26.236 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_dzr15_00_000
17:52:26.249 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc12_00_000
17:52:26.252 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zm1602_00_000
17:52:26.254 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zm1602_00_000
17:52:26.256 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:vision_zm1602_00_000
17:52:26.258 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:philio_psr04_00_000
17:52:26.273 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:everspring_ad147_00_000
17:52:26.275 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:horstmann_ssr302_00_000
17:52:26.277 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw089_00_000
17:52:26.278 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw089_00_000
17:52:26.280 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw089_00_000
17:52:26.286 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_45606_00_000
17:52:26.288 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:reitz_064394_00_000
17:52:26.289 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsb54_00_000
17:52:26.291 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw075_00_000
17:52:26.292 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw075_00_000
17:52:26.332 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.333 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.335 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.337 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.339 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.341 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.343 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.344 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw056_00_000
17:52:26.346 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:philio_pst02c_00_000
17:52:26.347 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:dlink_dchz120_00_000
17:52:26.349 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:tkb_tz67_00_000
17:52:26.350 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsb45_00_000
17:52:26.352 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw098_00_000
17:52:26.353 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw098_00_000
17:52:26.355 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw098_00_000
17:52:26.397 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsc24_00_000
17:52:26.399 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhsd_00_000
17:52:26.400 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:remotec_zrc90_00_000
17:52:26.402 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_zw1001_00_000
17:52:26.403 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgsd002_00_000
17:52:26.405 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhbd_00_000
17:52:26.407 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:yale_ykfcon_00_000
17:52:26.408 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:wintop_wtrfid_00_000
17:52:26.409 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:eco_pir1_00_000
17:52:26.411 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsb28_00_000
17:52:26.413 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:ge_12718_00_000
17:52:26.415 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:linear_lb60z1_00_000
17:52:26.417 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:devolo_mt2653_00_000
17:52:26.419 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_dsd31_00_000
17:52:26.450 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw099_00_000
17:52:26.452 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw099_00_000
17:52:26.454 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw099_00_000
17:52:26.456 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw099_00_000
17:52:26.457 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:chromagic_hsm02_00_000
17:52:26.459 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:evolve_lfm20_00_000
17:52:26.461 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_vrs15_00_000
17:52:26.462 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:leviton_vrs15_00_000
17:52:26.464 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:aeon_zw095_00_000
17:52:26.467 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:yale_yrd220_00_000
17:52:26.469 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:kwikset_914trl_00_000
17:52:26.471 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:rcs_tbz48_00_000
17:52:26.490 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:greenwave_gwpn5_00_000
17:52:26.492 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgss001_00_000
17:52:26.494 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:fibaro_fgss001_00_000
17:52:26.497 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:devolo_mt02646_00_000
17:52:26.499 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:qubino_zmnhdd_00_000
17:52:26.501 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:evolve_lsm15_00_000
17:52:26.503 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - Checking zwave:horstmann_scsc17_00_000
17:52:26.506 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device could not be resolved to a thingType! 0086:0002:0064::1.4
17:52:26.651 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:8396a088:node3' to inbox.
17:52:26.653 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2
17:52:26.655 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true.
17:52:26.659 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:26.662 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 01 70 
17:52:26.664 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 01 70 
17:52:26.666 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}
17:52:26.668 [INFO ] [smarthome.event.InboxAddedEvent     ] - Discovery Result with UID 'zwave:device:8396a088:node3' has been added.
17:52:26.676 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 01 00 00 B2 
17:52:26.680 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:26.683 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 01 00 00 B2 
17:52:26.703 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 01 00 00 B2 
17:52:26.684 [INFO ] [marthome.event.ItemStateChangedEvent] - zwave_serial_zstick_8396a088_serial_ack changed from 213 to 214
17:52:26.705 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 01 00 00 
17:52:26.707 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Learn ready.
17:52:26.708 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
17:52:26.710 [INFO ] [marthome.event.ItemStateChangedEvent] - zwave_serial_zstick_8396a088_serial_sof changed from 528 to 529
17:52:26.712 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=C1 01 
17:52:26.715 [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 
17:52:26.716 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
17:52:26.717 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
17:52:26.719 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 52ms/4902ms.
17:52:55.492 [DEBUG] [ve.internal.protocol.ZWaveController] - Stopping inclusion timer.
17:52:55.494 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Ending INCLUSION mode.
17:52:55.496 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
17:52:55.497 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller end inclusion
17:52:55.504 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:52:55.507 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A 05 01 B4 
17:52:55.509 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A 05 01 B4 
17:52:55.529 [INFO ] [marthome.event.ItemStateChangedEvent] - zwave_serial_zstick_8396a088_serial_ack changed from 214 to 215
17:52:55.571 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 4A 01 06 02 00 B7 
17:52:55.575 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:52:55.574 [INFO ] [marthome.event.ItemStateChangedEvent] - zwave_serial_zstick_8396a088_serial_sof changed from 529 to 530
17:52:55.577 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 4A 01 06 02 00 B7 
17:52:55.580 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 4A 01 06 02 00 B7 
17:52:55.583 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 02 00 
17:52:55.585 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Add Node: Done.
17:52:55.586 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInclusionEvent
17:52:55.588 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovered
17:52:55.590 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveInclusionEvent
17:52:55.593 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 2: Newly included node already initialising at DONE
17:52:55.595 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=05 01 
17:52:55.598 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=AddNodeToNetwork[0x4A], type=Request[0x00], priority=High, dest=255, callback=0, payload=01 06 02 00 
17:52:55.599 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, callback id=0, expected=AddNodeToNetwork, cancelled=false        transaction complete!
17:52:55.600 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
17:52:55.602 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 92ms/4902ms.

[chris (Chris Jackson)]

For the Fibaro, if there’s anything earlier in the log that might be related, then we should look at that, but otherwise, if the binding isn’t sending anything I’m not sure what else to suggest.

Thanks for the log - it’s given me something to think about as to what might be the issue. This is an error that isn’t uncommon, but I’ve not been able to replicate it here so hopefully this will help.

Chris

[chris (Chris Jackson)]

@mnoack can you try this again - I want to see if it’s repeatable in the same way… Also, can you provide the log when OH starts.

There are two interesting things in the log. Firstly, both node 2 and 3 are discovered at the same time, so this might indicate an issue with thread synchronisation. Additionally, your device isn’t in the list of devices, so this might indicate that ESH isn’t reading the XML properly/fully before the binding starts.

What I’d like to do is if you could restart the binding a couple of times and send me the full log each time so I can maybe try to narrow this down. You can email the logs to me at chris -at- cd-jackson.com if you like (it’s probably easier for both of us than trying to paste it into the forum .

Thanks
Chris