Fibaro motion sensor FGMS001 Gen5 (Zwaveplus)

psixilambda · January 4, 2017, 9:14pm

Thank you Chris,

I can confirm I have this sensor (Firmware 3.2 Z-Wave Plus) working with the latest definitions and it is picking up motion alarm and tamper alarm separately.

Below I included the xml for the working sensor.

node5.xml (19.9 KB)

chris · January 4, 2017, 9:17pm

Good to hear - thanks for the feedback. .

sprehn · January 4, 2017, 9:57pm

Hi,

Ran into a similar issue.

In OH1 the item bound to motion was of type Contact, which did not work in OH2.
In now upgraded to the latest built of OH2 and noticed that I cannot reconnect to a Contact type item. Instead only items of type Switch were offered. Ich changed my motion item to Switch it all works fine for me now.

Sebastian

Edward_Moyse · January 5, 2017, 10:36am

Sure:
node21.xml (16.3 KB)

chris · January 5, 2017, 7:12pm

Interesting - the older version uses totally different command classes - it will need a bit more of a closer look and for sure the changes I made for the notification command class are not going to help with the older version (below version 2.8)…

chris · January 5, 2017, 7:27pm

@Edward_Moyse can you get a log of each different event? The device is reporting sensors called AUX, GLASSBREAK, and RESERVED - none of which make a lot of sense when trying to correlate to what I’d expect - ie MOTION, and maybe TILT or TAMPER or…

Edward_Moyse · January 6, 2017, 10:35am

Can I do this by sending a command (i.e. do it remotely)? If not then I’m afraid it’ll take me a few weeks as I’m absent at the moment.

chris · January 6, 2017, 11:08am

No - it will need you to trigger the different alarms… A couple of weeks is fine for me anyway

heskja · January 15, 2017, 7:26pm

Any update to this? Have just tried to get my new motion sensor to work, but can’t get any motion events from the sensor.

When adding the device, I see that the Paper UI reports the device as following:

Z-Wave Node 3: FGMS001 Motion Sensor
Unknown Device

Have compared the XML file, and it is similar to the one reported by Edward_Moyse.

The closest I have gotten is that I have seen the alarm_motion-event changed from OFF to ON when I move my hand across the sensor. No binary sensor changes. The alarm_motion changes back to OFF after about a minute, even with motion during that time.

I am also unable to detect the tamper alarm, even if I see the LED blink red, blue and white upon movement.

FraFis · February 5, 2017, 3:26pm

Hi, I can also not include the sensor. It’s unknown.

zwave_class_basic ROUTING_SLAVE
zwave_class_generic SENSOR_NOTIFICATION
zwave_frequent false
zwave_neighbours 1
zwave_version 3.2
zwave_listening false
zwave_plus_devicetype HOME_SECURITY_SENSOR
zwave_deviceid 4097
zwave_nodeid 15
zwave_routing true
zwave_wakeup_time 2017-02-05T15:20:08Z
zwave_beaming true
zwave_class_specific NOTIFICATION_SENSOR
zwave_manufacturer 271
zwave_devicetype 2049

