[SOLVED] Z-wave Fakro ZWS12 manual button press event

Let my just frist say: openHAB rocks :slight_smile:
I just started using Openhab after testing dozens of other products/software which all had their flaws.
But the degree of flexibility with openHAB is just amazing.

With the use of CD-Jackson my Fakro (passive house) windows with integrated ZWS12 motors were added to the database (different edition of the product). And i could download the unstable snapshot after a few days (talking about rapid development).
Fakro ZWS12 database listing

Now i can control my windows but i have a issue with the manual control button on the window. When this button is pressed i dont get an event to be used in my rules.

I turned on debug in the binding and i get these log lines:

closed by pressing button on the window once:


2018-08-05 16:27:47.467 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 20 03 00 D0 

2018-08-05 16:27:47.479 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-05 16:27:47.484 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 20 03 00 D0 

2018-08-05 16:27:47.488 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 20 03 00 D0 

2018-08-05 16:27:47.493 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 03 00 

2018-08-05 16:27:47.496 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

2018-08-05 16:27:47.499 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-05 16:27:47.503 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1c18812 already registered

2018-08-05 16:27:47.507 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class BASIC

2018-08-05 16:27:47.511 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Received Basic Request

2018-08-05 16:27:47.514 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Basic report, value = 0x00

2018-08-05 16:27:47.518 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-05 16:27:47.521 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-05 16:27:47.524 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0

2018-08-05 16:27:47.528 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=129, payload=02 02 26 02 

2018-08-05 16:27:47.533 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 03 00 

2018-08-05 16:27:47.535 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=129, expected=ApplicationCommandHandler, cancelled=false        transaction complete!

2018-08-05 16:27:47.538 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-05 16:27:47.541 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

Opened by pressing button on the window (message comes in after window has completely opened:

2018-08-05 16:30:14.682 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 20 03 63 B3 

2018-08-05 16:30:14.689 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-05 16:30:14.696 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 20 03 63 B3 

2018-08-05 16:30:14.704 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 20 03 63 B3 

2018-08-05 16:30:14.711 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 03 63 

2018-08-05 16:30:14.718 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

2018-08-05 16:30:14.720 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-05 16:30:14.725 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1c18812 already registered

2018-08-05 16:30:14.729 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class BASIC

2018-08-05 16:30:14.733 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Received Basic Request

2018-08-05 16:30:14.737 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Basic report, value = 0x63

2018-08-05 16:30:14.741 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-05 16:30:14.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-05 16:30:14.749 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 99

2018-08-05 16:30:14.755 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=129, payload=02 02 26 02 

2018-08-05 16:30:14.761 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 03 63 

2018-08-05 16:30:14.765 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=129, expected=ApplicationCommandHandler, cancelled=false        transaction complete!

2018-08-05 16:30:14.769 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-05 16:30:14.773 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

Any suggestions how to see when someone pressed the button to open or close it manually?

The device is reporting BASIC for manual events and it doesn’t look like there is a way to configure what is sent. You’ll need to tick the Treat as Basic in either the SWITCH_BINARY or SWITCH_MULTILEVEL. I’m not sure if BASIC can report multilevel, so @chris or maybe @sihui can tell you which one.

1 Like

Yes, it can.

1 Like

Any hint how i can do this with my items? I got 2 switches:

Switch AtticRoofWindow_Switch "Dakraam zolder aan-uit"{channel="zwave:device:96ff320c:node2:switch_binary",autoupdate="false"}
Dimmer AtticRoofWindow_Dimmer "Dakraam zolder dimmer " {channel="zwave:device:96ff320c:node2:switch_dimmer"}

You can’t do this in the items directly - it needs a database update which will be done in the coming few days.

1 Like

This is not a change to your items. You will need to update the device database. Edit the SWITCH_MULTILEVEL channel and tick the Treat as Basic box. The next database export and zwave binding build will then have the change. From the OP, it sounds like you already have access to the db. If not, or if you have trouble with it, let me know and I’ll tick the box.

I tested the new snapshot version (1208) which contains the basic command change.
But no luck. I cant see the BASIC commmand debug lines anymore.

But now i have a lot of these “timeout lines” ?:

2018-08-12 11:19:18.264 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Stage DELETE_ROUTES. Initialisation retry timer triggered. Increased to 320000

2018-08-12 11:19:18.269 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer - DELETE_ROUTES: queue length(0), free to send(false)

2018-08-12 11:19:18.274 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Initialisation retry timer started 320000

2018-08-12 11:19:18.278 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer: Retries exceeded at DELETE_ROUTES

2018-08-12 11:19:18.282 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Retry timout: Advancing

2018-08-12 11:19:18.287 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer: loop - RETURN_ROUTES try 0: stageAdvanced(false)

2018-08-12 11:19:18.291 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Generate return routes list

2018-08-12 11:19:18.296 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Adding return route to 1

2018-08-12 11:19:18.302 [DEBUG] [essage.AssignReturnRouteMessageClass] - NODE 2: Assigning return route to node 1

2018-08-12 11:19:18.308 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer - queued packet. Queue length is 1

2018-08-12 11:19:18.313 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:19:18.313 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:19:18.319 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:18.325 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:23.328 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 2 attempts left!

2018-08-12 11:19:23.332 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:19:23.337 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:19:23.342 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:23.348 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:28.352 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!

2018-08-12 11:19:28.354 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:19:28.359 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:19:28.364 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:28.370 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:33.374 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!

2018-08-12 11:19:33.378 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:19:33.382 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:19:33.387 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:33.393 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 23 9F 

2018-08-12 11:19:38.398 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class=AssignReturnRoute[0x46], type=Request[0x00], priority=High, dest=255, callback=0, payload=02 01 23 
2018-08-12 11:24:26.496 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2018-08-12 11:24:26.501 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 11:24:26.506 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2018-08-12 11:24:26.511 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2018-08-12 11:24:26.513 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2F 00 00 02 C6 

2018-08-12 11:24:26.515 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2018-08-12 11:24:26.519 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

2018-08-12 11:24:26.523 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 11:24:26.528 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2F 00 00 02 00 00 C8 

2018-08-12 11:24:26.533 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2F 00 00 02 00 00 C8 

2018-08-12 11:24:26.538 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2F 00 00 02 

2018-08-12 11:24:26.542 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 47, Status = Transmission complete and ACK received(0)

2018-08-12 11:24:26.545 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 11:24:26.548 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 11:24:26.554 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=2, callback=47, payload=02 03 26 01 00 

2018-08-12 11:24:26.559 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2F 00 00 02 

2018-08-12 11:24:26.562 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=47, expected=SendData, cancelled=false        transaction complete!

2018-08-12 11:24:26.565 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-12 11:24:26.569 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-12 11:24:26.571 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 85ms/1184ms.

2018-08-12 11:24:38.273 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Stage RETURN_ROUTES. Initialisation retry timer triggered. Increased to 640000

2018-08-12 11:24:38.277 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer - RETURN_ROUTES: queue length(0), free to send(false)

2018-08-12 11:24:38.280 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Initialisation retry timer started 640000

2018-08-12 11:24:38.284 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer: loop - RETURN_ROUTES try 1: stageAdvanced(false)

2018-08-12 11:24:38.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Generate return routes list

2018-08-12 11:24:38.290 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Adding return route to 1

2018-08-12 11:24:38.293 [DEBUG] [essage.AssignReturnRouteMessageClass] - NODE 2: Assigning return route to node 1

2018-08-12 11:24:38.297 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Node advancer - queued packet. Queue length is 1

2018-08-12 11:24:38.303 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:24:38.306 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}

