FGWP102 Metered Wall Plug Switch OH2 (2.1) warnings

Setup, Configuration and Use Beginners
,
1

I am getting the following warnings in the log:

2017-07-03 07:45:29.900 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184.

2017-07-03 07:45:29.902 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

2017-07-03 07:45:29.905 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SENSOR_MULTILEVEL (0x31) was on the root node.

2017-07-03 07:33:38.911 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184.

2017-07-03 07:33:38.919 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

2017-07-03 07:33:38.926 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class METER (0x32) was on the root node.

Don’t know if it started after updating to OH2.1
anyway it says “notify author”

2

Do you have a debug log that you can provide that shows the actual messages?

Cheers
Chris

3

full log below:

> 2017-07-03 21:17:39.663 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling...

> 2017-07-03 21:17:39.672 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:switch_binary

> 2017-07-03 21:17:39.675 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 6: Generating poll message for SWITCH_BINARY, endpoint 0

> 2017-07-03 21:17:39.678 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_GET

> 2017-07-03 21:17:39.682 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:sensor_power

> 2017-07-03 21:17:39.685 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 6: Generating poll message for SENSOR_MULTILEVEL, endpoint 0

> 2017-07-03 21:17:39.688 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Creating new message for command SENSOR_MULTI_LEVEL_GET

> 2017-07-03 21:17:39.692 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:meter_watts

> 2017-07-03 21:17:39.695 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for METER, endpoint 0

> 2017-07-03 21:17:39.698 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for METER, endpoint 0

> 2017-07-03 21:17:39.701 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET

> 2017-07-03 21:17:39.705 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:meter_kwh

> 2017-07-03 21:17:39.708 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for METER, endpoint 0

> 2017-07-03 21:17:39.711 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for METER, endpoint 0

> 2017-07-03 21:17:39.714 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Creating new message for application command METER_GET

> 2017-07-03 21:17:39.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:meter_reset

> 2017-07-03 21:17:39.719 [DEBUG] [ternal.converter.ZWaveMeterConverter] - NODE 6: Generating poll message for METER, endpoint 0

> 2017-07-03 21:17:39.721 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Polling zwave:device:15cf518cecd:node6:alarm_power

> 2017-07-03 21:17:39.746 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 09 00 13 06 02 25 02 25 EB 08 

> 2017-07-03 21:17:39.785 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:17:40.011 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 235, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:17:40.014 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:40.147 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:17:40.151 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:40.157 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class SWITCH_BINARY

> 2017-07-03 21:17:40.164 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 0

> 2017-07-03 21:17:40.170 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

> 2017-07-03 21:17:40.174 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0

> 2017-07-03 21:17:40.178 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:switch_binary to OFF [OnOffType]

> 2017-07-03 21:17:40.202 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:17:40.206 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 443ms/4864ms.

> 2017-07-03 21:17:40.215 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0B 00 13 06 04 31 04 04 00 25 EC 1D 

> 2017-07-03 21:17:40.240 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:17:40.492 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 236, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:17:40.495 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:40.642 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:17:40.647 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:40.655 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class SENSOR_MULTILEVEL

> 2017-07-03 21:17:40.662 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5

> 2017-07-03 21:17:40.666 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Multi Level REPORT received

> 2017-07-03 21:17:40.670 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Power(4), Scale = 0

> 2017-07-03 21:17:40.674 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 0E+1

> 2017-07-03 21:17:40.682 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

> 2017-07-03 21:17:40.686 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 0E+1

> 2017-07-03 21:17:40.691 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:sensor_power to 0 [DecimalType]

> 2017-07-03 21:17:40.718 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:17:40.719 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 502ms/4864ms.

> 2017-07-03 21:17:40.730 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 10 25 ED 08 

> 2017-07-03 21:17:40.763 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:17:40.978 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 237, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:17:40.981 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:41.138 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:17:41.140 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:41.146 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class METER

> 2017-07-03 21:17:41.149 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Received METER command V2

> 2017-07-03 21:17:41.152 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Meter: Type=Electric(1), Scale=W(2), Value=0E+1

> 2017-07-03 21:17:41.157 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMeterValueEvent

> 2017-07-03 21:17:41.160 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 0E+1

> 2017-07-03 21:17:41.164 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:meter_watts to 0 [DecimalType]

> 2017-07-03 21:17:41.185 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:17:41.187 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 454ms/4864ms.

