Z-wave switch turning off, but not on again

palengh · April 2, 2020, 8:33am

This z-wave switch is switching off, but will not switch on, using the control from the states view.

I would expect the z-wave network to be busy right after start, but now the entire networks has been running overnight, so there should be no stress on the network at this time. I would appreciate if you would help me figuring this out.

The image below was taken when the light is off.

There is one strange issue that could be helping explaining this…
Node 13 has defined two switches in the states view.
Even when flipping the second switch (switch.utelys_inngang_switch_2) nothing happends.

Here are the properties for the node.

Here is the log showing what was going on switching on/off.

2020-04-02 10:06:51.821 Info, Node013, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-04-02 10:06:51.821 Info, Node013, SwitchBinary::Set - Setting node 13 to Off
2020-04-02 10:06:51.821 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x3f, 0xbb
2020-04-02 10:06:51.821 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x40, 0xc5
2020-04-02 10:06:51.821 Detail,
2020-04-02 10:06:51.821 Info, Node013, Sending (Send) message (Callback ID=0x3f, Expected Reply=0x13) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x3f, 0xbb
2020-04-02 10:06:51.832 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:06:51.833 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:06:51.852 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x3f, 0x00, 0x00, 0x03, 0xd7
2020-04-02 10:06:51.852 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x3f received (expected 0x3f)
2020-04-02 10:06:51.852 Info, Node013, Request RTT 30 Average Request RTT 30
2020-04-02 10:06:51.852 Detail,   Expected callbackId was received
2020-04-02 10:06:51.852 Detail,   Expected reply was received
2020-04-02 10:06:51.852 Detail,   Message transaction complete
2020-04-02 10:06:51.852 Detail,
2020-04-02 10:06:51.853 Detail, Node013, Removing current message
2020-04-02 10:06:51.853 Detail,
2020-04-02 10:06:51.853 Info, Node013, Sending (Send) message (Callback ID=0x40, Expected Reply=0x04) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x40, 0xc5
2020-04-02 10:06:51.861 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:06:51.861 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:06:51.888 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x40, 0x00, 0x00, 0x03, 0xa8
2020-04-02 10:06:51.888 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x40 received (expected 0x40)
2020-04-02 10:06:51.888 Info, Node013, Request RTT 35 Average Request RTT 32
2020-04-02 10:06:51.888 Detail,   Expected callbackId was received
2020-04-02 10:07:01.854 Error, Node013, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-02 10:07:01.854 Detail, Node013, Removing current message
2020-04-02 10:07:01.855 Detail, Node013, Notification: Notification - TimeOut
2020-04-02 10:07:10.296 Info, Node013, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-04-02 10:07:10.297 Info, Node013, SwitchBinary::Set - Setting node 13 to Off
2020-04-02 10:07:10.297 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x41, 0xc5
2020-04-02 10:07:10.297 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x42, 0xc7
2020-04-02 10:07:10.298 Detail,
2020-04-02 10:07:10.298 Info, Node013, Sending (Send) message (Callback ID=0x41, Expected Reply=0x13) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x41, 0xc5
2020-04-02 10:07:10.309 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:10.309 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:10.328 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x41, 0x00, 0x00, 0x03, 0xa9
2020-04-02 10:07:10.328 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x41 received (expected 0x41)
2020-04-02 10:07:10.328 Info, Node013, Request RTT 30 Average Request RTT 31
2020-04-02 10:07:10.328 Detail,   Expected callbackId was received
2020-04-02 10:07:10.328 Detail,   Expected reply was received
2020-04-02 10:07:10.328 Detail,   Message transaction complete
2020-04-02 10:07:10.328 Detail,
2020-04-02 10:07:10.328 Detail, Node013, Removing current message
2020-04-02 10:07:10.328 Detail,
2020-04-02 10:07:10.328 Info, Node013, Sending (Send) message (Callback ID=0x42, Expected Reply=0x04) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x42, 0xc7
2020-04-02 10:07:10.338 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:10.338 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:10.359 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x42, 0x00, 0x00, 0x03, 0xaa
2020-04-02 10:07:10.360 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x42 received (expected 0x42)
2020-04-02 10:07:10.360 Info, Node013, Request RTT 30 Average Request RTT 30
2020-04-02 10:07:10.360 Detail,   Expected callbackId was received
2020-04-02 10:07:15.421 Info, Node013, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-04-02 10:07:15.422 Info, Node013, SwitchBinary::Set - Setting node 13 to Off
2020-04-02 10:07:15.422 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x43, 0xc7
2020-04-02 10:07:15.422 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x44, 0xc1
2020-04-02 10:07:20.330 Error, Node013, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-02 10:07:20.330 Detail, Node013, Removing current message
2020-04-02 10:07:20.331 Detail, Node013, Notification: Notification - TimeOut
2020-04-02 10:07:20.336 Detail,
2020-04-02 10:07:20.336 Info, Node013, Sending (Send) message (Callback ID=0x43, Expected Reply=0x13) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x43, 0xc7
2020-04-02 10:07:20.346 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:20.346 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:20.367 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x43, 0x00, 0x00, 0x03, 0xab
2020-04-02 10:07:20.367 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x43 received (expected 0x43)
2020-04-02 10:07:20.367 Info, Node013, Request RTT 31 Average Request RTT 30
2020-04-02 10:07:20.368 Detail,   Expected callbackId was received
2020-04-02 10:07:20.368 Detail,   Expected reply was received
2020-04-02 10:07:20.368 Detail,   Message transaction complete
2020-04-02 10:07:20.368 Detail,
2020-04-02 10:07:20.368 Detail, Node013, Removing current message
2020-04-02 10:07:20.368 Detail,
2020-04-02 10:07:20.368 Info, Node013, Sending (Send) message (Callback ID=0x44, Expected Reply=0x04) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x44, 0xc1
2020-04-02 10:07:20.377 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:20.377 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:20.399 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x44, 0x00, 0x00, 0x03, 0xac
2020-04-02 10:07:20.399 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x44 received (expected 0x44)
2020-04-02 10:07:20.399 Info, Node013, Request RTT 30 Average Request RTT 30
2020-04-02 10:07:20.399 Detail,   Expected callbackId was received
2020-04-02 10:07:20.898 Info, Node013, Value::Set - COMMAND_CLASS_SWITCH_BINARY - Switch - 0 - 1 - False
2020-04-02 10:07:20.898 Info, Node013, SwitchBinary::Set - Setting node 13 to Off
2020-04-02 10:07:20.898 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x45, 0xc1
2020-04-02 10:07:20.899 Detail, Node013, Queuing (Send) MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x46, 0xc3
2020-04-02 10:07:30.369 Error, Node013, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-02 10:07:30.369 Detail, Node013, Removing current message
2020-04-02 10:07:30.369 Detail, Node013, Notification: Notification - TimeOut
2020-04-02 10:07:30.373 Detail,
2020-04-02 10:07:30.373 Info, Node013, Sending (Send) message (Callback ID=0x45, Expected Reply=0x13) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Set (Node=13): 0x01, 0x0e, 0x00, 0x13, 0x0d, 0x07, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x01, 0x00, 0x25, 0x45, 0xc1
2020-04-02 10:07:30.382 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:30.382 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:30.407 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x45, 0x00, 0x00, 0x02, 0xac
2020-04-02 10:07:30.407 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x45 received (expected 0x45)
2020-04-02 10:07:30.407 Info, Node013, Request RTT 33 Average Request RTT 31
2020-04-02 10:07:30.407 Detail,   Expected callbackId was received
2020-04-02 10:07:30.408 Detail,   Expected reply was received
2020-04-02 10:07:30.408 Detail,   Message transaction complete
2020-04-02 10:07:30.408 Detail,
2020-04-02 10:07:30.408 Detail, Node013, Removing current message
2020-04-02 10:07:30.408 Detail,
2020-04-02 10:07:30.408 Info, Node013, Sending (Send) message (Callback ID=0x46, Expected Reply=0x04) - MultiChannel Encapsulated (instance=1): SwitchBinaryCmd_Get (Node=13): 0x01, 0x0d, 0x00, 0x13, 0x0d, 0x06, 0x60, 0x0d, 0x01, 0x01, 0x25, 0x02, 0x25, 0x46, 0xc3
2020-04-02 10:07:30.417 Detail, Node013,   Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2020-04-02 10:07:30.417 Detail, Node013,   ZW_SEND_DATA delivered to Z-Wave stack
2020-04-02 10:07:30.439 Detail, Node013,   Received: 0x01, 0x07, 0x00, 0x13, 0x46, 0x00, 0x00, 0x03, 0xae
2020-04-02 10:07:30.439 Detail, Node013,   ZW_SEND_DATA Request with callback ID 0x46 received (expected 0x46)
2020-04-02 10:07:30.439 Info, Node013, Request RTT 30 Average Request RTT 30
2020-04-02 10:07:30.439 Detail,   Expected callbackId was received
2020-04-02 10:07:40.409 Error, Node013, ERROR: Dropping command, expected response not received after 1 attempt(s)
2020-04-02 10:07:40.410 Detail, Node013, Removing current message
2020-04-02 10:07:40.410 Detail, Node013, Notification: Notification - TimeOut

Canedje · April 2, 2020, 1:42pm

The node id is 13!! that says enough

Did you try to unpair/pair the device again?
Is it part of agood meshnetwork?

palengh · April 2, 2020, 2:11pm

The node has 17 neighbors, and in addition is located 14 cm from the hub.

Unpair/pair…? I have reconnected, waked it (from sleep), but not sure how I can unpair it and make sure it gets the same Node ID after…?

I am not sure if the log tells me anything useful for this matter?

Canedje · April 2, 2020, 2:42pm

use the “remove node” button at zwave section to unpair and “add node” to pair again.
It will recieve a new node id, but is that a problem?

palengh · April 2, 2020, 2:50pm

Not a problem, but would be nice to avoid it.
Is it possible to get the same node Id if following this sequence?

Mark node as failed
Remove failed node
Factory reset the node
Set node into inclusion mode?
Replace failed node

Canedje · April 2, 2020, 2:52pm

As far as I know not
I think “factory reset” is an important step to fix indeed fix your problem.
Your sequence is right
but i don’t think replace failed node will work, only add node or add secure node