2018-08-12 11:24:38.310 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:38.314 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:43.318 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 2 attempts left!

2018-08-12 11:24:43.321 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:24:43.324 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:24:43.329 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:43.333 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:48.336 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!

2018-08-12 11:24:48.341 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:24:48.346 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:24:48.351 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:48.354 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:53.358 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!

2018-08-12 11:24:53.361 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 11:24:53.363 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 11:24:53.368 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:53.372 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Port closed sending REQUEST Message = 01 06 00 46 02 01 24 98 

2018-08-12 11:24:58.376 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class=AssignReturnRoute[0x46], type=Request[0x00], priority=High, dest=255, callback=0, payload=02 01 24 

Hmm after 8 minutes it seems something does come in:

2018-08-12 13:04:55.717 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...

2018-08-12 13:04:55.722 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_binary

2018-08-12 13:04:55.725 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 2: Generating poll message for SWITCH_BINARY, endpoint 0

2018-08-12 13:04:55.728 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Creating new message for application command SWITCH_BINARY_GET

2018-08-12 13:04:55.732 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_dimmer

2018-08-12 13:04:55.735 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 2: Generating poll message for SWITCH_MULTILEVEL, endpoint 0

2018-08-12 13:04:55.738 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_GET

2018-08-12 13:04:55.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_dimmer

2018-08-12 13:04:55.745 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 2: Generating poll message for SWITCH_MULTILEVEL, endpoint 0

2018-08-12 13:04:55.749 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_GET

2018-08-12 13:04:55.753 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:alarm_power

2018-08-12 13:04:55.756 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:alarm_flood

2018-08-12 13:04:55.760 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2018-08-12 13:04:55.763 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}

2018-08-12 13:04:55.766 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 25 02 25 57 B0 

2018-08-12 13:04:55.767 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2018-08-12 13:04:55.772 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 25 02 25 57 B0 