> 2017-07-03 21:17:41.194 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 32 01 00 25 EE 1B 

> 2017-07-03 21:17:41.222 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:17:41.473 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 238, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:17:41.478 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:41.635 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:17:41.636 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:17:41.639 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class METER

> 2017-07-03 21:17:41.641 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Received METER command V2

> 2017-07-03 21:17:41.643 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 6: Meter: Type=Electric(1), Scale=kWh(0), Value=0.04

> 2017-07-03 21:17:41.647 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMeterValueEvent

> 2017-07-03 21:17:41.649 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 0.04

> 2017-07-03 21:17:41.654 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:meter_kwh to 0.04 [DecimalType]

> 2017-07-03 21:17:41.668 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:17:41.670 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 472ms/4864ms.

> 2017-07-03 21:25:07.058 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Command received zwave:device:15cf518cecd:node6:switch_binary --> ON

> 2017-07-03 21:25:07.061 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_SET

> 2017-07-03 21:25:07.091 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 25 01 FF 25 FD E0 

> 2017-07-03 21:25:07.119 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:25:07.330 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 253, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:25:07.332 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:07.339 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:25:07.340 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 242ms/4864ms.

> 2017-07-03 21:25:07.482 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:25:07.484 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:07.488 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

> 2017-07-03 21:25:07.490 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

> 2017-07-03 21:25:07.491 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

> 2017-07-03 21:25:07.493 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

> 2017-07-03 21:25:07.495 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SWITCH_BINARY (0x25)

> 2017-07-03 21:25:07.497 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SWITCH_BINARY (0x25) was on the root node.

> 2017-07-03 21:25:07.499 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

> 2017-07-03 21:25:07.502 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 255

> 2017-07-03 21:25:07.506 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

> 2017-07-03 21:25:07.508 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 255

> 2017-07-03 21:25:07.510 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:switch_binary to ON [OnOffType]

> 2017-07-03 21:25:08.868 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Command received zwave:device:15cf518cecd:node6:switch_binary --> OFF

> 2017-07-03 21:25:08.869 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_SET

> 2017-07-03 21:25:08.874 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 25 01 00 25 FE 1C 

> 2017-07-03 21:25:08.899 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:25:08.900 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:08.902 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

> 2017-07-03 21:25:08.907 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

> 2017-07-03 21:25:08.908 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

> 2017-07-03 21:25:08.910 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

> 2017-07-03 21:25:08.911 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SENSOR_MULTILEVEL (0x31)

> 2017-07-03 21:25:08.913 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SENSOR_MULTILEVEL (0x31) was on the root node.

> 2017-07-03 21:25:08.916 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

> 2017-07-03 21:25:08.917 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5

> 2017-07-03 21:25:08.919 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Multi Level REPORT received

> 2017-07-03 21:25:08.920 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Power(4), Scale = 0

> 2017-07-03 21:25:08.921 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 3.9

> 2017-07-03 21:25:08.924 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

> 2017-07-03 21:25:08.925 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 3.9

> 2017-07-03 21:25:08.929 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:sensor_power to 3.9 [DecimalType]

> 2017-07-03 21:25:13.884 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Sending ABORT Message = 01 03 00 16 EA 

> 2017-07-03 21:25:13.889 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Timeout while sending message. Requeueing - 2 attempts left!

> 2017-07-03 21:25:13.890 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Got an error while sending data. Resending message.

> 2017-07-03 21:25:13.897 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 25 01 00 25 FF 1D 

> 2017-07-03 21:25:13.915 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

> 2017-07-03 21:25:14.137 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 255, Status = Transmission complete and ACK received(0)

> 2017-07-03 21:25:14.140 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:14.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

> 2017-07-03 21:25:14.165 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 267ms/4864ms.

> 2017-07-03 21:25:14.286 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:25:14.289 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:14.293 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

> 2017-07-03 21:25:14.295 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

> 2017-07-03 21:25:14.298 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

> 2017-07-03 21:25:14.300 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

> 2017-07-03 21:25:14.302 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SWITCH_BINARY (0x25)

> 2017-07-03 21:25:14.304 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SWITCH_BINARY (0x25) was on the root node.

> 2017-07-03 21:25:14.307 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

> 2017-07-03 21:25:14.311 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 0

> 2017-07-03 21:25:14.315 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

> 2017-07-03 21:25:14.317 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0

