Z-Wave Aeotec ZW120-C not working with OH2 (no status but log entries)

tomjones · March 26, 2017, 10:05am

Dear all,

I bought an Aeotec ZW120-C and ZME_UZB1 controller on Friday, tried it out the whole Saturday but I have a lot of issues to get the items updated with the correct status.

Does anybody has a runnig setup with the ZW120-C?
@chris you wrote in another post that your ZW120-C is working fine, isn’t it?

My problems and know issues:

Burglar alarm events are triggered and displayed at any time in the openhab.log but the item “Window_Alarm” never gets any status (state update)
The window/door status update worked today suddenly for 5 minutes after I came back from breakfast. Nothing was changed in the configuration. Now, it’s no longer working.
Currently, when I move the magnet away and towards the sensor no more entries where logged (but the burglar alarm events are sill logged)
Battery status is only updated when I release the burglar contact several times

Setup:

Openhab 2.0.0-1 stable
Raspberry PI 3 Model B
Z-Wave ZME_UZB1
1x Aeotec ZW120-C (Door/Window Sensor GEN5)

This is how my _default.items looks like:

Switch Window "Fenster [%s]" {channel="zwave:device:f2fd2007:node2:sensor_binary"}
Switch Window_Alarm "Alarm [%s]" {channel="zwave:device:f2fd2007:node2:alarm_general"}
Number Window_Battery "Batterie [%d %%]" <battery> {channel="zwave:device:f2fd2007:node2:battery-level"}

And here is my _default.sitemap:

Group item=Living2 label="Wohnzimmer NEW" icon="sofa" {
			Frame label="Heizung" {
				Text item=Heating_Living_Temperature label="Ist-Temperatur"
				Text item=Heating_Living_Temperature_Target label="Soll-Temperatur"
				Setpoint item=Heating_Living_Temperature_Target_Comfort label="Komfort-Temperatur" step=0.5 minValue=18 maxValue=23
				Selection item=Heating_Living_Mode label="Modus" icon=heatingmode mappings=[1="Komfort", 2="Standby", 3="Nacht", 4="Frost"]
			}
			Frame label="Sensoren" {
				Text item=Window
				Text item=Window_Alarm
				Text item=Window_Battery
			}
			
		}

This is my openhab.log after I pressed the burglar alarm:

==> /var/log/openhab2/openhab.log <==
2017-03-26 09:42:42.066 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 00 00 00 6F 
2017-03-26 09:42:42.073 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:42:42.077 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 00 00 00 6F 
2017-03-26 09:42:42.080 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 00 00 00 6F 
2017-03-26 09:42:42.083 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 00 00 00 
2017-03-26 09:42:42.085 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 09:42:42.086 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 09:42:42.088 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 09:42:42.089 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class ALARM
2017-03-26 09:42:42.090 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Received ALARM command V4
2017-03-26 09:42:42.091 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Process NOTIFICATION_REPORT V4
2017-03-26 09:42:42.092 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: NOTIFICATION report - 0 = 0, event=0, status=255
2017-03-26 09:42:42.093 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Alarm Type = BURGLAR (0)
2017-03-26 09:42:42.094 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2017-03-26 09:42:42.096 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2017-03-26 09:42:42.097 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
2017-03-26 09:42:42.098 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
2017-03-26 09:42:42.100 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=162, payload=02 02 84 08 
2017-03-26 09:42:42.104 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 00 00 00 
2017-03-26 09:42:42.105 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=162, expected=SendData, cancelled=false      MISMATCH

And after I released the Burglar alarm:

==> /var/log/openhab2/openhab.log <==
2017-03-26 09:43:14.791 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 03 00 00 6C 
2017-03-26 09:43:14.797 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:43:14.801 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 03 00 00 6C 
2017-03-26 09:43:14.804 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 02 0A 71 05 00 00 00 FF 07 03 00 00 6C 
2017-03-26 09:43:14.808 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 03 00 00 
2017-03-26 09:43:14.809 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 09:43:14.811 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 09:43:14.813 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 09:43:14.815 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class ALARM
2017-03-26 09:43:14.817 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Received ALARM command V4
2017-03-26 09:43:14.818 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Process NOTIFICATION_REPORT V4
2017-03-26 09:43:14.820 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: NOTIFICATION report - 0 = 0, event=3, status=255
2017-03-26 09:43:14.825 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 2: Alarm Type = BURGLAR (0)
2017-03-26 09:43:14.827 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
2017-03-26 09:43:14.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2017-03-26 09:43:14.829 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
2017-03-26 09:43:14.831 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
2017-03-26 09:43:14.834 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=162, payload=02 02 84 08 
2017-03-26 09:43:14.837 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 0A 71 05 00 00 00 FF 07 03 00 00 
2017-03-26 09:43:14.838 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=162, expected=SendData, cancelled=false      MISMATCH

This is the openhab.log where the battery status update worked:

2017-03-26 09:32:28.432 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 02 02 84 07 70 
2017-03-26 09:32:28.437 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:32:28.441 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 02 02 84 07 70 
2017-03-26 09:32:28.444 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 02 02 84 07 70 
2017-03-26 09:32:28.446 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 02 84 07 
2017-03-26 09:32:28.449 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 09:32:28.450 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 09:32:28.452 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 09:32:28.454 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class WAKE_UP
2017-03-26 09:32:28.456 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 2: Received Wake Up Request
2017-03-26 09:32:28.457 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 2: Received WAKE_UP_NOTIFICATION
2017-03-26 09:32:28.459 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 2: Is awake with 2 messages in the wake-up queue.
2017-03-26 09:32:28.462 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveWakeUpEvent
2017-03-26 09:32:28.464 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveWakeUpEvent
2017-03-26 09:32:28.470 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-03-26 09:32:28.470 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-03-26 09:32:28.474 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Message has Ack Pending: Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=156, payload=02 05 71 04 00 00 00 
2017-03-26 09:32:28.474 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 80 02 25 9D DF 
2017-03-26 09:32:28.480 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 80 02 25 9D DF 
2017-03-26 09:32:28.493 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2017-03-26 09:32:28.498 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:32:28.501 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2017-03-26 09:32:28.504 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2017-03-26 09:32:28.506 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2017-03-26 09:32:28.509 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
2017-03-26 09:32:28.510 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 9D 00 00 02 74 
2017-03-26 09:32:28.514 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:32:28.518 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 9D 00 00 02 00 00 7A 
2017-03-26 09:32:28.521 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 9D 00 00 02 00 00 7A 
2017-03-26 09:32:28.522 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 80 03 64 14 
2017-03-26 09:32:28.524 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=9D 00 00 02 
2017-03-26 09:32:28.526 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 157, Status = Transmission complete and ACK received(0)
2017-03-26 09:32:28.528 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 09:32:28.530 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 09:32:28.533 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=157, payload=02 02 80 02 
2017-03-26 09:32:28.535 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=9D 00 00 02 
2017-03-26 09:32:28.536 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=157, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2017-03-26 09:32:28.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 09:32:28.538 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 80 03 64 14 
2017-03-26 09:32:28.540 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 80 03 64 14 
2017-03-26 09:32:28.542 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 80 03 64 
2017-03-26 09:32:28.543 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 09:32:28.544 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 09:32:28.546 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 09:32:28.547 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class BATTERY
2017-03-26 09:32:28.548 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 2: Received Battery Request
2017-03-26 09:32:28.549 [DEBUG] [ommandclass.ZWaveBatteryCommandClass] - NODE 2: Battery report value = 100
2017-03-26 09:32:28.550 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-03-26 09:32:28.552 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-03-26 09:32:28.553 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = BATTERY, value = 100
2017-03-26 09:32:28.554 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:f2fd2007:node2:battery-level to 100 [DecimalType]
2017-03-26 09:32:28.559 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=157, payload=02 02 80 02 

==> /var/log/openhab2/events.log <==
2017-03-26 09:32:28.561 [ItemStateChangedEvent     ] - Window_Battery changed from NULL to 100

Here is the log where the contact sensor worked the first time:

==> /var/log/openhab2/openhab.log <==
2017-03-26 08:52:38.669 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-03-26 08:52:38.669 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2017-03-26 08:52:38.671 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 02 02 30 02 25 69 9B 
2017-03-26 08:52:38.672 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 09 00 13 02 02 30 02 25 69 9B 
2017-03-26 08:52:38.670 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2017-03-26 08:52:38.677 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2017-03-26 08:52:38.683 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2017-03-26 08:52:38.685 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 08:52:38.686 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2017-03-26 08:52:38.687 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2017-03-26 08:52:38.688 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2017-03-26 08:52:38.689 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: Sent Data successfully placed on stack.
2017-03-26 08:52:38.698 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 69 00 00 02 80 
2017-03-26 08:52:38.700 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 08:52:38.701 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 69 00 00 02 00 00 8E 
2017-03-26 08:52:38.702 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 69 00 00 02 00 00 8E 
2017-03-26 08:52:38.704 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=69 00 00 02 
2017-03-26 08:52:38.705 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 105, Status = Transmission complete and ACK received(0)
2017-03-26 08:52:38.706 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 08:52:38.707 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 08:52:38.708 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=105, payload=02 02 30 02 
2017-03-26 08:52:38.709 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=69 00 00 02 
2017-03-26 08:52:38.710 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=105, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2017-03-26 08:52:38.711 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 30 03 FF 3F 
2017-03-26 08:52:38.713 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 08:52:38.714 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 30 03 FF 3F 
2017-03-26 08:52:38.715 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 30 03 FF 3F 
2017-03-26 08:52:38.716 [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 
2017-03-26 08:52:38.717 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 08:52:38.718 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 08:52:38.719 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 08:52:38.719 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class SENSOR_BINARY
2017-03-26 08:52:38.720 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Received SENSOR_BINARY command V1
2017-03-26 08:52:38.721 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 2: Sensor Binary report, type=Unknown, value=255
2017-03-26 08:52:38.722 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveBinarySensorValueEvent
2017-03-26 08:52:38.723 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2017-03-26 08:52:38.724 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = SENSOR_BINARY, value = 255
2017-03-26 08:52:38.725 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:f2fd2007:node2:sensor_binary to ON [OnOffType]
2017-03-26 08:52:38.727 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=2, callback=105, payload=02 02 30 02 

==> /var/log/openhab2/events.log <==
2017-03-26 08:52:38.733 [ItemStateChangedEvent     ] - Window changed from NULL to ON

And here is the log where the status change from ON to OFF and vice versa worked for the first time:

==> /var/log/openhab2/openhab.log <==
2017-03-26 08:52:52.614 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 02 03 20 01 00 D2 
2017-03-26 08:52:52.616 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2017-03-26 08:52:52.617 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 02 03 20 01 00 D2 
2017-03-26 08:52:52.618 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 02 03 20 01 00 D2 
2017-03-26 08:52:52.619 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 01 00 
2017-03-26 08:52:52.620 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Application Command Request (ALIVE:DONE)
2017-03-26 08:52:52.620 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 2: Starting initialisation from DONE
2017-03-26 08:52:52.621 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1983315 already registered
2017-03-26 08:52:52.622 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 2: Incoming command class BASIC
2017-03-26 08:52:52.623 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Received Basic Request
2017-03-26 08:52:52.623 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Basic Set sent to the controller will be processed as Basic Report
2017-03-26 08:52:52.624 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 2: Basic report, value = 0x00
2017-03-26 08:52:52.624 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2017-03-26 08:52:52.625 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2017-03-26 08:52:52.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
2017-03-26 08:52:52.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Updating channel state zwave:device:f2fd2007:node2:sensor_binary to OFF [OnOffType]
2017-03-26 08:52:52.628 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Immediate, dest=2, callback=113, payload=02 02 84 08 
2017-03-26 08:52:52.631 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 02 03 20 01 00 

==> /var/log/openhab2/events.log <==
2017-03-26 08:52:52.632 [ItemStateChangedEvent     ] - Windows changed from ON to OFF

mathknop · November 22, 2017, 10:58pm

Did you fix this? I have the same problem with this burglar alarm firing an event but not changing my alarm status…

tomjones · April 29, 2018, 2:41pm

Sorry, I didn’t get a notification with your question.
I had no change to fix this issue.
I sent back all my Z-Wave equipment and didn’t try it again with other hardware.

Were you able to fix your issue in the meantime?