The Logfile:
2017-02-05 16:22:51.651 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 0F 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A CA
2017-02-05 16:22:51.658 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:53.496 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 49 84 0F 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A CA
2017-02-05 16:22:53.502 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 1D 00 49 84 0F 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A CA
2017-02-05 16:22:53.541 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 0F 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A
2017-02-05 16:22:53.543 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 15: Application update request. Node information received.
2017-02-05 16:22:53.545 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:53.547 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:53.548 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Is awake with 0 messages in the wake-up queue.
2017-02-05 16:22:53.550 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2017-02-05 16:22:53.552 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-02-05 16:22:53.556 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=9, callback=158, payload=09 02 84 08
2017-02-05 16:22:53.561 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 0F 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A
2017-02-05 16:22:53.563 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationUpdate, callback id=158, expected=SendData, cancelled=false MISMATCH
2017-02-05 16:22:54.555 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: No more messages, go back to sleep
2017-02-05 16:22:54.556 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2017-02-05 16:22:54.557 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-02-05 16:22:54.557 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-02-05 16:22:54.559 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0F 02 84 08 25 9F DE
2017-02-05 16:22:54.561 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 15: Sending REQUEST Message = 01 09 00 13 0F 02 84 08 25 9F DE
2017-02-05 16:22:54.572 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-02-05 16:22:54.575 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:54.576 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-02-05 16:22:54.577 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-02-05 16:22:54.578 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-02-05 16:22:54.580 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: Sent Data successfully placed on stack.
2017-02-05 16:22:54.589 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9F 00 00 02 76
2017-02-05 16:22:54.592 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:54.594 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 9F 00 00 02 00 00 78
2017-02-05 16:22:54.597 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 9F 00 00 02 00 00 78
2017-02-05 16:22:54.599 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=9F 00 00 02
2017-02-05 16:22:54.601 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: SendData Request. CallBack ID = 159, Status = Transmission complete and ACK received(0)
2017-02-05 16:22:54.602 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:54.604 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:54.606 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=159, payload=0F 02 84 08
2017-02-05 16:22:54.608 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=9F 00 00 02
2017-02-05 16:22:54.610 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=159, expected=SendData, cancelled=false transaction complete!
2017-02-05 16:22:54.611 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-02-05 16:22:54.613 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Went to sleep
2017-02-05 16:22:54.614 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Is sleeping
2017-02-05 16:22:54.615 [DEBUG] [curityCommandClassWithInitialization] - NODE 15: updating lastSentMessageTimestamp
2017-02-05 16:22:54.617 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-02-05 16:22:54.618 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 15: Response processed after 56ms/5012ms.
2017-02-05 16:22:55.948 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 0F 02 84 07 7D
2017-02-05 16:22:55.951 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:55.953 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 0F 02 84 07 7D
2017-02-05 16:22:55.954 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 0F 02 84 07 7D
2017-02-05 16:22:55.957 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 02 84 07
2017-02-05 16:22:55.958 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Application Command Request (ALIVE:DONE)
2017-02-05 16:22:55.960 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:55.961 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:55.963 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Incoming command class WAKE_UP
2017-02-05 16:22:55.964 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Received Wake Up Request
2017-02-05 16:22:55.966 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Received WAKE_UP_NOTIFICATION
2017-02-05 16:22:55.967 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Is awake with 0 messages in the wake-up queue.
2017-02-05 16:22:55.969 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2017-02-05 16:22:55.970 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-02-05 16:22:55.974 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=159, payload=0F 02 84 08
2017-02-05 16:22:55.976 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 02 84 07
2017-02-05 16:22:55.977 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=159, expected=SendData, cancelled=true MISMATCH
2017-02-05 16:22:56.973 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: No more messages, go back to sleep
2017-02-05 16:22:56.974 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2017-02-05 16:22:56.975 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-02-05 16:22:56.975 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-02-05 16:22:56.978 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 0F 02 84 08 25 A0 E1
2017-02-05 16:22:56.980 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 15: Sending REQUEST Message = 01 09 00 13 0F 02 84 08 25 A0 E1
2017-02-05 16:22:56.991 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2017-02-05 16:22:56.993 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:56.994 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2017-02-05 16:22:56.996 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2017-02-05 16:22:56.997 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2017-02-05 16:22:56.998 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: Sent Data successfully placed on stack.
2017-02-05 16:22:57.009 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A0 00 00 02 49
2017-02-05 16:22:57.012 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:57.014 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 A0 00 00 02 00 00 47
2017-02-05 16:22:57.016 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 A0 00 00 02 00 00 47
2017-02-05 16:22:57.019 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=A0 00 00 02
2017-02-05 16:22:57.020 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 15: SendData Request. CallBack ID = 160, Status = Transmission complete and ACK received(0)
2017-02-05 16:22:57.020 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:57.021 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:57.023 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=160, payload=0F 02 84 08
2017-02-05 16:22:57.024 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=A0 00 00 02
2017-02-05 16:22:57.025 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=160, expected=SendData, cancelled=false transaction complete!
2017-02-05 16:22:57.026 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2017-02-05 16:22:57.027 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Went to sleep
2017-02-05 16:22:57.027 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 15: Is sleeping
2017-02-05 16:22:57.028 [DEBUG] [curityCommandClassWithInitialization] - NODE 15: updating lastSentMessageTimestamp
2017-02-05 16:22:57.029 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2017-02-05 16:22:57.029 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 15: Response processed after 48ms/5012ms.
2017-02-05 16:22:57.897 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0F 06 31 05 03 0A 00 30 F3
2017-02-05 16:22:57.900 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:57.902 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 0F 06 31 05 03 0A 00 30 F3
2017-02-05 16:22:57.905 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 0F 06 31 05 03 0A 00 30 F3
2017-02-05 16:22:57.907 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 03 0A 00 30
2017-02-05 16:22:57.907 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Application Command Request (ALIVE:DONE)
2017-02-05 16:22:57.908 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:57.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:57.910 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Incoming command class SENSOR_MULTILEVEL
2017-02-05 16:22:57.910 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
2017-02-05 16:22:57.911 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Multi Level REPORT received
2017-02-05 16:22:57.912 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Type = Luminance(3), Scale = 1
2017-02-05 16:22:57.913 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Value = 48
2017-02-05 16:22:57.913 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:57.914 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:57.915 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 48
2017-02-05 16:22:57.917 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=160, payload=0F 02 84 08
2017-02-05 16:22:57.919 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 03 0A 00 30
2017-02-05 16:22:57.919 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=160, expected=SendData, cancelled=false MISMATCH
2017-02-05 16:22:58.057 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0F 06 31 05 01 22 01 13 FB
2017-02-05 16:22:58.061 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:58.064 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 0F 06 31 05 01 22 01 13 FB
2017-02-05 16:22:58.065 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 0F 06 31 05 01 22 01 13 FB
2017-02-05 16:22:58.068 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 01 22 01 13
2017-02-05 16:22:58.068 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Application Command Request (ALIVE:DONE)
2017-02-05 16:22:58.069 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:58.070 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:58.071 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Incoming command class SENSOR_MULTILEVEL
2017-02-05 16:22:58.072 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
2017-02-05 16:22:58.072 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Multi Level REPORT received
2017-02-05 16:22:58.073 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Type = Temperature(1), Scale = 0
2017-02-05 16:22:58.074 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Value = 27.5
2017-02-05 16:22:58.075 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:58.075 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:58.076 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 27.5
2017-02-05 16:22:58.078 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=160, payload=0F 02 84 08
2017-02-05 16:22:58.080 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 01 22 01 13
2017-02-05 16:22:58.081 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=160, expected=SendData, cancelled=false MISMATCH
2017-02-05 16:22:58.392 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 0F 06 31 05 01 22 01 05 ED
2017-02-05 16:22:58.395 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-02-05 16:22:58.397 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 0F 06 31 05 01 22 01 05 ED
2017-02-05 16:22:58.400 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 0F 06 31 05 01 22 01 05 ED
2017-02-05 16:22:58.402 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 01 22 01 05
2017-02-05 16:22:58.403 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Application Command Request (ALIVE:DONE)
2017-02-05 16:22:58.403 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 15: Starting initialisation from DONE
2017-02-05 16:22:58.404 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@9e4e0e already registered
2017-02-05 16:22:58.405 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 15: Incoming command class SENSOR_MULTILEVEL
2017-02-05 16:22:58.406 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
2017-02-05 16:22:58.406 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Multi Level REPORT received
2017-02-05 16:22:58.407 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Type = Temperature(1), Scale = 0
2017-02-05 16:22:58.408 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 15: Sensor Value = 26.1
2017-02-05 16:22:58.409 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:58.409 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
2017-02-05 16:22:58.410 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 15: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_MULTILEVEL, value = 26.1
2017-02-05 16:22:58.412 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=15, callback=160, payload=0F 02 84 08
2017-02-05 16:22:58.414 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0F 06 31 05 01 22 01 05
2017-02-05 16:22:58.415 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=160, expected=SendData, cancelled=false MISMATCH

