Periodic issues with Aeotec Multi 6 and Neo Coolcam power plug

plysdyret · May 23, 2019, 10:54am

Platform information:
- Hardware: VM
- OS: Ubuntu
- openHAB version: 2.4 with zwave binding 2.5 snapshot

I don’t have any logs since this only happened a few times and the logs have been rotated but posting in case someone has experienced similar problems.

I have the aeotec sensor in my bathroom and the neocam plug is in the attic above, controlling a ventilation pump. It works most of the time with this simple rule:

rule "BathRoomVentilation"
when
        Item Bathroom_humidity changed or
        Item Bathroom_motion changed
then
        if (Ventilation.state == ON) {
                if (Bathroom_motion.state == OFF ) {

                        if (Bathroom_humidity.state >= 60 ) {
                                if (Metered_plug_ventilation_switch.state == OFF ) {
                                        sendCommand(Metered_plug_ventilation_switch, ON)
                                }
                        } else {
                                if (Metered_plug_ventilation_switch.state == ON ) {
                                        sendCommand(Metered_plug_ventilation_switch, OFF)
                                }
                        }
                } else {
                        if (Metered_plug_ventilation_switch.state == ON ) {
                                sendCommand(Metered_plug_ventilation_switch, OFF)
                        }
                }
        } else {
                if (Metered_plug_ventilation_switch.state == ON ) {
                        sendCommand(Metered_plug_ventilation_switch, OFF)
                }
        }
end

E.g. run the ventilation if relative humidity is above 60, unless motion is detected (the ventilation thing makes a lot of noise).

Problem 1:

Last night the aeotec stopped detecting motion. This has happened a handful of times the last few months and it always starts working again after a while. I have a switch linked to the motion state in habpanel and it showed motion off. The aeotec device didn’t do the green led blink either when I did a little dance in front of it. Something to do with the device?

Problem 2.

This morning the coolcam plug was shown by a habpanel switch to be OFF even though it was ON and the ventilation was running. Toggling the switch turned off the ventilation as expected. How could the switch end up in the wrong state?

Any suggestions off the top of someones head? =)

Regarding the missing logs, is it possible to get openhab to log to a syslog server?

Thanks,

plysdyret · May 24, 2019, 7:44am

Oh well, need the logs

Regarding the missing logs, is it possible to get openhab to log to a syslog server?

I got this to work reasonably well, posting a few configuration snippets in case it can help someone else.

In /etc/rsyslog.d/00-custom.conf on my log server:

template (name="DynaFile" type="string" string="/tank/syslog/%fromhost-ip%/%programname%.log")

*.* ?DynaFile; RSYSLOG_TraditionalFileFormat

In /var/lib/openhab2/etc/org.ops4j.pax.logging.cfg on the openhab box:

# ZWave Logger
log4j2.logger.ZWave.name = org.openhab.binding.zwave
log4j2.logger.ZWave.level = DEBUG
log4j2.logger.ZWave.additivity = false
log4j2.logger.ZWave.appenderRefs = zwave
log4j2.logger.ZWave.appenderRef.ZWave.ref = zwave

# Socket appender for zwave debug
log4j2.appender.zwave.type = Socket
log4j2.appender.zwave.name = zwave
log4j2.appender.zwave.host = 192.168.40.3
log4j2.appender.zwave.port = 514
log4j2.appender.zwave.protocol = UDP
log4j2.appender.zwave.layout.type = PatternLayout
log4j2.appender.zwave.layout.pattern = %d{MMM d hh:mm:ss} openhab zwave [%-5.5p] [%-36.36c] %m%n

The log server has unlimited space, so no more rolling logs. Now I just need the problems to happen again.

Mvh.

Torkil

plysdyret · May 27, 2019, 5:09am

Aeotec support suggest a MOTION ON event was missed by OpenHAB which I guess would fit the symptoms. The same could be the case for the plug ending up in the wrong state, just the other way around, with OpenHAB sending MOTION OFF and the plug not receiving the signal.

Any suggestions on how to make it more robust?

Thanks,

Torkil

rossko57 · May 27, 2019, 9:31am

I’m unfamiliar with these things - but I guess the plug reports its on/off state to openHAB?
If you disable autoupdate for your OH Item, this will stop OH ‘helpfully’ changing the Item state internally when a command is sent. e.g. your Item will more accurately reflect the actual state of the end device.

Once you’ve got that, you can construct something like a command triggered rule that kicks off a timer to verify if the command was actioned. You could then retry, or log things out, or whatever.