2018-08-12 13:04:55.773 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}

2018-08-12 13:04:55.778 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...

2018-08-12 13:04:55.781 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_binary

2018-08-12 13:04:55.785 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 2: Generating poll message for SWITCH_BINARY, endpoint 0

2018-08-12 13:04:55.788 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Creating new message for application command SWITCH_BINARY_GET

2018-08-12 13:04:55.788 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2018-08-12 13:04:55.792 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_dimmer

2018-08-12 13:04:55.796 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.797 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 2: Generating poll message for SWITCH_MULTILEVEL, endpoint 0

2018-08-12 13:04:55.801 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_GET

2018-08-12 13:04:55.803 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2018-08-12 13:04:55.803 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 57 00 00 02 BE 

2018-08-12 13:04:55.805 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:switch_dimmer

2018-08-12 13:04:55.807 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 2: Generating poll message for SWITCH_MULTILEVEL, endpoint 0

2018-08-12 13:04:55.808 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2018-08-12 13:04:55.810 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_GET

2018-08-12 13:04:55.813 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:alarm_power

2018-08-12 13:04:55.813 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2018-08-12 13:04:55.815 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:96ff320c:node2:alarm_flood

2018-08-12 13:04:55.816 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

2018-08-12 13:04:55.818 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.818 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 25 03 00 D5 

2018-08-12 13:04:55.819 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 3. Queue={}

2018-08-12 13:04:55.822 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 4. Queue={}

2018-08-12 13:04:55.825 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 5. Queue={}

2018-08-12 13:04:55.828 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 57 00 00 02 00 00 B0 

2018-08-12 13:04:55.831 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 57 00 00 02 00 00 B0 

2018-08-12 13:04:55.834 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=57 00 00 02 

2018-08-12 13:04:55.836 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 87, Status = Transmission complete and ACK received(0)

2018-08-12 13:04:55.838 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:55.840 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:55.843 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=87, payload=02 02 25 02 

2018-08-12 13:04:55.845 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=57 00 00 02 

2018-08-12 13:04:55.847 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=87, expected=ApplicationCommandHandler, cancelled=false      MISMATCH

2018-08-12 13:04:55.850 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.853 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 25 03 00 D5 

2018-08-12 13:04:55.856 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 25 03 00 D5 

2018-08-12 13:04:55.859 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 25 03 00 

2018-08-12 13:04:55.861 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

2018-08-12 13:04:55.863 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:55.865 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:55.868 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SWITCH_BINARY

2018-08-12 13:04:55.870 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 2

2018-08-12 13:04:55.872 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 2: Switch Binary report, value = 0

2018-08-12 13:04:55.874 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-12 13:04:55.876 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-12 13:04:55.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0

2018-08-12 13:04:55.880 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:96ff320c:node2:switch_binary to OFF [OnOffType]

2018-08-12 13:04:55.885 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=87, payload=02 02 25 02 

2018-08-12 13:04:55.889 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 25 03 00 

2018-08-12 13:04:55.892 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=87, expected=ApplicationCommandHandler, cancelled=false        transaction complete!

2018-08-12 13:04:55.894 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-12 13:04:55.897 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-12 13:04:55.900 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 122ms/1184ms.

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

2018-08-12 13:04:55.901 [vent.ItemStateChangedEvent] - DakraamZolder_Switch changed from ON to OFF

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

2018-08-12 13:04:55.905 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 4

2018-08-12 13:04:55.910 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 26 02 25 58 BC 

2018-08-12 13:04:55.910 [INFO ] [script.nieuwe status dakraam switch ] - OFF

2018-08-12 13:04:55.915 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 26 02 25 58 BC 

2018-08-12 13:04:55.928 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2018-08-12 13:04:55.930 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.933 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2018-08-12 13:04:55.936 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2018-08-12 13:04:55.939 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2018-08-12 13:04:55.942 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

2018-08-12 13:04:55.944 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 58 00 00 03 B0 

2018-08-12 13:04:55.948 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.952 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 58 00 00 03 00 00 BE 

2018-08-12 13:04:55.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 58 00 00 03 00 00 BE 

2018-08-12 13:04:55.959 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:55.961 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=58 00 00 03 

2018-08-12 13:04:55.964 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 88, Status = Transmission complete and ACK received(0)

2018-08-12 13:04:55.966 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:55.969 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:55.972 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=88, payload=02 02 26 02 

2018-08-12 13:04:55.975 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=58 00 00 03 

2018-08-12 13:04:55.978 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=88, expected=ApplicationCommandHandler, cancelled=false      MISMATCH

2018-08-12 13:04:55.980 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:55.983 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:55.987 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:55.990 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 26 03 00 

2018-08-12 13:04:55.992 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

2018-08-12 13:04:55.995 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:55.997 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:56.000 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SWITCH_MULTILEVEL

