Qubino smartmeter "switch relay" channel trigger and item update issue

Txs for the advise. I’m not an expert in Zwave network neither in OpenHab low levels. I’ll dig into this as best as I can.

Yes that is the documented recommendation. In the past year I have never seen them need TRACE logs.

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!!

part 2

2020-08-07 11:37:17.778 [vent.ItemStateChangedEvent] - CarSmartMeter_Current changed from 0.003 to 0.005
==> /var/log/openhab2/openhab.log <==
2020-08-07 11:37:17.779 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-08-07 11:37:17.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-08-07 11:37:17.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:17.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:17.788 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 12 06 60 0D 01 02 25 02 25 C0 59 
2020-08-07 11:37:17.790 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 18: Sending REQUEST Message = 01 0D 00 13 12 06 60 0D 01 02 25 02 25 C0 59 
2020-08-07 11:37:17.792 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-08-07 11:37:17.794 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 476: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:17.795 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-08-07 11:37:17.797 [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.804 [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.804 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2020-08-07 11:37:17.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:17.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-08-07 11:37:17.807 [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.809 [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.811 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:17.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:17.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 476: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:17.816 [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.818 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: sentData successfully placed on stack.
2020-08-07 11:37:17.820 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 476: Advanced to WAIT_REQUEST
2020-08-07 11:37:17.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 476: Transaction not completed
2020-08-07 11:37:17.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:17.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:17.999 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C0 00 00 14 3F 
2020-08-07 11:37:18.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 14 
2020-08-07 11:37:18.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 14 
2020-08-07 11:37:18.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 476: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:18.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-08-07 11:37:18.009 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 476: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 192
2020-08-07 11:37:18.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 476: (Callback 192)
2020-08-07 11:37:18.013 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-08-07 11:37:18.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 476: callback 192
2020-08-07 11:37:18.017 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 14 
2020-08-07 11:37:18.018 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 18: SendData Request. CallBack ID = 192, Status = Transmission complete and ACK received(0)
2020-08-07 11:37:18.021 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:18.022 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 476: Advanced to WAIT_DATA
2020-08-07 11:37:18.024 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: TID 476: Transaction not completed
2020-08-07 11:37:18.026 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:18.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-08-07 11:37:18.131 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 12 07 60 0D 02 01 25 03 00 AB 
2020-08-07 11:37:18.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 07 60 0D 02 01 25 03 00 
2020-08-07 11:37:18.136 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 07 60 0D 02 01 25 03 00 
2020-08-07 11:37:18.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:18.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:18.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:18.144 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:18.148 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 2
2020-08-07 11:37:18.151 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
2020-08-07 11:37:18.154 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
2020-08-07 11:37:18.157 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 18: Switch Binary report, value = 0
2020-08-07 11:37:18.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-08-07 11:37:18.163 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=2, command class=COMMAND_CLASS_SWITCH_BINARY, value=0
2020-08-07 11:37:18.167 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:switch_binary2 to OFF [OnOffType]
2020-08-07 11:37:18.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:18.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13405d2.
2020-08-07 11:37:18.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@13405d2.
2020-08-07 11:37:18.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: notifyTransactionResponse TID:476 DONE
==> /var/log/openhab2/events.log <==
2020-08-07 11:37:18.180 [vent.ItemStateChangedEvent] - CarSmartMeterSwitchRelay changed from ON to OFF
==> /var/log/openhab2/openhab.log <==
2020-08-07 11:37:18.189 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-08-07 11:37:18.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-08-07 11:37:18.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:18.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:18.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:18.457 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 0B ED 16 
2020-08-07 11:37:18.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0B ED 
2020-08-07 11:37:18.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0B ED 
2020-08-07 11:37:18.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:18.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:18.467 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:18.469 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:18.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:18.473 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:18.474 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:18.476 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 305.3
2020-08-07 11:37:18.478 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:18.480 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=305.3
2020-08-07 11:37:18.482 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:18.483 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 305.3 [DecimalType]
2020-08-07 11:37:18.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:18.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a10adf.
2020-08-07 11:37:18.488 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:18.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:18.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:18.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:19.463 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 03 F3 00 
2020-08-07 11:37:19.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 03 F3 
2020-08-07 11:37:19.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 03 F3 
2020-08-07 11:37:19.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:19.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:19.478 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:19.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:19.483 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:19.485 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:19.488 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:19.490 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 101.1
2020-08-07 11:37:19.493 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:19.495 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=101.1
2020-08-07 11:37:19.498 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:19.501 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 101.1 [DecimalType]
2020-08-07 11:37:19.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:19.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@47a263.
2020-08-07 11:37:19.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:19.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:19.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:19.515 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:20.458 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 05 77 82 
2020-08-07 11:37:20.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 05 77 
2020-08-07 11:37:20.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 05 77 
2020-08-07 11:37:20.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:20.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:20.473 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:20.476 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:20.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:20.481 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:20.484 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:20.486 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 139.9
2020-08-07 11:37:20.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:20.491 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=139.9
2020-08-07 11:37:20.494 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:20.497 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 139.9 [DecimalType]
2020-08-07 11:37:20.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:20.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11c7373.
2020-08-07 11:37:20.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:20.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:20.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:20.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:23.068 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 ED 
2020-08-07 11:37:23.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 
2020-08-07 11:37:23.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 
2020-08-07 11:37:23.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:23.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:23.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:23.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:23.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:23.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:23.094 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:23.097 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=-8.7
2020-08-07 11:37:23.100 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:23.102 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=-8.7
2020-08-07 11:37:23.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:23.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@39f6d7.
2020-08-07 11:37:23.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:23.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:23.116 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:23.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:24.669 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 04 00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 1E 00 00 00 2D 
2020-08-07 11:37:24.676 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 1E 00 00 00 
2020-08-07 11:37:24.680 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 1E 00 00 00 
2020-08-07 11:37:24.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:24.685 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:24.687 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:24.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:24.692 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:24.694 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:24.697 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:24.700 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=(7), Value=3
2020-08-07 11:37:24.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:24.706 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=3
2020-08-07 11:37:24.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:24.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@161fefd.
2020-08-07 11:37:24.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:24.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:24.718 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:24.720 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:25.458 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 0A BD 47 
2020-08-07 11:37:25.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0A BD 
2020-08-07 11:37:25.466 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0A BD 
2020-08-07 11:37:25.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:25.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:25.473 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:25.476 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:25.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:25.481 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:25.483 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:25.486 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 274.9
2020-08-07 11:37:25.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:25.491 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=274.9
2020-08-07 11:37:25.494 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:25.497 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 274.9 [DecimalType]
2020-08-07 11:37:25.500 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:25.502 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@dc08c8.
2020-08-07 11:37:25.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:25.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:25.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:25.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:26.269 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 94 00 00 25 22 00 00 9C 
2020-08-07 11:37:26.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 25 22 00 00 
2020-08-07 11:37:26.280 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 25 22 00 00 
2020-08-07 11:37:26.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:26.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:26.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:26.290 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:26.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:26.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:26.297 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:26.300 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=Power Factor(6), Value=0.9506
2020-08-07 11:37:26.303 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:26.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.9506
2020-08-07 11:37:26.309 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_powerfactor1 to 0.9506 [DecimalType]
2020-08-07 11:37:26.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:26.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ef618b.
2020-08-07 11:37:26.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:26.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:26.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:26.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:26.457 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 04 37 C3 
2020-08-07 11:37:26.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 04 37 
2020-08-07 11:37:26.462 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 04 37 
2020-08-07 11:37:26.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:26.465 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:26.467 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:26.468 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:26.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:26.471 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:26.473 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:26.474 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 107.9
2020-08-07 11:37:26.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:26.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=107.9
2020-08-07 11:37:26.480 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:26.481 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 107.9 [DecimalType]
2020-08-07 11:37:26.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:26.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c7b34f.
2020-08-07 11:37:26.486 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:26.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:26.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:26.490 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:27.867 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 62 00 00 40 
2020-08-07 11:37:27.873 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 62 00 00 
2020-08-07 11:37:27.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 62 00 00 
2020-08-07 11:37:27.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:27.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:27.883 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:27.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:27.888 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:27.890 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:27.892 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:27.895 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=V(4), Value=240.2
2020-08-07 11:37:27.898 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:27.900 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=240.2
2020-08-07 11:37:27.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:27.905 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@199341c.
2020-08-07 11:37:27.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:27.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:27.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:27.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:29.466 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 21 00 00 42 
2020-08-07 11:37:29.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 21 00 00 
2020-08-07 11:37:29.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 21 00 00 
2020-08-07 11:37:29.473 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:29.475 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:29.476 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:29.478 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:29.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:29.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:29.482 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:29.484 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=A(5), Value=0.033
2020-08-07 11:37:29.486 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:29.487 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.033
2020-08-07 11:37:29.489 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:29.491 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cdf82f.
2020-08-07 11:37:29.492 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:29.493 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:29.495 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:29.496 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:37.459 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 05 70 85 
2020-08-07 11:37:37.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 05 70 
2020-08-07 11:37:37.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 05 70 
2020-08-07 11:37:37.469 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:37.471 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:37.474 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:37.477 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:37:37.481 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:37:37.484 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:37:37.489 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:37:37.491 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 139.2
2020-08-07 11:37:37.495 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:37:37.498 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=139.2
2020-08-07 11:37:37.502 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:37:37.506 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 139.2 [DecimalType]
2020-08-07 11:37:37.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:37:37.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@cf25e4.
2020-08-07 11:37:37.517 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:37.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:37.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:37.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:48.669 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF B5 00 00 F1 
2020-08-07 11:37:48.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF B5 00 00 
2020-08-07 11:37:48.682 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF B5 00 00 
2020-08-07 11:37:48.684 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:48.687 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:48.689 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:48.691 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:48.694 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:48.696 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:48.698 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:48.701 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=-7.5
2020-08-07 11:37:48.703 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:48.706 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=-7.5
2020-08-07 11:37:48.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:48.711 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@92a839.
2020-08-07 11:37:48.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:48.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0

txs!

part 3

2020-08-07 11:37:48.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:48.719 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:50.269 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 04 00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 20 00 00 00 13 
2020-08-07 11:37:50.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 20 00 00 00 
2020-08-07 11:37:50.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 20 00 00 00 
2020-08-07 11:37:50.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:50.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:50.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:50.293 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:50.298 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:50.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:50.304 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:50.308 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=(7), Value=3.2
2020-08-07 11:37:50.311 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:50.314 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=3.2
2020-08-07 11:37:50.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:50.323 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@975ea3.
2020-08-07 11:37:50.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:50.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:50.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:50.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:51.869 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 09 00 00 B6 
2020-08-07 11:37:51.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 09 00 00 
2020-08-07 11:37:51.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 09 00 00 
2020-08-07 11:37:51.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:51.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:51.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:51.888 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:51.890 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:51.893 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:51.895 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:51.899 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=Power Factor(6), Value=0.9225
2020-08-07 11:37:51.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:51.903 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.9225
2020-08-07 11:37:51.907 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_powerfactor1 to 0.9225 [DecimalType]
2020-08-07 11:37:51.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:51.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@cfcede.
2020-08-07 11:37:51.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:51.916 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:51.918 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:51.920 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:53.468 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 69 00 00 4B 
2020-08-07 11:37:53.474 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 69 00 00 
2020-08-07 11:37:53.478 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 69 00 00 
2020-08-07 11:37:53.480 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:53.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:53.484 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:53.487 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:53.489 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:53.491 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:53.493 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:53.496 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=V(4), Value=240.9
2020-08-07 11:37:53.498 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:53.501 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=240.9
2020-08-07 11:37:53.504 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:53.506 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18c3d66.
2020-08-07 11:37:53.508 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:53.510 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:53.512 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:53.514 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:55.068 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 25 00 00 46 
2020-08-07 11:37:55.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 25 00 00 
2020-08-07 11:37:55.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 6C 00 00 00 25 00 00 
2020-08-07 11:37:55.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:55.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:55.089 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:55.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:55.094 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:55.097 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:55.100 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:55.103 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=A(5), Value=0.037
2020-08-07 11:37:55.106 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:55.109 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.037
2020-08-07 11:37:55.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:55.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@108c0e.
2020-08-07 11:37:55.118 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:55.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:55.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:55.125 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:58.268 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 ED 
2020-08-07 11:37:58.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 
2020-08-07 11:37:58.281 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 21 34 FF FF FF A9 00 00 
2020-08-07 11:37:58.283 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:58.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:58.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:58.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:58.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:58.297 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:58.299 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:58.303 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=W(2), Value=-8.7
2020-08-07 11:37:58.305 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:58.308 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=-8.7
2020-08-07 11:37:58.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:58.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2ea329.
2020-08-07 11:37:58.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:58.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:58.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:58.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:37:59.869 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 04 00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 25 00 00 00 16 
2020-08-07 11:37:59.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 25 00 00 00 
2020-08-07 11:37:59.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0F 60 0D 01 01 32 02 A1 3C 00 00 00 25 00 00 00 
2020-08-07 11:37:59.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:37:59.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:37:59.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:37:59.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:37:59.890 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:37:59.892 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:37:59.894 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:37:59.897 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=(7), Value=3.7
2020-08-07 11:37:59.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:37:59.901 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=3.7
2020-08-07 11:37:59.904 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:37:59.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5b248c.
2020-08-07 11:37:59.909 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:59.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:37:59.913 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:37:59.915 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:38:01.469 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 26 00 00 99 
2020-08-07 11:38:01.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 26 00 00 
2020-08-07 11:38:01.479 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 94 00 00 24 26 00 00 
2020-08-07 11:38:01.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:38:01.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:38:01.486 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:38:01.489 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:38:01.492 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:38:01.494 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:38:01.496 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:38:01.500 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=Power Factor(6), Value=0.9254
2020-08-07 11:38:01.502 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:38:01.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=0.9254
2020-08-07 11:38:01.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Updating channel state zwave:device:4b3d1634:node18:meter_powerfactor1 to 0.9254 [DecimalType]
2020-08-07 11:38:01.511 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:38:01.513 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4c572c.
2020-08-07 11:38:01.516 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:01.518 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:01.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:38:01.523 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:38:02.459 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 31 05 04 22 0B 2B D0 
2020-08-07 11:38:02.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0B 2B 
2020-08-07 11:38:02.468 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 31 05 04 22 0B 2B 
2020-08-07 11:38:02.470 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:38:02.472 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2020-08-07 11:38:02.474 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2020-08-07 11:38:02.477 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
2020-08-07 11:38:02.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
2020-08-07 11:38:02.482 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
2020-08-07 11:38:02.484 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Type = Power(4), Scale = 0
2020-08-07 11:38:02.487 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 19: Sensor Value = 285.9
2020-08-07 11:38:02.489 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2020-08-07 11:38:02.492 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=285.9
2020-08-07 11:38:02.495 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 19: Sensor conversion not performed for POWER.
2020-08-07 11:38:02.497 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Updating channel state zwave:device:4b3d1634:node19:sensor_power to 285.9 [DecimalType]
2020-08-07 11:38:02.501 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2020-08-07 11:38:02.503 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e6cc17.
2020-08-07 11:38:02.505 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:02.507 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:02.509 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:38:02.512 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-08-07 11:38:03.068 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 67 00 00 45 
2020-08-07 11:38:03.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 67 00 00 
2020-08-07 11:38:03.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=18, callback=0, payload=00 12 0E 60 0D 01 01 32 02 A1 24 00 00 09 67 00 00 
2020-08-07 11:38:03.081 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-08-07 11:38:03.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Application Command Request (ALIVE:DONE)
2020-08-07 11:38:03.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: resetResendCount initComplete=true isDead=false
2020-08-07 11:38:03.088 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2020-08-07 11:38:03.090 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: Incoming command class COMMAND_CLASS_METER, endpoint 1
2020-08-07 11:38:03.092 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 18: SECURITY NOT required on COMMAND_CLASS_METER
2020-08-07 11:38:03.095 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 18: Received COMMAND_CLASS_METER V3 METER_REPORT
2020-08-07 11:38:03.097 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 18: Meter: Type=Electric(1), Scale=V(4), Value=240.7
2020-08-07 11:38:03.100 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got an event from Z-Wave network: ZWaveMeterValueEvent
2020-08-07 11:38:03.102 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 18: Got a value event from Z-Wave network, endpoint=1, command class=COMMAND_CLASS_METER, value=240.7
2020-08-07 11:38:03.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Commands processed 1.
2020-08-07 11:38:03.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 18: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1a61c49.
2020-08-07 11:38:03.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:03.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-08-07 11:38:03.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-08-07 11:38:03.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

et voilà…
hope this will help.

Just letting everyone know that I finally found the culprit to one of the behavior (update item via UI) : Thing setting “Command Poll period” that was defaulted to 30 minutes. When disabling command poll period altogether I now get the item updated on UI almost instantly when actioning the button . Not sure why though. If anyone could explain I’d be grateful.

When actioning the relay via the hardware button, I still get no sign of life at item level though. When asking GOAP/Qubino they just answer the issue must be with Openhab since in their lab setup all works fine (Hardware button does report status back to controller).

I decided to invest in a Zniffer setup to try understand what is goin on. If anybody has experience using the zniffer setup, please let me know.

These issues might or not be related to my new post : None of my energy metering able ZWAVE devices report based on push settings, only at poll interval

All I can suggest is to search the forum. I have set mine up but not used it much in a while.

Looks like I’ll need @chris help on the remaining issue with Qubino Smart meter relay endpoint not updating Openhab when using hardware switch to switch relay on or off.
I contacted Goap/Qubino support and they believe there must be an issue with how openhab expect messages from this device.

Herewith some relevant extracts from support thread :

Qubino support :
From the reports of the integration test that we do I have the note on this issue.
Note: The reports are sent MC encapsulated, the GUI widgets probably expect the reports to be non-MC encapsulated, as in configuration #1 (this configuration is without IKA or Bicom contactor).
From the results that we get this seems an issue that reside in the OpenHAB programming. You will hav to look with them to se where is the issue and on how to fix it. From our side the device was working properly and sending the proper reports.

Me:
Txs for your reply. I must admit I found your reply to be a bit cryptic. Not sure how to go from here.
What do you mean with “The reports are sent MC encapsulated, the GUI widgets probably expect the reports to be non-MC encapsulated, as in configuration #1 (this configuration is without IKA or Bicom contactor)”
What does “MS encapsulated” mean? What is “configuration 1”?
It would help a lot if you could expand on this a bit with more context and explanations so I can bring this to the attention of OpenHab developers.

Qubino support:
There’s basically 2 ways that the devices communicate to the Gateway Single channel lifeline (SC) and the Multi-channel lifeline (MC). MC is used when a basic command like On / Off have to be sent to more than one “place”
Let’s take the example with the Flush Dimmer. Without I2 and I3 there’s only one location from and to where the On / Off command will be sent / received that is the I1. While you’re activating the I2 and or I3 now you have a device that have 3 places that the On / Off command can go and here in this case the device have to “become” MC. Like this the On / Off command knows on which endpoint (that how is called) to turn On / Off or dim.
The configuration 1 is the basic configuration that we use for the tests, the one without no contactors in your case.

Me:
It does in some way clarify MC as multi-Channel. What I really find strange in your answer is that you tell me you use the basic (SC) config in your test. How can you then claim that MC is working as documented?
I urge you to make the following test:
Use parameter 100 to enable endpoints IR or external relay or both
Use the manual page 31 diagram to hook a switch between terminal 1 and line.
Use the switch to open and close the relay
Now on your controller confirm you get the open and close messages (on mine I get none)
If that test config works on your side, please let me know and I will check further on OpenHab side why it doesn’t catch the open close messages. If it doesn’t, then the device will need a firmware patch. In that case please let me know when the patch will be available

Qubino support:
I’m adding you the video of the test I did. The parameter was set before re-inclusion. As you can see from the report the Parameter 100 (64 hex) is set to 2. In the video you can see the Wiring used and you can see from the log that the binary report was sent every time i switch the IKA on or off.

https://support.qubino.com/helpdesk/attachments/2043166563136

@chris would you be able to help based on this?

Thanks a lot!

Luc.

Most of the channels that are configured on this device have indeed been configured on the root endpoint so are not expecting multichannel encapsulation, so this would need to be updated in the database.

Hi @chris. Thanks for this. Please let me know next steps.

The next steps are that the relevant channels need to be added to the database to support the respective endpoints. I’m not really sure what this is - I could guess based on what’s in the database now, but probably people guessing is what created the error in the first place :wink:

1 Like

I see. I believe Qubino support would be happy to help. They are keen to get supported by more platforms. I’d be happy to play a role into this but my OH knowledge is not that deep. Who could serve as proxy with Qubino support? Are you still actively involved?
I believe the effort is really worth it as this smartmeter is quite unique on the market.

Ypu just need to convert the channels appropriately to endpoint 1.

If you’re unsure what to do, pease provide the XML for your device and I’ll try and find some time to do this.

1 Like

Hi Chris. I’ll have a go at it and see if I can figure out. I guess any edit on XML file is overiden by UI if I edit device parameters later on? If so it would mean any further edit should be done directly on XML file right? What can be done to ensure OH discovery, habmin and paper UI treat this right in the future?

The editing needs to be done in the database directly - if you just update your own binding, then it will only be applicable for you, and will be overwritten when the binding is updated.

1 Like

Thx chris. I’ll try that. (Sorry for delay answering. Been quite busy for work)

Hi @chris
Just read through the database guide and see I don’t have edit access to modify a device:

Database Instructions:
To upload an XML, select the Update Device option from the tools menu in the top right of the device summary page

I can’t see any Update device link/button on device summary page or any other page.
Is this an access right issue? If so, can you please update my access rights?

Thanks!

Luc.

Hi @chris
I found the issue and will try (if I sort out structure, definitions etc…) first to correct in xml to test if ok. The issue is that in zwave database, it is configured as

  • Endpoint 0 (root) = OK
  • Endpoint 1: IR external relay = Wrong
  • endpoint 2: External relay = Wrong;

It should be as follow:

  • Endpoint 0 (Root)
  • Endpoint 1 : (Generic Meter)
  • Endpoint 2 : External Relay
  • Endpoint 3 : IR relay

The Qubino support guy also mentioned their current documentation PDF has currently errors and needs updating. I’ll try to get latest corrected doc before committing to database (if you grant me access…)

2 Likes

I just checked, and you should already have access :confounded: