Hi,
I’ve a problem with my z-wave network or with a rule (see below).
The 2 switches of WS_BM_TERASSE_01 and the switch of WS_BM_TERASSE_01 are changing their states multpile times after the rule is tiggered.
The rule is triggered more than once although a button on the remote is only pressed once.
The attached log (DEBUG enabled for z-wave binding) shows a lot of traffic regarding these things.
The log is truncated.
Does someone have an idea what could cause these traffic and why the rule is triggered more than once?
Thanks
Norbert
z-wave node id Device type Thing name
62 DEVOLO MT2653 Keyfob RC_01_DEVOLO
9 FGWP102 Metered Wall Plug Switch WP_BM_CARPORT_01
21 FGS223 Double Switch 2 WS_BM_TERASSE_01
RC_01_DEVOLO Configured to send “central scene to gateway”
The log viewer shows some long runs for some messages and some rejected messages:
2018-08-22 20:21:14.418 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 3E 05 5B 03 F2 00 06 67
2018-08-22 20:21:14.421 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:14.423 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 04 00 3E 05 5B 03 F2 00 06 67
2018-08-22 20:21:14.425 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0B 00 04 00 3E 05 5B 03 F2 00 06 67
2018-08-22 20:21:14.427 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 3E 05 5B 03 F2 00 06
2018-08-22 20:21:14.429 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:14.431 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE
2018-08-22 20:21:14.432 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1fc41d9 already registered
2018-08-22 20:21:14.434 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE
2018-08-22 20:21:14.436 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1
2018-08-22 20:21:14.437 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 6 at key 0 [Single Press]
2018-08-22 20:21:14.440 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-08-22 20:21:14.441 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-08-22 20:21:14.443 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 6.0
2018-08-22 20:21:14.445 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 6.0 [DecimalType]
2018-08-22 20:21:14.448 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 35: Transaction not completed: node address inconsistent. lastSent=35, incoming=255
2018-08-22 20:21:14.471 [INFO ] [ipse.smarthome.model.script.r_RC_001] - rule r_RC_DEVOLO_01_Scene_Number_rcv_upd
RC_DEVOLO_01_Scene_Number received command = "6.0"
2018-08-22 20:21:14.492 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Command received zwave:device:c1f45758:node21:switch_binary1 --> OFF
2018-08-22 20:21:14.495 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 21: Creating new message for application command SWITCH_BINARY_SET
2018-08-22 20:21:14.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: Encapsulating message, instance / endpoint 1
2018-08-22 20:21:14.509 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 1
2018-08-22 20:21:14.511 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-08-22 20:21:14.511 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-08-22 20:21:14.513 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 15 07 60 0D 01 01 25 01 00 25 26 BA
2018-08-22 20:21:14.516 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 21: Sending REQUEST Message = 01 0E 00 13 15 07 60 0D 01 01 25 01 00 25 26 BA
2018-08-22 20:21:14.499 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Command received zwave:device:c1f45758:node9:switch_binary --> OFF
2018-08-22 20:21:14.520 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 9: Creating new message for application command SWITCH_BINARY_SET
2018-08-22 20:21:14.522 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Command received zwave:device:c1f45758:node21:switch_binary2 --> OFF
2018-08-22 20:21:14.527 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 21: Creating new message for application command SWITCH_BINARY_SET
2018-08-22 20:21:14.527 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-08-22 20:21:14.529 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 21: Encapsulating message, instance / endpoint 2
2018-08-22 20:21:14.533 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2018-08-22 20:21:14.536 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-08-22 20:21:14.539 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:14.541 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-08-22 20:21:14.535 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2018-08-22 20:21:14.543 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-08-22 20:21:14.545 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-08-22 20:21:14.547 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 21: Sent Data successfully placed on stack.
2018-08-22 20:21:14.754 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 26 00 00 17 DA
2018-08-22 20:21:14.758 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:14.760 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 26 00 00 17 00 00 D4
2018-08-22 20:21:14.762 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 26 00 00 17 00 00 D4
2018-08-22 20:21:14.764 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=26 00 00 17
2018-08-22 20:21:14.765 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 21: SendData Request. CallBack ID = 38, Status = Transmission complete and ACK received(0)
2018-08-22 20:21:14.767 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 21: Starting initialisation from DONE
2018-08-22 20:21:14.774 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b6081d already registered
2018-08-22 20:21:14.776 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=21, callback=38, payload=15 07 60 0D 01 01 25 01 00
2018-08-22 20:21:14.784 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=26 00 00 17
2018-08-22 20:21:14.794 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=38, expected=SendData, cancelled=false transaction complete!
2018-08-22 20:21:14.795 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-08-22 20:21:14.797 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-08-22 20:21:14.809 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 21: Response processed after 291ms/4958ms.
2018-08-22 20:21:14.818 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
2018-08-22 20:21:14.820 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 09 03 25 01 00 25 27 CA
2018-08-22 20:21:14.822 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 9: Sending REQUEST Message = 01 0A 00 13 09 03 25 01 00 25 27 CA
2018-08-22 20:21:14.833 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-08-22 20:21:14.837 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:14.840 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-08-22 20:21:14.844 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-08-22 20:21:14.848 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-08-22 20:21:14.851 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: Sent Data successfully placed on stack.
2018-08-22 20:21:14.912 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:14.915 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:14.917 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:14.918 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:14.920 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 15 07 60 0D 01 00 25 03 00
2018-08-22 20:21:14.923 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:14.925 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 21: Starting initialisation from DONE
2018-08-22 20:21:14.926 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b6081d already registered
2018-08-22 20:21:14.928 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Incoming command class MULTI_INSTANCE
2018-08-22 20:21:14.929 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Received MULTI_INSTANCE command V2
2018-08-22 20:21:14.931 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Requested Command Class = SWITCH_BINARY (0x25)
2018-08-22 20:21:14.932 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Endpoint = 1, calling handleApplicationCommandRequest.
2018-08-22 20:21:14.934 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 21
2018-08-22 20:21:14.935 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 21: Switch Binary report, value = 0
2018-08-22 20:21:14.937 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-08-22 20:21:14.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-08-22 20:21:14.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got a value event from Z-Wave network, endpoint = 1, command class = SWITCH_BINARY, value = 0
2018-08-22 20:21:14.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Updating channel state zwave:device:c1f45758:node21:switch_binary1 to OFF [OnOffType]
2018-08-22 20:21:14.950 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
2018-08-22 20:21:18.633 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 27 00 01 7C B1
2018-08-22 20:21:18.636 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:18.638 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 27 00 01 7C 00 00 BF
2018-08-22 20:21:18.640 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 27 00 01 7C 00 00 BF
2018-08-22 20:21:18.641 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=27 00 01 7C
2018-08-22 20:21:18.643 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 9: SendData Request. CallBack ID = 39, Status = Transmission complete and ACK received(0)
2018-08-22 20:21:18.644 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 9: Starting initialisation from DONE
2018-08-22 20:21:18.646 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@14fd291 already registered
2018-08-22 20:21:18.648 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=9, callback=39, payload=09 03 25 01 00
2018-08-22 20:21:18.649 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=27 00 01 7C
2018-08-22 20:21:18.651 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=39, expected=SendData, cancelled=false transaction complete!
2018-08-22 20:21:18.653 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-08-22 20:21:18.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-08-22 20:21:18.656 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 9: Response processed after 3831ms/4958ms.
2018-08-22 20:21:18.658 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-08-22 20:21:18.660 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 15 07 60 0D 01 02 25 01 00 25 28 B7
2018-08-22 20:21:18.662 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 21: Sending REQUEST Message = 01 0E 00 13 15 07 60 0D 01 02 25 01 00 25 28 B7
2018-08-22 20:21:18.675 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-08-22 20:21:18.677 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:18.679 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-08-22 20:21:18.680 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-08-22 20:21:18.682 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-08-22 20:21:18.683 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 21: Sent Data successfully placed on stack.
2018-08-22 20:21:20.493 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:20.497 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:20.501 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:20.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0D 00 04 00 15 07 60 0D 01 00 25 03 00 AE
2018-08-22 20:21:20.509 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 15 07 60 0D 01 00 25 03 00
2018-08-22 20:21:20.512 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:20.515 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 21: Starting initialisation from DONE
2018-08-22 20:21:20.518 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1b6081d already registered
2018-08-22 20:21:20.521 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Incoming command class MULTI_INSTANCE
2018-08-22 20:21:20.524 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Received MULTI_INSTANCE command V2
2018-08-22 20:21:20.527 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Requested Command Class = SWITCH_BINARY (0x25)
2018-08-22 20:21:20.529 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 21: Endpoint = 1, calling handleApplicationCommandRequest.
2018-08-22 20:21:20.532 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 21
2018-08-22 20:21:20.535 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 21: Switch Binary report, value = 0
2018-08-22 20:21:20.538 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-08-22 20:21:20.541 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-08-22 20:21:20.543 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Got a value event from Z-Wave network, endpoint = 1, command class = SWITCH_BINARY, value = 0
2018-08-22 20:21:20.548 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 21: Updating channel state zwave:device:c1f45758:node21:switch_binary1 to OFF [OnOffType]
2018-08-22 20:21:20.557 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=21, callback=40, payload=15 07 60 0D 01 02 25 01 00
2018-08-22 20:21:20.561 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 15 07 60 0D 01 00 25 03 00
2018-08-22 20:21:20.566 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=40, expected=SendData, cancelled=false MISMATCH
2018-08-22 20:21:22.526 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 21 03 80 03 2D 7E
2018-08-22 20:21:22.531 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:22.534 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 21 03 80 03 2D 7E
2018-08-22 20:21:22.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 21 03 80 03 2D 7E
2018-08-22 20:21:22.541 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 21 03 80 03 2D
2018-08-22 20:21:22.546 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 21 06 43 03 01 42 08 98 43
2018-08-22 20:21:22.553 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:22.556 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 33: Starting initialisation from DONE
2018-08-22 20:21:22.559 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@10f65d8 already registered
2018-08-22 20:21:22.562 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Incoming command class BATTERY
2018-08-22 20:21:22.566 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 21 04 46 08 00 7F E5
2018-08-22 20:21:22.571 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 33: Received Battery Request
2018-08-22 20:21:22.573 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 33: Battery report value = 45
2018-08-22 20:21:22.576 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-08-22 20:21:22.579 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-08-22 20:21:22.582 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 45
2018-08-22 20:21:22.586 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Updating channel state zwave:device:c1f45758:node33:battery-level to 45 [DecimalType]
2018-08-22 20:21:22.591 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 21 06 31 05 01 42 09 85 2B
2018-08-22 20:21:22.594 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Transaction not completed: node address inconsistent. lastSent=21, incoming=255
2018-08-22 20:21:22.598 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
2018-08-22 20:21:22.603 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 21 06 43 03 01 42 08 98 43
2018-08-22 20:21:22.615 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 21 06 43 03 01 42 08 98 43
2018-08-22 20:21:22.622 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 21 06 43 03 01 42 08 98
2018-08-22 20:21:22.616 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 21 02 84 07 53
2018-08-22 20:21:22.627 [INFO ] [odel.script.batterydevices_bat_level] - r_BattDevs_Upd_LastUpdateItem : Updating all last update items
2018-08-22 20:21:22.628 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:22.631 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 33: Starting initialisation from DONE
2018-08-22 20:21:22.634 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@10f65d8 already registered
2018-08-22 20:21:22.637 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Incoming command class THERMOSTAT_SETPOINT
2018-08-22 20:21:22.639 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 33: Received Thermostat Setpoint Request
2018-08-22 20:21:22.642 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 33: Thermostat Setpoint report Scale = 0
2018-08-22 20:21:22.644 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 33: Thermostat Setpoint Value = 22
2018-08-22 20:21:22.647 [DEBUG] [.ZWaveThermostatSetpointCommandClass] - NODE 33: Thermostat Setpoint Report, Type Heating (1), value = 22
2018-08-22 20:21:22.649 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveThermostatSetpointValueEvent
2018-08-22 20:21:22.652 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got an event from Z-Wave network: ZWaveThermostatSetpointValueEvent
2018-08-22 20:21:22.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got a value event from Z-Wave network, endpoint = 0, command class = THERMOSTAT_SETPOINT, value = 22
2018-08-22 20:21:22.656 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 22C to 22C
2018-08-22 20:21:22.660 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Updating channel state zwave:device:c1f45758:node33:thermostat_setpoint_heating to 22 [DecimalType]
2018-08-22 20:21:22.665 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Transaction not completed: node address inconsistent. lastSent=21, incoming=255
2018-08-22 20:21:22.667 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=2
2018-08-22 20:21:22.669 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 21 04 46 08 00 7F E5
2018-08-22 20:21:22.672 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 21 04 46 08 00 7F E5
2018-08-22 20:21:22.676 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 21 04 46 08 00 7F
2018-08-22 20:21:22.678 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:22.681 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 33: Starting initialisation from DONE
2018-08-22 20:21:22.684 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@10f65d8 already registered
2018-08-22 20:21:22.686 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Incoming command class CLIMATE_CONTROL_SCHEDULE
2018-08-22 20:21:22.689 [DEBUG] [veClimateControlScheduleCommandClass] - NODE 33: Received CLIMATE_CONTROL_SCHEDULE command V1
2018-08-22 20:21:22.691 [INFO ] [veClimateControlScheduleCommandClass] - NODE 33 reported: Override type: NO_OVERRIDE, ScheduleState: [UNUSED]
2018-08-22 20:21:22.693 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Transaction not completed: node address inconsistent. lastSent=21, incoming=255
2018-08-22 20:21:22.695 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
2018-08-22 20:21:22.697 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 21 06 31 05 01 42 09 85 2B
2018-08-22 20:21:22.700 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 21 06 31 05 01 42 09 85 2B
2018-08-22 20:21:22.702 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 21 06 31 05 01 42 09 85
2018-08-22 20:21:22.704 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:22.706 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 33: Starting initialisation from DONE
2018-08-22 20:21:22.709 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@10f65d8 already registered
2018-08-22 20:21:22.712 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Incoming command class SENSOR_MULTILEVEL
2018-08-22 20:21:22.714 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 33: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V6
2018-08-22 20:21:22.716 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 33: Sensor Multi Level REPORT received
2018-08-22 20:21:22.721 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 33: Sensor Type = Temperature(1), Scale = 0
2018-08-22 20:21:22.725 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 33: Sensor Value = 24.37
2018-08-22 20:21:22.728 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2018-08-22 20:21:22.731 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2018-08-22 20:21:22.734 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 24.37
2018-08-22 20:21:22.738 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 33: Sensor is reporting scale 0, requiring conversion to 0. Value is now 24.37.
2018-08-22 20:21:22.741 [DEBUG] [converter.ZWaveCommandClassConverter] - Converted temperature from 24.37C to 24.37C
2018-08-22 20:21:22.744 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 33: Updating channel state zwave:device:c1f45758:node33:sensor_temperature to 24.37 [DecimalType]
2018-08-22 20:21:22.749 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 21: Transaction not completed: node address inconsistent. lastSent=21, incoming=255
2018-08-22 20:21:22.754 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-22 20:21:22.757 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 21 02 84 07 53
2018-08-22 20:21:22.759 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 21 02 84 07 53
2018-08-22 20:21:22.762 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 21 02 84 07
2018-08-22 20:21:22.764 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 33: Application Command Request (ALIVE:DONE)
2018-08-22 20:21:22.766 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 33: Starting initialisation from DONE
2018-08-22 20:21:22.769 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@10f65d8 already registered