> 2017-07-03 21:25:14.320 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:switch_binary to OFF [OnOffType]

> 2017-07-03 21:25:14.905 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

> 2017-07-03 21:25:14.909 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

> 2017-07-03 21:25:14.916 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

> 2017-07-03 21:25:14.919 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

> 2017-07-03 21:25:14.922 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

> 2017-07-03 21:25:14.925 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

> 2017-07-03 21:25:14.929 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SENSOR_MULTILEVEL (0x31)

> 2017-07-03 21:25:14.932 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SENSOR_MULTILEVEL (0x31) was on the root node.

> 2017-07-03 21:25:14.935 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

> 2017-07-03 21:25:14.939 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5

> 2017-07-03 21:25:14.942 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Multi Level REPORT received

> 2017-07-03 21:25:14.945 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Power(4), Scale = 0

> 2017-07-03 21:25:14.947 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 0E+1

> 2017-07-03 21:25:14.952 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

> 2017-07-03 21:25:14.954 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 0E+1

> 2017-07-03 21:25:14.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:sensor_power to 0 [DecimalType]
4

Unfortunately it looks like you’ve filtered the results and this has removed the information I need…

Also, when posting a log, please format with the </> button sot it’s easier to read and process.

5

Sorry.
Unfiltered and in friendly format for the post.

2017-07-03 22:12:09.496 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Command received zwave:device:15cf518cecd:node6:switch_binary --> OFF

2017-07-03 22:12:09.498 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Creating new message for application command SWITCH_BINARY_SET

2017-07-03 22:12:09.500 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0

2017-07-03 22:12:09.500 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}

2017-07-03 22:12:09.503 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 06 03 25 01 00 25 2B C9 

2017-07-03 22:12:09.505 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 6: Sending REQUEST Message = 01 0A 00 13 06 03 25 01 00 25 2B C9 

==> /var/log/openhab2/events.log <==

2017-07-03 22:12:09.506 [ItemCommandEvent          ] - Item 'WallPlugSwitch1_Switch' received command OFF

==> /var/log/openhab2/openhab.log <==

2017-07-03 22:12:09.517 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 

==> /var/log/openhab2/events.log <==

2017-07-03 22:12:09.522 [ItemStateChangedEvent     ] - WallPlugSwitch1_Switch changed from ON to OFF

==> /var/log/openhab2/openhab.log <==

2017-07-03 22:12:09.523 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2017-07-03 22:12:09.525 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 

2017-07-03 22:12:09.527 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 

2017-07-03 22:12:09.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 

2017-07-03 22:12:09.532 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: Sent Data successfully placed on stack.

2017-07-03 22:12:09.731 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2B 00 00 16 D6 

2017-07-03 22:12:09.735 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2017-07-03 22:12:09.738 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2B 00 00 16 00 00 D8 

2017-07-03 22:12:09.740 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2B 00 00 16 00 00 D8 

2017-07-03 22:12:09.742 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2B 00 00 16 

2017-07-03 22:12:09.744 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 6: SendData Request. CallBack ID = 43, Status = Transmission complete and ACK received(0)

2017-07-03 22:12:09.746 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

2017-07-03 22:12:09.748 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@75c27b already registered

2017-07-03 22:12:09.750 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=6, callback=43, payload=06 03 25 01 00 

2017-07-03 22:12:09.753 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2B 00 00 16 

2017-07-03 22:12:09.754 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=43, expected=SendData, cancelled=false        transaction complete!

2017-07-03 22:12:09.756 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent

2017-07-03 22:12:09.758 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent

2017-07-03 22:12:09.760 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 6: Response processed after 249ms/4864ms.

2017-07-03 22:12:10.157 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0D 00 04 00 06 07 60 0D 00 B8 25 03 00 04 

2017-07-03 22:12:10.162 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2017-07-03 22:12:10.165 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 04 00 06 07 60 0D 00 B8 25 03 00 04 

2017-07-03 22:12:10.169 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0D 00 04 00 06 07 60 0D 00 B8 25 03 00 04 

2017-07-03 22:12:10.172 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 06 07 60 0D 00 B8 25 03 00 

2017-07-03 22:12:10.175 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

2017-07-03 22:12:10.178 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

2017-07-03 22:12:10.181 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@75c27b already registered

2017-07-03 22:12:10.184 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

2017-07-03 22:12:10.187 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

2017-07-03 22:12:10.190 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

2017-07-03 22:12:10.192 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

