Hi Bruce,
Here a full DEBUG log after changing Relay switch from OFF to ON with same result that the relay HW does switch ON but item only stays ON for 2 secs and then goes back to OFF
Part 1
2020-08-07 11:37:14.886 [ome.event.ItemCommandEvent] - Item 'CarSmartMeterSwitchRelay' received command ON
2020-08-07 11:37:14.929 [nt.ItemStatePredictedEvent] - CarSmartMeterSwitchRelay predicted to become ON
2020-08-07 11:37:14.956 [vent.ItemStateChangedEvent] - CarSmartMeterSwitchRelay changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2020-08-07 11:37:14.986 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Command received zwave:device:4b3d1634:node18:switch_binary2 --> ON [OnOffType]
2020-08-07 11:37:14.990 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 18: Creating new message for application command SWITCH_BINARY_SET
2020-08-07 11:37:14.993 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Encapsulating message, instance / endpoint 2
2020-08-07 11:37:14.996 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 18: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2020-08-07 11:37:14.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:15.002 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-08-07 11:37:15.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Adding to device queue
2020-08-07 11:37:15.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Added 472 to queue - size 2
2020-08-07 11:37:15.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:15.013 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0E 00 13 12 07 60 0D 01 02 25 01 FF 25 BC DB
2020-08-07 11:37:15.016 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 18: Sending REQUEST Message = 01 0E 00 13 12 07 60 0D 01 02 25 01 FF 25 BC DB
2020-08-07 11:37:15.018 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-08-07 11:37:15.020 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-08-07 11:37:15.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 472: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:15.023 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling initialised at 86400 seconds - start in 1500 milliseconds.
2020-08-07 11:37:15.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:15.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 472: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.028 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-08-07 11:37:15.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-08-07 11:37:15.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:15.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:15.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:15.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:15.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 472: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:15.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 472: [WAIT_RESPONSE] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.043 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:15.045 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: sentData successfully placed on stack.
2020-08-07 11:37:15.047 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 472: Advanced to WAIT_REQUEST
2020-08-07 11:37:15.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 472: Transaction not completed
2020-08-07 11:37:15.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:15.053 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:15.186 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BC 00 00 10 47
2020-08-07 11:37:15.189 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 10
2020-08-07 11:37:15.191 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 10
2020-08-07 11:37:15.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 472: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:15.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 472: [WAIT_REQUEST] priority=Set, requiresResponse=true, callback: 188
2020-08-07 11:37:15.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 472: (Callback 188)
2020-08-07 11:37:15.200 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-08-07 11:37:15.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 472: callback 188
2020-08-07 11:37:15.204 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 10
2020-08-07 11:37:15.206 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: SendData Request. CallBack ID = 188, Status = Transmission complete and ACK received(0)
2020-08-07 11:37:15.208 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:15.210 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 472: Transaction COMPLETED
2020-08-07 11:37:15.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Response processed after 191ms
2020-08-07 11:37:15.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 472: Transaction completed
2020-08-07 11:37:15.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: notifyTransactionResponse TID:472 DONE
2020-08-07 11:37:15.218 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-08-07 11:37:15.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:15.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:16.523 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling...
2020-08-07 11:37:16.526 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling zwave:device:4b3d1634:node18:meter_watts
2020-08-07 11:37:16.528 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.531 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.533 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Creating new message for application command METER_GET
2020-08-07 11:37:16.536 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:16.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Command Class COMMAND_CLASS_METER is NOT required to be secured
2020-08-07 11:37:16.543 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling zwave:device:4b3d1634:node18:meter_kwh
2020-08-07 11:37:16.546 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.550 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.553 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Creating new message for application command METER_GET
2020-08-07 11:37:16.557 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:16.561 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Command Class COMMAND_CLASS_METER is NOT required to be secured
2020-08-07 11:37:16.564 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling zwave:device:4b3d1634:node18:meter_current
2020-08-07 11:37:16.567 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.570 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 18: Generating poll message for COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.573 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Creating new message for application command METER_GET
2020-08-07 11:37:16.576 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:16.580 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Command Class COMMAND_CLASS_METER is NOT required to be secured
2020-08-07 11:37:16.584 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling zwave:device:4b3d1634:node18:switch_binary2
2020-08-07 11:37:16.587 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 18: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 2
2020-08-07 11:37:16.589 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 18: Creating new message for application command SWITCH_BINARY_GET
2020-08-07 11:37:16.592 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Encapsulating message, instance / endpoint 2
2020-08-07 11:37:16.595 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 18: Creating new message for command MULTI_CHANNEL_ENCAP endpoint 2
2020-08-07 11:37:16.598 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:16.600 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Command Class COMMAND_CLASS_MULTI_CHANNEL is NOT required to be secured
2020-08-07 11:37:16.603 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Polling skipped for zwave:device:4b3d1634:node18:switch_binary2 on COMMAND_CLASS_BASIC
2020-08-07 11:37:16.606 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Adding to device queue
2020-08-07 11:37:16.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Added 473 to queue - size 2
2020-08-07 11:37:16.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:16.615 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 12 03 32 01 10 25 BD 4C
2020-08-07 11:37:16.618 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 18: Sending REQUEST Message = 01 0A 00 13 12 03 32 01 10 25 BD 4C
2020-08-07 11:37:16.621 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-08-07 11:37:16.624 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-08-07 11:37:16.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 473: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Adding to device queue
2020-08-07 11:37:16.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:16.631 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:16.631 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Added 474 to queue - size 2
2020-08-07 11:37:16.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 473: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.632 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-08-07 11:37:16.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-08-07 11:37:16.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Adding to device queue
2020-08-07 11:37:16.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Added 475 to queue - size 3
2020-08-07 11:37:16.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:16.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:16.637 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.639 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:16.641 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Adding to device queue
2020-08-07 11:37:16.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 473: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.643 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Added 476 to queue - size 4
2020-08-07 11:37:16.645 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.648 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:16.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 473: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.651 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:16.653 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: sentData successfully placed on stack.
2020-08-07 11:37:16.655 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 473: Advanced to WAIT_REQUEST
2020-08-07 11:37:16.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 473: Transaction not completed
2020-08-07 11:37:16.658 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:16.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.783 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BD 00 00 10 46
2020-08-07 11:37:16.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 10
2020-08-07 11:37:16.798 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 10
2020-08-07 11:37:16.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 473: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:16.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 473: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 189
2020-08-07 11:37:16.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 473: (Callback 189)
2020-08-07 11:37:16.806 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-08-07 11:37:16.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 473: callback 189
2020-08-07 11:37:16.810 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 10
2020-08-07 11:37:16.812 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: SendData Request. CallBack ID = 189, Status = Transmission complete and ACK received(0)
2020-08-07 11:37:16.814 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:16.816 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 473: Advanced to WAIT_DATA
2020-08-07 11:37:16.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 473: Transaction not completed
2020-08-07 11:37:16.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:16.821 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.926 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 34 00 00 00 00 00 00 D6
2020-08-07 11:37:16.929 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 34 00 00 00 00 00 00
2020-08-07 11:37:16.932 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 34 00 00 00 00 00 00
2020-08-07 11:37:16.933 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:16.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:16.937 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:16.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:16.941 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:16.943 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:16.944 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
2020-08-07 11:37:16.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:16.948 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1
2020-08-07 11:37:16.951 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_watts to 0 [DecimalType]
2020-08-07 11:37:16.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:16.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a7615a.
2020-08-07 11:37:16.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a7615a.
2020-08-07 11:37:16.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: notifyTransactionResponse TID:473 DONE
2020-08-07 11:37:16.964 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-08-07 11:37:16.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-08-07 11:37:16.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:16.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:16.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:16.974 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 12 03 32 01 00 25 BE 5F
2020-08-07 11:37:16.977 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 18: Sending REQUEST Message = 01 0A 00 13 12 03 32 01 00 25 BE 5F
2020-08-07 11:37:16.979 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-08-07 11:37:16.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 474: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:16.981 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-08-07 11:37:16.983 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:16.986 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:16.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 474: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:16.988 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-08-07 11:37:16.990 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-08-07 11:37:16.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:16.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:16.994 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:16.996 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:16.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 474: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:17.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:17.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 474: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:17.004 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:17.006 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: sentData successfully placed on stack.
2020-08-07 11:37:17.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 474: Advanced to WAIT_REQUEST
2020-08-07 11:37:17.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 474: Transaction not completed
2020-08-07 11:37:17.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.194 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BE 00 00 15 40
2020-08-07 11:37:17.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 15
2020-08-07 11:37:17.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 15
2020-08-07 11:37:17.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 474: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:17.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:17.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 474: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 190
2020-08-07 11:37:17.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 474: (Callback 190)
2020-08-07 11:37:17.208 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-08-07 11:37:17.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 474: callback 190
2020-08-07 11:37:17.212 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 15
2020-08-07 11:37:17.214 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: SendData Request. CallBack ID = 190, Status = Transmission complete and ACK received(0)
2020-08-07 11:37:17.216 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:17.218 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 474: Advanced to WAIT_DATA
2020-08-07 11:37:17.219 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 474: Transaction not completed
2020-08-07 11:37:17.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.222 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.337 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 21 24 00 00 07 47 00 00 86
2020-08-07 11:37:17.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 24 00 00 07 47 00 00
2020-08-07 11:37:17.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 21 24 00 00 07 47 00 00
2020-08-07 11:37:17.350 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:17.353 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:17.356 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:17.360 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:17.362 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:17.366 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:17.369 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=kWh(0), Value=186.3
2020-08-07 11:37:17.372 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:17.375 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=186.3
2020-08-07 11:37:17.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_kwh to 186.3 [DecimalType]
2020-08-07 11:37:17.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:17.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9e2161.
2020-08-07 11:37:17.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@9e2161.
2020-08-07 11:37:17.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: notifyTransactionResponse TID:474 DONE
2020-08-07 11:37:17.400 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-08-07 11:37:17.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-08-07 11:37:17.405 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:17.408 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:17.414 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 12 03 32 01 28 25 BF 76
2020-08-07 11:37:17.417 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 18: Sending REQUEST Message = 01 0A 00 13 12 03 32 01 28 25 BF 76
2020-08-07 11:37:17.420 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-08-07 11:37:17.423 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-08-07 11:37:17.423 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 475: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.426 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:17.429 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-08-07 11:37:17.430 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-08-07 11:37:17.431 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 475: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.433 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-08-07 11:37:17.434 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:17.435 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.439 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:17.441 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 475: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.442 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:17.444 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 475: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.445 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-08-07 11:37:17.447 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: sentData successfully placed on stack.
2020-08-07 11:37:17.448 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 475: Advanced to WAIT_REQUEST
2020-08-07 11:37:17.450 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 475: Transaction not completed
2020-08-07 11:37:17.451 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.452 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.604 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BF 00 00 12 46
2020-08-07 11:37:17.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 12
2020-08-07 11:37:17.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 12
2020-08-07 11:37:17.610 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 475: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:17.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 475: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 191
2020-08-07 11:37:17.614 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 475: (Callback 191)
2020-08-07 11:37:17.616 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-08-07 11:37:17.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 475: callback 191
2020-08-07 11:37:17.619 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 12
2020-08-07 11:37:17.621 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: SendData Request. CallBack ID = 191, Status = Transmission complete and ACK received(0)
2020-08-07 11:37:17.622 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:17.624 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 475: Advanced to WAIT_DATA
2020-08-07 11:37:17.625 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 475: Transaction not completed
2020-08-07 11:37:17.627 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.746 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 12 0A 32 02 A1 6C 00 00 00 05 00 00 0B
2020-08-07 11:37:17.750 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 A1 6C 00 00 00 05 00 00
2020-08-07 11:37:17.752 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0A 32 02 A1 6C 00 00 00 05 00 00
2020-08-07 11:37:17.753 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:17.755 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:17.756 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:17.758 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 0
2020-08-07 11:37:17.760 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:17.761 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:17.763 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=A(5), Value=0.005
2020-08-07 11:37:17.765 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:17.766 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0.005
2020-08-07 11:37:17.768 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_current to 0.005 [DecimalType]
2020-08-07 11:37:17.771 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:17.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1844b8b.
2020-08-07 11:37:17.774 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1844b8b.
2020-08-07 11:37:17.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: notifyTransactionResponse TID:475 DONE
part 2 will follow …
Let me know what else I can share to help resolving.
Txs!!