Gerd_Sender · April 14, 2017, 10:25am

@FraFis: Adding the Fibaro Motion Sensor “out of the box” produced an “unknown” thing. So I removed it (Paper UI delete and three button presses on the sensor)

This worked: resetting the sensor (pressing the button until the eye gets yellow), then I searched it with Paper UI inbox. After that I had a sensor in the inbox and added it as a thing. It then showed all channels.

But it does not report anything. All items created show NaN or nothing. No events show in the log.

I found this in the log:

2017-04-14 11:32:07.136 [INFO ] [ome.io.rest.core.thing.ThingResource] - Received HTTP PUT request for update config at 'things/zwave:device:92397a03:node2/config' for the unknown thing 'zwave:device:92397a03:node2'.

2

Paper UI Error
I tried to change parameter 1 to 32 (someone reported this did help) from the Paper UI. This did not work. I found this error in the log:

2017-04-14 11:19:39.640 [INFO ] [ome.io.rest.core.thing.ThingResource] - Received HTTP PUT request for update config at 'things/zwave:device:92397a03:node2/config' for the unknown thing 'zwave:device:92397a03:node2'.
2017-04-14 11:19:39.716 [ERROR] [ome.core.thing.internal.ThingManager] - Exception occured while calling thing updated at ThingHandler 'org.openhab.binding.zwave.handler.ZWaveThingHandler@c939f0': java.lang.NullPointerException
java.util.concurrent.ExecutionException: java.lang.NullPointerException
	at java.util.concurrent.FutureTask.report(FutureTask.java:122)[:1.8.0_121]
	at java.util.concurrent.FutureTask.get(FutureTask.java:206)[:1.8.0_121]
	at org.eclipse.smarthome.core.common.SafeMethodCaller.callAsynchronous(SafeMethodCaller.java:194)
	at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:83)
	at org.eclipse.smarthome.core.common.SafeMethodCaller.call(SafeMethodCaller.java:67)
	at org.eclipse.smarthome.core.thing.internal.ThingManager.thingUpdated(ThingManager.java:506)
	at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.notifyTrackers(ThingRegistryImpl.java:227)
	at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.notifyListenersAboutUpdatedElement(ThingRegistryImpl.java:150)
	at org.eclipse.smarthome.core.thing.internal.ThingRegistryImpl.notifyListenersAboutUpdatedElement(ThingRegistryImpl.java:1)
	at org.eclipse.smarthome.core.common.registry.AbstractRegistry.updated(AbstractRegistry.java:167)
	at org.eclipse.smarthome.core.common.registry.AbstractProvider.notifyListeners(AbstractProvider.java:57)
	at org.eclipse.smarthome.core.common.registry.AbstractProvider.notifyListenersAboutUpdatedElement(AbstractProvider.java:82)
	at org.eclipse.smarthome.core.common.registry.AbstractManagedProvider.update(AbstractManagedProvider.java:134)
	at org.eclipse.smarthome.io.rest.core.thing.ThingResource.update(ThingResource.java:422)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)[:1.8.0_121]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)[:1.8.0_121]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)[:1.8.0_121]
	at java.lang.reflect.Method.invoke(Method.java:498)[:1.8.0_121]
	at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:315)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:297)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.internal.Errors.process(Errors.java:267)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317)[158:org.glassfish.jersey.core.jersey-common:2.22.2]
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)[159:org.glassfish.jersey.core.jersey-server:2.22.2]
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228)[156:org.glassfish.jersey.containers.jersey-container-servlet-core:2.22.2]
	at com.eclipsesource.jaxrs.publisher.internal.ServletContainerBridge.service(ServletContainerBridge.java:76)[10:com.eclipsesource.jaxrs.publisher:5.3.1.201602281253]
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:812)[82:org.eclipse.jetty.servlet:9.2.19.v20160908]
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:587)[82:org.eclipse.jetty.servlet:9.2.19.v20160908]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceServletHandler.doHandle(HttpServiceServletHandler.java:71)[173:org.ops4j.pax.web.pax-web-jetty:4.3.0]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:577)[80:org.eclipse.jetty.security:9.2.19.v20160908]
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:223)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.ops4j.pax.web.service.jetty.internal.HttpServiceContext.doHandle(HttpServiceContext.java:287)[173:org.ops4j.pax.web.pax-web-jetty:4.3.0]
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)[82:org.eclipse.jetty.servlet:9.2.19.v20160908]
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.ops4j.pax.web.service.jetty.internal.JettyServerHandlerCollection.handle(JettyServerHandlerCollection.java:80)[173:org.ops4j.pax.web.pax-web-jetty:4.3.0]
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.Server.handle(Server.java:499)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:311)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)[81:org.eclipse.jetty.server:9.2.19.v20160908]
	at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:544)[72:org.eclipse.jetty.io:9.2.19.v20160908]
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)[84:org.eclipse.jetty.util:9.2.19.v20160908]
	at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)[84:org.eclipse.jetty.util:9.2.19.v20160908]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