It’s difficult to know when you miss an “incoming” event (since you don’t know about it!). But if you can detect outgoing events that get missed, you’ve a better chance of understanding the circumstances.

plysdyret · May 27, 2019, 10:12am

Ah, thanks for pointing me to the autoupdate setting. I’ve set that to false for the devices in question and will see if that sorts the plug.

I tried toggling the plug on from HABPanel and as far as I can determine it does report the state:

May 27 11:59:23 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT

Full log:

May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Command received zwave:device:071de70e:node4:switch_binary --> ON [OnOffType]
May 27 11:59:23 openhab zwave [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] NODE 4: Creating new message for application command SWITCH_BINARY_SET
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5248 to queue - size 1
May 27 11:59:23 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 25 01 FF 25 F1 EE 
May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling intialised at 86400 seconds - start in 1500 milliseconds.
May 27 11:59:23 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5248: Transaction not completed
May 27 11:59:23 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 241, Status = Transmission complete and ACK received(0)
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Response processed after 68ms
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5248: Transaction completed
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5248 DONE
May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
May 27 11:59:23 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
May 27 11:59:23 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
May 27 11:59:23 openhab zwave [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] NODE 4: Switch Binary report, value = 255
May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255
May 27 11:59:23 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:switch_binary to ON [OnOffType]
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:23 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4d8b190a.
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling...
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling zwave:device:071de70e:node4:switch_binary
May 27 11:59:24 openhab zwave [DEBUG] [converter.ZWaveBinarySwitchConverter] NODE 4: Generating poll message for COMMAND_CLASS_SWITCH_BINARY, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] NODE 4: Creating new message for application command SWITCH_BINARY_GET
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_SWITCH_BINARY is NOT required to be secured
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling skipped for zwave:device:071de70e:node4:switch_binary on COMMAND_CLASS_BASIC
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling zwave:device:071de70e:node4:meter_kwh
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Creating new message for application command METER_GET
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling zwave:device:071de70e:node4:meter_watts
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Creating new message for application command METER_GET
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling zwave:device:071de70e:node4:meter_current
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Creating new message for application command METER_GET
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
May 27 11:59:24 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Polling zwave:device:071de70e:node4:meter_voltage
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [ternal.converter.ZWaveMeterConverter] NODE 4: Generating poll message for COMMAND_CLASS_METER, endpoint 0
May 27 11:59:24 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Creating new message for application command METER_GET
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:24 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Command Class COMMAND_CLASS_METER is NOT required to be secured
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5249 to queue - size 1
May 27 11:59:24 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 25 02 25 F2 13 
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5250 to queue - size 1
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5251 to queue - size 2
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5252 to queue - size 3
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Adding to device queue
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Added 5253 to queue - size 4
May 27 11:59:24 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:24 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5249: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 242, Status = Transmission complete and ACK received(0)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5249: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_SWITCH_BINARY, endpoint 0
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_SWITCH_BINARY
May 27 11:59:25 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_SWITCH_BINARY V1 SWITCH_BINARY_REPORT
May 27 11:59:25 openhab zwave [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] NODE 4: Switch Binary report, value = 255
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_BINARY, value=255
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:switch_binary to ON [OnOffType]
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@65a75029.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@65a75029.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5249 DONE
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:25 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 32 01 00 25 F3 04 
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5250: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 243, Status = Transmission complete and ACK received(0)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5250: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:25 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:25 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=kWh(0), Value=-21474816.35
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=-21474816.35
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_kwh to -21474816.35 [DecimalType]
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1baf3e5a.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1baf3e5a.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5250 DONE
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:25 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 32 01 10 25 F4 13 
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5251: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 244, Status = Transmission complete and ACK received(0)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5251: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:25 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:25 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=0E+1
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_watts to 0 [DecimalType]
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4e1d6ba5.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4e1d6ba5.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5251 DONE
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:25 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 32 01 28 25 F5 2A 
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5252: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 245, Status = Transmission complete and ACK received(0)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5252: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:25 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:25 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=A(5), Value=0E+1
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0E+1
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_current to 0 [DecimalType]
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5bcb6825.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5bcb6825.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5252 DONE
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:25 openhab zwave [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] NODE 4: Sending REQUEST Message = 01 0A 00 13 04 03 32 01 20 25 F6 21 
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: sentData successfully placed on stack.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5253: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [l.serialmessage.SendDataMessageClass] NODE 4: SendData Request. CallBack ID = 246, Status = Transmission complete and ACK received(0)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: TID 5253: Transaction not completed
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:25 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:25 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:25 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=V(4), Value=240.92
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=240.92
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_voltage to 240.92 [DecimalType]
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4324d6d.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@4324d6d.
May 27 11:59:25 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: notifyTransactionResponse TID:5253 DONE
May 27 11:59:25 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:28 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:28 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=kWh(0), Value=-21474816.35
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=-21474816.35
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_kwh to -21474816.35 [DecimalType]
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a972c3e.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:28 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:28 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=84.72
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=84.72
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_watts to 84.72 [DecimalType]
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@35bf4dcf.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:28 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:28 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=V(4), Value=239.78
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=239.78
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_voltage to 239.78 [DecimalType]
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@34c0d124.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:28 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:28 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:28 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=A(5), Value=0.48
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0.48
May 27 11:59:28 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_current to 0.48 [DecimalType]
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:28 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@ba83deb.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:58 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:58 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=kWh(0), Value=-21474816.35
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=-21474816.35
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_kwh to -21474816.35 [DecimalType]
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@2ad9a08a.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:58 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:58 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=W(2), Value=84.48
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=84.48
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_watts to 84.48 [DecimalType]
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5633b84e.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:58 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:58 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=V(4), Value=239.93
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=239.93
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_voltage to 239.93 [DecimalType]
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@e6cf7b3.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Application Command Request (ALIVE:DONE)
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: resetResendCount initComplete=true isDead=false
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: Incoming command class COMMAND_CLASS_METER, endpoint 0
May 27 11:59:58 openhab zwave [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] NODE 4: SECURITY NOT required on COMMAND_CLASS_METER
May 27 11:59:58 openhab zwave [DEBUG] [tocol.commandclass.ZWaveCommandClass] NODE 4: Received COMMAND_CLASS_METER V3 METER_REPORT
May 27 11:59:58 openhab zwave [DEBUG] [.commandclass.ZWaveMeterCommandClass] NODE 4: Meter: Type=Electric(1), Scale=A(5), Value=0.48
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got an event from Z-Wave network: ZWaveMeterValueEvent
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=0.48
May 27 11:59:58 openhab zwave [DEBUG] [ding.zwave.handler.ZWaveThingHandler] NODE 4: Updating channel state zwave:device:071de70e:node4:meter_current to 0.48 [DecimalType]
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Commands processed 1.
May 27 11:59:58 openhab zwave [DEBUG] [nal.protocol.ZWaveTransactionManager] NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@357b594a.

