Z-Wave Sub-parameter testing

Continuing the discussion from Testing Z-Wave binding on openHAB-2:

@shorty707 I’m starting a new thread on this just to avoid confusion in the main ZWave test thread…

I’ve now configured the siren to use sub-parameters -:

The original parameter 37 I made an advanced parameter so will only be shown if advanced mode is enabled. In theory, you should now be able to control the sound and the volume separately without having to deal with bitshifts, hex numbers and other fun conversions that can confuse the best of us…

It would be great if you can try this with the latest ZWave JAR I just loaded, and provide the same log you did earlier…

1 Like

ok the menu to set values is a lot easier :wink:

however trying to set new sound / volume there ends in continuesly alarm sounding from the siren until I shut down openhab completely

just pasting the end of the log … seems like queue loop?

15:34:00.712 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
15:34:00.713 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
15:34:00.714 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
15:34:00.715 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 515, size = 2
15:34:00.716 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
15:34:00.717 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
15:34:00.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@101af88
15:34:00.719 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 515
15:34:00.720 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating sub-parameter 37 to 515
15:34:00.721 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting parameter 37 to 515
15:34:00.721 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_SET
15:34:00.723 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 35
15:34:00.724 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
15:34:00.725 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 36
15:34:00.788 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 69 00 00 30 B2 
15:34:00.797 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:00.798 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 69 00 00 30 00 00 BC 
15:34:00.800 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 69 00 00 30 00 00 BC 
15:34:00.801 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 69 00 00 30 
15:34:00.802 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 105, Status = Transmission complete and ACK received(0)
15:34:00.803 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
15:34:00.804 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 69 00 00 30 
15:34:00.805 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
15:34:05.306 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 2 attempts left!
15:34:05.307 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
15:34:05.307 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 37
15:34:05.308 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 36
15:34:05.309 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 91 03 
15:34:05.312 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 91 03 
15:34:05.325 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
15:34:05.331 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:05.332 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
15:34:05.333 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
15:34:05.334 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
15:34:05.334 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
15:34:05.446 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 03 00 00 AF 
15:34:05.454 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:05.455 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 03 00 00 AF 
15:34:05.456 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 03 00 00 AF 
15:34:05.457 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 03 00 00 
15:34:05.457 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
15:34:05.458 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
15:34:05.458 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
15:34:05.458 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 515, size = 2
15:34:05.459 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
15:34:05.461 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
15:34:05.463 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@101af88
15:34:05.465 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 515
15:34:05.467 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating sub-parameter 37 to 515
15:34:05.468 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting parameter 37 to 515
15:34:05.469 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_SET
15:34:05.470 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 37
15:34:05.471 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
15:34:05.472 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 38
15:34:10.313 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
15:34:10.314 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Sending ABORT Message = 01 03 00 16 EA 
15:34:10.314 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
15:34:10.315 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
15:34:10.316 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 1 attempts left!
15:34:10.316 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
15:34:10.317 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 39
15:34:10.318 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 38
15:34:10.318 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 94 06 
15:34:10.327 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 94 06 
15:34:10.348 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
15:34:10.352 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:10.353 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
15:34:10.354 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
15:34:10.355 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
15:34:10.383 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
15:34:10.579 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 94 01 00 17 69 
15:34:10.583 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:10.584 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 94 01 00 17 00 00 67 
15:34:10.584 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 94 01 00 17 00 00 67 
15:34:10.585 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 94 01 00 17 
15:34:10.585 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 148, Status = Transmission complete, no ACK received(1)
15:34:10.586 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
15:34:10.587 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 39
15:34:10.587 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
15:34:10.587 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
15:34:10.588 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 258ms/3133ms.
15:34:10.588 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 38
15:34:10.589 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 95 07 
15:34:10.590 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 95 07 
15:34:10.608 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
15:34:10.612 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:10.613 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
15:34:10.613 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
15:34:10.614 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
15:34:10.614 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
15:34:12.135 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 95 00 00 99 E7 
15:34:12.138 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:12.139 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 95 00 00 99 00 00 E9 
15:34:12.139 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 95 00 00 99 00 00 E9 
15:34:12.140 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 95 00 00 99 
15:34:12.140 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 149, Status = Transmission complete and ACK received(0)
15:34:12.141 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
15:34:12.142 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 95 00 00 99 
15:34:12.142 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
15:34:15.490 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 95 01 01 E9 97 
15:34:15.493 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:15.494 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 95 01 01 E9 00 00 99 
15:34:15.495 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 95 01 01 E9 00 00 99 
15:34:15.495 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 95 01 01 E9 
15:34:15.496 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 149, Status = Transmission complete, no ACK received(1)
15:34:15.497 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
15:34:15.498 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 39
15:34:15.498 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
15:34:15.499 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
15:34:15.499 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 4908ms/4908ms.
15:34:15.500 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 38
15:34:15.501 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 96 04 
15:34:15.502 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 96 04 
15:34:15.521 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
15:34:15.524 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
15:34:15.525 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
15:34:15.525 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
15:34:15.526 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
15:34:15.526 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.

