[SOLVED] Z-Wave Lights stopped responding

Setup, Configuration and Use Beginners
1

Running: OH 2.4-Stable
RPi 3 B+
Aeotec Z-Wave stick
openjdk version “1.8.0_152”

I have a rule to turn my garage lights (3) on at dusk. When I came home last night they were not on. HabMin does not show them as not responding so I k now (think?) they are powered.

I can turn them on via the PaperUI and it thinks they are on, but the lights do not actually come on.

The rule has been working just fine for months up until this.

Other rules (triggered and manual) still run fine.

I turned on DEBUG mode and tried to turn on my Garage Lights Group and here is what showed:

2018-12-30 12:32:01.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Command received zwave:device:512:node2:switch_dimmer --> ON [OnOffType]
2018-12-30 12:32:01.330 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_SET
2018-12-30 12:32:01.334 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2018-12-30 12:32:01.336 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-12-30 12:32:01.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2018-12-30 12:32:01.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 8207 to queue - size 3
2018-12-30 12:32:01.352 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 26 01 FF 25 2E 34
2018-12-30 12:32:01.377 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling intialised at 86400 seconds - start in 1500 milliseconds.
2018-12-30 12:32:01.431 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2018-12-30 12:32:01.437 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 8207: Transaction not completed
2018-12-30 12:32:02.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling...
2018-12-30 12:32:02.882 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling zwave:device:512:node2:switch_dimmer
2018-12-30 12:32:02.885 [DEBUG] [erter.ZWaveMultiLevelSwitchConverter] - NODE 2: Generating poll message for COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2018-12-30 12:32:02.887 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 2: Creating new message for command SWITCH_MULTILEVEL_GET
2018-12-30 12:32:02.890 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
2018-12-30 12:32:02.893 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_SWITCH_MULTILEVEL is NOT required to be secured
2018-12-30 12:32:02.896 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Polling skipped for zwave:device:512:node2:switch_dimmer on COMMAND_CLASS_BASIC
2018-12-30 12:32:02.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2018-12-30 12:32:02.903 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 8210 to queue - size 5
2018-12-30 12:32:06.438 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 2: TID 8207: Timeout at state WAIT_REQUEST. 3 retries remaining.
2018-12-30 12:32:06.462 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
2018-12-30 12:32:08.649 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 46, Status = Transmission complete, no ACK received(1)
2018-12-30 12:32:08.658 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-12-30 12:32:08.662 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: CANCEL while sending message. Requeueing - 2 attempts left!
2018-12-30 12:32:08.668 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
2018-12-30 12:32:08.670 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: Added 8207 to queue - size 8
2018-12-30 12:32:08.682 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 26 01 FF 25 2F 35
2018-12-30 12:32:08.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 8207: Transaction not completed
2018-12-30 12:32:08.735 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
2018-12-30 12:32:08.740 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 2: TID 8207: Transaction not completed
2018-12-30 12:32:13.740 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 2: TID 8207: Timeout at state WAIT_REQUEST. 2 retries remaining.
2018-12-30 12:32:13.750 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA

Here is the definition of my lights and groups:

Group allLights
Group outsideLights
Group:Dimmer garageLights

Dimmer sGarageLights  "Garage Lights"  <switch> [ "Lighting" ] // For Alexa
Dimmer sOutdoorLights "Outdoor Lights" <switch> [ "Lighting" ] // For Alexa
Dimmer sDenLights     "Den Lights"     <switch> [ "Lighting" ] // For Alexa


Switch Light_Garage_Side "Garage Side"   <light> (outsideLights, garageLights, allLights)
{
  channel="zwave:device:512:node2:switch_dimmer"
}

Switch Light_Garage_Right "Garage Right"   <light> (outsideLights, garageLights, allLights)
{
  channel="zwave:device:512:node15:switch_dimmer"
}

Switch Light_Garage_Left "Garage Left"   <light> (outsideLights, garageLights, allLights)
{
  channel="zwave:device:512:node16:switch_dimmer"
}

Since I cannot manually trigger the rule, I don’t think this is a problem with the Astro binding.

Anyone ever seen this kind of behavior?

Happy New year!
=C=

2

Can we see the rule?

3 
rule "Dusk, Lights ON"
when
  Channel "astro:sun:local:civilDusk#event" triggered START
then
  garageLights.sendCommand(ON)
  Light_Kitchen_Sink.sendCommand(ON)
  denLights.sendCommand(ON)
end

But since I can’t just turn on the group Garage Lights I don’t think the rule is the problem.

I could be wrong.

Cheers! :slight_smile:
=C=

4

Sure you can…

Change the rule:

rule "Dusk, Lights ON"
when
    Channel "astro:sun:local:civilDusk#event" triggered START or
    Item testSwitch changed to ON or
    Time cron "0 0/5 * ? * * *"
then
    garageLights.sendCommand(ON)
    Light_Kitchen_Sink.sendCommand(ON)
    denLights.sendCommand(ON)
end

Create a testSwitch item and add to sitemap (I have a couple of these for testing)
Or with a cron trigger (This one is every 5 minutes)

5

Sorry, I was unclear. I have the ability to manually turn on the garage lights. Among other things, I have a button on a HABPanel. When I manually trigger the group, OH acts like it has done what I asked (Turn on, Turn off, set to 50%, etc) but the physical lights themselves do not change.

Cheers! :slight_smile:
=C=

6

I don’t know Zwave but have you tried resetting the light?

7

Not yet. That’s my next step. :slight_smile:

=C=

8

This was a problem of my own (actually my wife) making. There are two light switches that turn these lights on and off and while we know not to touch the main one, the secondary accidentally got flipped during holiday cleanup.

It is odd to me that even though the bulbs did not have power, they did not report “NOT RESPONDING” on HabMin or Paper UI. I would have thought if they didn’t check in after 24 hours, that a problem would have been reported.

Anyhow, totes my fault here.

Cheers! :slight_smile:
=C=