Caused by: java.lang.NullPointerException
	at org.openhab.binding.zwave.handler.ZWaveThingHandler.updateNodeProperties(ZWaveThingHandler.java:1401)[186:org.openhab.binding.zwave:2.1.0.201703310852]
	at org.openhab.binding.zwave.handler.ZWaveThingHandler.bridgeStatusChanged(ZWaveThingHandler.java:457)[186:org.openhab.binding.zwave:2.1.0.201703310852]
	at org.openhab.binding.zwave.handler.ZWaveThingHandler.initialize(ZWaveThingHandler.java:150)[186:org.openhab.binding.zwave:2.1.0.201703310852]
	at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.thingUpdated(BaseThingHandler.java:192)[106:org.eclipse.smarthome.core.thing:0.9.0.201703201701]
	at org.eclipse.smarthome.core.thing.internal.ThingManager$7.call(ThingManager.java:510)[106:org.eclipse.smarthome.core.thing:0.9.0.201703201701]
	at org.eclipse.smarthome.core.thing.internal.ThingManager$7.call(ThingManager.java:1)[106:org.eclipse.smarthome.core.thing:0.9.0.201703201701]
	at org.eclipse.smarthome.core.common.SafeMethodCaller$CallableWrapper.call(SafeMethodCaller.java:181)[99:org.eclipse.smarthome.core:0.9.0.201703201701]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	... 1 more

