Log file warning when using Qubino's 3-Phase Smart Meter

eshabi · January 30, 2019, 12:51am

I’m running openHAB 2.4.0 stable on a RPI 3 B+ and using a Qubino z-wave 3 Phase Smart Meter (listed in the z-wave devices database as ZMNHXD).
Every 10 minutes when this device’s various parameters are being reported I get the following warning in the log file:

[WARN ] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Invalid meter scale 7

These are my two related items:

Number GEN_CURR_POWER "Current Power" <energy> {channel="zwave:device:afbbd679:node7:meter_watts1"}
Number GEN_ACCUM_POWER "Accumulated Power" <energy> {channel="zwave:device:afbbd679:node7:meter_kwh1"}

and this is the log file before and after the above warning:

2019-01-11 10:14:52.720 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 07 0E 60 0D 04 01 32 02 21 34 00 00 04 DE 00 00 71 
2019-01-11 10:14:52.727 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 04 01 32 02 21 34 00 00 04 DE 00 00 
2019-01-11 10:14:52.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 04 01 32 02 21 34 00 00 04 DE 00 00 
2019-01-11 10:14:52.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-11 10:14:52.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2019-01-11 10:14:52.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2019-01-11 10:14:52.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-01-11 10:14:52.745 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 4
2019-01-11 10:14:52.748 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2019-01-11 10:14:52.751 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-01-11 10:14:52.754 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=W(2), Value=124.6
2019-01-11 10:14:52.757 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-01-11 10:14:52.760 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 4, command class = COMMAND_CLASS_METER, value = 124.6
2019-01-11 10:14:52.764 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:afbbd679:node7:meter_watts4 to 124.6 [DecimalType]
2019-01-11 10:14:52.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2019-01-11 10:14:52.770 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e1b809.
2019-01-11 10:14:52.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:52.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:52.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-11 10:14:52.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-11 10:14:55.120 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 07 0E 60 0D 01 01 32 02 21 24 00 00 24 C6 00 00 5C 
2019-01-11 10:14:55.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 01 01 32 02 21 24 00 00 24 C6 00 00 
2019-01-11 10:14:55.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 01 01 32 02 21 24 00 00 24 C6 00 00 
2019-01-11 10:14:55.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-11 10:14:55.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2019-01-11 10:14:55.140 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2019-01-11 10:14:55.143 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-01-11 10:14:55.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-01-11 10:14:55.149 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2019-01-11 10:14:55.152 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-01-11 10:14:55.155 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=kWh(0), Value=941.4
2019-01-11 10:14:55.159 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-01-11 10:14:55.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_METER, value = 941.4
2019-01-11 10:14:55.166 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:afbbd679:node7:meter_kwh1 to 941.4 [DecimalType]
2019-01-11 10:14:55.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2019-01-11 10:14:55.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@163951e.
2019-01-11 10:14:55.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:55.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:55.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-11 10:14:55.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-11 10:14:55.920 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 04 00 07 0F 60 0D 01 01 32 02 A1 3C 00 00 0A 0E 00 00 01 23 
2019-01-11 10:14:55.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0F 60 0D 01 01 32 02 A1 3C 00 00 0A 0E 00 00 01 
2019-01-11 10:14:55.931 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0F 60 0D 01 01 32 02 A1 3C 00 00 0A 0E 00 00 01 
2019-01-11 10:14:55.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-11 10:14:55.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2019-01-11 10:14:55.939 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2019-01-11 10:14:55.942 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-01-11 10:14:55.945 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-01-11 10:14:55.948 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2019-01-11 10:14:55.951 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-01-11 10:14:55.954 [WARN ] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Invalid meter scale 7
2019-01-11 10:14:55.957 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2019-01-11 10:14:55.959 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1ddd122.
2019-01-11 10:14:55.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:55.965 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:55.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-11 10:14:55.971 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-11 10:14:56.718 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 15 00 04 00 07 0F 60 0D 01 01 32 02 21 2C 00 00 28 57 00 00 01 C8 
2019-01-11 10:14:56.724 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0F 60 0D 01 01 32 02 21 2C 00 00 28 57 00 00 01 
2019-01-11 10:14:56.728 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0F 60 0D 01 01 32 02 21 2C 00 00 28 57 00 00 01 
2019-01-11 10:14:56.731 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-11 10:14:56.734 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2019-01-11 10:14:56.736 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2019-01-11 10:14:56.739 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-01-11 10:14:56.742 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-01-11 10:14:56.745 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2019-01-11 10:14:56.748 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-01-11 10:14:56.751 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=kVAh(1), Value=1032.7
2019-01-11 10:14:56.754 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-01-11 10:14:56.757 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_METER, value = 1032.7
2019-01-11 10:14:56.760 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:afbbd679:node7:meter_kvah1 to 1032.7 [DecimalType]
2019-01-11 10:14:56.764 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2019-01-11 10:14:56.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@30144e.
2019-01-11 10:14:56.769 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:56.772 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:56.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-11 10:14:56.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-01-11 10:14:59.919 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 07 0E 60 0D 01 01 32 02 21 34 00 00 34 60 00 00 FA 
2019-01-11 10:14:59.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 01 01 32 02 21 34 00 00 34 60 00 00 
2019-01-11 10:14:59.930 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=7, callback=0, payload=00 07 0E 60 0D 01 01 32 02 21 34 00 00 34 60 00 00 
2019-01-11 10:14:59.932 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-01-11 10:14:59.935 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Application Command Request (ALIVE:DONE)
2019-01-11 10:14:59.938 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: resetResendCount initComplete=true isDead=false
2019-01-11 10:14:59.941 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Decapsulating COMMAND_CLASS_MULTI_CHANNEL
2019-01-11 10:14:59.944 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: Incoming command class COMMAND_CLASS_METER, endpoint 1
2019-01-11 10:14:59.946 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 7: SECURITY NOT required on COMMAND_CLASS_METER
2019-01-11 10:14:59.949 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 7: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-01-11 10:14:59.953 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 7: Meter: Type=Electric(1), Scale=W(2), Value=1340.8
2019-01-11 10:14:59.955 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-01-11 10:14:59.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Got a value event from Z-Wave network, endpoint = 1, command class = COMMAND_CLASS_METER, value = 1340.8
2019-01-11 10:14:59.962 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Updating channel state zwave:device:afbbd679:node7:meter_watts1 to 1340.8 [DecimalType]
2019-01-11 10:14:59.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Commands processed 1.
2019-01-11 10:14:59.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 7: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@35de31.
2019-01-11 10:14:59.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:59.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-01-11 10:14:59.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-01-11 10:14:59.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Any clue what I’m doing wrong?

rug · May 4, 2019, 6:36am

Did you ever get this working right? I have the same problem and then some… I get the same error every 10 minutes when the meter sends updates. I also get communication errors sometime and have to reset the meter. The final problem is that every time it reports KWh values it first sets the value to 0 before updating the new KWh value.

I am thinking of sending this unit back. Just googling this thing shows many have trouble with it. Wish I had googles this unit before I ordered it though

/Carl

eshabi · May 4, 2019, 10:12pm

I still have this error. Found out it is related to one of the measurements published by this unit: “Energy – Reactive power accumulated total”. This measurement’s units/scale is “kvarh” which is reported as scale 7. I looked at the code and found that this scale is not supported.
Beside this issue, the unit is working for me as expected. No loss of communication.

chris · May 5, 2019, 8:29am

Please raise an issue on GH and I’ll update the binding to support this.

eshabi · May 6, 2019, 5:59pm

Just did that.