Light switch (DZ15S ) stops sending updates after a while

Steven_Reimer · May 4, 2018, 2:45am

Using the latest stable build on Ubuntu and Leviton DZ15S switches. I have two lights in a room that are on separate switches so I have a rule to turn the other light on if one is switched on physically. eg. if switch 1 is turned on or off, switch 2 does the same and vise-versa.

This works fine for a while (usually 2-3 weeks) and then the switches stop sending updates when physically switched. If I use the site map the rules work fine. The only way I can get it working again is to exclude both and re-include. I usually factory reset the switches as well.

Has anyone experienced this with a similar setup? I’m wondering if there’s an odd issue with my rule.

Steven_Reimer · May 4, 2018, 3:26am

I just put openHab in debug mode and saw some communication from the switch when physically switching it. But the rule isn’t triggered. I also noticed another switch spamming the network with what looks like repeated initializations. I will focus on getting that switch under control and then maybe things will stabilize.

2018-05-03 22:07:02.871 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.872 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:02.872 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.872 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.872 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 04 22 01 00 00 
2018-05-03 22:07:02.873 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Application Command Request (ALIVE:DONE)
2018-05-03 22:07:02.873 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE
2018-05-03 22:07:02.873 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@67fc1dc7 already registered
2018-05-03 22:07:02.873 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Incoming command class APPLICATION_STATUS
2018-05-03 22:07:02.873 [DEBUG] [andclass.ZWaveApplicationStatusClass] - NODE Node 4. Manufacturer 001D, Type 1B03, Id 0334: Application Status message
2018-05-03 22:07:02.873 [DEBUG] [andclass.ZWaveApplicationStatusClass] - NODE Node 4. Manufacturer 001D, Type 1B03, Id 0334: is busy and will be polled in 2 seconds
2018-05-03 22:07:02.874 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveDelayedPollEvent
2018-05-03 22:07:02.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveDelayedPollEvent
2018-05-03 22:07:02.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling intialised at 1800 seconds - start in 2000 milliseconds.
2018-05-03 22:07:02.874 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=4, callback=149, payload=04 02 26 02 
2018-05-03 22:07:02.874 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 04 22 01 00 00 
2018-05-03 22:07:02.874 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=149, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-03 22:07:02.874 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-03 22:07:02.874 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-03 22:07:02.875 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 4: Response processed after 117ms/5000ms.
2018-05-03 22:07:02.875 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-05-03 22:07:02.875 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 04 02 26 02 25 96 74 
2018-05-03 22:07:02.875 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 26 02 25 96 74 
2018-05-03 22:07:02.893 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-03 22:07:02.896 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:02.896 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-03 22:07:02.897 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-03 22:07:02.897 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-03 22:07:02.897 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: Sent Data successfully placed on stack.
2018-05-03 22:07:02.910 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 2C 02 82 01 5E 
2018-05-03 22:07:02.913 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:02.913 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 08 00 04 00 2C 02 82 01 5E 
2018-05-03 22:07:02.914 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 08 00 04 00 2C 02 82 01 5E 
2018-05-03 22:07:02.914 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 2C 02 82 01 
2018-05-03 22:07:02.914 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 44: Application Command Request (ALIVE:MANUFACTURER)
2018-05-03 22:07:02.914 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 44: Incoming command class HAIL
2018-05-03 22:07:02.915 [DEBUG] [l.commandclass.ZWaveHailCommandClass] - NODE 44: Received Hail command (v0)
2018-05-03 22:07:02.915 [DEBUG] [l.commandclass.ZWaveHailCommandClass] - NODE 44: Request an update of the dynamic values
2018-05-03 22:07:02.915 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Transaction not completed: node address inconsistent.  lastSent=4, incoming=255
2018-05-03 22:07:02.924 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 96 00 7F 
2018-05-03 22:07:02.935 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:02.936 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 13 96 00 00 00 7D 
2018-05-03 22:07:02.936 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 13 96 00 00 00 7D 
2018-05-03 22:07:02.936 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=96 00 
2018-05-03 22:07:02.936 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 150, Status = Transmission complete and ACK received(0)
2018-05-03 22:07:02.936 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE
2018-05-03 22:07:02.936 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@67fc1dc7 already registered
2018-05-03 22:07:02.936 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=4, callback=150, payload=04 02 26 02 
2018-05-03 22:07:02.937 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=96 00 
2018-05-03 22:07:02.937 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=150, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-03 22:07:02.952 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.955 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:02.955 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:02.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 04 22 01 00 00 
2018-05-03 22:07:02.956 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Application Command Request (ALIVE:DONE)
2018-05-03 22:07:02.956 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE
2018-05-03 22:07:02.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@67fc1dc7 already registered
2018-05-03 22:07:02.956 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Incoming command class APPLICATION_STATUS
2018-05-03 22:07:02.956 [DEBUG] [andclass.ZWaveApplicationStatusClass] - NODE Node 4. Manufacturer 001D, Type 1B03, Id 0334: Application Status message
2018-05-03 22:07:02.956 [DEBUG] [andclass.ZWaveApplicationStatusClass] - NODE Node 4. Manufacturer 001D, Type 1B03, Id 0334: is busy and will be polled in 2 seconds
2018-05-03 22:07:02.956 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveDelayedPollEvent
2018-05-03 22:07:02.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveDelayedPollEvent
2018-05-03 22:07:02.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling intialised at 1800 seconds - start in 2000 milliseconds.
2018-05-03 22:07:02.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=4, callback=150, payload=04 02 26 02 
2018-05-03 22:07:02.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 04 22 01 00 00 
2018-05-03 22:07:02.957 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=150, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-03 22:07:02.957 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-03 22:07:02.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-03 22:07:02.958 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 4: Response processed after 82ms/5000ms.
2018-05-03 22:07:04.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling...
2018-05-03 22:07:04.957 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:c1f463e7:node4:switch_dimmer
2018-05-03 22:07:04.958 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 4: Generating poll message for SWITCH_MULTILEVEL, endpoint 0
2018-05-03 22:07:04.958 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_GET
2018-05-03 22:07:04.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:c1f463e7:node4:switch_dimmer
2018-05-03 22:07:04.958 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 4: Generating poll message for SWITCH_MULTILEVEL, endpoint 0
2018-05-03 22:07:04.958 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 4: Creating new message for command SWITCH_MULTILEVEL_GET
2018-05-03 22:07:04.958 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Polling zwave:device:c1f463e7:node4:scene_number
2018-05-03 22:07:04.958 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-05-03 22:07:04.958 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-05-03 22:07:04.958 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-05-03 22:07:04.958 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 04 02 26 02 25 97 75 
2018-05-03 22:07:04.959 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 4: Sending REQUEST Message = 01 09 00 13 04 02 26 02 25 97 75 
2018-05-03 22:07:04.977 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-03 22:07:04.981 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:04.981 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-03 22:07:04.981 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-03 22:07:04.981 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-03 22:07:04.981 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: Sent Data successfully placed on stack.
2018-05-03 22:07:04.997 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 97 00 7E 
2018-05-03 22:07:05.008 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:05.008 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 07 00 13 97 00 00 00 7C 
2018-05-03 22:07:05.009 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 07 00 13 97 00 00 00 7C 
2018-05-03 22:07:05.009 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=97 00 
2018-05-03 22:07:05.010 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 4: SendData Request. CallBack ID = 151, Status = Transmission complete and ACK received(0)
2018-05-03 22:07:05.010 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE
2018-05-03 22:07:05.010 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@67fc1dc7 already registered
2018-05-03 22:07:05.010 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=4, callback=151, payload=04 02 26 02 
2018-05-03 22:07:05.010 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=97 00 
2018-05-03 22:07:05.010 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=151, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-03 22:07:05.104 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:05.106 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-03 22:07:05.107 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:05.107 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 04 04 22 01 00 00 D2 
2018-05-03 22:07:05.107 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 04 04 22 01 00 00 
2018-05-03 22:07:05.107 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 4: Application Command Request (ALIVE:DONE)
2018-05-03 22:07:05.107 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 4: Starting initialisation from DONE