Changing the parameter with HABMin seems to work.

Still OpenHAB does not show any values from the sensor. Any suggestions what I could try? Thanks.

sihui · April 14, 2017, 2:50pm

I think you messed up your config:

You deleted the thing from openHAB, not the sensor from the controller.

This added the sensor again to your controller, because that performed a new network inclusion, but because the “old” sensor is still referenced in openHAB, you get that error message.

You have to remove all the old references from your first attempt (things, channels, items), then start new with the newly included sensor, add the thing, bind your items to the available channels.

Gerd_Sender · April 16, 2017, 9:52am

@sihui: I think everything worked after the second try. It just took some time for the values (temperature, lux) to show up. Pressing the button made the values immeadiatly available. The motion detection does not show up in the Paper UI, but I expected this.

Summary:

Rresetting the Firbaro Motion Sensor first and then adding it was successful.
When the sensor shows up as unknown: first removing it from the network (with three button presses) and second deleting all reference in Paper UI and third adding it again showed all channels.

How to see motion detection: add a channel for motion detection and then look into the events.log.

tail -f /var/log/openhab2/events.log

ohmicha · June 5, 2017, 10:59am

I found this topic right after I described my own similar problems. Did anyone got the sensor working as expected?

sihui · June 5, 2017, 11:03am

Yes, works since almost three years with all available zwave bindings.

Wake the device up (press the inside button three times quickly) and wait for completing initialization. Do that in close range to the controller.

ohmicha · June 5, 2017, 11:17am

Thank you for the tip, I woke up the device and corresponding to the initialization was completed

I am not able to paste or upload full log here, is there anything in the log that I can look for?