Thanks,

Torkil

rossko57 · May 27, 2019, 10:15am

By the way

I think this gives the lie to …

plysdyret · May 27, 2019, 10:21am

I think it only does the green thing if alarm_motion is OFF and it detects motion. They suggest the device is actually in the ON state and the ON event was dropped. I’ll check if it blinks in the ON state when I get home and if it does you are of course right.

Mvh.

Torkil

rossko57 · May 27, 2019, 12:46pm

As said, I know nothing of these things. You have to send a command to the sensor to make the LED work?

chris · May 27, 2019, 1:03pm

It is unlikely that the event is dropped by the binding - the most likely scenario, if you are sure that the device sent it, is that there is a communication issue and the message was not received by the controller. If thre’s nothing in the logs, then this is likely the case, otherwise, please provide the debug logs and I will take a look.

plysdyret · May 27, 2019, 7:24pm

The thing has an internal timer which start whenever alarm_motion is detected and set to ON. If no more motion is detected within the configured time it sets alarm_motion to OFF when the timer is up. If subsequent motion is detected before the timer is up it resets the timer.

It seems it only flashes the LED the first time, corresponding to setting alarm_motion to ON.

Thanks, I’ll have to see if there if there is anything of interest in the log if/when it happens again. It’s difficult to determine if a packet is sent, I suppose, if it’s not seen in the log. It could be either the device malfunctioning or a packet lost.

plysdyret · May 28, 2019, 6:43pm

It only blinks when detecting motion the fist time, e.g. going OFF to ON, and then again when it de-triggers and goes OFF to ON.

I’ve ordered a NESDR Mini 2+ (https://www.nooelec.com/store/sdr/sdr-receivers/nesdr-mini-2-plus.html) with which I should be able to sniff zwave traffic with wireshark, so I can hopefully determine if a packet is sent or not.

Thank you for your help,

Torkil

Andrew_Rowe · June 13, 2019, 4:35pm

Wow, that gizmo is cheap! Let us know how it works