2018-08-12 13:04:56.003 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Received SWITCH_MULTILEVEL command V3

2018-08-12 13:04:56.005 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 0

2018-08-12 13:04:56.007 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-12 13:04:56.010 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-12 13:04:56.013 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 0

2018-08-12 13:04:56.015 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:96ff320c:node2:switch_dimmer to 0 [PercentType]

2018-08-12 13:04:56.022 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=88, payload=02 02 26 02 

2018-08-12 13:04:56.027 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 26 03 00 

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

2018-08-12 13:04:56.027 [vent.ItemStateChangedEvent] - DakraamZolder_Dimmer changed from 100 to 0

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

2018-08-12 13:04:56.031 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=88, expected=ApplicationCommandHandler, cancelled=false        transaction complete!

2018-08-12 13:04:56.035 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-12 13:04:56.038 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-12 13:04:56.041 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 123ms/1184ms.

2018-08-12 13:04:56.041 [INFO ] [.script.nieuwe status dakraam dimmer] - 0

2018-08-12 13:04:56.045 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 3

2018-08-12 13:04:56.048 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 26 02 25 59 BD 

2018-08-12 13:04:56.051 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 26 02 25 59 BD 

2018-08-12 13:04:56.063 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2018-08-12 13:04:56.066 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:56.069 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2018-08-12 13:04:56.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2018-08-12 13:04:56.074 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2018-08-12 13:04:56.076 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

2018-08-12 13:04:56.080 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 59 00 00 02 B0 

2018-08-12 13:04:56.082 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:56.085 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 59 00 00 02 00 00 BE 

2018-08-12 13:04:56.088 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 59 00 00 02 00 00 BE 

2018-08-12 13:04:56.092 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=59 00 00 02 

2018-08-12 13:04:56.092 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:56.094 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 89, Status = Transmission complete and ACK received(0)

2018-08-12 13:04:56.096 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:56.098 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:56.101 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=89, payload=02 02 26 02 

2018-08-12 13:04:56.104 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=59 00 00 02 

2018-08-12 13:04:56.106 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=89, expected=ApplicationCommandHandler, cancelled=false      MISMATCH

2018-08-12 13:04:56.109 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:56.112 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:56.114 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 26 03 00 D6 

2018-08-12 13:04:56.117 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 26 03 00 

2018-08-12 13:04:56.120 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

2018-08-12 13:04:56.122 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE

2018-08-12 13:04:56.124 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@5b9071 already registered

2018-08-12 13:04:56.126 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SWITCH_MULTILEVEL

2018-08-12 13:04:56.129 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Received SWITCH_MULTILEVEL command V3

2018-08-12 13:04:56.131 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Switch Multi Level report, value = 0

2018-08-12 13:04:56.133 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-12 13:04:56.136 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-12 13:04:56.138 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_MULTILEVEL, value = 0

2018-08-12 13:04:56.140 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:96ff320c:node2:switch_dimmer to 0 [PercentType]

2018-08-12 13:04:56.148 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=89, payload=02 02 26 02 

2018-08-12 13:04:56.152 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 26 03 00 

2018-08-12 13:04:56.155 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=89, expected=ApplicationCommandHandler, cancelled=false        transaction complete!

2018-08-12 13:04:56.157 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2018-08-12 13:04:56.159 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2018-08-12 13:04:56.161 [INFO ] [.script.nieuwe status dakraam dimmer] - 0

2018-08-12 13:04:56.162 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 109ms/1184ms.

2018-08-12 13:04:56.164 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 2

2018-08-12 13:04:56.167 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 25 02 25 5A BD 

2018-08-12 13:04:56.170 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 25 02 25 5A BD 

2018-08-12 13:04:56.181 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

2018-08-12 13:04:56.183 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-12 13:04:56.185 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2018-08-12 13:04:56.187 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2018-08-12 13:04:56.189 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2018-08-12 13:04:56.191 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

2018-08-12 13:04:56.198 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 5A 00 00 02 B3 

I just tested some more. But the flood channel (rain sensor) doesnt seem to be triggered any more.
tested with putting some water on the sensor (tested few times). The windows closes but with the old binding version i got an event for this channel/item. and with the new version nothing gets into the console.
Instead it triggers a binary switch > ON and multilevel > 0 events. So there’s no way to see if it’s done by the rainsensor.

Could this in someway be connected to the basic binding change in the database for this device?

I removed the thing in paper ui added it again and now rain sensor also works.
Moving on to the next devices for integration :slight_smile:

Sorry… I missed your posts. When you make changes to a device’s channel configuration, you are changing the Thing definition. To use the updated Thing definition from the updated binding, you need to delete and rediscover the Thing. Until you do this, I’ve noticed sometimes Things do not work properly.

1 Like