13:05:03.601 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 1D 00 49 84 02 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A C7 
13:05:03.602 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.602 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 1D 00 49 84 02 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A C7 
13:05:03.603 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 1D 00 49 84 02 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A C7 
13:05:03.603 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 02 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A 
13:05:03.603 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 2: Application update request. Node information received.
13:05:03.604 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.604 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.604 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 2: Is awake with 6 messages in the wake-up queue.
13:05:03.604 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
13:05:03.605 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
13:05:03.605 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
13:05:03.605 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=226, payload=02 02 84 08 
13:05:03.605 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 30 02 25 E9 1B 
13:05:03.606 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 30 02 25 E9 1B 
13:05:03.606 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationUpdate[0x49], type=Request[0x00], priority=High, dest=255, callback=0, payload=84 02 17 04 07 01 5E 20 86 72 5A 59 85 73 84 80 71 56 70 31 8E 22 30 9C 98 7A 
13:05:03.606 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationUpdate, callback id=226, expected=SendData, cancelled=false      MISMATCH
13:05:03.614 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
13:05:03.614 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.614 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
13:05:03.615 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
13:05:03.615 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
13:05:03.615 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
13:05:03.629 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 E9 00 00 02 00 
13:05:03.630 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.630 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 E9 00 00 02 00 00 0E 
13:05:03.631 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 E9 00 00 02 00 00 0E 
13:05:03.631 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=E9 00 00 02 
13:05:03.631 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 233, Status = Transmission complete and ACK received(0)
13:05:03.631 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.632 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.632 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=233, payload=02 02 30 02 
13:05:03.632 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=E9 00 00 02 
13:05:03.632 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=233, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
13:05:03.641 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 30 03 FF 3F 
13:05:03.641 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.642 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 30 03 FF 3F 
13:05:03.642 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 30 03 FF 3F 
13:05:03.642 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 30 03 FF 
13:05:03.642 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
13:05:03.643 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.643 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.643 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SENSOR_BINARY
13:05:03.643 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Received SENSOR_BINARY command V1
13:05:03.643 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Sensor Binary report, type=Unknown, value=255
13:05:03.644 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveBinarySensorValueEvent
13:05:03.644 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=233, payload=02 02 30 02 
13:05:03.644 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 30 03 FF 
13:05:03.644 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=233, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
13:05:03.645 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
13:05:03.645 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
13:05:03.645 [DEBUG] [curityCommandClassWithInitialization] - NODE 2: updating lastSentMessageTimestamp
13:05:03.645 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 39ms/1664ms.
13:05:03.646 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
13:05:03.646 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 01 00 25 EA 1A 
13:05:03.646 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 01 00 25 EA 1A 
13:05:03.654 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
13:05:03.654 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.655 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
13:05:03.655 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
13:05:03.656 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
13:05:03.656 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
13:05:03.670 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 EA 00 00 02 03 
13:05:03.671 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.671 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 EA 00 00 02 00 00 0D 
13:05:03.672 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 EA 00 00 02 00 00 0D 
13:05:03.672 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=EA 00 00 02 
13:05:03.672 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 234, Status = Transmission complete and ACK received(0)
13:05:03.672 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.673 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.673 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=234, payload=02 04 31 04 01 00 
13:05:03.673 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=EA 00 00 02 
13:05:03.674 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=234, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
13:05:03.682 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 02 06 31 05 01 22 00 E8 0C 
13:05:03.682 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.683 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 04 00 02 06 31 05 01 22 00 E8 0C 
13:05:03.683 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0C 00 04 00 02 06 31 05 01 22 00 E8 0C 
13:05:03.684 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 06 31 05 01 22 00 E8 
13:05:03.684 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
13:05:03.684 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.684 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.684 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SENSOR_MULTILEVEL
13:05:03.685 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
13:05:03.685 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Multi Level REPORT received
13:05:03.685 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Temperature(1), Scale = 0
13:05:03.685 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 23.2
13:05:03.686 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
13:05:03.686 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=234, payload=02 04 31 04 01 00 
13:05:03.686 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 06 31 05 01 22 00 E8 
13:05:03.686 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=234, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
13:05:03.687 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
13:05:03.687 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
13:05:03.687 [DEBUG] [curityCommandClassWithInitialization] - NODE 2: updating lastSentMessageTimestamp
13:05:03.687 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Response processed after 40ms/1664ms.
13:05:03.687 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
13:05:03.688 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 02 04 31 04 19 00 25 EB 03 
13:05:03.688 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0B 00 13 02 04 31 04 19 00 25 EB 03 
13:05:03.696 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
13:05:03.696 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.697 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
13:05:03.697 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
13:05:03.697 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
13:05:03.698 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
13:05:03.712 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 EB 00 00 03 03 
13:05:03.713 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
13:05:03.713 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 EB 00 00 03 00 00 0D 
13:05:03.713 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 EB 00 00 03 00 00 0D 
13:05:03.714 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=EB 00 00 03 
13:05:03.714 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 235, Status = Transmission complete and ACK received(0)
13:05:03.714 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:03.715 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:03.715 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=235, payload=02 04 31 04 19 00 
13:05:03.715 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=EB 00 00 03 
13:05:03.715 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=235, expected=ApplicationCommandHandler, cancelled=false      MISMATCH