openhab>
`

Yes - I was removing the wrong thing from the queue to it was continuing to do the SET/GET…

I’ve updated the binding - hopefully this will cut down the noise :slightly_smiling:

loop is gone

but still when setting a new sound/volume it starts the alarm … a click on the physical button on the device itself stops the sound

and the values are gone afterwards in habmin and both drop down list show a blank value?

16:17:55.937 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
16:17:55.937 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
16:17:55.937 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
16:17:55.938 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 515, size = 2
16:17:55.938 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
16:17:55.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
16:17:55.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@90d3dc
16:17:55.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 515
16:17:55.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating sub-parameter 37 to 514
16:17:55.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting parameter 37 to 514
16:17:55.941 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_SET
16:17:55.942 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
16:17:55.942 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
16:17:55.942 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 2
16:17:55.943 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
16:17:55.944 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 03 00 00 
16:17:55.944 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
16:17:55.944 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
16:17:55.945 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
16:17:55.945 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
16:17:55.945 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 184ms/4863ms.
16:17:55.946 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
16:17:55.947 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 02 06 70 04 25 02 02 02 25 4D DE 
16:17:55.948 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0D 00 13 02 06 70 04 25 02 02 02 25 4D DE 
16:17:55.960 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
16:17:55.962 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
16:17:55.963 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
16:17:55.963 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
16:17:55.964 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
16:17:55.965 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
16:17:56.074 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4D 00 00 0C AA 
16:17:56.077 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
16:17:56.078 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 4D 00 00 0C 00 00 A4 
16:17:56.078 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 4D 00 00 0C 00 00 A4 
16:17:56.079 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 4D 00 00 0C 
16:17:56.080 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 77, Status = Transmission complete and ACK received(0)
16:17:56.080 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 06 70 04 25 02 02 02 
16:17:56.081 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4D 00 00 0C 
16:17:56.081 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
16:17:56.082 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
16:17:56.082 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
16:17:56.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
16:17:56.083 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 135ms/4863ms.
16:17:56.084 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
16:17:56.085 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 4E DC 
16:17:56.086 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 4E DC 
16:17:56.099 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
16:17:56.101 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
16:17:56.101 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
16:17:56.102 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
16:17:56.102 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
16:17:56.103 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
16:17:56.173 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4E 00 00 08 AD 
16:17:56.175 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
16:17:56.176 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 4E 00 00 08 00 00 A3 
16:17:56.176 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 4E 00 00 08 00 00 A3 
16:17:56.177 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 4E 00 00 08 
16:17:56.178 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 78, Status = Transmission complete and ACK received(0)
16:17:56.178 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
16:17:56.179 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 4E 00 00 08 
16:17:56.179 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
16:17:56.253 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
16:17:56.255 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
16:17:56.257 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
16:17:56.258 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
16:17:56.258 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
16:17:56.259 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
16:17:56.259 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
16:17:56.260 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
16:17:56.260 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
16:17:56.260 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
16:17:56.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
16:17:56.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@90d3dc
16:17:56.261 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
16:17:56.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000202
16:17:56.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000202
16:17:56.447 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
16:17:56.448 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
16:17:56.453 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
16:17:56.454 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
16:17:56.455 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
16:17:56.456 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
16:17:56.459 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 373ms/4863ms.
essage = 01 0A 00 13 02 03 70 05 25 25 88 1A

Generally this looks like it (nearly) worked ok…

I don’t see anything wrong, so I suspect that the device might do this when you change these settings? That’s a guess though - I couldn’t see this in the docs, but I don’t see anything wrong with the settings ether…

It looks like the bit at the end didn’t work - when it receives the update from the device, it should process all 3 parameters - the ‘real’ parameter 37, plus the two sub-parameters and it seems that isn’t quite right…

I’ll take a look at the code and get back to you… Thanks :slightly_smiling:.

I’ve updated the binding - it will work exactly the same as previously, just with a lot more debug at the end…

Also, I found this on the web -:

ah … wow thats odd…
the manual says that it can be used to sound different alarms for different events…

could that mean they designed it to make the alarm sound by selecting sound / volume? based in qualifications?

updated log

17:11:12.835 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
17:11:12.836 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
17:11:12.836 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
17:11:12.836 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
17:11:12.837 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
17:11:12.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
17:11:12.837 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@14c502d
17:11:12.838 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
17:11:12.838 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
17:11:12.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
17:11:12.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
17:11:12.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
17:11:12.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = 
17:11:12.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
17:11:12.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
17:11:12.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = 
17:11:12.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = 
17:11:12.841 [ERROR] [ve.internal.protocol.ZWaveController] - Exception during ZWave thread: Input. 3
17:11:17.767 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 0 attempts left!
17:11:17.768 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
17:11:17.769 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
17:11:17.769 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
17:11:17.770 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 32 A0 
17:11:17.771 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 32 A0 
17:11:17.780 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
17:11:17.782 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:11:17.783 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
17:11:17.783 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
17:11:17.784 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
17:11:17.784 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
17:11:17.802 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 32 00 00 03 DA 
17:11:17.808 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:11:17.809 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 32 00 00 03 00 00 D4 
17:11:17.809 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 32 00 00 03 00 00 D4 
17:11:17.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 32 00 00 03 
17:11:17.810 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 50, Status = Transmission complete and ACK received(0)
17:11:17.811 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
17:11:17.812 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 32 00 00 03 
17:11:17.812 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
17:11:17.830 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
17:11:17.834 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:11:17.835 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
17:11:17.836 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
17:11:17.836 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
17:11:17.837 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
17:11:17.837 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
17:11:17.837 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
17:11:17.838 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
17:11:17.838 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
17:11:17.838 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
17:11:17.838 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@14c502d
17:11:17.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
17:11:17.839 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
17:11:17.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
17:11:17.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
17:11:17.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
17:11:17.840 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = 
17:11:17.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
17:11:17.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
17:11:17.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = 
17:11:17.841 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = 
17:11:17.842 [ERROR] [ve.internal.protocol.ZWaveController] - Exception during ZWave thread: Input. 3

openhab>

I’ve no idea - I have however seen @ccheng-aeotec on this forum, so he might be able to comment…

I’ll take a look at the log… Thanks.

I’ve upload another version - again, just with debug changes… (sorry for the slow debug cycle - I’m hopeful that once we get this running well, which should be soon, it will really help the Aeon devices especially since they make extensive use of this).

Cheers
Chris

@bhuism this latest version also has the updated version numbers for the Greenwave device.

@ccheng-aeotec an statement from aeotec would be very helpful on this :

@chris latest log:

17:45:35.082 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
17:45:35.082 [DEBUG] [nitialization.ZWaveNodeStageAdvancer] - NODE 2: Node advancer - DETAILS: Transaction complete (SendData:Request) success(true)
17:45:35.083 [DEBUG] [nitialization.ZWaveNodeStageAdvancer] - NODE 2: Node advancer - checking initialisation queue. Queue size 0.
17:45:35.083 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
17:45:35.084 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
17:45:35.085 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
17:45:35.085 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
17:45:35.086 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DETAILS)
17:45:35.086 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
17:45:35.086 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
17:45:35.087 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
17:45:35.087 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
17:45:35.087 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
17:45:35.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@1f937df
17:45:35.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
17:45:35.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
17:45:35.109 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
17:45:35.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
17:45:35.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
17:45:35.110 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = '252'
17:45:35.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
17:45:35.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
17:45:35.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = '37'
17:45:35.111 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = '2'
17:45:35.112 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000202
17:45:35.112 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000202>>FFFFFFFF
17:45:35.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000202>>FFFFFFFF
17:45:35.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000202 [514]
17:45:35.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1
17:45:35.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 len=3
17:45:35.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 - id = '80'
17:45:35.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing group_1
17:45:35.114 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing nodeid
17:45:35.333 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config updated
17:45:35.334 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
17:45:35.335 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
17:45:35.335 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
17:45:35.336 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
17:45:35.336 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
17:45:35.337 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
17:45:35.337 [DEBUG] [nitialization.ZWaveNodeStageAdvancer] - NODE 2: Node advancer - DETAILS: Transaction complete (SendData:Request) success(true)
17:45:35.337 [DEBUG] [nitialization.ZWaveNodeStageAdvancer] - NODE 2: Node advancer - checking initialisation queue. Queue size 0.

openhab>

Thanks - can you have a look a little earlier in the log to find an entry -:

NODE 2: Configuration update received

I don’t see it trying to process the sub-parameters here - only the main parameter. I also expect to see it processing config_37_2_00000F00 and config_37_2_0000000F :confused:.

there was another update on the binding. grabbed that. waited until node was online and fired.
thats the complete log after it was online: (part 1)

18:38:23.930 [DEBUG] [nitialization.ZWaveNodeStageAdvancer] - NODE 2: Node advancer: Initialisation complete!
18:38:23.931 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveInitializationStateEvent
18:38:23.931 [DEBUG] [g.zwave.internal.ZWaveNetworkMonitor] - NODE 2: Network initialised - starting network monitor.
18:38:23.931 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveInitializationStateEvent
18:38:23.932 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting ONLINE
18:38:23.956 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 375ms/4996ms.
18:38:23.956 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Controller initialised. Starting device intialisation.
18:38:49.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update received
18:38:49.910 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update config_37_2_0000000F to 4
18:38:49.914 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE2: Set sub-parameter 37 from 15 / 00000004
18:38:49.916 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
18:38:49.929 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
18:38:49.930 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 2A B8 
18:38:49.930 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 2A B8 
18:38:49.931 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 0
18:38:49.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update config_37_2_00000F00 to 1
18:38:49.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE2: Set sub-parameter 37 from 3840 / 00000001
18:38:49.940 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:38:49.942 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:49.942 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:38:49.943 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:38:49.943 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:38:49.944 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:38:50.502 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2A 00 00 39 F8 
18:38:50.503 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:50.504 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2A 00 00 39 00 00 F6 
18:38:50.505 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2A 00 00 39 00 00 F6 
18:38:50.506 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2A 00 00 39 
18:38:50.506 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 42, Status = Transmission complete and ACK received(0)
18:38:50.508 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:38:50.508 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 2A 00 00 39 
18:38:50.509 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
18:38:50.586 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:50.588 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:50.589 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:50.590 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:50.591 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:38:50.592 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
18:38:50.592 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
18:38:50.594 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
18:38:50.595 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
18:38:50.596 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
18:38:50.598 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
18:38:50.600 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@1952899
18:38:50.610 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
18:38:50.620 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating sub-parameter 37 to 516
18:38:50.621 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting parameter 37 to 516
18:38:50.622 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_SET
18:38:50.623 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
18:38:50.629 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
18:38:50.630 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 2
18:38:50.631 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:38:50.633 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:38:50.633 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
18:38:50.634 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
18:38:50.634 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:38:50.634 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:38:50.635 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 704ms/4996ms.
18:38:50.635 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
18:38:50.636 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 02 06 70 04 25 02 02 04 25 2B BE 
18:38:50.637 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0D 00 13 02 06 70 04 25 02 02 04 25 2B BE 
18:38:50.650 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:38:50.651 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:50.652 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:38:50.653 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:38:50.653 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:38:50.654 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:38:51.115 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2B 00 00 2F EF 
18:38:51.117 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:51.118 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2B 00 00 2F 00 00 E1 
18:38:51.118 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2B 00 00 2F 00 00 E1 
18:38:51.119 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2B 00 00 2F 
18:38:51.120 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 43, Status = Transmission complete and ACK received(0)
18:38:51.121 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 06 70 04 25 02 02 04 
18:38:51.122 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 2B 00 00 2F 
18:38:51.123 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
18:38:51.124 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
18:38:51.124 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:38:51.124 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:38:51.125 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 487ms/4996ms.
18:38:51.126 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
18:38:51.126 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 2C BE 
18:38:51.127 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 2C BE 
18:38:51.140 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:38:51.142 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:51.143 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:38:51.143 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:38:51.144 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:38:51.145 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:38:51.285 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 10 02 08 70 06 25 02 02 02 00 00 BE 
18:38:51.287 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:51.288 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 10 02 08 70 06 25 02 02 02 00 00 BE 
18:38:51.289 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 10 02 08 70 06 25 02 02 02 00 00 BE 
18:38:51.290 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 10 02 08 70 06 25 02 02 02 00 00 
18:38:51.291 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
18:38:51.292 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
18:38:51.292 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
18:38:51.293 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
18:38:51.294 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
18:38:51.295 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
18:38:51.295 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@1952899
18:38:51.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
18:38:51.296 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
18:38:51.297 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
18:38:51.309 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
18:38:51.310 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
18:38:51.310 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = '252'
18:38:51.312 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
18:38:51.313 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
18:38:51.313 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = '37'
18:38:51.314 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = '2'
18:38:51.314 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000202
18:38:51.319 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000202>>FFFFFFFF
18:38:51.320 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000202>>FFFFFFFF
18:38:51.321 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000202 [514]
18:38:51.321 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1
18:38:51.322 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 len=3
18:38:51.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 - id = '80'
18:38:51.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing group_1
18:38:51.370 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing nodeid
18:38:51.621 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config updated
18:38:56.129 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
18:38:56.130 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Sending ABORT Message = 01 03 00 16 EA 
18:38:56.130 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
18:38:56.131 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
18:38:56.132 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 2 attempts left!
18:38:56.132 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
18:38:56.133 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
18:38:56.133 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
18:38:56.135 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 2D BF 
18:38:56.136 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 2D BF 
18:38:56.146 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:38:56.148 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:56.149 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:38:56.150 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:38:56.150 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:38:56.151 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:38:56.327 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2D 01 00 13 D4 
18:38:56.328 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:56.329 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2D 01 00 13 00 00 DA 
18:38:56.330 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2D 01 00 13 00 00 DA 
18:38:56.331 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2D 01 00 13 
18:38:56.332 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 45, Status = Transmission complete, no ACK received(1)
18:38:56.332 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
18:38:56.333 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
18:38:56.333 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:38:56.333 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:38:56.334 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 197ms/4996ms.
18:38:56.335 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
18:38:56.336 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 2E BC 
18:38:56.337 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 2E BC 
18:38:56.347 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:38:56.348 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:56.349 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:38:56.350 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:38:56.351 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:38:56.351 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:38:56.451 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2E 00 00 0B CE 
18:38:56.459 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:56.460 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2E 00 00 0B 00 00 C0 
18:38:56.461 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2E 00 00 0B 00 00 C0 
18:38:56.462 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2E 00 00 0B 
18:38:56.463 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 46, Status = Transmission complete and ACK received(0)
18:38:56.463 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:38:56.464 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 2E 00 00 0B 
18:38:56.465 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
18:38:56.613 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:56.615 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:38:56.617 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:56.618 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:38:56.619 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:38:56.620 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
18:38:56.620 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
18:38:56.621 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
18:38:56.621 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
18:38:56.621 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
18:38:56.622 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
18:38:56.622 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@1952899
18:38:56.623 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
18:38:56.623 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
18:38:56.624 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
18:38:56.624 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
18:38:56.624 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
18:38:56.625 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = '252'
18:38:56.625 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
18:38:56.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
18:38:56.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = '37'
18:38:56.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = '2'
18:38:56.627 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000202
18:38:56.627 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000202>>FFFFFFFF
18:38:56.628 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000202>>FFFFFFFF
18:38:56.628 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000202 [514]
18:38:56.628 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1
18:38:56.629 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 len=3
18:38:56.649 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 - id = '80'
18:38:56.649 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing group_1
18:38:56.650 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing nodeid
18:38:56.916 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config updated
18:38:56.917 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:38:56.918 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:38:56.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
18:38:56.939 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
18:38:56.940 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:38:56.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:38:56.940 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 603ms/4996ms.
18:39:00.760 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2E 01 01 BA 7F 
18:39:00.764 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:39:00.765 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2E 01 01 BA 00 00 71 
18:39:00.766 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2E 01 01 BA 00 00 71 
18:39:00.767 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2E 01 01 BA 
18:39:00.767 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 46, Status = Transmission complete, no ACK received(1)
18:39:00.768 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
18:39:00.769 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
18:39:00.770 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 2F BD 
18:39:00.771 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 2F BD 
18:39:00.772 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 0
18:39:00.772 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:39:00.775 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
18:39:00.776 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 5ms/4996ms.
18:39:00.785 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
18:39:00.788 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:39:00.789 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
18:39:00.791 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
18:39:00.793 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
18:39:00.794 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
18:39:00.879 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2F 00 00 0B CF 
18:39:00.883 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:39:00.884 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2F 00 00 0B 00 00 C1 
18:39:00.885 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2F 00 00 0B 00 00 C1 
18:39:00.886 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 2F 00 00 0B 
18:39:00.887 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 47, Status = Transmission complete and ACK received(0)
18:39:00.888 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:39:00.888 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 2F 00 00 0B 
18:39:00.888 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
18:39:00.907 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:39:00.913 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
18:39:00.914 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:39:00.916 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
18:39:00.918 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:39:00.929 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)

part2

18:39:00.931 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
18:39:00.931 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
18:39:00.932 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
18:39:00.933 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
18:39:00.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
18:39:00.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@1952899
18:39:00.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
18:39:00.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config about to update...
18:39:00.936 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing action_reinit
18:39:00.936 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1
18:39:00.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 len=3
18:39:00.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_252_1 - id = '252'
18:39:00.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
18:39:00.938 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
18:39:00.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = '37'
18:39:00.939 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = '2'
18:39:00.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000202
18:39:00.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000202>>FFFFFFFF
18:39:00.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000202>>FFFFFFFF
18:39:00.941 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000202 [514]
18:39:00.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1
18:39:00.942 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 len=3
18:39:00.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 - id = '80'
18:39:00.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing group_1
18:39:00.943 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing nodeid
18:39:01.278 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE {}: Config updated
18:39:01.309 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
18:39:01.310 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
18:39:01.311 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
18:39:01.311 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
18:39:01.312 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
18:39:01.312 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

openhab>

Thanks @shorty707. I’ve fixed a couple of small bugs (and one not so small bug) and have put a new version on GH if you have some time for another test at some stage… I’m still confused as to why I don’t see the parameters when I loop through the config after the data is received - I’m wondering if something has not updated after the database was updated (ie if something is being cached in OH2/ESH).

Ideally, if you have a few minutes, can you do two tests - exactly as you did earlier - firstly with the new binding and the existing config, then delete the thing, add it back in, and rerun. I just want to see if anything changes.

(thanks again :slightly_smiling:)

Test 1: simply replaced binding (please note this also reinitializes all devices… I think)

–> logging is super short; tried multiple times; always same result;
siren stays also silent during this …

19:54:47.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update received
19:54:47.688 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update config_37_2_00000F00 to 1
19:54:47.696 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Set sub-parameter 37 from 1 / 00000F00
19:54:47.710 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Parameter 37 set value 00000001 mask 00000F00

Test 2: removed thing and readded…

  • this time the values from sound and volume were still in the thing … and not blank as before

  • running the command for sound and volume now triggered again the alarm to sound

      19:58:58.081 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 2: Including node.
      19:58:58.081 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 2: Newly included node already exists - not initialising.
      19:58:58.082 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = 05 
      19:58:58.082 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = AddNodeToNetwork (0x4A), type = Request (0x00), payload = FF 06 02 00 
      19:58:58.083 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=AddNodeToNetwork, expected=AddNodeToNetwork, cancelled=false
      19:58:58.083 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
      19:58:58.083 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
      19:58:58.084 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Response processed after 167ms/925ms.
      19:59:08.259 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update received
      19:59:08.266 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update config_37_2_0000000F to 3
      19:59:08.268 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Set sub-parameter 37 from 3 / 0000000F
      19:59:08.271 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Parameter 37 set value 00000003 mask 0000000F
      19:59:08.272 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
      19:59:08.280 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
      19:59:08.281 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 26 B4 
      19:59:08.282 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 26 B4 
      19:59:08.282 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 0
      19:59:08.294 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
      19:59:08.297 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.298 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
      19:59:08.298 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
      19:59:08.299 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
      19:59:08.299 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
      19:59:08.303 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Configuration update config_37_2_00000F00 to 1
      19:59:08.304 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Set sub-parameter 37 from 1 / 00000F00
      19:59:08.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Parameter 37 set value 00000001 mask 00000F00
      19:59:08.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 26 00 00 02 CF 
      19:59:08.318 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.319 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 26 00 00 02 00 00 C1 
      19:59:08.320 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 26 00 00 02 00 00 C1 
      19:59:08.321 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 26 00 00 02 
      19:59:08.321 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 38, Status = Transmission complete and ACK received(0)
      19:59:08.322 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
      19:59:08.322 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 26 00 00 02 
      19:59:08.323 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
      19:59:08.406 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
      19:59:08.413 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.414 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
      19:59:08.415 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 02 02 00 00 AE 
      19:59:08.416 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
      19:59:08.417 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
      19:59:08.417 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
      19:59:08.417 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
      19:59:08.418 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 514, size = 2
      19:59:08.419 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
      19:59:08.421 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
      19:59:08.424 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@dcc5e1
      19:59:08.426 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 514
      19:59:08.427 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating sub-parameter 37 to 259
      19:59:08.428 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Setting parameter 37 to 259
      19:59:08.436 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_SET
      19:59:08.437 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
      19:59:08.438 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Creating new message for application command CONFIGURATIONCMD_GET
      19:59:08.444 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 2
      19:59:08.446 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
      19:59:08.448 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 02 02 00 00 
      19:59:08.450 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, expected=ApplicationCommandHandler, cancelled=false
      19:59:08.452 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
      19:59:08.452 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
      19:59:08.455 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
      19:59:08.469 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 187ms/925ms.
      19:59:08.470 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
      19:59:08.471 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 02 06 70 04 25 02 01 03 25 27 B6 
      19:59:08.472 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0D 00 13 02 06 70 04 25 02 01 03 25 27 B6 
      19:59:08.482 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
      19:59:08.489 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.491 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
      19:59:08.492 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
      19:59:08.498 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 27 00 00 02 CE 
      19:59:08.506 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
      19:59:08.507 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
      19:59:08.508 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.509 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 27 00 00 02 00 00 C0 
      19:59:08.510 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 27 00 00 02 00 00 C0 
      19:59:08.511 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 27 00 00 02 
      19:59:08.513 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 39, Status = Transmission complete and ACK received(0)
      19:59:08.520 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 06 70 04 25 02 01 03 
      19:59:08.521 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 27 00 00 02 
      19:59:08.522 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=SendData, cancelled=false
      19:59:08.522 [DEBUG] [.serialmessage.ZWaveCommandProcessor] -          transaction complete!
      19:59:08.523 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
      19:59:08.524 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
      19:59:08.524 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 52ms/925ms.
      19:59:08.525 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
      19:59:08.526 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 28 BA 
      19:59:08.526 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 28 BA 
      19:59:08.538 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
      19:59:08.547 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.548 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
      19:59:08.549 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
      19:59:08.553 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 28 00 00 02 C1 
      19:59:08.564 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Response (0x01), payload = 01 
      19:59:08.579 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 01 03 00 00 AC 
      19:59:08.586 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
      19:59:08.588 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
      19:59:08.589 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 28 00 00 02 00 00 CF 
      19:59:08.590 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 28 00 00 02 00 00 CF 
      19:59:08.591 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = SendData (0x13), type = Request (0x00), payload = 28 00 00 02 
      19:59:08.599 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 40, Status = Transmission complete and ACK received(0)
      19:59:08.600 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 02 03 70 05 25 
      19:59:08.601 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Recv message Message: class = SendData (0x13), type = Request (0x00), payload = 28 00 00 02 
      19:59:08.602 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, expected=ApplicationCommandHandler, cancelled=false
      19:59:08.609 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
      19:59:08.611 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 04 00 02 08 70 06 25 02 01 03 00 00 AC 
      19:59:08.612 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0E 00 04 00 02 08 70 06 25 02 01 03 00 00 AC 
      19:59:08.614 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 00 02 08 70 06 25 02 01 03 00 00 
      19:59:08.615 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
      19:59:08.620 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class CONFIGURATION
      19:59:08.621 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Received Configuration Request
      19:59:08.629 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 2: Node configuration report, parameter = 37, value = 259, size = 2
      19:59:08.630 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveConfigurationParameterEvent
      19:59:08.630 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
      19:59:08.631 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = CONFIGURATION, value = org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@dcc5e1
      19:59:08.649 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Update CONFIGURATION 37/2 to 259
      19:59:08.650 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Config about to update 5 parameters...
      19:59:08.651 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2
      19:59:08.652 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 len=3
      19:59:08.653 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - id = '37'
      19:59:08.653 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2 - size = '2'
      19:59:08.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2 is 00000103
      19:59:08.655 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000103>>FFFFFFFF
      19:59:08.656 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000103>>FFFFFFFF
      19:59:08.656 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2 is 00000103 [259]
      19:59:08.669 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1
      19:59:08.670 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 len=3
      19:59:08.670 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_80_1 - id = '80'
      19:59:08.671 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F
      19:59:08.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F len=4
      19:59:08.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F - id = '37'
      19:59:08.673 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F - size = '2'
      19:59:08.673 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F - bitmask = '0000000F'
      19:59:08.674 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_0000000F - dec = '15'
      19:59:08.675 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2_0000000F is 00000003
      19:59:08.675 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000003>>0000000F
      19:59:08.676 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Post-processing 00000003>>0000000F
      19:59:08.677 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter setting config_37_2_0000000F is 00000003 [3]
      19:59:08.677 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00
      19:59:08.678 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00 len=4
      19:59:08.678 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00 - id = '37'
      19:59:08.679 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00 - size = '2'
      19:59:08.680 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00 - bitmask = '00000F00'
      19:59:08.689 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Processing config_37_2_00000F00 - dec = '3840'
      19:59:08.690 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Sub-parameter config_37_2_00000F00 is 00000100
      19:59:08.690 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Pre-processing  00000100>>00000F00
      19:59:13.191 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 49 84 02 12 04 10 05 5E 25 70 85 59 72 2B 2C 86 7A 73 98 EF 5A 82 A9 
      19:59:13.527 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 2 attempts left!
      19:59:13.528 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.
      19:59:13.577 [DEBUG] [ve.internal.protocol.ZWaveController] - Enqueueing message. Queue length = 1
      19:59:18.578 [WARN ] [ocol.ZWaveController$ZWaveSendThread] - Receive queue TIMEOUT:
      19:59:18.579 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
      19:59:18.583 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 70 05 25 25 29 BB 
      19:59:18.584 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 70 05 25 25 29 BB 
      19:59:18.594 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
      19:59:18.612 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 29 00 00 02 C0 
      19:59:18.638 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 02 08 70 06 25 02 01 03 00 00 AC

Since the last build of the binding the sirene goes offline in habmin2 constantly?

Mhh

Sorry - I’ve just pushed an update that might fix the problem - there was a hotloop part way through processing the sub-parameters…

yup … its back online

more logs? :grinning:

Why not :wink:. I think it’s nearly there - it would be good if you could set both sound and volume at the same time…

I’ll try and make this the last one or your neighbours will be getting annoyed (you can blame me :slightly_smiling:).