Hi all,
I have a Vision ZD2102-5 (gen 5, z-wave plus) device which I have integrated with openHAB and I’m having a problem with the state of the sensor as it’s only reporting one state - closed.
2.4.0.M3 - ZWave Binding / openHAB Core
The device has the default configuration - there isn’t much to configure with these - but I’m not getting a satisfactory response from it.
I have tried excluding it and re-adding it from the controller, I have reset the device and I have also manually created items for it to see if I had a problem with the mapping of the automatically created items via PaperUI. But, none of this has helped.
The problem looks similar to this problem, but it’s not a dead-ringer.
When I pull the debug data from a manual open-close cycle of the device and input it into the z-wave log viewer, I can see the basic-set alarm-report being sent for the device opening and a v1level of 0xFF (255), but there is no corresponding state-update command being created.
When the device is closed, again I can see the basic-set alarm-report but with a v1level of 0x00 (0), but it’s followed by state-update CLOSED command.
Here’s the debug file with the open event at 21:34:13 and the corresponding close-event at 21:34:18…
21:34:13.624 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 0C 03 20 01 FF 23
21:34:13.628 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
21:34:13.632 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 0C 03 20 01 FF 23
21:34:13.634 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 0C 03 20 01 FF 23
21:34:13.636 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 03 20 01 FF
21:34:13.638 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
21:34:13.640 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
21:34:13.642 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1565ee1e already registered
21:34:13.644 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Incoming command class BASIC
21:34:13.644 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Received Basic Request
21:34:13.645 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Basic Set sent to the controller will be processed as Basic Report
21:34:13.646 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Basic report, value = 0xFF
21:34:13.647 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
21:34:13.648 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
21:34:13.649 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
21:34:13.650 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
21:34:13.651 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 255
21:34:13.652 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
21:34:13.807 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0C 0A 71 05 06 FF 00 FF 06 16 00 00 8F
21:34:13.809 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
21:34:13.811 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 0C 0A 71 05 06 FF 00 FF 06 16 00 00 8F
21:34:13.812 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 0C 0A 71 05 06 FF 00 FF 06 16 00 00 8F
21:34:13.813 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 0A 71 05 06 FF 00 FF 06 16 00 00
21:34:13.814 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
21:34:13.816 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
21:34:13.817 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1565ee1e already registered
21:34:13.819 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Incoming command class ALARM
21:34:13.820 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Received ALARM command V4
21:34:13.821 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Process NOTIFICATION_REPORT V4
21:34:13.822 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: NOTIFICATION report - 6 = 255, event=22, status=255
21:34:13.824 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Alarm Type = ACCESS_CONTROL (6)
21:34:13.825 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
21:34:13.826 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:13.828 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:13.829 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:13.831 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 22, type OpenClosedType
21:34:13.832 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:13.834 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:13.835 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:13.836 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 22, type OpenClosedType
21:34:13.837 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
21:34:18.301 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 0C 03 20 01 00 DC
21:34:18.304 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
21:34:18.307 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 04 00 0C 03 20 01 00 DC
21:34:18.308 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 09 00 04 00 0C 03 20 01 00 DC
21:34:18.312 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 03 20 01 00
21:34:18.314 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
21:34:18.315 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
21:34:18.316 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1565ee1e already registered
21:34:18.318 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Incoming command class BASIC
21:34:18.319 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Received Basic Request
21:34:18.321 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Basic Set sent to the controller will be processed as Basic Report
21:34:18.323 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 12: Basic report, value = 0x00
21:34:18.324 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
21:34:18.325 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
21:34:18.326 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
21:34:18.327 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
21:34:18.328 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = BASIC, value = 0
21:34:18.329 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
21:34:18.484 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.485 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
21:34:18.487 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.491 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.493 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 0A 71 05 06 00 00 FF 06 17 00 00
21:34:18.495 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
21:34:18.496 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
21:34:18.496 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1565ee1e already registered
21:34:18.497 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Incoming command class ALARM
21:34:18.498 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Received ALARM command V4
21:34:18.499 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Process NOTIFICATION_REPORT V4
21:34:18.500 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: NOTIFICATION report - 6 = 0, event=23, status=255
21:34:18.501 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Alarm Type = ACCESS_CONTROL (6)
21:34:18.502 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
21:34:18.503 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:18.504 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:18.506 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:18.507 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 23, type OpenClosedType
21:34:18.508 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION 3
21:34:18.510 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:c7937d7e:node12:sensor_door to CLOSED [OpenClosedType]
21:34:18.512 [WARN ] [e.core.thing.binding.BaseThingHandler] - Handler ZWaveThingHandler of thing zwave:device:c7937d7e:node12 tried updating channel sensor_door although the handler was already disposed.
21:34:18.513 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:18.514 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:18.516 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:18.517 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 23, type OpenClosedType
21:34:18.519 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION 3
21:34:18.520 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:c7937d7e:node12:sensor_door to CLOSED [OpenClosedType]
21:34:18.522 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
21:34:18.674 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.677 [DEBUG] [ave.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
21:34:18.679 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.680 [DEBUG] [ave.internal.protocol.ZWaveController] - Process Message = 01 10 00 04 00 0C 0A 71 05 06 00 00 FF 06 17 00 00 71
21:34:18.682 [DEBUG] [ave.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 0C 0A 71 05 06 00 00 FF 06 17 00 00
21:34:18.683 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Application Command Request (ALIVE:DONE)
21:34:18.684 [DEBUG] [ialization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
21:34:18.685 [DEBUG] [ave.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1565ee1e already registered
21:34:18.687 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 12: Incoming command class ALARM
21:34:18.692 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Received ALARM command V4
21:34:18.693 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Process NOTIFICATION_REPORT V4
21:34:18.694 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: NOTIFICATION report - 6 = 0, event=23, status=255
21:34:18.695 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 12: Alarm Type = ACCESS_CONTROL (6)
21:34:18.696 [DEBUG] [ave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveAlarmValueEvent
21:34:18.697 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:18.699 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:18.703 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:18.704 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 23, type OpenClosedType
21:34:18.705 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION 3
21:34:18.706 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:c7937d7e:node12:sensor_door to CLOSED [OpenClosedType]
21:34:18.708 [WARN ] [e.core.thing.binding.BaseThingHandler] - Handler ZWaveThingHandler of thing zwave:device:c7937d7e:node12 tried updating channel sensor_door although the handler was already disposed.
21:34:18.712 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:34:18.713 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Got a value event from Z-Wave network, endpoint = 0, command class = ALARM, value = 255
21:34:18.714 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter processing NOTIFICATION
21:34:18.715 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION event is 23, type OpenClosedType
21:34:18.716 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - Alarm converter NOTIFICATION 3
21:34:18.725 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 12: Updating channel state zwave:device:c7937d7e:node12:sensor_door to CLOSED [OpenClosedType]
21:34:18.728 [DEBUG] [essage.ApplicationCommandMessageClass] - NODE 9: Transaction not completed: node address inconsistent. lastSent=9, incoming=255
Can anybody shed some light on the issue as it’s evading my attempts at prodding it.
Thanks, R.