13:05:46.604 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
13:05:46.604 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@269bc29c already registered
13:05:46.605 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SENSOR_MULTILEVEL
13:05:46.605 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Received COMMAND_CLASS_SENSOR_MULTILEVEL command V8
13:05:46.605 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Multi Level REPORT received
13:05:46.606 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Type = Luminance(3), Scale = 1
13:05:46.606 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 2: Sensor Value = 13
13:05:46.606 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveMultiLevelSensorValueEvent
13:05:46.607 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=240, payload=02 02 84 08 
13:05:46.608 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 06 31 05 03 0A 00 0D 
13:05:46.608 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=240, expected=SendData, cancelled=false      MISMATCH

but still the motion alarm is not sent - even as it is detected (sensor flashing) - and the values for temperature and battery are still empty.

sihui · June 5, 2017, 11:27am

Load your debug log into the Zwave log viewer:
http://www.cd-jackson.com/index.php/openhab/zwave-log-viewer
it’s much easier to read:

Actually it looks fine, data is coming in.

Verify you have the correct channels linked to your items:

Number	FibEye1_Battery	"Motion Sensor: [%d %%]"	<battery>	(gBattery,gInfluxdb) {channel="zwave:device:158b44ebf65:node3:battery-level"}
Switch	FibEye1_Motion_S	"Motion Hallway up [%s]" <motion> (gRestore) {channel="zwave:device:158b44ebf65:node3:sensor_binary"}

ohmicha · June 5, 2017, 11:46am

OK thank you, great tool!
I removed all my items and recreated them, then I woke up device again as you described. So now at least values for all sensors are shown and the motion alert is fired. I will observe if they are updated as expected.

chimera · July 11, 2017, 5:40am

I have the same problems as others have had in this post. I’ve followed the same steps, but cannot get events logging for motion. I had to remove from PaperUI, remove from ZWave stick, then include again for it to show up correctly. If I edit the thing in PaperUI, then change say, parameter 1 to 30, then click to Save, it says at the bottom right of the GUI:

"Thing updated"
and then 1 second later:
“HTTP: 500 - Internal Server Error”

and in the openhab.log I get:

2017-07-11 17:26:46.905 [ERROR] [ome.io.rest.core.thing.ThingResource] - Exception during HTTP PUT request for update config at ‘things/zwave:device:fbcf2efa:node13/config’ for thing ‘zwave:device:fbcf2efa:node13’: For input string: “org.openhab.binding.zwave.internal.protocol.ZWaveAssociationGroup@55f01b”

I’ve been going around in circles with this for ages and am now at a loss on what to do next. I don’t think I need to exclude/include again, as it picks it up with all the parameters fine in PaperUI.

chris · July 11, 2017, 6:31am

I would strongly recommend not to use PaperUI for configuring ZWave - it does a number of strange things and doesn’t handle some constructs properly.