2017-07-03 22:12:10.195 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SWITCH_BINARY (0x25)

2017-07-03 22:12:10.198 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SWITCH_BINARY (0x25) was on the root node.

2017-07-03 22:12:10.201 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

2017-07-03 22:12:10.204 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - Received Switch Binary Request for Node ID = 6

2017-07-03 22:12:10.206 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 6: Switch Binary report, value = 0

2017-07-03 22:12:10.209 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2017-07-03 22:12:10.212 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2017-07-03 22:12:10.215 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SWITCH_BINARY, value = 0

2017-07-03 22:12:10.219 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:switch_binary to OFF [OnOffType]

2017-07-03 22:12:10.225 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=6, callback=43, payload=06 03 25 01 00 

2017-07-03 22:12:10.230 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 06 07 60 0D 00 B8 25 03 00 

2017-07-03 22:12:10.232 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=43, expected=SendData, cancelled=false      MISMATCH

2017-07-03 22:12:10.587 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 06 0A 60 0D 00 B8 31 05 04 22 00 00 20 

2017-07-03 22:12:10.591 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2017-07-03 22:12:10.594 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 06 0A 60 0D 00 B8 31 05 04 22 00 00 20 

2017-07-03 22:12:10.597 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 06 0A 60 0D 00 B8 31 05 04 22 00 00 20 

2017-07-03 22:12:10.600 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 06 0A 60 0D 00 B8 31 05 04 22 00 00 

2017-07-03 22:12:10.602 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Application Command Request (ALIVE:DONE)

2017-07-03 22:12:10.604 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 6: Starting initialisation from DONE

2017-07-03 22:12:10.606 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@75c27b already registered

2017-07-03 22:12:10.608 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 6: Incoming command class MULTI_INSTANCE

2017-07-03 22:12:10.609 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received MULTI_INSTANCE command V0

2017-07-03 22:12:10.611 [INFO ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Received a multi instance encapsulation with a destination endpoint = 184. 

2017-07-03 22:12:10.613 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: The originating endpoint is 0. Please notify author.

2017-07-03 22:12:10.615 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Requested Command Class = SENSOR_MULTILEVEL (0x31)

2017-07-03 22:12:10.617 [WARN ] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Originating Command Class SENSOR_MULTILEVEL (0x31) was on the root node.

2017-07-03 22:12:10.618 [DEBUG] [class.ZWaveMultiInstanceCommandClass] - NODE 6: Endpoint = 0, calling handleApplicationCommandRequest.

2017-07-03 22:12:10.620 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V5

2017-07-03 22:12:10.622 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Multi Level REPORT received

2017-07-03 22:12:10.623 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Type = Power(4), Scale = 0

2017-07-03 22:12:10.625 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 6: Sensor Value = 0E+1

2017-07-03 22:12:10.627 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent

2017-07-03 22:12:10.629 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent

2017-07-03 22:12:10.630 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 0E+1

2017-07-03 22:12:10.633 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 6: Updating channel state zwave:device:15cf518cecd:node6:sensor_power to 0 [DecimalType]

2017-07-03 22:12:10.635 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=6, callback=43, payload=06 03 25 01 00 

2017-07-03 22:12:10.639 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 06 0A 60 0D 00 B8 31 05 04 22 00 00 

2017-07-03 22:12:10.641 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=43, expected=SendData, cancelled=false      MISMATCH
6

Interesting.

Can you provide the XML for this device? Also what firmware version is your device?

This is a multicast message - first time I’ve seen this. Basically this message is sent to multiple endpoints - although I’ve no idea why! Basically this means it’s addressed to endpoints 4 5 and 6 - which is very strange.

Have you been using this with some other automation system? In the first instance I’d try a complete reset to see if that sorts it. If not I’ll take a look at supporting this in the binding, but it shouldn’t be necessary I think.

7

I have reinstalled the whole openhab system when I switched from the ua-netinst image to the openhabian image.
This was due to the upgrade fail of the last kernel.
After the new install I did not reset any module so the nodes came up connected as before.
The WallPlug is new, I just received it.
Looking at it’s properties from PaperUI I see: zwave_version 3.2

Here are the nodes files:
node4.xml (26.0 KB)
node6.xml (16.7 KB)
node5.xml (26.0 KB)

edit update:
I reset the node.
Excluded, Included. It received a different node id.
So far so good.
No warnings as before.